builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0224 starttime: 1473165257.4 results: success (0) buildid: 20160906030431 builduid: 004647c314cb429c94afcf628aa7deff revision: 8c9c4e816e86f903c1d820f3f29715dc070a5a4a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:17.396732) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:17.397221) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:17.397530) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:17.519111) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:17.519465) ========= 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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-09-06 05:34:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.11M=0.001s 2016-09-06 05:34:18 (8.11 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.530846 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:18.081126) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:18.081668) ========= 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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.020212 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:18.134515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 05:34:18.135179) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c9c4e816e86f903c1d820f3f29715dc070a5a4a --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 8c9c4e816e86f903c1d820f3f29715dc070a5a4a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-09-06 05:34:18,224 truncating revision to first 12 chars 2016-09-06 05:34:18,224 Setting DEBUG logging. 2016-09-06 05:34:18,224 attempt 1/10 2016-09-06 05:34:18,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c9c4e816e86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 05:34:19,210 unpacking tar archive at: mozilla-central-8c9c4e816e86/testing/mozharness/ program finished with exit code 0 elapsedTime=1.271964 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 05:34:19.433757) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:19.434427) ========= script_repo_revision: 8c9c4e816e86f903c1d820f3f29715dc070a5a4a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:19.435317) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:19.435851) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 05:34:19.455510) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-06 05:34:19.456230) ========= /tools/buildbot/bin/python 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', '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:34:19 INFO - MultiFileLogger online at 20160906 05:34:19 in /builds/slave/test 05:34:19 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 05:34:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:34:19 INFO - {'append_to_log': False, 05:34:19 INFO - 'base_work_dir': '/builds/slave/test', 05:34:19 INFO - 'blob_upload_branch': 'Firefox', 05:34:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:34:19 INFO - 'branch': 'Firefox', 05:34:19 INFO - 'buildbot_json_path': 'buildprops.json', 05:34:19 INFO - 'config_files': ('talos/mac_config.py',), 05:34:19 INFO - 'default_actions': ('clobber', 05:34:19 INFO - 'read-buildbot-config', 05:34:19 INFO - 'download-and-extract', 05:34:19 INFO - 'populate-webroot', 05:34:19 INFO - 'create-virtualenv', 05:34:19 INFO - 'install', 05:34:19 INFO - 'run-tests'), 05:34:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:34:19 INFO - 'download_minidump_stackwalk': True, 05:34:19 INFO - 'download_symbols': 'ondemand', 05:34:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:34:19 INFO - 'tooltool.py': '/tools/tooltool.py', 05:34:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:34:19 INFO - '/tools/misc-python/virtualenv.py')}, 05:34:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:34:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:34:19 INFO - 'installer_path': 'installer.exe', 05:34:19 INFO - 'log_level': 'info', 05:34:19 INFO - 'log_name': 'talos', 05:34:19 INFO - 'log_to_console': True, 05:34:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:34:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:34:19 INFO - 'opt_config_files': (), 05:34:19 INFO - 'pip_index': False, 05:34:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:34:19 INFO - 'cmd': ('bash', 05:34:19 INFO - '-c', 05:34:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:34:19 INFO - 'enabled': True, 05:34:19 INFO - 'halt_on_failure': False, 05:34:19 INFO - 'name': 'check_screen_resolution'},), 05:34:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:34:19 INFO - 'cmd': ('bash', 05:34:19 INFO - '-c', 05:34:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:34:19 INFO - 'enabled': True, 05:34:19 INFO - 'halt_on_failure': False, 05:34:19 INFO - 'name': 'check_screen_resolution'},), 05:34:19 INFO - 'run_cmd_checks_enabled': True, 05:34:19 INFO - 'sps_profile': False, 05:34:19 INFO - 'sps_profile_interval': 0, 05:34:19 INFO - 'suite': 'dromaeojs-e10s', 05:34:19 INFO - 'system_bits': '32', 05:34:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:34:19 INFO - 'title': 't-yosemite-r7-0224', 05:34:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:34:19 INFO - 'use_talos_json': True, 05:34:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:34:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:34:19 INFO - 'work_dir': 'build'} 05:34:19 INFO - [mozharness: 2016-09-06 12:34:19.668085Z] Running clobber step. 05:34:19 INFO - Running pre-action listener: _resource_record_pre_action 05:34:19 INFO - Running main action method: clobber 05:34:19 INFO - rmtree: /builds/slave/test/build 05:34:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:34:21 INFO - Running post-action listener: _resource_record_post_action 05:34:21 INFO - [mozharness: 2016-09-06 12:34:21.284101Z] Finished clobber step (success) 05:34:21 INFO - [mozharness: 2016-09-06 12:34:21.284232Z] Running read-buildbot-config step. 05:34:21 INFO - Running pre-action listener: _resource_record_pre_action 05:34:21 INFO - Running main action method: read_buildbot_config 05:34:21 INFO - Using buildbot properties: 05:34:21 INFO - { 05:34:21 INFO - "project": "", 05:34:21 INFO - "product": "firefox", 05:34:21 INFO - "script_repo_revision": "production", 05:34:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:34:21 INFO - "repository": "", 05:34:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:34:21 INFO - "buildid": "20160906030431", 05:34:21 INFO - "stage_platform": "macosx64", 05:34:21 INFO - "basedir": "/builds/slave/test", 05:34:21 INFO - "nightly_build": "True", 05:34:21 INFO - "buildnumber": 108, 05:34:21 INFO - "slavename": "t-yosemite-r7-0224", 05:34:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:34:21 INFO - "platform": "yosemite_r7", 05:34:21 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:34:21 INFO - "branch": "mozilla-central", 05:34:21 INFO - "repo_path": "mozilla-central", 05:34:21 INFO - "revision": "8c9c4e816e86f903c1d820f3f29715dc070a5a4a", 05:34:21 INFO - "pgo_build": "False", 05:34:21 INFO - "builduid": "004647c314cb429c94afcf628aa7deff", 05:34:21 INFO - "slavebuilddir": "test" 05:34:21 INFO - } 05:34:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:34:21 INFO - Running post-action listener: _resource_record_post_action 05:34:21 INFO - [mozharness: 2016-09-06 12:34:21.287714Z] Finished read-buildbot-config step (success) 05:34:21 INFO - [mozharness: 2016-09-06 12:34:21.287850Z] Running download-and-extract step. 05:34:21 INFO - Running pre-action listener: _resource_record_pre_action 05:34:21 INFO - Running main action method: download_and_extract 05:34:21 INFO - mkdir: /builds/slave/test/build/tests 05:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:34:21 INFO - trying https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:34:21 INFO - Downloading https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:34:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 05:34:22 INFO - Downloaded 1199 bytes. 05:34:22 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:34:22 INFO - Using the following test package requirements: 05:34:22 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:34:22 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:22 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:34:22 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:22 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:34:22 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:22 INFO - u'jsshell-mac.zip'], 05:34:22 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:22 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:34:22 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:34:22 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:22 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:34:22 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:22 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:34:22 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:22 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:34:22 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:34:22 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:34:22 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:34:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:34:22 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:34:29 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:34:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:34:30 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:34:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:34:37 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:34:39 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')]} 05:34:39 INFO - trying https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:34:39 INFO - Downloading https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:34:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 05:34:41 INFO - Downloaded 111667452 bytes. 05:34:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:34:41 INFO - mkdir: /builds/slave/test/properties 05:34:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:34:41 INFO - Writing to file /builds/slave/test/properties/build_url 05:34:41 INFO - Contents: 05:34:41 INFO - build_url:https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:34:42 INFO - Running post-action listener: _resource_record_post_action 05:34:42 INFO - Running post-action listener: set_extra_try_arguments 05:34:42 INFO - [mozharness: 2016-09-06 12:34:42.629451Z] Finished download-and-extract step (success) 05:34:42 INFO - [mozharness: 2016-09-06 12:34:42.629566Z] Running populate-webroot step. 05:34:42 INFO - Running pre-action listener: _resource_record_pre_action 05:34:42 INFO - Running main action method: populate_webroot 05:34:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:34:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:34:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:34:42 INFO - '--tppagecycles', 05:34:42 INFO - '7'], 05:34:42 INFO - 'tests': ['tsvgm']}}, 05:34:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:34:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:34:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:34:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:34:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:42 INFO - 'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:34:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:34:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:42 INFO - 'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['damp', 'tps']}, 05:34:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:42 INFO - 'tests': ['damp', 'tps']}, 05:34:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['dromaeo_dom']}, 05:34:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:34:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['basic_compositor_video']}, 05:34:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:34:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['a11yr', 05:34:42 INFO - 'ts_paint', 05:34:42 INFO - 'tpaint', 05:34:42 INFO - 'sessionrestore', 05:34:42 INFO - 'sessionrestore_no_auto_restore', 05:34:42 INFO - 'tabpaint']}, 05:34:42 INFO - 'other-e10s': {'tests': ['a11yr', 05:34:42 INFO - 'ts_paint', 05:34:42 INFO - 'tpaint', 05:34:42 INFO - 'sessionrestore', 05:34:42 INFO - 'sessionrestore_no_auto_restore', 05:34:42 INFO - 'tabpaint']}, 05:34:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:34:42 INFO - 'ts_paint', 05:34:42 INFO - 'tpaint', 05:34:42 INFO - 'sessionrestore', 05:34:42 INFO - 'sessionrestore_no_auto_restore', 05:34:42 INFO - 'tabpaint']}, 05:34:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:34:42 INFO - 'ts_paint', 05:34:42 INFO - 'tpaint', 05:34:42 INFO - 'sessionrestore', 05:34:42 INFO - 'sessionrestore_no_auto_restore', 05:34:42 INFO - 'tabpaint']}, 05:34:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['a11yr', 05:34:42 INFO - 'ts_paint', 05:34:42 INFO - 'tpaint', 05:34:42 INFO - 'sessionrestore', 05:34:42 INFO - 'sessionrestore_no_auto_restore', 05:34:42 INFO - 'tabpaint']}, 05:34:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['a11yr', 05:34:42 INFO - 'ts_paint', 05:34:42 INFO - 'tpaint', 05:34:42 INFO - 'sessionrestore', 05:34:42 INFO - 'sessionrestore_no_auto_restore', 05:34:42 INFO - 'tabpaint']}, 05:34:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['tsvgx', 05:34:42 INFO - 'tsvgr_opacity', 05:34:42 INFO - 'tart', 05:34:42 INFO - 'tscrollx', 05:34:42 INFO - 'cart']}, 05:34:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:34:42 INFO - 'tsvgr_opacity', 05:34:42 INFO - 'tart', 05:34:42 INFO - 'tscrollx', 05:34:42 INFO - 'cart']}, 05:34:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:42 INFO - 'talos_options': ['--disable-e10s'], 05:34:42 INFO - 'tests': ['tp5o']}, 05:34:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:42 INFO - 'tests': ['tp5o']}, 05:34:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:42 INFO - 'talos_options': ['--disable-e10s', 05:34:42 INFO - '--xperf_path', 05:34:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:34:42 INFO - 'tests': ['tp5n']}, 05:34:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:34:42 INFO - 'talos_options': ['--xperf_path', 05:34:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:34:42 INFO - 'tests': ['tp5n']}}, 05:34:42 INFO - 'talos.zip': {'path': '', 05:34:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:34:42 INFO - Running post-action listener: _resource_record_post_action 05:34:42 INFO - [mozharness: 2016-09-06 12:34:42.648346Z] Finished populate-webroot step (success) 05:34:42 INFO - [mozharness: 2016-09-06 12:34:42.648433Z] Running create-virtualenv step. 05:34:42 INFO - Running pre-action listener: _resource_record_pre_action 05:34:42 INFO - Running main action method: create_virtualenv 05:34:42 INFO - Creating virtualenv /builds/slave/test/build/venv 05:34:42 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 05:34:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:34:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:34:42 INFO - Using real prefix '/tools/python27' 05:34:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:34:43 INFO - Installing distribute.............................................................................................................................................................................................done. 05:34:46 INFO - Installing pip.................done. 05:34:46 INFO - Return code: 0 05:34:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:34: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')]} 05:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34: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')]} 05:34:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:46 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:34:46 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 05:34:46 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 05:34:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:34:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:46 INFO - 'HOME': '/Users/cltbld', 05:34:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:46 INFO - 'LOGNAME': 'cltbld', 05:34:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:46 INFO - 'MOZ_NO_REMOTE': '1', 05:34:46 INFO - 'NO_EM_RESTART': '1', 05:34:46 INFO - 'PAGER': '/bin/cat', 05:34:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:46 INFO - 'PWD': '/builds/slave/test', 05:34:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:46 INFO - 'SHELL': '/bin/bash', 05:34:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:34:46 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:34:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:46 INFO - 'USER': 'cltbld', 05:34:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:46 INFO - 'XPC_FLAGS': '0x0', 05:34:46 INFO - 'XPC_SERVICE_NAME': '0', 05:34:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:46 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 05:34:46 INFO - Cleaning up... 05:34:46 INFO - Return code: 0 05:34:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:34: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')]} 05:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34: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')]} 05:34:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:46 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:34:46 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 05:34:46 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 05:34:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:34:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:46 INFO - 'HOME': '/Users/cltbld', 05:34:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:46 INFO - 'LOGNAME': 'cltbld', 05:34:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:46 INFO - 'MOZ_NO_REMOTE': '1', 05:34:46 INFO - 'NO_EM_RESTART': '1', 05:34:46 INFO - 'PAGER': '/bin/cat', 05:34:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:46 INFO - 'PWD': '/builds/slave/test', 05:34:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:46 INFO - 'SHELL': '/bin/bash', 05:34:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:34:46 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:34:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:46 INFO - 'USER': 'cltbld', 05:34:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:46 INFO - 'XPC_FLAGS': '0x0', 05:34:46 INFO - 'XPC_SERVICE_NAME': '0', 05:34:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:46 INFO - Downloading/unpacking psutil>=3.1.1 05:34:46 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 05:34:46 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 05:34:46 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 05:34:46 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 05:34:46 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 05:34:46 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 05:34:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:34:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:34:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:34:50 INFO - Installing collected packages: psutil 05:34:50 INFO - Running setup.py install for psutil 05:34:50 INFO - building 'psutil._psutil_osx' extension 05:34:50 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 05:34:50 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 05:34:50 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 05:34:50 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 05:34:50 INFO - building 'psutil._psutil_posix' extension 05:34:50 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 05:34:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:34:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:34:50 INFO - ^ 05:34:50 INFO - 1 warning generated. 05:34:50 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 05:34:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:34:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:34:51 INFO - Successfully installed psutil 05:34:51 INFO - Cleaning up... 05:34:51 INFO - Return code: 0 05:34:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:51 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:34:51 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 05:34:51 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 05:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:51 INFO - 'HOME': '/Users/cltbld', 05:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:51 INFO - 'LOGNAME': 'cltbld', 05:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:51 INFO - 'MOZ_NO_REMOTE': '1', 05:34:51 INFO - 'NO_EM_RESTART': '1', 05:34:51 INFO - 'PAGER': '/bin/cat', 05:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:51 INFO - 'PWD': '/builds/slave/test', 05:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:51 INFO - 'SHELL': '/bin/bash', 05:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:34:51 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:51 INFO - 'USER': 'cltbld', 05:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:51 INFO - 'XPC_FLAGS': '0x0', 05:34:51 INFO - 'XPC_SERVICE_NAME': '0', 05:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:34:51 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 05:34:51 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 05:34:51 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 05:34:51 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 05:34:51 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 05:34:51 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 05:34:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:34:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:34:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:34:53 INFO - Installing collected packages: mozsystemmonitor 05:34:53 INFO - Running setup.py install for mozsystemmonitor 05:34:54 INFO - Successfully installed mozsystemmonitor 05:34:54 INFO - Cleaning up... 05:34:54 INFO - Return code: 0 05:34:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:34:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:34:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:34:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:54 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:34:54 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 05:34:54 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 05:34:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:34:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:54 INFO - 'HOME': '/Users/cltbld', 05:34:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:54 INFO - 'LOGNAME': 'cltbld', 05:34:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:54 INFO - 'MOZ_NO_REMOTE': '1', 05:34:54 INFO - 'NO_EM_RESTART': '1', 05:34:54 INFO - 'PAGER': '/bin/cat', 05:34:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:54 INFO - 'PWD': '/builds/slave/test', 05:34:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:54 INFO - 'SHELL': '/bin/bash', 05:34:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:34:54 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:34:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:54 INFO - 'USER': 'cltbld', 05:34:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:54 INFO - 'XPC_FLAGS': '0x0', 05:34:54 INFO - 'XPC_SERVICE_NAME': '0', 05:34:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:54 INFO - Downloading/unpacking jsonschema==2.5.1 05:34:54 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 05:34:54 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 05:34:54 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 05:34:54 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 05:34:54 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 05:34:54 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 05:34:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:34:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:34:56 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 05:34:56 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 05:34:56 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 05:34:56 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 05:34:56 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 05:34:56 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 05:34:57 INFO - Downloading functools32-3.2.3-2.tar.gz 05:34:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:34:57 INFO - warning: no files found matching '*.txt' 05:34:57 INFO - no previously-included directories found matching 'build' 05:34:57 INFO - no previously-included directories found matching 'dist' 05:34:57 INFO - no previously-included directories found matching '.git*' 05:34:57 INFO - Installing collected packages: jsonschema, functools32 05:34:57 INFO - Running setup.py install for functools32 05:34:57 INFO - warning: no files found matching '*.txt' 05:34:57 INFO - no previously-included directories found matching 'build' 05:34:57 INFO - no previously-included directories found matching 'dist' 05:34:57 INFO - no previously-included directories found matching '.git*' 05:34:57 INFO - Successfully installed jsonschema functools32 05:34:57 INFO - Cleaning up... 05:34:57 INFO - Return code: 0 05:34:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:34:57 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')]} 05:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:57 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')]} 05:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:57 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:34:57 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 05:34:57 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 05:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:57 INFO - 'HOME': '/Users/cltbld', 05:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:57 INFO - 'LOGNAME': 'cltbld', 05:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:57 INFO - 'MOZ_NO_REMOTE': '1', 05:34:57 INFO - 'NO_EM_RESTART': '1', 05:34:57 INFO - 'PAGER': '/bin/cat', 05:34:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:57 INFO - 'PWD': '/builds/slave/test', 05:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:57 INFO - 'SHELL': '/bin/bash', 05:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:34:57 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:57 INFO - 'USER': 'cltbld', 05:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:57 INFO - 'XPC_FLAGS': '0x0', 05:34:57 INFO - 'XPC_SERVICE_NAME': '0', 05:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:34:57 INFO - Cleaning up... 05:34:57 INFO - Return code: 0 05:34:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:34:57 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')]} 05:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:57 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')]} 05:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:34:57 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:34:57 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 05:34:57 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 05:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:34:57 INFO - 'HOME': '/Users/cltbld', 05:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:34:57 INFO - 'LOGNAME': 'cltbld', 05:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:57 INFO - 'MOZ_NO_REMOTE': '1', 05:34:57 INFO - 'NO_EM_RESTART': '1', 05:34:57 INFO - 'PAGER': '/bin/cat', 05:34:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:57 INFO - 'PWD': '/builds/slave/test', 05:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:34:57 INFO - 'SHELL': '/bin/bash', 05:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:34:57 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:34:57 INFO - 'USER': 'cltbld', 05:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:57 INFO - 'XPC_FLAGS': '0x0', 05:34:57 INFO - 'XPC_SERVICE_NAME': '0', 05:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:34:58 INFO - Downloading/unpacking blobuploader==1.2.4 05:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:34:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:00 INFO - Downloading blobuploader-1.2.4.tar.gz 05:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:35:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:35:00 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 05:35:00 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 05:35:00 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 05:35:00 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 05:35:00 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 05:35:00 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 05:35:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:35:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:35:01 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 05:35:01 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 05:35:01 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 05:35:01 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 05:35:01 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 05:35:01 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 05:35:01 INFO - Downloading docopt-0.6.1.tar.gz 05:35:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:35:01 INFO - Installing collected packages: blobuploader, requests, docopt 05:35:01 INFO - Running setup.py install for blobuploader 05:35:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:35:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:35:02 INFO - Running setup.py install for requests 05:35:02 INFO - Running setup.py install for docopt 05:35:02 INFO - Successfully installed blobuploader requests docopt 05:35:02 INFO - Cleaning up... 05:35:02 INFO - Return code: 0 05:35:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:02 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:35:02 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 05:35:02 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 05:35:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:35:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:02 INFO - 'HOME': '/Users/cltbld', 05:35:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:02 INFO - 'LOGNAME': 'cltbld', 05:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:02 INFO - 'MOZ_NO_REMOTE': '1', 05:35:02 INFO - 'NO_EM_RESTART': '1', 05:35:02 INFO - 'PAGER': '/bin/cat', 05:35:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:02 INFO - 'PWD': '/builds/slave/test', 05:35:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:02 INFO - 'SHELL': '/bin/bash', 05:35:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:35:02 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:35:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:02 INFO - 'USER': 'cltbld', 05:35:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:02 INFO - 'XPC_FLAGS': '0x0', 05:35:02 INFO - 'XPC_SERVICE_NAME': '0', 05:35:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-8JGsqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-qiIQmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-VX2Hwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-H9YW5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-H4IHuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-y8OCo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-RCGYNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-icO40V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:03 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-5xycfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:04 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-DY6Idb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:04 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-SAlf9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:04 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-sPL6ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:04 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-HyiRwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:04 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-Dr8dTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:04 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-0iCRXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:04 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-I6d94x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:04 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-DnwN4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:35:04 INFO - Running setup.py install for manifestparser 05:35:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:35:05 INFO - Running setup.py install for mozcrash 05:35:05 INFO - Running setup.py install for mozdebug 05:35:05 INFO - Running setup.py install for mozdevice 05:35:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:35:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:35:05 INFO - Running setup.py install for mozfile 05:35:05 INFO - Running setup.py install for mozhttpd 05:35:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:35:05 INFO - Running setup.py install for mozinfo 05:35:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:35:05 INFO - Running setup.py install for mozInstall 05:35:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Running setup.py install for mozleak 05:35:06 INFO - Running setup.py install for mozlog 05:35:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Running setup.py install for moznetwork 05:35:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Running setup.py install for mozprocess 05:35:06 INFO - Running setup.py install for mozprofile 05:35:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Running setup.py install for mozrunner 05:35:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:35:06 INFO - Running setup.py install for mozscreenshot 05:35:07 INFO - Running setup.py install for moztest 05:35:07 INFO - Running setup.py install for mozversion 05:35:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:35:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:35:07 INFO - Cleaning up... 05:35:07 INFO - Return code: 0 05:35:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:07 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:35:07 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 05:35:07 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 05:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:07 INFO - 'HOME': '/Users/cltbld', 05:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:07 INFO - 'LOGNAME': 'cltbld', 05:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:07 INFO - 'MOZ_NO_REMOTE': '1', 05:35:07 INFO - 'NO_EM_RESTART': '1', 05:35:07 INFO - 'PAGER': '/bin/cat', 05:35:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:07 INFO - 'PWD': '/builds/slave/test', 05:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:07 INFO - 'SHELL': '/bin/bash', 05:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:35:07 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:07 INFO - 'USER': 'cltbld', 05:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:07 INFO - 'XPC_FLAGS': '0x0', 05:35:07 INFO - 'XPC_SERVICE_NAME': '0', 05:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-4StmK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:07 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)) 05:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-0HxiDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:07 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-FOXmkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:08 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-DNzAcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-aFdO2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:08 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-7VaxPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:08 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-N9b1K7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:08 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-pIEDk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:08 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-J9WzIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:08 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-OiuD99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-q48sKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:08 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-b7cKmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:08 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)) 05:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:08 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-CoLBjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:09 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)) 05:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:09 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-E84N2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:09 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-HTWtWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:09 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)) 05:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:09 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-MpepYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:09 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)) 05:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:09 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-sFXtJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:09 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)) 05:35:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:35:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:35:09 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)) 05:35:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:35:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:35:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:11 INFO - Downloading blessings-1.6.tar.gz 05:35:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:35:12 INFO - Installing collected packages: blessings 05:35:12 INFO - Running setup.py install for blessings 05:35:12 INFO - Successfully installed blessings 05:35:12 INFO - Cleaning up... 05:35:12 INFO - Return code: 0 05:35:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:35:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:12 INFO - Reading from file tmpfile_stdout 05:35:12 INFO - Current package versions: 05:35:12 INFO - blessings == 1.6 05:35:12 INFO - blobuploader == 1.2.4 05:35:12 INFO - docopt == 0.6.1 05:35:12 INFO - functools32 == 3.2.3-2 05:35:12 INFO - jsonschema == 2.5.1 05:35:12 INFO - manifestparser == 1.1 05:35:12 INFO - mozInstall == 1.12 05:35:12 INFO - mozcrash == 0.17 05:35:12 INFO - mozdebug == 0.1 05:35:12 INFO - mozdevice == 0.48 05:35:12 INFO - mozfile == 1.2 05:35:12 INFO - mozhttpd == 0.7 05:35:12 INFO - mozinfo == 0.9 05:35:12 INFO - mozleak == 0.1 05:35:12 INFO - mozlog == 3.3 05:35:12 INFO - moznetwork == 0.27 05:35:12 INFO - mozprocess == 0.23 05:35:12 INFO - mozprofile == 0.28 05:35:12 INFO - mozrunner == 6.12 05:35:12 INFO - mozscreenshot == 0.1 05:35:12 INFO - mozsystemmonitor == 0.3 05:35:12 INFO - moztest == 0.7 05:35:12 INFO - mozversion == 1.4 05:35:12 INFO - psutil == 3.1.1 05:35:12 INFO - requests == 1.2.3 05:35:12 INFO - wsgiref == 0.1.2 05:35:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:12 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 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:35:12 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 05:35:12 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 05:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:12 INFO - 'HOME': '/Users/cltbld', 05:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:12 INFO - 'LOGNAME': 'cltbld', 05:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:12 INFO - 'MOZ_NO_REMOTE': '1', 05:35:12 INFO - 'NO_EM_RESTART': '1', 05:35:12 INFO - 'PAGER': '/bin/cat', 05:35:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:12 INFO - 'PWD': '/builds/slave/test', 05:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:12 INFO - 'SHELL': '/bin/bash', 05:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:35:12 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:12 INFO - 'USER': 'cltbld', 05:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:12 INFO - 'XPC_FLAGS': '0x0', 05:35:12 INFO - 'XPC_SERVICE_NAME': '0', 05:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 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)) 05:35:12 INFO - Cleaning up... 05:35:12 INFO - Return code: 0 05:35:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e158a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1ed618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb943f01a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, '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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 05:35:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:12 INFO - 'HOME': '/Users/cltbld', 05:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:12 INFO - 'LOGNAME': 'cltbld', 05:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:12 INFO - 'MOZ_NO_REMOTE': '1', 05:35:12 INFO - 'NO_EM_RESTART': '1', 05:35:12 INFO - 'PAGER': '/bin/cat', 05:35:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:12 INFO - 'PWD': '/builds/slave/test', 05:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:12 INFO - 'SHELL': '/bin/bash', 05:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:35:12 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:12 INFO - 'USER': 'cltbld', 05:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:12 INFO - 'XPC_FLAGS': '0x0', 05:35:12 INFO - 'XPC_SERVICE_NAME': '0', 05:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:35:13 INFO - Cleaning up... 05:35:13 INFO - Return code: 0 05:35:13 INFO - Running post-action listener: _resource_record_post_action 05:35:13 INFO - Running post-action listener: _start_resource_monitoring 05:35:13 INFO - Starting resource monitoring. 05:35:13 INFO - [mozharness: 2016-09-06 12:35:13.130201Z] Finished create-virtualenv step (success) 05:35:13 INFO - [mozharness: 2016-09-06 12:35:13.131189Z] Running install step. 05:35:13 INFO - Running pre-action listener: _resource_record_pre_action 05:35:13 INFO - Running main action method: install 05:35:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:13 INFO - Reading from file tmpfile_stdout 05:35:13 INFO - Detecting whether we're running mozinstall >=1.0... 05:35:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:35:13 INFO - Reading from file tmpfile_stdout 05:35:13 INFO - Output received: 05:35:13 INFO - Usage: mozinstall [options] installer 05:35:13 INFO - Options: 05:35:13 INFO - -h, --help show this help message and exit 05:35:13 INFO - -d DEST, --destination=DEST 05:35:13 INFO - Directory to install application into. [default: 05:35:13 INFO - "/builds/slave/test"] 05:35:13 INFO - --app=APP Application being installed. [default: firefox] 05:35:13 INFO - mkdir: /builds/slave/test/build/application 05:35:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:35:47 INFO - Reading from file tmpfile_stdout 05:35:47 INFO - Output received: 05:35:47 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:35:47 INFO - Running post-action listener: _resource_record_post_action 05:35:47 INFO - [mozharness: 2016-09-06 12:35:47.871570Z] Finished install step (success) 05:35:47 INFO - [mozharness: 2016-09-06 12:35:47.871721Z] Running run-tests step. 05:35:47 INFO - Running pre-action listener: _resource_record_pre_action 05:35:47 INFO - Running main action method: run_tests 05:35:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:35:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:35:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:35:47 INFO - 2016-09-06 05:35:47.886 screenresolution[1956:24512] starting screenresolution argv=screenresolution get 05:35:47 INFO - 2016-09-06 05:35:47.888 screenresolution[1956:24512] Display 0: 1600x1200x32@60 05:35:47 INFO - 2016-09-06 05:35:47.894 screenresolution[1957:24514] starting screenresolution argv=screenresolution list 05:35:47 INFO - Available Modes on Display 0 05:35:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:35:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:35:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:35:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:35:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:35:48 INFO - Intel Iris: 05:35:48 INFO - Chipset Model: Intel Iris 05:35:48 INFO - Type: GPU 05:35:48 INFO - Bus: Built-In 05:35:48 INFO - VRAM (Dynamic, Max): 1536 MB 05:35:48 INFO - Vendor: Intel (0x8086) 05:35:48 INFO - Device ID: 0x0a2e 05:35:48 INFO - Revision ID: 0x0009 05:35:48 INFO - Displays: 05:35:48 INFO - Display: 05:35:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:35:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:35:48 INFO - Display Serial Number: 1600x1200 60 05:35:48 INFO - Main Display: Yes 05:35:48 INFO - Mirror: Off 05:35:48 INFO - Online: Yes 05:35:48 INFO - Rotation: Supported 05:35:48 INFO - Return code: 0 05:35:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:35:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:35:48 INFO - Python 2.7.3 05:35:48 INFO - Return code: 0 05:35:48 INFO - grabbing minidump binary from tooltool 05:35:48 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')]} 05:35:48 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 0x10e0bcd30>, '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 0x10de21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:35:48 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 05:35:48 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 05:35:48 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 05:35:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:35:48 INFO - Return code: 0 05:35:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:35:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:35:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:35:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:35:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:35:48 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 05:35:48 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0224 --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 05:35:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DZ3Xr2cwxE/Render', 05:35:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:48 INFO - 'HOME': '/Users/cltbld', 05:35:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:48 INFO - 'LOGNAME': 'cltbld', 05:35:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:35:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:35:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:48 INFO - 'MOZ_NO_REMOTE': '1', 05:35:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:35:48 INFO - 'NO_EM_RESTART': '1', 05:35:48 INFO - 'PAGER': '/bin/cat', 05:35:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:48 INFO - 'PWD': '/builds/slave/test', 05:35:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:35:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:48 INFO - 'SHELL': '/bin/bash', 05:35:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwdW3yWyb3/Listeners', 05:35:48 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 05:35:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:48 INFO - 'USER': 'cltbld', 05:35:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:48 INFO - 'XPC_FLAGS': '0x0', 05:35:48 INFO - 'XPC_SERVICE_NAME': '0', 05:35:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:48 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 05:35:48 INFO - mozversion application_buildid: 20160906030431 05:35:48 INFO - mozversion application_changeset: 8c9c4e816e86f903c1d820f3f29715dc070a5a4a 05:35:48 INFO - mozversion application_display_name: Nightly 05:35:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:35:48 INFO - mozversion application_name: Firefox 05:35:48 INFO - mozversion application_remotingname: firefox 05:35:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:35:48 INFO - mozversion application_vendor: Mozilla 05:35:48 INFO - mozversion application_version: 51.0a1 05:35:48 INFO - mozversion platform_buildid: 20160906030431 05:35:48 INFO - mozversion platform_changeset: 8c9c4e816e86f903c1d820f3f29715dc070a5a4a 05:35:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:35:48 INFO - mozversion platform_version: 51.0a1 05:35:48 INFO - using testdate: 1473165348 05:35:48 INFO - actual date: 1473165348 05:35:48 INFO - starting webserver on 'localhost:56104' 05:35:48 INFO - SUITE-START | Running 2 tests 05:35:48 INFO - TEST-START | dromaeo_css 05:35:48 INFO - operating with platform_type : mac_ 05:35:48 INFO - Initialising browser for dromaeo_css test... 05:35:48 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpUpDOjn/profile http://localhost:56104/getInfo.html) 05:35:51 INFO - PROCESS | 1967 | __metrics Screen width/height:1600/1200 05:35:51 INFO - PROCESS | 1967 | colorDepth:24 05:35:51 INFO - PROCESS | 1967 | Browser inner width/height: 1024/689 05:35:51 INFO - PROCESS | 1967 | __metrics 05:35:51 ERROR - PROCESS | 1967 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 05:35:52 INFO - TEST-INFO | 1967: exit 0 05:35:52 INFO - Browser initialized. 05:35:52 INFO - Running cycle 1/1 for dromaeo_css test... 05:35:52 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpUpDOjn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:04 INFO - PROCESS | 1971 | RSS: Main: 243793920 05:36:04 INFO - PROCESS | 1971 | 05:36:53 INFO - PROCESS | 1971 | 1473165413798 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] 05:39:05 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:56104/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56104/tests/dromaeo/cssquery-ext.html) 05:39:07 INFO - PROCESS | 1971 | RSS: Main: 264515584 05:39:07 INFO - PROCESS | 1971 | 05:42:09 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:56104/tests/dromaeo/cssquery-ext.html (next: http://localhost:56104/tests/dromaeo/cssquery-jquery.html) 05:42:10 INFO - PROCESS | 1971 | RSS: Main: 271601664 05:42:10 INFO - PROCESS | 1971 | 05:45:12 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:56104/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56104/tests/dromaeo/cssquery-mootools.html) 05:45:13 INFO - PROCESS | 1971 | RSS: Main: 272150528 05:45:13 INFO - PROCESS | 1971 | 05:48:14 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:56104/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56104/tests/dromaeo/cssquery-prototype.html) 05:48:15 INFO - PROCESS | 1971 | RSS: Main: 268419072 05:48:15 INFO - PROCESS | 1971 | 05:51:18 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:56104/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56104/tests/dromaeo/cssquery-yui.html) 05:51:19 INFO - PROCESS | 1971 | RSS: Main: 274239488 05:51:19 INFO - PROCESS | 1971 | 05:54:21 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:56104/tests/dromaeo/cssquery-yui.html (next: http://localhost:56104/tests/dromaeo/cssquery-dojo.html) 05:54:22 INFO - PROCESS | 1971 | RSS: Main: 277258240 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - PROCESS | 1971 | __start_tp_report 05:54:22 INFO - PROCESS | 1971 | _x_x_mozilla_page_load 05:54:22 INFO - PROCESS | 1971 | _x_x_mozilla_page_load_details 05:54:22 INFO - PROCESS | 1971 | |i|pagename|runs| 05:54:22 INFO - PROCESS | 1971 | |0;dojo.html;3395.3888300105973;3424.6404127566607;3434.5535080439545;3434.639362866899;3468.098294443444;5790.33411157717;5828.679422631753;5858.882822343545;5875.089361149024;5930.703464826763;5866.882662346761;5871.911921321183;5883.941160588403;5893.204417403649;5912.763489460416;5285.471452854713;5344.599155063386;5388.326459192601;5405.243265942771;5413.729313534327;4164.562720914306;4782.617390608761;5184.196449550307;5244.370675518943;5286.577073834084;5305.681659100466;5323.946760532383;5325.866853328698;5338.973305133488;5343.492368225013;5306.336707911511;5309.256704061435;5316.707581083039;5326.600504962104;5395.082835917903;5085.364329458817;5174.120399532088;5242.397124330712;5253.894922101536;5255.789768409298;5747.798827041033;5849.941500585025;5865.266841644794;5884.6763428011445;5946.494547963428;6162;6171.166892469504;6183.412575805291;6198.566100372976;6236.095766113934;6102.337773232528;6117.938820611781;6135.324535308531;6253.874922501524;6260.78087266948;4742.264948932921;5840.153177789197;5850;5882;5883.352831188566;6259.18630578031;6315.810525684236;6331.905021424683;6338.936610633927;6346.841328966812;6070;6142.805741014737;6176.876462470816;6176.969115154486;6191.606888450153;6285.968570157212;6302.4958003359625;6306.905396419057;6313.652749098752;6324.33594472583;6208;6224.906626400608;6243.094751261042;6245.5628106033055;6265.091561723524;6090;6100.793122958854;6149.13912052313;6241.7191226394925;6247.375262473807;5065.594752419797;5072.619553533426;5078.314427552307;5095.388553373545;5118.718470484159;4557.680962332605;4561.840335588238;4568.086382723469;4585.770711464414;4621.145088158702;5632.2678051853;5967.313758917693;5979.431953964367;6024.819255422344;6044.818655440344;6267.6866156691985;6271.498280137578;6276.623402595767;6281.466075383552;6290.622562646165;5990.520758339322;6015.54883383748;6016;6029.547783916224;6039.939600603938;5380.1929710542945;5386.91919621206;5412.431694672082;5478.369987451414;5612.438756124355;6216.564840461305;6247.219097612799;6247.468965137923;6264.843378915563;6265.592736472206;6160.291566469913;6209.2548894133;6222.564420490522;6244.281907580505;6254.21822272216;6193.411625895533;6234.470070043915;6303.747850085945;6308.747650094128;6340.873182536416;5680.233168522362;5889.764409423575;6235.719392627252;6244.594101383395;6261.78083767066;6222.906656400067;6225.688715564294;6246.000639897595;6254.374562543891;6260.968695156495;6068.757249710139;6145.279321789906;6164.044573091178;6174.047630935072;6196.287426945959;5226.346706661668;6058.7273572688455;6213.316535181036;6223.906641400292;6244.125822384779;5814.622049566765;5838.474537291664;5911.99496085675;6102.298235702951;6179.227596550431;4838.935434204469;4847.903041939212;4912.312276281252;5965.34381218057;6208.906866396917;6159.353267906985;6168.691565421801;6173.135760993375;6178.567500274938;6181.990852972712 05:54:22 INFO - PROCESS | 1971 | |1;ext.html;473.7962676049298;514.4058612302791;518.9455107213743;531.859162097301;539.2908325551897;27424.451510969768;35191.824040879794;40536.79731601338;40736;40787;40870.18259634806;41452;41500.79249603755;41566;41581;9597.616095356178;9825.263105267099;9830.164436022947;9834;9841.409515429097;9750.414975101481;9867.753306167331;9868.407895526256;9884;9887.011298870128;9845.36005159666;9875.753106172331;9880.752981175492;9891.554880030415;9934.45360505172;9808.411495310233;9837.852432213522;9883.357581757164;9888.357256778287;9910;9707.466089365082;9715.61137554497;9745.951270243604;9751.90248097517;9790.265730070201;39757;40008.99977500557;40023.19953600941;40041;40050.59949400498;3242.1084201844446;3307.107081088117;3347.782394144397;3398.269372084989;3842.692584593226;30722.771089156406;31165.844170779226;31224.531632025537;31313.530297045563;31967.680323196702;3234.8544315505624;3236.6277878043857;3242.238074052596;3337.599488061448;3343.164208947763;506121.4693926507;508602.4569877164;510608;512662.4366878179;513970;32292.35415291728;33071;57085.42914570884;57479.85040299171;57576.84846303134;34288.65711342855;34314.48528272078;34780;34853.1286717829;34903.82548087243;14538.982271241122;14684.41262349494;14698.191599462132;14739.852601474062;14743.041702289316;12414.81377779334;12502.3123728194;12562.246265223934;12564.308963006939;12580.4338804754;7626.771196864103;7647.349975252166;7683.0780306363595;7732.7680169595005;7750.914871917936;3240.951385729216;3295.2256219788333;3297.3405318936307;3303.157694787814;3322.850471728778;852.2372476633378;969.7042402067437;1002.7443002034435;1016.3190662256306;1038.1435315864442;28088.314701117997;28658.85670571634;29891.293547168923;30040.948566800056;30118.84940575283;974.8635191073255;1016.6390931219458;1034.8447732839984;1084.4957094950885;1122.9556512443505;13082.345882705828;15151.272770901343;15342.386304547694;15650.765238521204;15843.366265349487;667237;674498;677494;682076.5377038696;682196;50883.982320354124;56212;56276.71861640666;56831.43168568344;56893;1100.1254003067581;1345.663584103974;1346.6431395680152;1357.151780137414;1357.4570171931307;28550.286242844097;29379.41241175122;29692.25769355697;29954;30656.233594160323;47511.52488475071;47577.52422475731;47593.81015474641;47609;48148;608923.5538222983;609299;609861;610949;612405;20878.37364879056;25321.62017569701;26095;26099;26164.476710466064;45654.54345456503;46344;46722.29916551187;46793.66031698469;46853;37085.814570926974;38032.19678032175;38364;39297;39849.60150398575;32672.856450024134;37847;37895.81052094722;37899.24201515899;39303.60696393114;36877;37500.812495937345;37547.249055019296;37798.81100594479;37998.62001379951;26833.787465940055;28013.719862801925;33335.33299339069;33609.83195084009;33923.66076339304;24157;25184.11855585046;25240;25309.873450633364;25470.235892923243 05:54:22 INFO - PROCESS | 1971 | |2;jquery.html;375.6206231705978;376.792763979811;383.1035377217312;387.08261420433575;399.57445320733433;18702.71945920812;20140.899295503503;21447.571048579037;21649.567008659837;21700.240491582797;11508.769824603503;12103;12113.818292725595;12138.028957683386;19196.040197990078;4766.73782941938;5305.840824775243;5582.581306402036;5582.8325150245355;5596.132599447072;5297.47025297471;5484.067708489546;5501.092319767254;5535.169724541331;5552.167174923753;5520.834374968737;5532.6680399176175;5537;5553.555715542767;5565.888682226353;5520.6687598744;6656.36764507371;7928;8003.8799418008775;8121.796955076111;7526.698932042712;7699.461037727361;7709.38324934004;7764.805879852993;7806.570638614874;28889.711102889123;28940.855295723388;29245.122646320644;29439.852800736073;29727.554086688717;2524.368907773057;2649.112547296658;2673.1980405878157;2871.3970066286106;2897.942041159165;16590.585235369213;16666.58333541659;16796.58008549796;17025;17147.228374723167;2014.9320859944253;2021.6967454881737;2024.5141166120025;2043.3767700851236;2050.8256798172174;147843;151786;153188;154268;156732;21908.780912190676;22524.662130068064;22690.773092268864;22886.885565572065;22919.656205156934;28372.439515826416;34862.477062844075;35401.29197416037;35407;35551.93344199678;10583.206259530565;10821;10938.726531836608;10976.780464390828;11062.174741167779;8994;9012.594433250519;9025.32310076747;9098.408603440756;9192.908070919339;9065.957414897239;9252.537373131316;9685.869108796054;9793.706188814347;9863.950680246553;3331.9666803332143;3632.564092308887;3765.755225910307;3908.1895152226352;3934.1738234970976;849.6983570832266;870.4777133719845;922.5525620074259;929.1080562659787;988.2489308125732;20859.478513037295;21100.366988990354;21644.91775411223;21879.343619691434;21902.12391504354;839.202757380491;913.7578541686458;951.871497347863;953.7839254949855;979.2606582030521;10565.894341056644;10962.561497540168;11438.027767639844;11482.827757583644;11527.365994870195;575096;582687;585437;598510;602025;81876;84097.57951210327;123497.76502235042;126748;130671;966.5940305071887;999.5402115027024;1016.9288149829589;1019.6278358399279;1024.329064462778;18893.338733144275;19811.900940495205;19939;20655.380338589865;20695.482612934196;57988;58121.70939145277;60292.39707603044;60382;60948.08577871247;65878;73699;79848;82190.17809821825;85425.57287213524;8106.39202059836;8139.715109971123;8255.504669719836;8290.839282500576;8356.331493480386;8374.455660382057;12799.232046077264;13018.674533136747;13025.153365031243;13403.731925361242;9108.49709797876;9838.409695418297;10575.36547807134;10785.514651840529;11364.829527556927;8195.508269503849;9080.273578113603;10038.548265327932;11264.493097810455;11664;9595;10452;11398.829017564909;11729.35488548138;11864.940675296913;8155.836883262183;8316.459430137022;8384.245417912418;8396.496210227406;8401.579921003804;7956;10418.635347762793;10702.304350217211;10748.516316765921;10865.456727163768 05:54:22 INFO - PROCESS | 1971 | |3;mootools.html;1404.8454669986304;1522.3529999750108;1729.5157355940332;1853.2123847364867;1894.564250222449;7933.2463415975535;8520.829583408336;8581.442206256588;9095.772605684853;9108.908910910888;8669.783255418602;8802.163794439519;8950.194482496574;8953.328500362466;8964.551772411385;2369.360272726367;2397.1010870923405;2400.0039983406864;2402.3033320337167;2411.3127758588807;2359.528094381131;2371.2530552875874;2383.392234980078;2410.987945060281;2425.3936515870946;2394.221877889684;2395.6167013277877;2401.5317013182434;2404.615261558151;2418.613021916494;2356.9057237710385;2357.3281614739803;2367.3844800351862;2373.181252467896;2398.8920498577604;2340.0639744102323;2369.6919400478;2392.7009123859516;2395.2335252719135;2395.6406539019113;8808.955955220246;8862.468251904906;8967.686130985385;8979.595918183697;9001.594928228245;5244.895102097975;5395.271638328814;5395.676259424408;5407.594430417735;5407.756650950717;3047.7104675056057;3642.8542858285637;3677.5586929568353;3691.151035261905;3717.275131349388;1084.3277168155794;1092.8633920759905;1096.5668560918393;1097.5993762276712;1115.068917453927;11643.650690479299;11712.941435292769;11815;11820.940895295469;11857.169998100137;4522.4573051234065;4647.140279048387;4788.712677239307;4829.1790395632825;4830.565249127525;9897.554610042565;9943.15483183923;9952.950235248922;9957.452340121363;9966.800663986825;5316.893662126736;5323.1749078892835;5341.572674186055;5348.465153484621;5353.544948679406;4405.581469760367;4771.165046116916;4906.435759887588;4912.606991440676;4929.901401971941;3884.4173373994126;3943.6253555912144;3995.860144894915;4022.718409711292;4054.4729185205733;1313.8620444853343;1320.8348956380453;1322.8677132286887;1330.700592366729;1335.3189873164756;751.7481643649329;786.3905473258156;908.2370808520742;918.701422037827;935.6771913689767;5679.488846003879;5795.623284486495;5932.466078052996;5959.344472108065;6086.330503644595;365.8719448193111;439.83506185180556;444.3956219541421;448.87655894628585;450.3357547617238;3502.614712381636;3510.57873055235;3586.1572530455323;3589.5513060867393;3624.800635965047;10606.310589811637;10854.403007834644;10936.070434013198;10969.725756856142;10981.560737570408;10825.971532704538;10843.51204195813;10938.234323597271;10961.616343427942;10974.561017559368;8033.317168040665;8042.477238979448;8228.753137405887;8317.334613230927;8345.4158208926;5755.53955683536;5808.593398462067;5862.648241105548;5878.3239927407185;5986;9949.054839790208;10139;10180.796384072426;10207.642732504326;10241.539130739284;9718.75703107428;10179.185665146622;10203.38779673222;10321.948390258302;10392.948035259775;6991.2309645937885;7374.336309731935;7559.28186822251;7567.31894129531;7664.195259497673;8515;8594.527300998505;8609.48343099416;8616.698415555426;8670.306375490072;7504.362129218859;7638.045244344457;7641.541507509567;7662.118856331594;7747.070351557849;7432.925670743223;7467.626618669154;7505.437092217996;7512.511686740344;7647.438108233581;6110.7250173741395;6945.340192681687;7087.149542054986;7093.432525397852;7101.147862256562;4916.139675556692;4919.729414882073;5060.316857224375;5082.440931497458;5090.949090509048;5006.574441172541;5019.573336266459;5093.18509038552;5116.437191908962;5173.844784656466 05:54:22 INFO - PROCESS | 1971 | |4;prototype.html;298.3555520076635;306.1565387357829;312.4111050669488;313.81484923894925;314.9763767717422;4360.651147908167;4758.310045043467;4912.820922978486;5239.52844244018;5253.922945796112;4653.883652908679;5107.565856902159;5324.893502129965;5569.6658200508;5595.944040559593;3748.9690335157775;3919;4048.1296521247928;4053.209624123297;4093.6111069448357;3890.9610903891025;4182.226288136704;4283.635890949273;4290.334998075304;4301.730989358131;3940.6453419192267;4166.791600451766;4173.123644034757;4185.21683080751;4185.8534951276715;4184.9449901572025;4187.518435379925;4192.224438478891;4205.3481710334945;4208.978955105235;3043.9847800761077;3576.5708115026105;3587.6053634100226;3607.6753092221825;3616.9819150904077;5294.41761406245;5340.91988620171;5388.595855310868;5415.620906536544;5442.428545002757;4555.314533622548;4800.879978000543;4808.230683090689;4813.486155106757;4843.467218605951;4495.415595972535;5548.6960564267765;5828.841475294395;5924.496417804492;5945.167676525247;3909.9218015639813;3930.347015764376;3937.330653788863;3960.485136932209;3960.6039396060164;6064.5432103700905;6099.634021958653;6130.601510901822;6147.907781383283;6243.469305109071;3966;3970.3845903884944;3970.4242884782125;3992.4210989406956;3995.9001024974027;3429.879954201641;3573.356795776733;3812.923741525154;3821.808909554511;4041.1715598302276;3999.7200195986;4691.648126390534;4717;4730.077634861238;4730.834420795324;4928.950710492921;4941.876453088702;4980.601551875841;5825.427039773757;5853.5317174625925;1069.9835156601105;1194.9820752688718;1287.8390201224847;1299.8505171905351;1359.5377571625695;879.947203167812;895.6730793260543;909.1136142261242;914.7621618379269;916.7891384981492;5360.303160589098;5468.125099983983;5469.835904922858;5474.972625136849;5527.208759576388;4516.277395616751;5280.234366016906;5737.799177028784;5995.340512543617;6002.868981511588;4032.9395059074136;4956;5070.721110338966;5437.537809286255;5569.078667859582;2160.0883218351873;2461.704595448522;2518.7607177318127;2695.5578765360438;2698.8110832241946;4945.530174633404;5123.692578445305;5133.820316288912;5165.767540460688;5200.791968321225;4553.833242582999;4972.453030166679;5073.213651883964;5223.720188553829;5946.851328716817;6343.651099189497;6384.52116091295;6390.808275751735;6392.712327945161;6456.418922297016;5651.858703532444;6061.727222275096;6170.629762214281;6263.054275806395;6270.83989461951;5721.456461636138;5989.401059893976;5997.460228579449;6061.666608336407;6106.755729770937;6168.766246750578;6215.57041880361;6300.243970723543;6358.823617630753;6399.9680001601555;3770.6040865708705;3848.9230215396096;3967.004911291956;4042.3330150526012;4044.9191016178925;4675.812967481263;6139.938600613937;6142.355052719578;6200.325912003824;6204.5967012144065;4780.426348838162;5203.16749320107;5721.570882183936;5742.425757424391;5872.853178670567;5830.154627579062;5924.407559244041;5948.821535353946;5953.255843019622;5962.37395073511;5057.46896575869;5156.61325400589;5179;5320.0049981257025;5456.590755693418;4458.398116254394;4538.02432477019;5703.258576385043;5859.780957710442;6011.849703757441;5089.6691715038405;5348.529154482393;5759.107338362561;5843.590948633555;5874.148248504029 05:54:22 INFO - PROCESS | 1971 | |5;yui.html;619.8171539395878;723.5405517496391;772.1352085664057;796.7450415866922;824.9051359093702;15566.533004009883;15891.523254302376;15950.680986380265;15963.52109436716;15971.84028159718;14248.21634810085;14284.714305713906;14386.784198237036;14409.855901440982;14414.855851441482;689.7310049080862;787.9330256928154;844.8648216285395;877.4165180155189;881.0220655072864;866.8136350684601;887.4719541872576;891.2246345679268;893.0756666849812;894.6376717429451;937.5827756648295;937.5921474158729;938.6433155400938;940.0646356874919;941.90110038446;878.8593824987971;925.1349987761464;927.8515437529996;932.6129656192672;935.5743136872707;696.1680791454214;697.7278861244118;699.248308068823;700.9684564194623;701.0605788243727;14773.630659233497;14940.775888361683;15009.849901500955;15014.24928753569;15029.699406011929;10621.25651204416;14589.854101458955;14646.780298295535;14692.632684182874;14922.925385373112;11728;11874.921888669449;11961.521539138424;11972.70068248292;11977;3780.489633899416;3784.8864534063746;3806.333891568992;3841.8847434577006;3946.4474973503734;124176.75823241743;126207;128001;129003.99999999905;130081.34959325143;10203.795924081476;10533.525991330409;10655.946720266504;10689.572417103229;10701.197410194267;20882.26912058101;21238.89380553087;21262;21453.785462145177;21463.570728585342;6646.767363142267;7637.42719296055;7662.61686915652;7817.771934998612;7884.526928384201;4255.1915136124035;4354.825806967686;4399.450068741407;4438;4466.419365482531;2847.4732174547776;3073.277779721808;3512.2097528056493;3523.9295214095573;3555.093451169935;572.3789688188363;607.3835057416726;658.203573675848;659.3604203922188;712.1418690477961;571.5713215088683;581.6103210848742;630.4294613374904;657.57914934442;672.186654148476;8614.095519970313;9371.671991480265;9550.904490955141;9585;9891.950540247253;530.5808411355064;647.4496677823906;659.4921910129269;669.7923643670478;687.3092541995263;3497.9065333087647;4068.2066996935314;4642.3500709900645;4690.061987602493;4830;96876;112604;124468;127965;130436.3478182603;122330.38834805947;130639;135805;136345;151703.24148379188;77779.61110194413;109484.90515094907;111172.44413777879;111299;112812;9932.950335248277;10168.186545076229;10243.282970192015;10341.017603327671;10364.119049880845;61386.38613613807;64566.67716661387;65768.6711566439;66693.99959000522;68442.3155768436;90196;99494;101426.47860282249;102082.46876296712;104274;11562.976479115952;13792;13793.724125517232;13910.652233693834;13938.79091813644;12174.87825121773;15032.3235454407;15095.32071056827;15220.467283645019;15240.161791101596;11386.57667791526;14076.788848167082;14227.644308891946;14447.711045779235;14733.631659208606;13857;14062.859371406155;14190;14244.07413518118;14887.255637217879;13394.799078013642;13460.730785384434;13801.17192968385;13999.930000349934;14210.21843798601;11316.547338106713;11341.206115571831;11533.250338727956;11562.88437115618;11704.765904682028;9658.468784216935;10055.69832905014;11397.202195846212;11549.711257218636;11580.420978951188 05:54:22 INFO - PROCESS | 1971 | __end_tp_report 05:54:22 INFO - PROCESS | 1971 | __start_cc_report 05:54:22 INFO - PROCESS | 1971 | _x_x_mozilla_cycle_collect,382 05:54:22 INFO - PROCESS | 1971 | __end_cc_report 05:54:22 INFO - PROCESS | 1971 | __startTimestamp1473166462049__endTimestamp 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - PROCESS | 1971 | ------- Summary: start ------- 05:54:22 INFO - PROCESS | 1971 | Number of tests: 6 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - PROCESS | 1971 | [#0] dojo.html Cycles:165 Average:5769.95 Median:6029.55 stddev:640.89 (10.6%) stddev-sans-first:615.19 05:54:22 INFO - PROCESS | 1971 | Values: 3395.4 3424.6 3434.6 3434.6 3468.1 5790.3 5828.7 5858.9 5875.1 5930.7 5866.9 5871.9 5883.9 5893.2 5912.8 5285.5 5344.6 5388.3 5405.2 5413.7 4164.6 4782.6 5184.2 5244.4 5286.6 5305.7 5323.9 5325.9 5339.0 5343.5 5306.3 5309.3 5316.7 5326.6 5395.1 5085.4 5174.1 5242.4 5253.9 5255.8 5747.8 5849.9 5865.3 5884.7 5946.5 6162.0 6171.2 6183.4 6198.6 6236.1 6102.3 6117.9 6135.3 6253.9 6260.8 4742.3 5840.2 5850.0 5882.0 5883.4 6259.2 6315.8 6331.9 6338.9 6346.8 6070.0 6142.8 6176.9 6177.0 6191.6 6286.0 6302.5 6306.9 6313.7 6324.3 6208.0 6224.9 6243.1 6245.6 6265.1 6090.0 6100.8 6149.1 6241.7 6247.4 5065.6 5072.6 5078.3 5095.4 5118.7 4557.7 4561.8 4568.1 4585.8 4621.1 5632.3 5967.3 5979.4 6024.8 6044.8 6267.7 6271.5 6276.6 6281.5 6290.6 5990.5 6015.5 6016.0 6029.5 6039.9 5380.2 5386.9 5412.4 5478.4 5612.4 6216.6 6247.2 6247.5 6264.8 6265.6 6160.3 6209.3 6222.6 6244.3 6254.2 6193.4 6234.5 6303.7 6308.7 6340.9 5680.2 5889.8 6235.7 6244.6 6261.8 6222.9 6225.7 6246.0 6254.4 6261.0 6068.8 6145.3 6164.0 6174.0 6196.3 5226.3 6058.7 6213.3 6223.9 6244.1 5814.6 5838.5 5912.0 6102.3 6179.2 4838.9 4847.9 4912.3 5965.3 6208.9 6159.4 6168.7 6173.1 6178.6 6182.0 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - PROCESS | 1971 | [#1] ext.html Cycles:180 Average:70691.08 Median:38198.10 stddev:161802.07 (423.6%) stddev-sans-first:162170.08 05:54:22 INFO - PROCESS | 1971 | Values: 473.8 514.4 518.9 531.9 539.3 27424.5 35191.8 40536.8 40736.0 40787.0 40870.2 41452.0 41500.8 41566.0 41581.0 9597.6 9825.3 9830.2 9834.0 9841.4 9750.4 9867.8 9868.4 9884.0 9887.0 9845.4 9875.8 9880.8 9891.6 9934.5 9808.4 9837.9 9883.4 9888.4 9910.0 9707.5 9715.6 9746.0 9751.9 9790.3 39757.0 40009.0 40023.2 40041.0 40050.6 3242.1 3307.1 3347.8 3398.3 3842.7 30722.8 31165.8 31224.5 31313.5 31967.7 3234.9 3236.6 3242.2 3337.6 3343.2 506121.5 508602.5 510608.0 512662.4 513970.0 32292.4 33071.0 57085.4 57479.9 57576.8 34288.7 34314.5 34780.0 34853.1 34903.8 14539.0 14684.4 14698.2 14739.9 14743.0 12414.8 12502.3 12562.2 12564.3 12580.4 7626.8 7647.3 7683.1 7732.8 7750.9 3241.0 3295.2 3297.3 3303.2 3322.9 852.2 969.7 1002.7 1016.3 1038.1 28088.3 28658.9 29891.3 30040.9 30118.8 974.9 1016.6 1034.8 1084.5 1123.0 13082.3 15151.3 15342.4 15650.8 15843.4 667237.0 674498.0 677494.0 682076.5 682196.0 50884.0 56212.0 56276.7 56831.4 56893.0 1100.1 1345.7 1346.6 1357.2 1357.5 28550.3 29379.4 29692.3 29954.0 30656.2 47511.5 47577.5 47593.8 47609.0 48148.0 608923.6 609299.0 609861.0 610949.0 612405.0 20878.4 25321.6 26095.0 26099.0 26164.5 45654.5 46344.0 46722.3 46793.7 46853.0 37085.8 38032.2 38364.0 39297.0 39849.6 32672.9 37847.0 37895.8 37899.2 39303.6 36877.0 37500.8 37547.2 37798.8 37998.6 26833.8 28013.7 33335.3 33609.8 33923.7 24157.0 25184.1 25240.0 25309.9 25470.2 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - PROCESS | 1971 | [#2] jquery.html Cycles:180 Average:36665.94 Median:2004.09 stddev:98785.55 (4929.2%) stddev-sans-first:99025.09 05:54:22 INFO - PROCESS | 1971 | Values: 375.6 376.8 383.1 387.1 399.6 18702.7 20140.9 21447.6 21649.6 21700.2 11508.8 12103.0 12113.8 12138.0 19196.0 4766.7 5305.8 5582.6 5582.8 5596.1 5297.5 5484.1 5501.1 5535.2 5552.2 5520.8 5532.7 5537.0 5553.6 5565.9 5520.7 6656.4 7928.0 8003.9 8121.8 7526.7 7699.5 7709.4 7764.8 7806.6 28889.7 28940.9 29245.1 29439.9 29727.6 2524.4 2649.1 2673.2 2871.4 2897.9 16590.6 16666.6 16796.6 17025.0 17147.2 2014.9 2021.7 2024.5 2043.4 2050.8 147843.0 151786.0 153188.0 154268.0 156732.0 21908.8 22524.7 22690.8 22886.9 22919.7 28372.4 34862.5 35401.3 35407.0 35551.9 10583.2 10821.0 10938.7 10976.8 11062.2 8994.0 9012.6 9025.3 9098.4 9192.9 9066.0 9252.5 9685.9 9793.7 9864.0 3332.0 3632.6 3765.8 3908.2 3934.2 849.7 870.5 922.6 929.1 988.2 20859.5 21100.4 21644.9 21879.3 21902.1 839.2 913.8 951.9 953.8 979.3 10565.9 10962.6 11438.0 11482.8 11527.4 575096.0 582687.0 585437.0 598510.0 602025.0 81876.0 84097.6 123497.8 126748.0 130671.0 966.6 999.5 1016.9 1019.6 1024.3 18893.3 19811.9 19939.0 20655.4 20695.5 57988.0 58121.7 60292.4 60382.0 60948.1 65878.0 73699.0 79848.0 82190.2 85425.6 8106.4 8139.7 8255.5 8290.8 8356.3 8374.5 12799.2 13018.7 13025.2 13403.7 9108.5 9838.4 10575.4 10785.5 11364.8 8195.5 9080.3 10038.5 11264.5 11664.0 9595.0 10452.0 11398.8 11729.4 11864.9 8155.8 8316.5 8384.2 8396.5 8401.6 7956.0 10418.6 10702.3 10748.5 10865.5 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - PROCESS | 1971 | [#3] mootools.html Cycles:180 Average:5749.28 Median:4909.52 stddev:3265.73 (66.5%) stddev-sans-first:3258.57 05:54:22 INFO - PROCESS | 1971 | Values: 1404.8 1522.4 1729.5 1853.2 1894.6 7933.2 8520.8 8581.4 9095.8 9108.9 8669.8 8802.2 8950.2 8953.3 8964.6 2369.4 2397.1 2400.0 2402.3 2411.3 2359.5 2371.3 2383.4 2411.0 2425.4 2394.2 2395.6 2401.5 2404.6 2418.6 2356.9 2357.3 2367.4 2373.2 2398.9 2340.1 2369.7 2392.7 2395.2 2395.6 8809.0 8862.5 8967.7 8979.6 9001.6 5244.9 5395.3 5395.7 5407.6 5407.8 3047.7 3642.9 3677.6 3691.2 3717.3 1084.3 1092.9 1096.6 1097.6 1115.1 11643.7 11712.9 11815.0 11820.9 11857.2 4522.5 4647.1 4788.7 4829.2 4830.6 9897.6 9943.2 9953.0 9957.5 9966.8 5316.9 5323.2 5341.6 5348.5 5353.5 4405.6 4771.2 4906.4 4912.6 4929.9 3884.4 3943.6 3995.9 4022.7 4054.5 1313.9 1320.8 1322.9 1330.7 1335.3 751.7 786.4 908.2 918.7 935.7 5679.5 5795.6 5932.5 5959.3 6086.3 365.9 439.8 444.4 448.9 450.3 3502.6 3510.6 3586.2 3589.6 3624.8 10606.3 10854.4 10936.1 10969.7 10981.6 10826.0 10843.5 10938.2 10961.6 10974.6 8033.3 8042.5 8228.8 8317.3 8345.4 5755.5 5808.6 5862.6 5878.3 5986.0 9949.1 10139.0 10180.8 10207.6 10241.5 9718.8 10179.2 10203.4 10321.9 10392.9 6991.2 7374.3 7559.3 7567.3 7664.2 8515.0 8594.5 8609.5 8616.7 8670.3 7504.4 7638.0 7641.5 7662.1 7747.1 7432.9 7467.6 7505.4 7512.5 7647.4 6110.7 6945.3 7087.1 7093.4 7101.1 4916.1 4919.7 5060.3 5082.4 5090.9 5006.6 5019.6 5093.2 5116.4 5173.8 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - PROCESS | 1971 | [#4] prototype.html Cycles:180 Average:4632.47 Median:5081.44 stddev:1478.77 (29.1%) stddev-sans-first:1446.69 05:54:22 INFO - PROCESS | 1971 | Values: 298.4 306.2 312.4 313.8 315.0 4360.7 4758.3 4912.8 5239.5 5253.9 4653.9 5107.6 5324.9 5569.7 5595.9 3749.0 3919.0 4048.1 4053.2 4093.6 3891.0 4182.2 4283.6 4290.3 4301.7 3940.6 4166.8 4173.1 4185.2 4185.9 4184.9 4187.5 4192.2 4205.3 4209.0 3044.0 3576.6 3587.6 3607.7 3617.0 5294.4 5340.9 5388.6 5415.6 5442.4 4555.3 4800.9 4808.2 4813.5 4843.5 4495.4 5548.7 5828.8 5924.5 5945.2 3909.9 3930.3 3937.3 3960.5 3960.6 6064.5 6099.6 6130.6 6147.9 6243.5 3966.0 3970.4 3970.4 3992.4 3995.9 3429.9 3573.4 3812.9 3821.8 4041.2 3999.7 4691.6 4717.0 4730.1 4730.8 4929.0 4941.9 4980.6 5825.4 5853.5 1070.0 1195.0 1287.8 1299.9 1359.5 879.9 895.7 909.1 914.8 916.8 5360.3 5468.1 5469.8 5475.0 5527.2 4516.3 5280.2 5737.8 5995.3 6002.9 4032.9 4956.0 5070.7 5437.5 5569.1 2160.1 2461.7 2518.8 2695.6 2698.8 4945.5 5123.7 5133.8 5165.8 5200.8 4553.8 4972.5 5073.2 5223.7 5946.9 6343.7 6384.5 6390.8 6392.7 6456.4 5651.9 6061.7 6170.6 6263.1 6270.8 5721.5 5989.4 5997.5 6061.7 6106.8 6168.8 6215.6 6300.2 6358.8 6400.0 3770.6 3848.9 3967.0 4042.3 4044.9 4675.8 6139.9 6142.4 6200.3 6204.6 4780.4 5203.2 5721.6 5742.4 5872.9 5830.2 5924.4 5948.8 5953.3 5962.4 5057.5 5156.6 5179.0 5320.0 5456.6 4458.4 4538.0 5703.3 5859.8 6011.8 5089.7 5348.5 5759.1 5843.6 5874.1 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - PROCESS | 1971 | [#5] yui.html Cycles:180 Average:24874.88 Median:21250.45 stddev:39203.79 (184.5%) stddev-sans-first:39271.47 05:54:22 INFO - PROCESS | 1971 | Values: 619.8 723.5 772.1 796.7 824.9 15566.5 15891.5 15950.7 15963.5 15971.8 14248.2 14284.7 14386.8 14409.9 14414.9 689.7 787.9 844.9 877.4 881.0 866.8 887.5 891.2 893.1 894.6 937.6 937.6 938.6 940.1 941.9 878.9 925.1 927.9 932.6 935.6 696.2 697.7 699.2 701.0 701.1 14773.6 14940.8 15009.8 15014.2 15029.7 10621.3 14589.9 14646.8 14692.6 14922.9 11728.0 11874.9 11961.5 11972.7 11977.0 3780.5 3784.9 3806.3 3841.9 3946.4 124176.8 126207.0 128001.0 129004.0 130081.3 10203.8 10533.5 10655.9 10689.6 10701.2 20882.3 21238.9 21262.0 21453.8 21463.6 6646.8 7637.4 7662.6 7817.8 7884.5 4255.2 4354.8 4399.5 4438.0 4466.4 2847.5 3073.3 3512.2 3523.9 3555.1 572.4 607.4 658.2 659.4 712.1 571.6 581.6 630.4 657.6 672.2 8614.1 9371.7 9550.9 9585.0 9892.0 530.6 647.4 659.5 669.8 687.3 3497.9 4068.2 4642.4 4690.1 4830.0 96876.0 112604.0 124468.0 127965.0 130436.3 122330.4 130639.0 135805.0 136345.0 151703.2 77779.6 109484.9 111172.4 111299.0 112812.0 9933.0 10168.2 10243.3 10341.0 10364.1 61386.4 64566.7 65768.7 66694.0 68442.3 90196.0 99494.0 101426.5 102082.5 104274.0 11563.0 13792.0 13793.7 13910.7 13938.8 12174.9 15032.3 15095.3 15220.5 15240.2 11386.6 14076.8 14227.6 14447.7 14733.6 13857.0 14062.9 14190.0 14244.1 14887.3 13394.8 13460.7 13801.2 13999.9 14210.2 11316.5 11341.2 11533.3 11562.9 11704.8 9658.5 10055.7 11397.2 11549.7 11580.4 05:54:22 INFO - PROCESS | 1971 | -------- Summary: end -------- 05:54:22 INFO - PROCESS | 1971 | 05:54:22 INFO - TEST-INFO | 1971: exit 0 05:54:22 INFO - TEST-OK | dromaeo_css | took 1114344ms 05:54:22 INFO - TEST-START | kraken 05:54:22 INFO - operating with platform_type : mac_ 05:54:22 INFO - Initialising browser for kraken test... 05:54:22 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpz_2TQh/profile http://localhost:56104/getInfo.html) 05:54:25 INFO - PROCESS | 1976 | __metrics Screen width/height:1600/1200 05:54:25 INFO - PROCESS | 1976 | colorDepth:24 05:54:25 INFO - PROCESS | 1976 | Browser inner width/height: 1024/689 05:54:25 INFO - PROCESS | 1976 | __metrics 05:54:25 ERROR - PROCESS | 1976 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 05:54:26 INFO - TEST-INFO | 1976: exit 0 05:54:26 INFO - Browser initialized. 05:54:26 INFO - Running cycle 1/1 for kraken test... 05:54:26 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpz_2TQh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:54:38 INFO - PROCESS | 1979 | RSS: Main: 244133888 05:54:38 INFO - PROCESS | 1979 | 05:54:41 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56104/tests/kraken/driver.html?testName=audio-beat-detection) 05:54:42 INFO - PROCESS | 1979 | RSS: Main: 256753664 05:54:42 INFO - PROCESS | 1979 | 05:54:45 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56104/tests/kraken/driver.html?testName=audio-dft) 05:54:45 INFO - PROCESS | 1979 | RSS: Main: 265523200 05:54:45 INFO - PROCESS | 1979 | 05:54:49 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56104/tests/kraken/driver.html?testName=audio-fft) 05:54:49 INFO - PROCESS | 1979 | RSS: Main: 263118848 05:54:49 INFO - PROCESS | 1979 | 05:54:51 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56104/tests/kraken/driver.html?testName=audio-oscillator) 05:54:52 INFO - PROCESS | 1979 | RSS: Main: 265580544 05:54:52 INFO - PROCESS | 1979 | 05:54:54 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56104/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:54:55 INFO - PROCESS | 1979 | RSS: Main: 265957376 05:54:55 INFO - PROCESS | 1979 | 05:54:58 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56104/tests/kraken/driver.html?testName=imaging-darkroom) 05:54:58 INFO - PROCESS | 1979 | RSS: Main: 266752000 05:54:58 INFO - PROCESS | 1979 | 05:55:01 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56104/tests/kraken/driver.html?testName=imaging-desaturate) 05:55:02 INFO - PROCESS | 1979 | RSS: Main: 266788864 05:55:02 INFO - PROCESS | 1979 | 05:55:05 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56104/tests/kraken/driver.html?testName=json-parse-financial) 05:55:06 INFO - PROCESS | 1979 | RSS: Main: 267104256 05:55:06 INFO - PROCESS | 1979 | 05:55:07 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56104/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:55:08 INFO - PROCESS | 1979 | RSS: Main: 266911744 05:55:08 INFO - PROCESS | 1979 | 05:55:10 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56104/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:55:10 INFO - PROCESS | 1979 | RSS: Main: 266620928 05:55:10 INFO - PROCESS | 1979 | 05:55:13 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56104/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:55:13 INFO - PROCESS | 1979 | RSS: Main: 267026432 05:55:13 INFO - PROCESS | 1979 | 05:55:16 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:55:17 INFO - PROCESS | 1979 | RSS: Main: 267096064 05:55:17 INFO - PROCESS | 1979 | 05:55:20 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:55:20 INFO - PROCESS | 1979 | RSS: Main: 267051008 05:55:20 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:56104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56104/tests/kraken/driver.html?testName=ai-astar) 05:55:22 INFO - PROCESS | 1979 | RSS: Main: 269094912 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | __start_tp_report 05:55:22 INFO - PROCESS | 1979 | _x_x_mozilla_page_load 05:55:22 INFO - PROCESS | 1979 | _x_x_mozilla_page_load_details 05:55:22 INFO - PROCESS | 1979 | |i|pagename|runs| 05:55:22 INFO - PROCESS | 1979 | |0;ai-astar;120;168;171;115;115;109;156;98;175;197 05:55:22 INFO - PROCESS | 1979 | |1;audio-beat-detection;149;110;139;162;113;131;131;113;205;97 05:55:22 INFO - PROCESS | 1979 | |2;audio-dft;211;233;189;194;204;161;164;208;214;273 05:55:22 INFO - PROCESS | 1979 | |3;audio-fft;66;87;76;147;59;62;62;62;146;60 05:55:22 INFO - PROCESS | 1979 | |4;audio-oscillator;70;73;130;65;79;129;144;97;72;114 05:55:22 INFO - PROCESS | 1979 | |5;imaging-gaussian-blur;89;90;130;87;97;121;89;88;77;98 05:55:22 INFO - PROCESS | 1979 | |6;imaging-darkroom;136;102;101;101;101;102;114;170;174;103 05:55:22 INFO - PROCESS | 1979 | |7;imaging-desaturate;150;143;91;93;114;148;90;91;91;93 05:55:22 INFO - PROCESS | 1979 | |8;json-parse-financial;56;58;58;58;58;57;57;57;55;57 05:55:22 INFO - PROCESS | 1979 | |9;json-stringify-tinderbox;64;71;69;77;87;108;100;108;107;71 05:55:22 INFO - PROCESS | 1979 | |10;stanford-crypto-aes;86;92;90;86;87;84;86;92;90;91 05:55:22 INFO - PROCESS | 1979 | |11;stanford-crypto-ccm;138;141;127;140;137;141;162;156;186;136 05:55:22 INFO - PROCESS | 1979 | |12;stanford-crypto-pbkdf2;170;175;241;173;173;174;169;169;172;172 05:55:22 INFO - PROCESS | 1979 | |13;stanford-crypto-sha256-iterative;71;67;70;67;69;68;70;84;95;87 05:55:22 INFO - PROCESS | 1979 | __end_tp_report 05:55:22 INFO - PROCESS | 1979 | __start_cc_report 05:55:22 INFO - PROCESS | 1979 | _x_x_mozilla_cycle_collect,949 05:55:22 INFO - PROCESS | 1979 | __end_cc_report 05:55:22 INFO - PROCESS | 1979 | __startTimestamp1473166522900__endTimestamp 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | ------- Summary: start ------- 05:55:22 INFO - PROCESS | 1979 | Number of tests: 14 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#0] ai-astar Cycles:10 Average:142.40 Median:169.50 stddev:34.63 (20.4%) stddev-sans-first:35.77 05:55:22 INFO - PROCESS | 1979 | Values: 120.0 168.0 171.0 115.0 115.0 109.0 156.0 98.0 175.0 197.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:144.00 stddev:31.46 (21.9%) stddev-sans-first:32.96 05:55:22 INFO - PROCESS | 1979 | Values: 149.0 110.0 139.0 162.0 113.0 131.0 131.0 113.0 205.0 97.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#2] audio-dft Cycles:10 Average:205.10 Median:209.50 stddev:32.54 (15.5%) stddev-sans-first:34.44 05:55:22 INFO - PROCESS | 1979 | Values: 211.0 233.0 189.0 194.0 204.0 161.0 164.0 208.0 214.0 273.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#3] audio-fft Cycles:10 Average:82.70 Median:62.00 stddev:34.71 (56.0%) stddev-sans-first:36.29 05:55:22 INFO - PROCESS | 1979 | Values: 66.0 87.0 76.0 147.0 59.0 62.0 62.0 62.0 146.0 60.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:71.00 stddev:29.60 (41.7%) stddev-sans-first:29.71 05:55:22 INFO - PROCESS | 1979 | Values: 70.0 73.0 130.0 65.0 79.0 129.0 144.0 97.0 72.0 114.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:89.00 stddev:16.41 (18.4%) stddev-sans-first:17.17 05:55:22 INFO - PROCESS | 1979 | Values: 89.0 90.0 130.0 87.0 97.0 121.0 89.0 88.0 77.0 98.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#6] imaging-darkroom Cycles:10 Average:120.40 Median:108.50 stddev:29.30 (27.0%) stddev-sans-first:30.53 05:55:22 INFO - PROCESS | 1979 | Values: 136.0 102.0 101.0 101.0 101.0 102.0 114.0 170.0 174.0 103.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#7] imaging-desaturate Cycles:10 Average:110.40 Median:91.00 stddev:26.26 (28.9%) stddev-sans-first:23.63 05:55:22 INFO - PROCESS | 1979 | Values: 150.0 143.0 91.0 93.0 114.0 148.0 90.0 91.0 91.0 93.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.50 stddev:0.99 (1.7%) stddev-sans-first:0.97 05:55:22 INFO - PROCESS | 1979 | Values: 56.0 58.0 58.0 58.0 58.0 57.0 57.0 57.0 55.0 57.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#9] json-stringify-tinderbox Cycles:10 Average:86.20 Median:70.00 stddev:17.98 (25.7%) stddev-sans-first:17.18 05:55:22 INFO - PROCESS | 1979 | Values: 64.0 71.0 69.0 77.0 87.0 108.0 100.0 108.0 107.0 71.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:90.00 stddev:2.91 (3.2%) stddev-sans-first:2.96 05:55:22 INFO - PROCESS | 1979 | Values: 86.0 92.0 90.0 86.0 87.0 84.0 86.0 92.0 90.0 91.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:141.00 stddev:17.15 (12.2%) stddev-sans-first:17.92 05:55:22 INFO - PROCESS | 1979 | Values: 138.0 141.0 127.0 140.0 137.0 141.0 162.0 156.0 186.0 136.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:173.00 stddev:21.95 (12.7%) stddev-sans-first:23.05 05:55:22 INFO - PROCESS | 1979 | Values: 170.0 175.0 241.0 173.0 173.0 174.0 169.0 169.0 172.0 172.0 05:55:22 INFO - PROCESS | 1979 | 05:55:22 INFO - PROCESS | 1979 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:70.50 stddev:10.02 (14.2%) stddev-sans-first:10.53 05:55:22 INFO - PROCESS | 1979 | Values: 71.0 67.0 70.0 67.0 69.0 68.0 70.0 84.0 95.0 87.0 05:55:22 INFO - PROCESS | 1979 | -------- Summary: end -------- 05:55:22 INFO - PROCESS | 1979 | 05:55:23 INFO - TEST-INFO | 1979: exit 0 05:55:23 INFO - TEST-OK | kraken | took 60885ms 05:55:23 INFO - SUITE-END | took 1175s 05:55:24 INFO - Completed test suite (00:19:35) 05:55:24 INFO - javascript benchmark 05:55:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7426.048874575457, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.3888300105973, 3424.6404127566607, 3434.5535080439545, 3434.639362866899, 3468.098294443444, 5790.33411157717, 5828.679422631753, 5858.882822343545, 5875.089361149024, 5930.703464826763, 5866.882662346761, 5871.911921321183, 5883.941160588403, 5893.204417403649, 5912.763489460416, 5285.471452854713, 5344.599155063386, 5388.326459192601, 5405.243265942771, 5413.729313534327, 4164.562720914306, 4782.617390608761, 5184.196449550307, 5244.370675518943, 5286.577073834084, 5305.681659100466, 5323.946760532383, 5325.866853328698, 5338.973305133488, 5343.492368225013, 5306.336707911511, 5309.256704061435, 5316.707581083039, 5326.600504962104, 5395.082835917903, 5085.364329458817, 5174.120399532088, 5242.397124330712, 5253.894922101536, 5255.789768409298, 5747.798827041033, 5849.941500585025, 5865.266841644794, 5884.6763428011445, 5946.494547963428, 6162.0, 6171.166892469504, 6183.412575805291, 6198.566100372976, 6236.095766113934, 6102.337773232528, 6117.938820611781, 6135.324535308531, 6253.874922501524, 6260.78087266948, 4742.264948932921, 5840.153177789197, 5850.0, 5882.0, 5883.352831188566, 6259.18630578031, 6315.810525684236, 6331.905021424683, 6338.936610633927, 6346.841328966812, 6070.0, 6142.805741014737, 6176.876462470816, 6176.969115154486, 6191.606888450153, 6285.968570157212, 6302.4958003359625, 6306.905396419057, 6313.652749098752, 6324.33594472583, 6208.0, 6224.906626400608, 6243.094751261042, 6245.5628106033055, 6265.091561723524, 6090.0, 6100.793122958854, 6149.13912052313, 6241.7191226394925, 6247.375262473807, 5065.594752419797, 5072.619553533426, 5078.314427552307, 5095.388553373545, 5118.718470484159, 4557.680962332605, 4561.840335588238, 4568.086382723469, 4585.770711464414, 4621.145088158702, 5632.2678051853, 5967.313758917693, 5979.431953964367, 6024.819255422344, 6044.818655440344, 6267.6866156691985, 6271.498280137578, 6276.623402595767, 6281.466075383552, 6290.622562646165, 5990.520758339322, 6015.54883383748, 6016.0, 6029.547783916224, 6039.939600603938, 5380.1929710542945, 5386.91919621206, 5412.431694672082, 5478.369987451414, 5612.438756124355, 6216.564840461305, 6247.219097612799, 6247.468965137923, 6264.843378915563, 6265.592736472206, 6160.291566469913, 6209.2548894133, 6222.564420490522, 6244.281907580505, 6254.21822272216, 6193.411625895533, 6234.470070043915, 6303.747850085945, 6308.747650094128, 6340.873182536416, 5680.233168522362, 5889.764409423575, 6235.719392627252, 6244.594101383395, 6261.78083767066, 6222.906656400067, 6225.688715564294, 6246.000639897595, 6254.374562543891, 6260.968695156495, 6068.757249710139, 6145.279321789906, 6164.044573091178, 6174.047630935072, 6196.287426945959, 5226.346706661668, 6058.7273572688455, 6213.316535181036, 6223.906641400292, 6244.125822384779, 5814.622049566765, 5838.474537291664, 5911.99496085675, 6102.298235702951, 6179.227596550431, 4838.935434204469, 4847.903041939212, 4912.312276281252, 5965.34381218057, 6208.906866396917, 6159.353267906985, 6168.691565421801, 6173.135760993375, 6178.567500274938, 6181.990852972712], "value": 5731.131725221995, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.7962676049298, 514.4058612302791, 518.9455107213743, 531.859162097301, 539.2908325551897, 27424.451510969768, 35191.824040879794, 40536.79731601338, 40736.0, 40787.0, 40870.18259634806, 41452.0, 41500.79249603755, 41566.0, 41581.0, 9597.616095356178, 9825.263105267099, 9830.164436022947, 9834.0, 9841.409515429097, 9750.414975101481, 9867.753306167331, 9868.407895526256, 9884.0, 9887.011298870128, 9845.36005159666, 9875.753106172331, 9880.752981175492, 9891.554880030415, 9934.45360505172, 9808.411495310233, 9837.852432213522, 9883.357581757164, 9888.357256778287, 9910.0, 9707.466089365082, 9715.61137554497, 9745.951270243604, 9751.90248097517, 9790.265730070201, 39757.0, 40008.99977500557, 40023.19953600941, 40041.0, 40050.59949400498, 3242.1084201844446, 3307.107081088117, 3347.782394144397, 3398.269372084989, 3842.692584593226, 30722.771089156406, 31165.844170779226, 31224.531632025537, 31313.530297045563, 31967.680323196702, 3234.8544315505624, 3236.6277878043857, 3242.238074052596, 3337.599488061448, 3343.164208947763, 506121.4693926507, 508602.4569877164, 510608.0, 512662.4366878179, 513970.0, 32292.35415291728, 33071.0, 57085.42914570884, 57479.85040299171, 57576.84846303134, 34288.65711342855, 34314.48528272078, 34780.0, 34853.1286717829, 34903.82548087243, 14538.982271241122, 14684.41262349494, 14698.191599462132, 14739.852601474062, 14743.041702289316, 12414.81377779334, 12502.3123728194, 12562.246265223934, 12564.308963006939, 12580.4338804754, 7626.771196864103, 7647.349975252166, 7683.0780306363595, 7732.7680169595005, 7750.914871917936, 3240.951385729216, 3295.2256219788333, 3297.3405318936307, 3303.157694787814, 3322.850471728778, 852.2372476633378, 969.7042402067437, 1002.7443002034435, 1016.3190662256306, 1038.1435315864442, 28088.314701117997, 28658.85670571634, 29891.293547168923, 30040.948566800056, 30118.84940575283, 974.8635191073255, 1016.6390931219458, 1034.8447732839984, 1084.4957094950885, 1122.9556512443505, 13082.345882705828, 15151.272770901343, 15342.386304547694, 15650.765238521204, 15843.366265349487, 667237.0, 674498.0, 677494.0, 682076.5377038696, 682196.0, 50883.982320354124, 56212.0, 56276.71861640666, 56831.43168568344, 56893.0, 1100.1254003067581, 1345.663584103974, 1346.6431395680152, 1357.151780137414, 1357.4570171931307, 28550.286242844097, 29379.41241175122, 29692.25769355697, 29954.0, 30656.233594160323, 47511.52488475071, 47577.52422475731, 47593.81015474641, 47609.0, 48148.0, 608923.5538222983, 609299.0, 609861.0, 610949.0, 612405.0, 20878.37364879056, 25321.62017569701, 26095.0, 26099.0, 26164.476710466064, 45654.54345456503, 46344.0, 46722.29916551187, 46793.66031698469, 46853.0, 37085.814570926974, 38032.19678032175, 38364.0, 39297.0, 39849.60150398575, 32672.856450024134, 37847.0, 37895.81052094722, 37899.24201515899, 39303.60696393114, 36877.0, 37500.812495937345, 37547.249055019296, 37798.81100594479, 37998.62001379951, 26833.787465940055, 28013.719862801925, 33335.33299339069, 33609.83195084009, 33923.66076339304, 24157.0, 25184.11855585046, 25240.0, 25309.873450633364, 25470.235892923243], "value": 18436.40990147654, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.6206231705978, 376.792763979811, 383.1035377217312, 387.08261420433575, 399.57445320733433, 18702.71945920812, 20140.899295503503, 21447.571048579037, 21649.567008659837, 21700.240491582797, 11508.769824603503, 12103.0, 12113.818292725595, 12138.028957683386, 19196.040197990078, 4766.73782941938, 5305.840824775243, 5582.581306402036, 5582.8325150245355, 5596.132599447072, 5297.47025297471, 5484.067708489546, 5501.092319767254, 5535.169724541331, 5552.167174923753, 5520.834374968737, 5532.6680399176175, 5537.0, 5553.555715542767, 5565.888682226353, 5520.6687598744, 6656.36764507371, 7928.0, 8003.8799418008775, 8121.796955076111, 7526.698932042712, 7699.461037727361, 7709.38324934004, 7764.805879852993, 7806.570638614874, 28889.711102889123, 28940.855295723388, 29245.122646320644, 29439.852800736073, 29727.554086688717, 2524.368907773057, 2649.112547296658, 2673.1980405878157, 2871.3970066286106, 2897.942041159165, 16590.585235369213, 16666.58333541659, 16796.58008549796, 17025.0, 17147.228374723167, 2014.9320859944253, 2021.6967454881737, 2024.5141166120025, 2043.3767700851236, 2050.8256798172174, 147843.0, 151786.0, 153188.0, 154268.0, 156732.0, 21908.780912190676, 22524.662130068064, 22690.773092268864, 22886.885565572065, 22919.656205156934, 28372.439515826416, 34862.477062844075, 35401.29197416037, 35407.0, 35551.93344199678, 10583.206259530565, 10821.0, 10938.726531836608, 10976.780464390828, 11062.174741167779, 8994.0, 9012.594433250519, 9025.32310076747, 9098.408603440756, 9192.908070919339, 9065.957414897239, 9252.537373131316, 9685.869108796054, 9793.706188814347, 9863.950680246553, 3331.9666803332143, 3632.564092308887, 3765.755225910307, 3908.1895152226352, 3934.1738234970976, 849.6983570832266, 870.4777133719845, 922.5525620074259, 929.1080562659787, 988.2489308125732, 20859.478513037295, 21100.366988990354, 21644.91775411223, 21879.343619691434, 21902.12391504354, 839.202757380491, 913.7578541686458, 951.871497347863, 953.7839254949855, 979.2606582030521, 10565.894341056644, 10962.561497540168, 11438.027767639844, 11482.827757583644, 11527.365994870195, 575096.0, 582687.0, 585437.0, 598510.0, 602025.0, 81876.0, 84097.57951210327, 123497.76502235042, 126748.0, 130671.0, 966.5940305071887, 999.5402115027024, 1016.9288149829589, 1019.6278358399279, 1024.329064462778, 18893.338733144275, 19811.900940495205, 19939.0, 20655.380338589865, 20695.482612934196, 57988.0, 58121.70939145277, 60292.39707603044, 60382.0, 60948.08577871247, 65878.0, 73699.0, 79848.0, 82190.17809821825, 85425.57287213524, 8106.39202059836, 8139.715109971123, 8255.504669719836, 8290.839282500576, 8356.331493480386, 8374.455660382057, 12799.232046077264, 13018.674533136747, 13025.153365031243, 13403.731925361242, 9108.49709797876, 9838.409695418297, 10575.36547807134, 10785.514651840529, 11364.829527556927, 8195.508269503849, 9080.273578113603, 10038.548265327932, 11264.493097810455, 11664.0, 9595.0, 10452.0, 11398.829017564909, 11729.35488548138, 11864.940675296913, 8155.836883262183, 8316.459430137022, 8384.245417912418, 8396.496210227406, 8401.579921003804, 7956.0, 10418.635347762793, 10702.304350217211, 10748.516316765921, 10865.456727163768], "value": 10731.96497249933, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.8454669986304, 1522.3529999750108, 1729.5157355940332, 1853.2123847364867, 1894.564250222449, 7933.2463415975535, 8520.829583408336, 8581.442206256588, 9095.772605684853, 9108.908910910888, 8669.783255418602, 8802.163794439519, 8950.194482496574, 8953.328500362466, 8964.551772411385, 2369.360272726367, 2397.1010870923405, 2400.0039983406864, 2402.3033320337167, 2411.3127758588807, 2359.528094381131, 2371.2530552875874, 2383.392234980078, 2410.987945060281, 2425.3936515870946, 2394.221877889684, 2395.6167013277877, 2401.5317013182434, 2404.615261558151, 2418.613021916494, 2356.9057237710385, 2357.3281614739803, 2367.3844800351862, 2373.181252467896, 2398.8920498577604, 2340.0639744102323, 2369.6919400478, 2392.7009123859516, 2395.2335252719135, 2395.6406539019113, 8808.955955220246, 8862.468251904906, 8967.686130985385, 8979.595918183697, 9001.594928228245, 5244.895102097975, 5395.271638328814, 5395.676259424408, 5407.594430417735, 5407.756650950717, 3047.7104675056057, 3642.8542858285637, 3677.5586929568353, 3691.151035261905, 3717.275131349388, 1084.3277168155794, 1092.8633920759905, 1096.5668560918393, 1097.5993762276712, 1115.068917453927, 11643.650690479299, 11712.941435292769, 11815.0, 11820.940895295469, 11857.169998100137, 4522.4573051234065, 4647.140279048387, 4788.712677239307, 4829.1790395632825, 4830.565249127525, 9897.554610042565, 9943.15483183923, 9952.950235248922, 9957.452340121363, 9966.800663986825, 5316.893662126736, 5323.1749078892835, 5341.572674186055, 5348.465153484621, 5353.544948679406, 4405.581469760367, 4771.165046116916, 4906.435759887588, 4912.606991440676, 4929.901401971941, 3884.4173373994126, 3943.6253555912144, 3995.860144894915, 4022.718409711292, 4054.4729185205733, 1313.8620444853343, 1320.8348956380453, 1322.8677132286887, 1330.700592366729, 1335.3189873164756, 751.7481643649329, 786.3905473258156, 908.2370808520742, 918.701422037827, 935.6771913689767, 5679.488846003879, 5795.623284486495, 5932.466078052996, 5959.344472108065, 6086.330503644595, 365.8719448193111, 439.83506185180556, 444.3956219541421, 448.87655894628585, 450.3357547617238, 3502.614712381636, 3510.57873055235, 3586.1572530455323, 3589.5513060867393, 3624.800635965047, 10606.310589811637, 10854.403007834644, 10936.070434013198, 10969.725756856142, 10981.560737570408, 10825.971532704538, 10843.51204195813, 10938.234323597271, 10961.616343427942, 10974.561017559368, 8033.317168040665, 8042.477238979448, 8228.753137405887, 8317.334613230927, 8345.4158208926, 5755.53955683536, 5808.593398462067, 5862.648241105548, 5878.3239927407185, 5986.0, 9949.054839790208, 10139.0, 10180.796384072426, 10207.642732504326, 10241.539130739284, 9718.75703107428, 10179.185665146622, 10203.38779673222, 10321.948390258302, 10392.948035259775, 6991.2309645937885, 7374.336309731935, 7559.28186822251, 7567.31894129531, 7664.195259497673, 8515.0, 8594.527300998505, 8609.48343099416, 8616.698415555426, 8670.306375490072, 7504.362129218859, 7638.045244344457, 7641.541507509567, 7662.118856331594, 7747.070351557849, 7432.925670743223, 7467.626618669154, 7505.437092217996, 7512.511686740344, 7647.438108233581, 6110.7250173741395, 6945.340192681687, 7087.149542054986, 7093.432525397852, 7101.147862256562, 4916.139675556692, 4919.729414882073, 5060.316857224375, 5082.440931497458, 5090.949090509048, 5006.574441172541, 5019.573336266459, 5093.18509038552, 5116.437191908962, 5173.844784656466], "value": 4527.575830159704, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.3555520076635, 306.1565387357829, 312.4111050669488, 313.81484923894925, 314.9763767717422, 4360.651147908167, 4758.310045043467, 4912.820922978486, 5239.52844244018, 5253.922945796112, 4653.883652908679, 5107.565856902159, 5324.893502129965, 5569.6658200508, 5595.944040559593, 3748.9690335157775, 3919.0, 4048.1296521247928, 4053.209624123297, 4093.6111069448357, 3890.9610903891025, 4182.226288136704, 4283.635890949273, 4290.334998075304, 4301.730989358131, 3940.6453419192267, 4166.791600451766, 4173.123644034757, 4185.21683080751, 4185.8534951276715, 4184.9449901572025, 4187.518435379925, 4192.224438478891, 4205.3481710334945, 4208.978955105235, 3043.9847800761077, 3576.5708115026105, 3587.6053634100226, 3607.6753092221825, 3616.9819150904077, 5294.41761406245, 5340.91988620171, 5388.595855310868, 5415.620906536544, 5442.428545002757, 4555.314533622548, 4800.879978000543, 4808.230683090689, 4813.486155106757, 4843.467218605951, 4495.415595972535, 5548.6960564267765, 5828.841475294395, 5924.496417804492, 5945.167676525247, 3909.9218015639813, 3930.347015764376, 3937.330653788863, 3960.485136932209, 3960.6039396060164, 6064.5432103700905, 6099.634021958653, 6130.601510901822, 6147.907781383283, 6243.469305109071, 3966.0, 3970.3845903884944, 3970.4242884782125, 3992.4210989406956, 3995.9001024974027, 3429.879954201641, 3573.356795776733, 3812.923741525154, 3821.808909554511, 4041.1715598302276, 3999.7200195986, 4691.648126390534, 4717.0, 4730.077634861238, 4730.834420795324, 4928.950710492921, 4941.876453088702, 4980.601551875841, 5825.427039773757, 5853.5317174625925, 1069.9835156601105, 1194.9820752688718, 1287.8390201224847, 1299.8505171905351, 1359.5377571625695, 879.947203167812, 895.6730793260543, 909.1136142261242, 914.7621618379269, 916.7891384981492, 5360.303160589098, 5468.125099983983, 5469.835904922858, 5474.972625136849, 5527.208759576388, 4516.277395616751, 5280.234366016906, 5737.799177028784, 5995.340512543617, 6002.868981511588, 4032.9395059074136, 4956.0, 5070.721110338966, 5437.537809286255, 5569.078667859582, 2160.0883218351873, 2461.704595448522, 2518.7607177318127, 2695.5578765360438, 2698.8110832241946, 4945.530174633404, 5123.692578445305, 5133.820316288912, 5165.767540460688, 5200.791968321225, 4553.833242582999, 4972.453030166679, 5073.213651883964, 5223.720188553829, 5946.851328716817, 6343.651099189497, 6384.52116091295, 6390.808275751735, 6392.712327945161, 6456.418922297016, 5651.858703532444, 6061.727222275096, 6170.629762214281, 6263.054275806395, 6270.83989461951, 5721.456461636138, 5989.401059893976, 5997.460228579449, 6061.666608336407, 6106.755729770937, 6168.766246750578, 6215.57041880361, 6300.243970723543, 6358.823617630753, 6399.9680001601555, 3770.6040865708705, 3848.9230215396096, 3967.004911291956, 4042.3330150526012, 4044.9191016178925, 4675.812967481263, 6139.938600613937, 6142.355052719578, 6200.325912003824, 6204.5967012144065, 4780.426348838162, 5203.16749320107, 5721.570882183936, 5742.425757424391, 5872.853178670567, 5830.154627579062, 5924.407559244041, 5948.821535353946, 5953.255843019622, 5962.37395073511, 5057.46896575869, 5156.61325400589, 5179.0, 5320.0049981257025, 5456.590755693418, 4458.398116254394, 4538.02432477019, 5703.258576385043, 5859.780957710442, 6011.849703757441, 5089.6691715038405, 5348.529154482393, 5759.107338362561, 5843.590948633555, 5874.148248504029], "value": 4157.090775120448, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.8171539395878, 723.5405517496391, 772.1352085664057, 796.7450415866922, 824.9051359093702, 15566.533004009883, 15891.523254302376, 15950.680986380265, 15963.52109436716, 15971.84028159718, 14248.21634810085, 14284.714305713906, 14386.784198237036, 14409.855901440982, 14414.855851441482, 689.7310049080862, 787.9330256928154, 844.8648216285395, 877.4165180155189, 881.0220655072864, 866.8136350684601, 887.4719541872576, 891.2246345679268, 893.0756666849812, 894.6376717429451, 937.5827756648295, 937.5921474158729, 938.6433155400938, 940.0646356874919, 941.90110038446, 878.8593824987971, 925.1349987761464, 927.8515437529996, 932.6129656192672, 935.5743136872707, 696.1680791454214, 697.7278861244118, 699.248308068823, 700.9684564194623, 701.0605788243727, 14773.630659233497, 14940.775888361683, 15009.849901500955, 15014.24928753569, 15029.699406011929, 10621.25651204416, 14589.854101458955, 14646.780298295535, 14692.632684182874, 14922.925385373112, 11728.0, 11874.921888669449, 11961.521539138424, 11972.70068248292, 11977.0, 3780.489633899416, 3784.8864534063746, 3806.333891568992, 3841.8847434577006, 3946.4474973503734, 124176.75823241743, 126207.0, 128001.0, 129003.99999999905, 130081.34959325143, 10203.795924081476, 10533.525991330409, 10655.946720266504, 10689.572417103229, 10701.197410194267, 20882.26912058101, 21238.89380553087, 21262.0, 21453.785462145177, 21463.570728585342, 6646.767363142267, 7637.42719296055, 7662.61686915652, 7817.771934998612, 7884.526928384201, 4255.1915136124035, 4354.825806967686, 4399.450068741407, 4438.0, 4466.419365482531, 2847.4732174547776, 3073.277779721808, 3512.2097528056493, 3523.9295214095573, 3555.093451169935, 572.3789688188363, 607.3835057416726, 658.203573675848, 659.3604203922188, 712.1418690477961, 571.5713215088683, 581.6103210848742, 630.4294613374904, 657.57914934442, 672.186654148476, 8614.095519970313, 9371.671991480265, 9550.904490955141, 9585.0, 9891.950540247253, 530.5808411355064, 647.4496677823906, 659.4921910129269, 669.7923643670478, 687.3092541995263, 3497.9065333087647, 4068.2066996935314, 4642.3500709900645, 4690.061987602493, 4830.0, 96876.0, 112604.0, 124468.0, 127965.0, 130436.3478182603, 122330.38834805947, 130639.0, 135805.0, 136345.0, 151703.24148379188, 77779.61110194413, 109484.90515094907, 111172.44413777879, 111299.0, 112812.0, 9932.950335248277, 10168.186545076229, 10243.282970192015, 10341.017603327671, 10364.119049880845, 61386.38613613807, 64566.67716661387, 65768.6711566439, 66693.99959000522, 68442.3155768436, 90196.0, 99494.0, 101426.47860282249, 102082.46876296712, 104274.0, 11562.976479115952, 13792.0, 13793.724125517232, 13910.652233693834, 13938.79091813644, 12174.87825121773, 15032.3235454407, 15095.32071056827, 15220.467283645019, 15240.161791101596, 11386.57667791526, 14076.788848167082, 14227.644308891946, 14447.711045779235, 14733.631659208606, 13857.0, 14062.859371406155, 14190.0, 14244.07413518118, 14887.255637217879, 13394.799078013642, 13460.730785384434, 13801.17192968385, 13999.930000349934, 14210.21843798601, 11316.547338106713, 11341.206115571831, 11533.250338727956, 11562.88437115618, 11704.765904682028, 9658.468784216935, 10055.69832905014, 11397.202195846212, 11549.711257218636, 11580.420978951188], "value": 7856.896343289923, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1621.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 168.0, 171.0, 115.0, 115.0, 109.0, 156.0, 98.0, 175.0, 197.0], "value": 142.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 110.0, 139.0, 162.0, 113.0, 131.0, 131.0, 113.0, 205.0, 97.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 233.0, 189.0, 194.0, 204.0, 161.0, 164.0, 208.0, 214.0, 273.0], "value": 205.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 87.0, 76.0, 147.0, 59.0, 62.0, 62.0, 62.0, 146.0, 60.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 130.0, 65.0, 79.0, 129.0, 144.0, 97.0, 72.0, 114.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 130.0, 87.0, 97.0, 121.0, 89.0, 88.0, 77.0, 98.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 102.0, 101.0, 101.0, 101.0, 102.0, 114.0, 170.0, 174.0, 103.0], "value": 120.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 143.0, 91.0, 93.0, 114.0, 148.0, 90.0, 91.0, 91.0, 93.0], "value": 110.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 58.0, 58.0, 57.0, 57.0, 57.0, 55.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 69.0, 77.0, 87.0, 108.0, 100.0, 108.0, 107.0, 71.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 92.0, 90.0, 86.0, 87.0, 84.0, 86.0, 92.0, 90.0, 91.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 127.0, 140.0, 137.0, 141.0, 162.0, 156.0, 186.0, 136.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 175.0, 241.0, 173.0, 173.0, 174.0, 169.0, 169.0, 172.0, 172.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 70.0, 67.0, 69.0, 68.0, 70.0, 84.0, 95.0, 87.0], "value": 74.8, "unit": "score"}]}]} 05:55:24 INFO - Return code: 0 05:55:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:55:24 ERROR - # TBPL SUCCESS # 05:55:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:55:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:55:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:55:24 INFO - 2016-09-06 05:55:24.220 screenresolution[1984:27181] starting screenresolution argv=screenresolution get 05:55:24 INFO - 2016-09-06 05:55:24.222 screenresolution[1984:27181] Display 0: 1600x1200x32@60 05:55:24 INFO - 2016-09-06 05:55:24.228 screenresolution[1985:27184] starting screenresolution argv=screenresolution list 05:55:24 INFO - Available Modes on Display 0 05:55:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:55:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:55:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:55:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:55:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:55:24 INFO - Intel Iris: 05:55:24 INFO - Chipset Model: Intel Iris 05:55:24 INFO - Type: GPU 05:55:24 INFO - Bus: Built-In 05:55:24 INFO - VRAM (Dynamic, Max): 1536 MB 05:55:24 INFO - Vendor: Intel (0x8086) 05:55:24 INFO - Device ID: 0x0a2e 05:55:24 INFO - Revision ID: 0x0009 05:55:24 INFO - Displays: 05:55:24 INFO - Display: 05:55:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:55:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:55:24 INFO - Display Serial Number: 1600x1200 60 05:55:24 INFO - Main Display: Yes 05:55:24 INFO - Mirror: Off 05:55:24 INFO - Online: Yes 05:55:24 INFO - Rotation: Supported 05:55:24 INFO - Return code: 0 05:55:24 INFO - Running post-action listener: _resource_record_post_action 05:55:24 INFO - [mozharness: 2016-09-06 12:55:24.461643Z] Finished run-tests step (success) 05:55:24 INFO - Running post-run listener: _resource_record_post_run 05:55:24 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 1953792; Write bytes: 708009984; Read time: 16; Write time: 2052 05:55:24 INFO - TinderboxPrint: CPU usage
54.5% 05:55:24 INFO - TinderboxPrint: I/O read bytes / time
1,953,792 / 16 05:55:24 INFO - TinderboxPrint: I/O write bytes / time
708,009,984 / 2,052 05:55:24 INFO - TinderboxPrint: CPU idle
2,203.4 (45.5%) 05:55:24 INFO - TinderboxPrint: CPU system
979.8 (20.2%) 05:55:24 INFO - TinderboxPrint: CPU user
1,658.5 (34.3%) 05:55:24 INFO - TinderboxPrint: Swap in / out
407,158,784 / 0 05:55:24 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391897600; Write bytes: 386594304; Read time: 29574; Write time: 836 05:55:24 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1855488; Write bytes: 299338240; Read time: 12; Write time: 1164 05:55:24 INFO - Running post-run listener: _upload_blobber_files 05:55:24 INFO - Blob upload gear active. 05:55:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:55:24 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:55:24 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:55:24 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:55:25 INFO - (blobuploader) - INFO - Open directory for files ... 05:55:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:55:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:55:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:26 INFO - (blobuploader) - INFO - Done attempting. 05:55:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:55:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:55:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:27 INFO - (blobuploader) - INFO - Done attempting. 05:55:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:55:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:55:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:29 INFO - (blobuploader) - INFO - Done attempting. 05:55:29 INFO - (blobuploader) - INFO - Iteration through files over. 05:55:29 INFO - Return code: 0 05:55:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:55:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:55:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eda77a3e5110998ca28c1e0bb1305cd6bf6f8d823ddd467aadb8b9590f27408a868f1a42d65f1205f0d35bf2b382fb0f8dd03c52a6261477ecbcc782fb228589", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8359ad487d018fc7a67f0b37c9522eb469f25718647551e41494ecb5e8969889caa73115b137b6d2d82b962f526107ce7762ac2263fdc566faccfbce4162af3b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/557d524363469856ffbf268cdef969b117d7d5eda5bcf53455cfadeafbdcc2cc8403e90643bbdeb3b98a53669d667ca6abf97bc11a11b0e65feb030dd535aed9"} 05:55:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:55:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:55:29 INFO - Contents: 05:55:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eda77a3e5110998ca28c1e0bb1305cd6bf6f8d823ddd467aadb8b9590f27408a868f1a42d65f1205f0d35bf2b382fb0f8dd03c52a6261477ecbcc782fb228589", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8359ad487d018fc7a67f0b37c9522eb469f25718647551e41494ecb5e8969889caa73115b137b6d2d82b962f526107ce7762ac2263fdc566faccfbce4162af3b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/557d524363469856ffbf268cdef969b117d7d5eda5bcf53455cfadeafbdcc2cc8403e90643bbdeb3b98a53669d667ca6abf97bc11a11b0e65feb030dd535aed9"} 05:55:29 INFO - Running post-run listener: copy_logs_to_upload_dir 05:55:29 INFO - Copying logs to upload dir... 05:55:29 INFO - mkdir: /builds/slave/test/build/upload/logs 05:55:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.238131 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-06 05:55:29.744746) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 05:55:29.750543) ========= 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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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/eda77a3e5110998ca28c1e0bb1305cd6bf6f8d823ddd467aadb8b9590f27408a868f1a42d65f1205f0d35bf2b382fb0f8dd03c52a6261477ecbcc782fb228589", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8359ad487d018fc7a67f0b37c9522eb469f25718647551e41494ecb5e8969889caa73115b137b6d2d82b962f526107ce7762ac2263fdc566faccfbce4162af3b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/557d524363469856ffbf268cdef969b117d7d5eda5bcf53455cfadeafbdcc2cc8403e90643bbdeb3b98a53669d667ca6abf97bc11a11b0e65feb030dd535aed9"} build_url:https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010484 build_url: 'https://queue.taskcluster.net/v1/task/KjT2LxtASeaIzkrjJZurBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eda77a3e5110998ca28c1e0bb1305cd6bf6f8d823ddd467aadb8b9590f27408a868f1a42d65f1205f0d35bf2b382fb0f8dd03c52a6261477ecbcc782fb228589", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8359ad487d018fc7a67f0b37c9522eb469f25718647551e41494ecb5e8969889caa73115b137b6d2d82b962f526107ce7762ac2263fdc566faccfbce4162af3b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/557d524363469856ffbf268cdef969b117d7d5eda5bcf53455cfadeafbdcc2cc8403e90643bbdeb3b98a53669d667ca6abf97bc11a11b0e65feb030dd535aed9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 05:55:29.785342) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 05:55:29.785809) ========= 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.DZ3Xr2cwxE/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.lwdW3yWyb3/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.005309 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 05:55:29.836473) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 05:55:29.836954) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 05:55:29.837407) ========= ========= Total master_lag: 0.21 =========