builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0103 starttime: 1476284598.82 results: success (0) buildid: 20161012061346 builduid: 6978c69d9fc74ac7b933b8d0301a44bd revision: 57667da3806c500acca44c2605cceebddaf1aef7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:18.816248) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:18.816963) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:18.817505) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:18.848909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:18.849361) ========= 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.yOES5JZtgj/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.MelvKhXabP/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-12 08:03:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-10-12 08:03:19 (14.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.488984 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:19.362604) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:19.363055) ========= 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.yOES5JZtgj/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.MelvKhXabP/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.029725 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:19.412945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:19.413401) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 57667da3806c500acca44c2605cceebddaf1aef7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 57667da3806c500acca44c2605cceebddaf1aef7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yOES5JZtgj/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.MelvKhXabP/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-12 08:03:19,489 truncating revision to first 12 chars 2016-10-12 08:03:19,489 Setting DEBUG logging. 2016-10-12 08:03:19,489 attempt 1/10 2016-10-12 08:03:19,489 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/57667da3806c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 08:03:20,206 unpacking tar archive at: autoland-57667da3806c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.975007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:20.410298) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:20.410618) ========= script_repo_revision: 57667da3806c500acca44c2605cceebddaf1aef7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:20.411059) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:20.411320) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 08:03:20.430935) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-12 08:03:20.431333) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yOES5JZtgj/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.MelvKhXabP/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:03:20 INFO - MultiFileLogger online at 20161012 08:03:20 in /builds/slave/test 08:03:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 08:03:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:03:20 INFO - {'append_to_log': False, 08:03:20 INFO - 'base_work_dir': '/builds/slave/test', 08:03:20 INFO - 'blob_upload_branch': 'Autoland', 08:03:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:03:20 INFO - 'branch': 'Autoland', 08:03:20 INFO - 'buildbot_json_path': 'buildprops.json', 08:03:20 INFO - 'config_files': ('talos/mac_config.py',), 08:03:20 INFO - 'default_actions': ('clobber', 08:03:20 INFO - 'read-buildbot-config', 08:03:20 INFO - 'download-and-extract', 08:03:20 INFO - 'populate-webroot', 08:03:20 INFO - 'create-virtualenv', 08:03:20 INFO - 'install', 08:03:20 INFO - 'run-tests'), 08:03:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:03:20 INFO - 'download_minidump_stackwalk': True, 08:03:20 INFO - 'download_symbols': 'ondemand', 08:03:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:03:20 INFO - 'tooltool.py': '/tools/tooltool.py', 08:03:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:03:20 INFO - '/tools/misc-python/virtualenv.py')}, 08:03:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:03:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:03:20 INFO - 'installer_path': 'installer.exe', 08:03:20 INFO - 'log_level': 'info', 08:03:20 INFO - 'log_name': 'talos', 08:03:20 INFO - 'log_to_console': True, 08:03:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:03:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:03:20 INFO - 'opt_config_files': (), 08:03:20 INFO - 'pip_index': False, 08:03:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:03:20 INFO - 'cmd': ('bash', 08:03:20 INFO - '-c', 08:03:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:03:20 INFO - 'enabled': True, 08:03:20 INFO - 'halt_on_failure': False, 08:03:20 INFO - 'name': 'check_screen_resolution'},), 08:03:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:03:20 INFO - 'cmd': ('bash', 08:03:20 INFO - '-c', 08:03:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:03:20 INFO - 'enabled': True, 08:03:20 INFO - 'halt_on_failure': False, 08:03:20 INFO - 'name': 'check_screen_resolution'},), 08:03:20 INFO - 'run_cmd_checks_enabled': True, 08:03:20 INFO - 'sps_profile': False, 08:03:20 INFO - 'sps_profile_interval': 0, 08:03:20 INFO - 'suite': 'dromaeojs-e10s', 08:03:20 INFO - 'system_bits': '32', 08:03:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:03:20 INFO - 'title': 't-yosemite-r7-0103', 08:03:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:03:20 INFO - 'use_talos_json': True, 08:03:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:03:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:03:20 INFO - 'work_dir': 'build'} 08:03:20 INFO - [mozharness: 2016-10-12 15:03:20.639383Z] Running clobber step. 08:03:20 INFO - Running pre-action listener: _resource_record_pre_action 08:03:20 INFO - Running main action method: clobber 08:03:20 INFO - rmtree: /builds/slave/test/build 08:03:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:03:22 INFO - Running post-action listener: _resource_record_post_action 08:03:22 INFO - [mozharness: 2016-10-12 15:03:22.955313Z] Finished clobber step (success) 08:03:22 INFO - [mozharness: 2016-10-12 15:03:22.955386Z] Running read-buildbot-config step. 08:03:22 INFO - Running pre-action listener: _resource_record_pre_action 08:03:22 INFO - Running main action method: read_buildbot_config 08:03:22 INFO - Using buildbot properties: 08:03:22 INFO - { 08:03:22 INFO - "project": "", 08:03:22 INFO - "product": "firefox", 08:03:22 INFO - "script_repo_revision": "production", 08:03:22 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 08:03:22 INFO - "repository": "", 08:03:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 08:03:22 INFO - "buildid": "20161012061346", 08:03:22 INFO - "pgo_build": "False", 08:03:22 INFO - "basedir": "/builds/slave/test", 08:03:22 INFO - "buildnumber": 242, 08:03:22 INFO - "slavename": "t-yosemite-r7-0103", 08:03:22 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:03:22 INFO - "platform": "yosemite_r7", 08:03:22 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 08:03:22 INFO - "branch": "autoland", 08:03:22 INFO - "repo_path": "integration/autoland", 08:03:22 INFO - "revision": "57667da3806c500acca44c2605cceebddaf1aef7", 08:03:22 INFO - "stage_platform": "macosx64", 08:03:22 INFO - "builduid": "6978c69d9fc74ac7b933b8d0301a44bd", 08:03:22 INFO - "slavebuilddir": "test" 08:03:22 INFO - } 08:03:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:03:22 INFO - Running post-action listener: _resource_record_post_action 08:03:22 INFO - [mozharness: 2016-10-12 15:03:22.961143Z] Finished read-buildbot-config step (success) 08:03:22 INFO - [mozharness: 2016-10-12 15:03:22.961360Z] Running download-and-extract step. 08:03:22 INFO - Running pre-action listener: _resource_record_pre_action 08:03:22 INFO - Running main action method: download_and_extract 08:03:22 INFO - mkdir: /builds/slave/test/build/tests 08:03:22 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')]} 08:03:22 INFO - trying https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:03:22 INFO - Downloading https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:03:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:03:23 INFO - Downloaded 1199 bytes. 08:03:23 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:03:23 INFO - Using the following test package requirements: 08:03:23 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:03:23 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:03:23 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:03:23 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:03:23 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:03:23 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:03:23 INFO - u'jsshell-mac.zip'], 08:03:23 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:03:23 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:03:23 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:03:23 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:03:23 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:03:23 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:03:23 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:03:23 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:03:23 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:03:23 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:03:23 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:03:23 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:03:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:03:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:03:23 INFO - Fetch https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:03:24 INFO - Http code: 200 08:03:24 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 08:03:24 INFO - x-amz-cf-id: L9PX7sy9WNJMOL1NOmBp3fU-dNbfXdI32zWv6mCGUGRZNk3hFkYZvw== 08:03:24 INFO - x-amz-version-id: t1hM8m9YeKJtJtxWuWiPupZsrQF2ruH_ 08:03:24 INFO - Expected file size: 16510539 08:03:24 INFO - Obtained file size: 16510539 08:03:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:03:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:03:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:03:31 INFO - Fetch https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:03:32 INFO - Http code: 200 08:03:32 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 08:03:32 INFO - x-amz-cf-id: S5rvCpAeHsc-MfNyXMaZjvJCCe9cPIqPzOsNXCALEU1qF-Sx-hIqvQ== 08:03:32 INFO - x-amz-version-id: t1hM8m9YeKJtJtxWuWiPupZsrQF2ruH_ 08:03:32 INFO - Expected file size: 16510539 08:03:32 INFO - Obtained file size: 16510539 08:03:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:03:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:03:38 INFO - Fetch https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:03:39 INFO - Http code: 200 08:03:39 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 08:03:39 INFO - x-amz-cf-id: pqh28_S97mcr1u4aBfgqAEFDTFBFdaQKAhV_l0KOTmTQPYd_mVcRYw== 08:03:39 INFO - x-amz-version-id: ngOJu9YkHRO5WrKSU4DzWQEufNsSI99J 08:03:39 INFO - Expected file size: 14409006 08:03:39 INFO - Obtained file size: 14409006 08:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:40 INFO - trying https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:03:40 INFO - Downloading https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:03:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:03:43 INFO - Downloaded 111721345 bytes. 08:03:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:03:43 INFO - mkdir: /builds/slave/test/properties 08:03:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:03:43 INFO - Writing to file /builds/slave/test/properties/build_url 08:03:43 INFO - Contents: 08:03:43 INFO - build_url:https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:03:44 INFO - Running post-action listener: _resource_record_post_action 08:03:44 INFO - Running post-action listener: set_extra_try_arguments 08:03:44 INFO - [mozharness: 2016-10-12 15:03:44.447199Z] Finished download-and-extract step (success) 08:03:44 INFO - [mozharness: 2016-10-12 15:03:44.447338Z] Running populate-webroot step. 08:03:44 INFO - Running pre-action listener: _resource_record_pre_action 08:03:44 INFO - Running main action method: populate_webroot 08:03:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:03:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:03:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:03:44 INFO - '--tppagecycles', 08:03:44 INFO - '7'], 08:03:44 INFO - 'tests': ['tsvgm']}}, 08:03:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:03:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:03:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:03:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:03:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:03:44 INFO - 'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:03:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:03:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:03:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:03:44 INFO - 'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['damp', 'tps']}, 08:03:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:03:44 INFO - 'tests': ['damp', 'tps']}, 08:03:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['dromaeo_dom']}, 08:03:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:03:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['basic_compositor_video']}, 08:03:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:03:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['a11yr', 08:03:44 INFO - 'ts_paint', 08:03:44 INFO - 'tpaint', 08:03:44 INFO - 'sessionrestore', 08:03:44 INFO - 'sessionrestore_no_auto_restore', 08:03:44 INFO - 'tabpaint']}, 08:03:44 INFO - 'other-e10s': {'tests': ['a11yr', 08:03:44 INFO - 'ts_paint', 08:03:44 INFO - 'tpaint', 08:03:44 INFO - 'sessionrestore', 08:03:44 INFO - 'sessionrestore_no_auto_restore', 08:03:44 INFO - 'tabpaint']}, 08:03:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:03:44 INFO - 'ts_paint', 08:03:44 INFO - 'tpaint', 08:03:44 INFO - 'sessionrestore', 08:03:44 INFO - 'sessionrestore_no_auto_restore', 08:03:44 INFO - 'tabpaint']}, 08:03:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:03:44 INFO - 'ts_paint', 08:03:44 INFO - 'tpaint', 08:03:44 INFO - 'sessionrestore', 08:03:44 INFO - 'sessionrestore_no_auto_restore', 08:03:44 INFO - 'tabpaint']}, 08:03:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['a11yr', 08:03:44 INFO - 'ts_paint', 08:03:44 INFO - 'tpaint', 08:03:44 INFO - 'sessionrestore', 08:03:44 INFO - 'sessionrestore_no_auto_restore', 08:03:44 INFO - 'tabpaint']}, 08:03:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['a11yr', 08:03:44 INFO - 'ts_paint', 08:03:44 INFO - 'tpaint', 08:03:44 INFO - 'sessionrestore', 08:03:44 INFO - 'sessionrestore_no_auto_restore', 08:03:44 INFO - 'tabpaint']}, 08:03:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['tsvgx', 08:03:44 INFO - 'tsvgr_opacity', 08:03:44 INFO - 'tart', 08:03:44 INFO - 'tscrollx', 08:03:44 INFO - 'cart']}, 08:03:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:03:44 INFO - 'tsvgr_opacity', 08:03:44 INFO - 'tart', 08:03:44 INFO - 'tscrollx', 08:03:44 INFO - 'cart']}, 08:03:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:03:44 INFO - 'talos_options': ['--disable-e10s'], 08:03:44 INFO - 'tests': ['tp5o']}, 08:03:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:03:44 INFO - 'tests': ['tp5o']}, 08:03:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:03:44 INFO - 'talos_options': ['--disable-e10s', 08:03:44 INFO - '--xperf_path', 08:03:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:03:44 INFO - 'tests': ['tp5n']}, 08:03:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:03:44 INFO - 'talos_options': ['--xperf_path', 08:03:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:03:44 INFO - 'tests': ['tp5n']}}, 08:03:44 INFO - 'talos.zip': {'path': '', 08:03:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:03:44 INFO - Running post-action listener: _resource_record_post_action 08:03:44 INFO - [mozharness: 2016-10-12 15:03:44.466169Z] Finished populate-webroot step (success) 08:03:44 INFO - [mozharness: 2016-10-12 15:03:44.466308Z] Running create-virtualenv step. 08:03:44 INFO - Running pre-action listener: _resource_record_pre_action 08:03:44 INFO - Running main action method: create_virtualenv 08:03:44 INFO - Creating virtualenv /builds/slave/test/build/venv 08:03:44 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 08:03:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:03:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:03:44 INFO - Using real prefix '/tools/python27' 08:03:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:03:45 INFO - Installing distribute.............................................................................................................................................................................................done. 08:03:48 INFO - Installing pip.................done. 08:03:48 INFO - Return code: 0 08:03:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:03:48 INFO - Reading from file tmpfile_stdout 08:03:48 INFO - Output received: 08:03:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:03:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:03: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')]} 08:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03: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')]} 08:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:48 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:03:48 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 08:03:48 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 08:03:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:03:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:48 INFO - 'HOME': '/Users/cltbld', 08:03:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:48 INFO - 'LOGNAME': 'cltbld', 08:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:48 INFO - 'MOZ_NO_REMOTE': '1', 08:03:48 INFO - 'NO_EM_RESTART': '1', 08:03:48 INFO - 'PAGER': '/bin/cat', 08:03:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:48 INFO - 'PWD': '/builds/slave/test', 08:03:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:48 INFO - 'SHELL': '/bin/bash', 08:03:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:03:48 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:03:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:48 INFO - 'USER': 'cltbld', 08:03:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:48 INFO - 'XPC_FLAGS': '0x0', 08:03:48 INFO - 'XPC_SERVICE_NAME': '0', 08:03:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:48 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 08:03:48 INFO - Cleaning up... 08:03:48 INFO - Return code: 0 08:03:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:03: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')]} 08:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03: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')]} 08:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:48 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:03:48 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 08:03:48 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 08:03:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:03:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:48 INFO - 'HOME': '/Users/cltbld', 08:03:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:48 INFO - 'LOGNAME': 'cltbld', 08:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:48 INFO - 'MOZ_NO_REMOTE': '1', 08:03:48 INFO - 'NO_EM_RESTART': '1', 08:03:48 INFO - 'PAGER': '/bin/cat', 08:03:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:48 INFO - 'PWD': '/builds/slave/test', 08:03:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:48 INFO - 'SHELL': '/bin/bash', 08:03:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:03:48 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:03:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:48 INFO - 'USER': 'cltbld', 08:03:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:48 INFO - 'XPC_FLAGS': '0x0', 08:03:48 INFO - 'XPC_SERVICE_NAME': '0', 08:03:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:48 INFO - Downloading/unpacking psutil>=3.1.1 08:03:48 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 08:03:48 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 08:03:48 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 08:03:48 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 08:03:48 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 08:03:48 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 08:03:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:03:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:03:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:03:52 INFO - Installing collected packages: psutil 08:03:52 INFO - Running setup.py install for psutil 08:03:52 INFO - building 'psutil._psutil_osx' extension 08:03:52 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 08:03:53 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 08:03:53 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 08:03:53 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 08:03:53 INFO - building 'psutil._psutil_posix' extension 08:03:53 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 08:03:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:03:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:03:53 INFO - ^ 08:03:53 INFO - 1 warning generated. 08:03:53 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 08:03:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:03:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:03:53 INFO - Successfully installed psutil 08:03:53 INFO - Cleaning up... 08:03:53 INFO - Return code: 0 08:03:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:03:53 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')]} 08:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:53 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')]} 08:03:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:53 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:03:53 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 08:03:53 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 08:03:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:03:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:53 INFO - 'HOME': '/Users/cltbld', 08:03:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:53 INFO - 'LOGNAME': 'cltbld', 08:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:53 INFO - 'MOZ_NO_REMOTE': '1', 08:03:53 INFO - 'NO_EM_RESTART': '1', 08:03:53 INFO - 'PAGER': '/bin/cat', 08:03:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:53 INFO - 'PWD': '/builds/slave/test', 08:03:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:53 INFO - 'SHELL': '/bin/bash', 08:03:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:03:53 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:03:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:53 INFO - 'USER': 'cltbld', 08:03:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:53 INFO - 'XPC_FLAGS': '0x0', 08:03:53 INFO - 'XPC_SERVICE_NAME': '0', 08:03:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:03: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 08:03: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 08:03: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 08:03: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 08:03: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 08:03: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 08:03:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:03:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:03:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:03:56 INFO - Installing collected packages: mozsystemmonitor 08:03:56 INFO - Running setup.py install for mozsystemmonitor 08:03:56 INFO - Successfully installed mozsystemmonitor 08:03:56 INFO - Cleaning up... 08:03:56 INFO - Return code: 0 08:03:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:03:56 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')]} 08:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:56 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')]} 08:03:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:56 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:03:56 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 08:03:56 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 08:03:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:03:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:56 INFO - 'HOME': '/Users/cltbld', 08:03:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:56 INFO - 'LOGNAME': 'cltbld', 08:03:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:56 INFO - 'MOZ_NO_REMOTE': '1', 08:03:56 INFO - 'NO_EM_RESTART': '1', 08:03:56 INFO - 'PAGER': '/bin/cat', 08:03:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:56 INFO - 'PWD': '/builds/slave/test', 08:03:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:56 INFO - 'SHELL': '/bin/bash', 08:03:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:03:56 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:03:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:56 INFO - 'USER': 'cltbld', 08:03:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:56 INFO - 'XPC_FLAGS': '0x0', 08:03:56 INFO - 'XPC_SERVICE_NAME': '0', 08:03:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:57 INFO - Downloading/unpacking jsonschema==2.5.1 08:03:57 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 08:03:57 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 08:03:57 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 08:03:57 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 08:03:57 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 08:03:57 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 08:03:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:03:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:03:59 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 08:03:59 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 08:03:59 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 08:03:59 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 08:03:59 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 08:03:59 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 08:04:00 INFO - Downloading functools32-3.2.3-2.tar.gz 08:04:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:04:00 INFO - warning: no files found matching '*.txt' 08:04:00 INFO - no previously-included directories found matching 'build' 08:04:00 INFO - no previously-included directories found matching 'dist' 08:04:00 INFO - no previously-included directories found matching '.git*' 08:04:00 INFO - Installing collected packages: jsonschema, functools32 08:04:00 INFO - Running setup.py install for functools32 08:04:00 INFO - warning: no files found matching '*.txt' 08:04:00 INFO - no previously-included directories found matching 'build' 08:04:00 INFO - no previously-included directories found matching 'dist' 08:04:00 INFO - no previously-included directories found matching '.git*' 08:04:00 INFO - Successfully installed jsonschema functools32 08:04:00 INFO - Cleaning up... 08:04:00 INFO - Return code: 0 08:04:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:04:00 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')]} 08:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:00 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')]} 08:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:00 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:04:00 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 08:04:00 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 08:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:00 INFO - 'HOME': '/Users/cltbld', 08:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:00 INFO - 'LOGNAME': 'cltbld', 08:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:00 INFO - 'MOZ_NO_REMOTE': '1', 08:04:00 INFO - 'NO_EM_RESTART': '1', 08:04:00 INFO - 'PAGER': '/bin/cat', 08:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:00 INFO - 'PWD': '/builds/slave/test', 08:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:00 INFO - 'SHELL': '/bin/bash', 08:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:04:00 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:00 INFO - 'USER': 'cltbld', 08:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:00 INFO - 'XPC_FLAGS': '0x0', 08:04:00 INFO - 'XPC_SERVICE_NAME': '0', 08:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:04:00 INFO - Cleaning up... 08:04:00 INFO - Return code: 0 08:04:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:04:00 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')]} 08:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:00 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')]} 08:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:00 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:04:00 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 08:04:00 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 08:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:00 INFO - 'HOME': '/Users/cltbld', 08:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:00 INFO - 'LOGNAME': 'cltbld', 08:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:00 INFO - 'MOZ_NO_REMOTE': '1', 08:04:00 INFO - 'NO_EM_RESTART': '1', 08:04:00 INFO - 'PAGER': '/bin/cat', 08:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:00 INFO - 'PWD': '/builds/slave/test', 08:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:00 INFO - 'SHELL': '/bin/bash', 08:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:04:00 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:00 INFO - 'USER': 'cltbld', 08:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:00 INFO - 'XPC_FLAGS': '0x0', 08:04:00 INFO - 'XPC_SERVICE_NAME': '0', 08:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:00 INFO - Downloading/unpacking blobuploader==1.2.4 08:04: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 08:04: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 08:04: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 08:04: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 08:04: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 08:04: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 08:04:03 INFO - Downloading blobuploader-1.2.4.tar.gz 08:04:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:04:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:04:03 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 08:04:03 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 08:04:03 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 08:04:03 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 08:04:03 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 08:04:03 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 08:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:04:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:04:04 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 08:04:04 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 08:04:04 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 08:04:04 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 08:04:04 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 08:04:04 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 08:04:04 INFO - Downloading docopt-0.6.1.tar.gz 08:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:04:04 INFO - Installing collected packages: blobuploader, requests, docopt 08:04:04 INFO - Running setup.py install for blobuploader 08:04:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:04:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:04:04 INFO - Running setup.py install for requests 08:04:05 INFO - Running setup.py install for docopt 08:04:05 INFO - Successfully installed blobuploader requests docopt 08:04:05 INFO - Cleaning up... 08:04:05 INFO - Return code: 0 08:04:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:05 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:04:05 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 08:04:05 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 08:04:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:04:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:05 INFO - 'HOME': '/Users/cltbld', 08:04:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:05 INFO - 'LOGNAME': 'cltbld', 08:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:05 INFO - 'MOZ_NO_REMOTE': '1', 08:04:05 INFO - 'NO_EM_RESTART': '1', 08:04:05 INFO - 'PAGER': '/bin/cat', 08:04:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:05 INFO - 'PWD': '/builds/slave/test', 08:04:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:05 INFO - 'SHELL': '/bin/bash', 08:04:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:04:05 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:04:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:05 INFO - 'USER': 'cltbld', 08:04:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:05 INFO - 'XPC_FLAGS': '0x0', 08:04:05 INFO - 'XPC_SERVICE_NAME': '0', 08:04:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:04:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-E9YGj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Wu5BTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-d1d89D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-0uUu3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-r9FTVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-ItnRaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-tpSH7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-SvtXR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-R4owpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Opd7KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:04:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-A6WdDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:04:07 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-oI1dEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:04:07 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-BcArkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:04:07 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-pCyTo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:07 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-FLQgo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:04:07 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-UDAmrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:04:07 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-DHLWd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:04:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:04:07 INFO - Running setup.py install for manifestparser 08:04:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:04:07 INFO - Running setup.py install for mozcrash 08:04:07 INFO - Running setup.py install for mozdebug 08:04:08 INFO - Running setup.py install for mozdevice 08:04:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:04:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:04:08 INFO - Running setup.py install for mozfile 08:04:08 INFO - Running setup.py install for mozhttpd 08:04:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:04:08 INFO - Running setup.py install for mozinfo 08:04:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:04:08 INFO - Running setup.py install for mozInstall 08:04:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:04:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:04:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:04:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:04:08 INFO - Running setup.py install for mozleak 08:04:09 INFO - Running setup.py install for mozlog 08:04:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:04:09 INFO - Running setup.py install for moznetwork 08:04:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:04:09 INFO - Running setup.py install for mozprocess 08:04:09 INFO - Running setup.py install for mozprofile 08:04:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:04:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:04:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:04:09 INFO - Running setup.py install for mozrunner 08:04:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:04:09 INFO - Running setup.py install for mozscreenshot 08:04:10 INFO - Running setup.py install for moztest 08:04:10 INFO - Running setup.py install for mozversion 08:04:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:04:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:04:10 INFO - Cleaning up... 08:04:10 INFO - Return code: 0 08:04:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:10 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:04:10 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 08:04:10 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 08:04:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:04:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:10 INFO - 'HOME': '/Users/cltbld', 08:04:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:10 INFO - 'LOGNAME': 'cltbld', 08:04:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:10 INFO - 'MOZ_NO_REMOTE': '1', 08:04:10 INFO - 'NO_EM_RESTART': '1', 08:04:10 INFO - 'PAGER': '/bin/cat', 08:04:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:10 INFO - 'PWD': '/builds/slave/test', 08:04:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:10 INFO - 'SHELL': '/bin/bash', 08:04:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:04:10 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:04:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:10 INFO - 'USER': 'cltbld', 08:04:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:10 INFO - 'XPC_FLAGS': '0x0', 08:04:10 INFO - 'XPC_SERVICE_NAME': '0', 08:04:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:04:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-X5Wt9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:04:10 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)) 08:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:04:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-CRx9nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:04:10 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)) 08:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:04:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-E6i532-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:04:10 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)) 08:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:04:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-TXsU9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-xpV9Eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-kC7uDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-3PYTLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-V2Gq21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-3FuOhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Fakuxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:04:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-WtJnUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-rTunw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:04:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-BG55e5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:04:11 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)) 08:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:04:12 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-40A3XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:04:12 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)) 08:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:12 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Yqs2Bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:12 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)) 08:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:04:12 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-QKx56t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:04:12 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)) 08:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:04:12 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-DTP_PM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:04:12 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)) 08:04:12 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)) 08:04:12 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)) 08:04:12 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)) 08:04:12 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)) 08:04:12 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)) 08:04:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:04:12 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 08:04:12 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 08:04:12 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 08:04:12 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 08:04:12 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 08:04:12 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 08:04:14 INFO - Downloading blessings-1.6.tar.gz 08:04:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:04:15 INFO - Installing collected packages: blessings 08:04:15 INFO - Running setup.py install for blessings 08:04:15 INFO - Successfully installed blessings 08:04:15 INFO - Cleaning up... 08:04:15 INFO - Return code: 0 08:04:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:04:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:04:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:04:15 INFO - Reading from file tmpfile_stdout 08:04:15 INFO - Current package versions: 08:04:15 INFO - blessings == 1.6 08:04:15 INFO - blobuploader == 1.2.4 08:04:15 INFO - docopt == 0.6.1 08:04:15 INFO - functools32 == 3.2.3-2 08:04:15 INFO - jsonschema == 2.5.1 08:04:15 INFO - manifestparser == 1.1 08:04:15 INFO - mozInstall == 1.12 08:04:15 INFO - mozcrash == 0.17 08:04:15 INFO - mozdebug == 0.1 08:04:15 INFO - mozdevice == 0.48 08:04:15 INFO - mozfile == 1.2 08:04:15 INFO - mozhttpd == 0.7 08:04:15 INFO - mozinfo == 0.9 08:04:15 INFO - mozleak == 0.1 08:04:15 INFO - mozlog == 3.4 08:04:15 INFO - moznetwork == 0.27 08:04:15 INFO - mozprocess == 0.23 08:04:15 INFO - mozprofile == 0.28 08:04:15 INFO - mozrunner == 6.12 08:04:15 INFO - mozscreenshot == 0.1 08:04:15 INFO - mozsystemmonitor == 0.3 08:04:15 INFO - moztest == 0.7 08:04:15 INFO - mozversion == 1.4 08:04:15 INFO - psutil == 3.1.1 08:04:15 INFO - requests == 1.2.3 08:04:15 INFO - wsgiref == 0.1.2 08:04:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:15 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:04:15 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 08:04:15 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 08:04:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:04:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:15 INFO - 'HOME': '/Users/cltbld', 08:04:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:15 INFO - 'LOGNAME': 'cltbld', 08:04:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:15 INFO - 'MOZ_NO_REMOTE': '1', 08:04:15 INFO - 'NO_EM_RESTART': '1', 08:04:15 INFO - 'PAGER': '/bin/cat', 08:04:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:15 INFO - 'PWD': '/builds/slave/test', 08:04:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:15 INFO - 'SHELL': '/bin/bash', 08:04:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:04:15 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:04:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:15 INFO - 'USER': 'cltbld', 08:04:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:15 INFO - 'XPC_FLAGS': '0x0', 08:04:15 INFO - 'XPC_SERVICE_NAME': '0', 08:04:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 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)) 08:04:15 INFO - Cleaning up... 08:04:15 INFO - Return code: 0 08:04:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:15 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 0x103294490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd459458d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, '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.yOES5JZtgj/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.MelvKhXabP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 08:04:15 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 08:04:15 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 08:04:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:04:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:15 INFO - 'HOME': '/Users/cltbld', 08:04:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:15 INFO - 'LOGNAME': 'cltbld', 08:04:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:15 INFO - 'MOZ_NO_REMOTE': '1', 08:04:15 INFO - 'NO_EM_RESTART': '1', 08:04:15 INFO - 'PAGER': '/bin/cat', 08:04:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:15 INFO - 'PWD': '/builds/slave/test', 08:04:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:15 INFO - 'SHELL': '/bin/bash', 08:04:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:04:15 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:04:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:15 INFO - 'USER': 'cltbld', 08:04:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:15 INFO - 'XPC_FLAGS': '0x0', 08:04:15 INFO - 'XPC_SERVICE_NAME': '0', 08:04:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:04:16 INFO - Cleaning up... 08:04:16 INFO - Return code: 0 08:04:16 INFO - Running post-action listener: _resource_record_post_action 08:04:16 INFO - Running post-action listener: _start_resource_monitoring 08:04:16 INFO - Starting resource monitoring. 08:04:16 INFO - [mozharness: 2016-10-12 15:04:16.166641Z] Finished create-virtualenv step (success) 08:04:16 INFO - [mozharness: 2016-10-12 15:04:16.167717Z] Running install step. 08:04:16 INFO - Running pre-action listener: _resource_record_pre_action 08:04:16 INFO - Running main action method: install 08:04:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:04:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:04:16 INFO - Reading from file tmpfile_stdout 08:04:16 INFO - Detecting whether we're running mozinstall >=1.0... 08:04:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:04:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:04:16 INFO - Reading from file tmpfile_stdout 08:04:16 INFO - Output received: 08:04:16 INFO - Usage: mozinstall [options] installer 08:04:16 INFO - Options: 08:04:16 INFO - -h, --help show this help message and exit 08:04:16 INFO - -d DEST, --destination=DEST 08:04:16 INFO - Directory to install application into. [default: 08:04:16 INFO - "/builds/slave/test"] 08:04:16 INFO - --app=APP Application being installed. [default: firefox] 08:04:16 INFO - mkdir: /builds/slave/test/build/application 08:04:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:04:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:04:50 INFO - Reading from file tmpfile_stdout 08:04:50 INFO - Output received: 08:04:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:04:50 INFO - Running post-action listener: _resource_record_post_action 08:04:50 INFO - [mozharness: 2016-10-12 15:04:50.391863Z] Finished install step (success) 08:04:50 INFO - [mozharness: 2016-10-12 15:04:50.392041Z] Running run-tests step. 08:04:50 INFO - Running pre-action listener: _resource_record_pre_action 08:04:50 INFO - Running main action method: run_tests 08:04:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:04:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:04:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:04:50 INFO - 2016-10-12 08:04:50.406 screenresolution[1647:7941] starting screenresolution argv=screenresolution get 08:04:50 INFO - 2016-10-12 08:04:50.409 screenresolution[1647:7941] Display 0: 1600x1200x32@60 08:04:50 INFO - 2016-10-12 08:04:50.415 screenresolution[1648:7943] starting screenresolution argv=screenresolution list 08:04:50 INFO - Available Modes on Display 0 08:04:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:04:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:04:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:04:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:04:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:04:50 INFO - Intel Iris: 08:04:50 INFO - Chipset Model: Intel Iris 08:04:50 INFO - Type: GPU 08:04:50 INFO - Bus: Built-In 08:04:50 INFO - VRAM (Dynamic, Max): 1536 MB 08:04:50 INFO - Vendor: Intel (0x8086) 08:04:50 INFO - Device ID: 0x0a2e 08:04:50 INFO - Revision ID: 0x0009 08:04:50 INFO - Displays: 08:04:50 INFO - Display: 08:04:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:04:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:04:50 INFO - Display Serial Number: 1600x1200 60 08:04:50 INFO - Main Display: Yes 08:04:50 INFO - Mirror: Off 08:04:50 INFO - Online: Yes 08:04:50 INFO - Rotation: Supported 08:04:50 INFO - Return code: 0 08:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:04:50 INFO - Python 2.7.3 08:04:50 INFO - Return code: 0 08:04:50 INFO - grabbing minidump binary from tooltool 08:04:50 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')]} 08:04:50 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 0x1030a8d30>, '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 0x102ecedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030a0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:04:50 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 08:04:50 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 08:04:50 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 08:04:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:04:50 INFO - Return code: 0 08:04:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:04:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:04:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:04:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:04:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 08:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0103 --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 08:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yOES5JZtgj/Render', 08:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:50 INFO - 'HOME': '/Users/cltbld', 08:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:50 INFO - 'LOGNAME': 'cltbld', 08:04:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:04:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:50 INFO - 'MOZ_NO_REMOTE': '1', 08:04:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:04:50 INFO - 'NO_EM_RESTART': '1', 08:04:50 INFO - 'PAGER': '/bin/cat', 08:04:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:50 INFO - 'PWD': '/builds/slave/test', 08:04:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:50 INFO - 'SHELL': '/bin/bash', 08:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MelvKhXabP/Listeners', 08:04:50 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 08:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:50 INFO - 'USER': 'cltbld', 08:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:50 INFO - 'XPC_FLAGS': '0x0', 08:04:50 INFO - 'XPC_SERVICE_NAME': '0', 08:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 08:04:51 INFO - mozversion application_buildid: 20161012061346 08:04:51 INFO - mozversion application_changeset: 57667da3806c500acca44c2605cceebddaf1aef7 08:04:51 INFO - mozversion application_display_name: Nightly 08:04:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:04:51 INFO - mozversion application_name: Firefox 08:04:51 INFO - mozversion application_remotingname: firefox 08:04:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:04:51 INFO - mozversion application_vendor: Mozilla 08:04:51 INFO - mozversion application_version: 52.0a1 08:04:51 INFO - mozversion platform_buildid: 20161012061346 08:04:51 INFO - mozversion platform_changeset: 57667da3806c500acca44c2605cceebddaf1aef7 08:04:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:04:51 INFO - mozversion platform_version: 52.0a1 08:04:51 INFO - using testdate: 1476284691 08:04:51 INFO - actual date: 1476284691 08:04:51 INFO - starting webserver on 'localhost:49272' 08:04:51 INFO - SUITE-START | Running 2 tests 08:04:51 INFO - TEST-START | dromaeo_css 08:04:51 INFO - operating with platform_type : mac_ 08:04:51 INFO - Initialising browser for dromaeo_css test... 08:04:51 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmp3PljDh/profile http://localhost:49272/getInfo.html) 08:04:53 INFO - PROCESS | 1658 | 2016-10-12 08:04:53.970 plugin-container[1660:8105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3b, name = 'com.apple.tsm.portname' 08:04:53 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:04:53 INFO - PROCESS | 1658 | 2016-10-12 08:04:53.972 plugin-container[1660:8105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 08:04:53 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:04:53 INFO - PROCESS | 1658 | 2016-10-12 08:04:53.972 plugin-container[1660:8105] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:04:54 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 08:04:54 INFO - PROCESS | 1658 | colorDepth:24 08:04:54 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 08:04:54 INFO - PROCESS | 1658 | __metrics 08:04:54 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 08:04:54 INFO - TEST-INFO | 1658: exit 0 08:04:54 INFO - Browser initialized. 08:04:54 INFO - Running cycle 1/1 for dromaeo_css test... 08:04:54 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmp3PljDh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:04:57 INFO - PROCESS | 1662 | 2016-10-12 08:04:57.145 plugin-container[1663:8232] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:04:57 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:04:57 INFO - PROCESS | 1662 | 2016-10-12 08:04:57.146 plugin-container[1663:8232] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 08:04:57 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:04:57 INFO - PROCESS | 1662 | 2016-10-12 08:04:57.146 plugin-container[1663:8232] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:05:06 INFO - PROCESS | 1662 | RSS: Main: 238862336 08:05:06 INFO - PROCESS | 1662 | 08:05:56 INFO - PROCESS | 1662 | 1476284756344 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] 08:08:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 08:08:11 INFO - PROCESS | 1662 | RSS: Main: 252063744 08:08:11 INFO - PROCESS | 1662 | 08:11:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 08:11:14 INFO - PROCESS | 1662 | RSS: Main: 262770688 08:11:14 INFO - PROCESS | 1662 | 08:14:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 08:14:16 INFO - PROCESS | 1662 | RSS: Main: 269103104 08:14:16 INFO - PROCESS | 1662 | 08:17:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 08:17:19 INFO - PROCESS | 1662 | RSS: Main: 268914688 08:17:19 INFO - PROCESS | 1662 | 08:20:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 08:20:22 INFO - PROCESS | 1662 | RSS: Main: 274157568 08:20:22 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 08:23:25 INFO - PROCESS | 1662 | RSS: Main: 277635072 08:23:25 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | __start_tp_report 08:23:25 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 08:23:25 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 08:23:25 INFO - PROCESS | 1662 | |i|pagename|runs| 08:23:25 INFO - PROCESS | 1662 | |0;dojo.html;3346.84939177737;3362.966370336296;3404.897853064409;3405.233822389962;3428.657134286573;5540.252065971093;5588.441155884409;5594.49649531542;5607.803726869555;5646.378898321182;5543.750531226095;5633.324001119871;5654.077613611361;5664.1220610805285;5728.971355143229;5133.101707201244;5141.082009190412;5185.507376799198;5204.609654275925;5244.711540865251;5263.1578947368425;5281.234221037967;5284.44513326101;5291.4708529147165;5340.572754179676;5116.539511443951;5235.005348983681;5280.023195708768;5288.576913846883;5313.282706834567;5159.329287192641;5214;5275.26146339509;5278.5249327560705;5291.4708529147165;5101.7194054327;5181.326427564431;5208.6093542984045;5214.322138122057;5255;4537.750423726694;5548.805791797267;5584.469475399871;5609.35492418373;5738.311402631711;6111.560547330611;6132.20254427538;6133.518290279305;6134.110553969649;6176;6235.563510554263;6264.498840092781;6265.342139075423;6267.96866015667;6284.87430251397;5692.6299790513485;5735.541156707537;5780.913286300709;5781.682007489587;5852.034414321589;6265.780697675651;6268.27914789805;6284;6284.057391391328;6308.990561510136;6110.388961103854;6126.754929802758;6127.785527506516;6128.601640893327;6143.723532441051;6103.277060575968;6110.908336374958;6130.662813545208;6136.7948077883175;6212.596181248205;4768.499307572724;5188.247704082887;6190.071489276645;6190.3190649028575;6193.4425901669065;6120.357362476965;6135;6195.96902015496;6197.070439434121;6217.533684973645;4789;4867.5132486751045;4876.561109500162;4918.262260660929;4924.975375123101;3500.072480792592;3734.514513113278;3987.2424239394422;4493.101379724003;4507.301368287921;5704.771809127588;5754.280714910637;5783.739731712083;5784.334801497882;5804.2744656917885;6089.512838972872;6098.129681996382;6180.47465965398;6180.47465965398;6192.164057852222;5720.656760594377;5780.814932938738;5793.131030345482;5844.970775146097;5891.852703682356;4710.95289047112;5374.892502149935;5378.542823860016;5386.084365657847;5397.298351214317;5623.746931388016;5926.288845338587;6251.562390632612;6256.937430625636;6279.905801412982;6113.9932453606225;6132.753252973555;6138.694953005908;6145.893739127;6211.192544969125;6262.749490020348;6271.529635277464;6290;6293.653849038347;6322.241331040304;5639;5652.717364131695;6126.326104128453;6156.230471191101;6184.072389141665;6180.072989051499;6226.937730622817;6227.532935029982;6236.189295391751;6242.781502647567;5686.061799802987;5767.769289228384;6096.664683442454;6127.662978536223;6129.938700612936;5713.857153571194;5790.855228619318;6025.367336429612;6085.695715214133;6098.664573448503;5544.112941929272;5715.085586306171;5908.911366329595;5921.6743079131065;6009.399060093956;5253.212018197301;6035.456808887221;6056;6058.90911636317;6100.267967843887;5721.885562288648;5928.021876390348;5928.081147422156;5931.6441013539325;5938.079597662369 08:23:25 INFO - PROCESS | 1662 | |1;ext.html;468.67427138139004;496.75907185015285;502.83155143027085;518.4504425309171;548.9011977843987;36934.44598331024;39260.803695981514;39344.21311573767;41004.38493422601;41157.58842411575;39529.802350988204;39938.40092398616;40038.797866117355;40056.39915401264;40122;9377.624895004208;9534.80930381392;9548.665796697116;9550.379225350365;9564.521773911276;7223.530470519399;8849.292056635486;9403.388779729295;9496.715098547054;9507.429554226768;7732.806679833021;9482.66810661627;9509;9516.952415237914;9536.427814331162;8121.675132994673;9355.438673679568;9485.098915603006;9487.051294870527;9517.5717092731;8439.57802109892;8931.598078086501;9321.72034838956;9428.669996550156;9442.57508412123;38692.87189204593;38709.22581548381;38823;38865.22269554621;38872.80563597192;3276.459384201604;3338.432466480704;3339.6159441664036;3341.381844358777;3343.314620502791;29158.854205728836;29186.85406572975;29765.95819146341;29962.101136965925;30000.64997075137;2396.604560247557;3027.8637461314293;3068.2789544457037;3074.200707815962;3158.4156930967843;457833;461459;464639.9999999966;465088;465576.3442365567;57491;58044.70977645085;58449.70775146182;58639;58684.82630347455;60707.69646151741;61229;61390;61393.38606613966;61408;16191.757123643154;16221;16433.589160271087;16439.753403698953;16445.177741112897;12652.873471265353;12750.107492475623;12751.872481275068;12772.297523636289;12787.872121278668;16864.494065178063;18121.54696132581;18157.7276340855;18235.81764182341;18315.450536483928;6542.901856472156;7581.166071732105;7597.696092156252;7616.695332186761;7705.383569314441;767.2021098058069;778.2256654628568;870.3602851903846;954.7660823098247;1021.8773747150241;22127.668084978737;22731;29319.267018324714;30150.396992060036;32383.67616323854;1021.7701017271203;1021.9284650074424;1023.6417253961054;1054.9578016879393;1092.071739021841;12259.264444133381;12385.380730963416;13560.050796444155;14384.85615143856;15242.390304387922;689400;691328;691625;699846;699996;47205.76397117993;51428;51972.22041669378;52018.69953251199;52134;1256;1318.854925958144;1324.920504769717;1325.8740419660116;1331.0815537279245;25104.62343064892;27545.862270688518;28290.907636625605;28722.569161462176;28934.42131157407;47326.29010564776;47941.76029119972;47983.04033919372;48035.03929921452;48035.5196448045;612621;614592;616388;616846;617378;24972.625410619217;25033.12384066549;25381.619275711248;25719.357016074748;26347.814348353986;43525.3471197926;43922;44956.550434496545;45062.549374505834;45148.32277515905;37192.628073718915;37351.43972840355;37725.62274377221;38598;38612.80693596514;37064;37275.88172354834;37354.8132259337;37866.62133378631;38607;36487.45268821023;36766.816165919;36887.26225475422;37165.07087322839;37430.251394972496;29811.70188298089;31629.525557117122;32586.85945991879;32919.17702057468;33121.50317745187;24177.63733543963;24280.392990175387;24851.62722559127;24882.751172488766;25158.371040724127 08:23:25 INFO - PROCESS | 1662 | |2;jquery.html;315.81366993473847;351.3710458279678;375.8590528551793;385.0662144300075;401.5764116208044;14881.925590372046;18949.715754263696;19917.800821991794;20214.39356819294;21175.68236476454;19379.709304360447;19797.406077817654;20250.797492025074;20255.189792408324;20328.695069573932;5704.857378565527;5725.828225153252;6016.548758843082;6034.8793024139495;6073.665948372838;5951.285845698522;6040.607360521574;6075.635461872302;6087.3303936566945;6100.145979562864;5882.588218824684;6051.243594550681;6052.9697351513405;6078.088286756978;6115.113308570255;5020.397552293712;6042.697865106749;6079.908801367983;6110.9694451527685;6115.266168059839;3899.629535194152;5339.225812257201;5371.248025276425;5377.973110134424;5411.18832175173;12639.988800895906;12838;12844.229346239255;18594.628107437777;24067.2779816604;1794.9891544446805;2016.536196674773;2028;2060.3818854343785;2229.2086309360106;9942.502874856229;9943.204543636492;10183.18534517237;11334.71663208418;13457.461701531927;1488.2409970914803;1530.4643374818725;1864.962700745991;1868.5702288473592;1873.9974113849025;108163;114466.85533144645;115168.42415787952;115614;117201;9334.673286435078;11209.775804483865;11231.38227397501;11408;11470.254433461798;14363.928180359031;14373.85626143746;14382.424703011762;14445.49440769568;14464.783028254584;8212.671493140328;8440.66237350499;8503.319734421231;8595.054544000155;8631.438956467935;7270.672819723125;8270.59399902018;8426.496837962282;8434.198751118634;8660.523671198018;3730.4891518934874;4443.111377724468;4536.877063178652;4603.125406172883;4627.6529260305615;1916.980830191708;1917.0606402862497;1933.9033048347526;1941.8737782044122;1957.9706304405445;803.5620586780219;833.5248908122434;842.1578421578422;876.268315956171;922.2115091596722;19572;20339.389818305473;20419.38741837747;20475.18099276042;20629.484262893548;878.0288252872342;878.5036454403241;883.1830556734994;887.3921363865772;896.4307664632875;10363.06732394088;10389.168866490814;10805.351678899291;10865.80821753595;11512.712182195344;564882;577716;580913.0954345202;595526;597579;86215;90175;96216.14060329909;100290;124912.37543812222;953.561361773578;992.2905122837062;1000.2698030437821;1004.5830980143305;1012.1346248957183;18500.9824459656;18752.437426877215;19163.808361916203;19180.80819191846;19216.03919803976;55086.72456637691;55108.72445637746;56080.71959640176;56499.71750141388;56931.71534142303;79064.60467697625;80963.59518202371;81575;83024;83821;6420.229572451336;7578.621068946641;7718.884216736641;7766.650500727594;7907.051153861574;9447.669331573637;11763.418068554549;11855.64433066975;11950.223235490013;11955.163138580216;9978.351407158512;10110.898891010997;10193.541290641791;10361.274710770474;10379.169666426516;9895.752606184902;9901.108900199017;9960.750981225527;10253.897461025294;10272.435016074187;9857.408555486694;10123.139533139765;10210.438425886647;10321.690349289533;10562.524686389284;7600.695972161052;7733;7742.690292388242;7831.843363132592;7849.136594974662;9030.593623286837;10221.846672300071;10252.38485690861;10330.845037324596;10472.057514823702 08:23:25 INFO - PROCESS | 1662 | |3;mootools.html;1277.6869666931602;1433.741926453238;1562.6249700071987;1755.4645833020923;1767.4079183473536;7683.731069412572;8018.198180181994;8160.387970902184;8724.8691269631;8728.694495692653;8205.425620206588;8532.14678532148;8546.957265213665;8635.093315201908;8636;2257.548490301942;2297.2878407693584;2303.8272129590264;2304.0553373117027;2304.9539009219807;2246.9662955055687;2286.3941055620276;2296.6555016747457;2311.3528212100637;2312.3756585721844;2272.488690044743;2279.3959600705743;2283.5318759654315;2292.621717416624;2293.564222797663;2242.921497747571;2252.6170551006285;2257.8419510634344;2258.9661155082686;2267.9319620411334;2247.5617254635354;2248.5952528545017;2252.6395776675818;2255.391044418014;2262.366537369539;8662.09048049952;8664.826703465895;8665.566721663954;8670.696525621574;8675.739727808175;5041.59667226621;5160.56135228506;5223.268742376071;5228.92156617651;5231.686098834082;3257.7068063874367;3311.3542859142476;3442.7417943654327;3446.5002574626537;3450.0857272822723;1040.2198351236573;1043.6347278452556;1053.1101219469535;1066.5094056733913;1068.0814499530397;11338.71653208668;11384.71538211543;11467;11487.827682584768;11519.769604607945;3981.462502562175;4002.8198731057173;4495.303227999665;4545.863624091283;4624.156143971433;9726.36778609388;9793.971632978577;9805.362651427635;9807.411555306562;9840.557174927002;4785.306130611042;5191.84424467259;5205.193195054772;5206.245094461357;5210.088234559013;3682.9447558286647;4231;4378.343248512749;4554.407926969473;4761.880952976203;3339.1819004343606;3753;3791.8672846450795;3917.0990672144994;3920.4707364506;1263.3746295583744;1279.8336216291823;1279.8656141105237;1286.408252203978;1290.2323117744927;787.8424315136996;823.8887750153773;841.7584063506401;857.0358346859783;882.1222883231226;5469.453054694499;5556.527695145877;5854;5889.558283128783;5952.553558483044;344.41105709236984;407.80425395810175;425.4511679932914;429.8538496911065;431.1162117658787;3466.2720828625766;3837.6929845612285;3867.3425517662067;3902.180542086194;3954.762714237155;10035.849462258071;10405.0635442809;10470.267081304235;10492.842607360895;10542.051215390498;10396.48017599117;10451.477426128664;10460.163186945027;10474.266801323987;10504;7523.473356864967;7867.9771629687775;7870.9606451967375;8000.039995200613;8005.639746211435;5509.421510741315;5700.315962084576;5829.912551311649;5893.469587737124;5984.581079324405;7027.61348125858;9937.65218217359;9947.552360143956;9978.900210998088;9988.950055249677;8565.700200492953;9818.067283608047;9961.153301969123;9986.300958932805;10142.768590909987;7348.301911318416;7367.336939675455;7400.074990626172;7428.368588670023;7474.065741782278;6819;8173.427860049739;8181.713640022571;8182.918170818216;8212.83574328498;6621.9337806621315;7240.420766338786;7380.815479613053;7464;7482.700691972478;7236.878283865799;7276.672549735381;7286.48994570375;7314;7359.77920662381;6814.2163651180745;6832.214295355899;6923.792286231421;6941.861162776615;6969.024336592983;4861.6596838220985;4879.365682461257;4909.729964851825;4948.38145231846;4989.12690279207;4790.353302304145;4876.21980483121;4903.31353610502;4972.105021096238;4978.850634480971 08:23:25 INFO - PROCESS | 1662 | |4;prototype.html;295.5906070092921;300.3107867444214;311.67430035612784;313.4827534567961;318.077575032406;4065.4308396824467;4803.423589169296;5002.7982091461445;5340.973295133524;5352.759125839337;5113.463086375933;5281.566981462051;5362.490563396481;5391.67903863553;5403.054900235912;3371.7134043606325;3931.842726290959;4074.514546126875;4092.7953602319913;4115.691323150776;3749;4017.8392864285393;4116.403121547374;4126.319157339036;4130.8967275818195;3919.0006548330134;4024.657904078142;4025.7181997260204;4049.2711311963835;4123.865936867355;3336.249343897628;3668.3763760160705;4003.4195041718935;4059.594040595917;4073.348264277701;3513.1744040150543;3517.6306487818665;3519.296140771856;3527.9294414111832;3533.805640689761;4313.360922849329;4827.034593081397;5126.641135120543;5135.87160320995;5238.711870847102;3623.2391197848483;4142.71001029928;4600.769961501945;4602.792874320664;4640.141573808856;4400.73595584266;4658.487566367697;4704.506026867164;5694.857628559278;5705.11570706537;3158.136699347396;3488.6511348865165;3499.7900125992523;3549.183687751806;3846.74996125251;5128.974355128201;5812.970935145339;5950.672713000783;5995.280566332024;6014.278286605591;3900.2979463696806;3915.686745060388;3922.2939870822956;3925.1364699766004;3935.7995811277833;3320.900372988814;3322.6511216321937;3682.3371793076967;3957.149212919179;3971.4439978403047;4599.9080018399445;4606.055758569485;4612.762810037129;4645.349651048856;4665.160271151158;4763.856674398119;5295.338082739658;5746.281714785652;5775.5090817281;5818.6217895836635;1096.270979798425;1128.90968722502;1251.6683078984076;1296.9027322950817;1356.2540602668494;807.935365170785;844.9239568438747;864.6843901975734;902.386377263454;904.8416527107861;5326.978131510685;5327.547158491494;5333.12003519415;5334.626576139711;5341.65279256847;5116.744162791845;5356.464353564613;5714.657120572779;5788.5369170466265;5797.449242322057;4731.645126615517;5048.419431765394;5208.739563021833;5313.521783039545;5373.447612384086;2198.4723666320288;2205.9448513787283;2470.0980245211445;2528.3805467660172;2594.4811037792515;4441.933935855436;4638.628909687284;4660.347551342815;4716.858494245178;4998.925016124761;4661.603763680125;4666.859994200179;4748.335233067373;4914.361133052752;4997.625178111656;4702.670813043054;4767.69010014348;5041.219085685736;5095.286659867547;5121.974390128025;4270.231358355526;4835.540623640749;4848.878778030577;4872.829450969199;4909.4354149273295;3945.6843452523153;4124.8143833526965;4376.9124617508105;4557.986215163294;4788.281757736368;4583.49581546023;4625.884352891205;4828.5412885775795;5009.618507507345;5036;3246.464333384965;3246.8863589774246;3409.5168601658356;3420.298838738003;3425.246445781924;4108.322126849036;4719.961608446137;4791.808327666994;4875.731834749123;5557.72211389437;4871.95128048715;4917.459079501324;5687.326956330518;5722.313322401172;5724.942750572441;5282.177648711188;5335.493128152819;5350.411454739898;5667.971660141673;5736.770529178953;4940.75296235194;5327.51048063519;5336.786528538815;5594.4125866785025;5614.9719251403485;5106.66806657566;5393.649412788156;5721.914171287517;5769.1634712967225;5771.826845194651;5616.1856530803625;5618.578606604603;5618.859528511656;5647.830565083054;5668.51817595509 08:23:25 INFO - PROCESS | 1662 | |5;yui.html;694.3715937076944;722.584513904505;731.798755342281;746.7685017644534;790.1584812175035;14548;15637.218139093056;15710.842891571081;15712.528624141294;15721.934222826554;14241.216733079702;14403.711925761478;14405.783913241283;14409.63975900603;14410.351534180962;662.8972509261048;687.9002544631026;818.6684392820918;834.3450391442703;846.8137009857821;814.1248158229927;853.4452605806214;854.9953804279977;855.6192494340021;855.6192494340021;900.0774206438388;903.5030733096803;906.1436942089728;906.8050369170627;907.587047893207;842.5576572299531;879.7316818370394;885.9379843410964;894.4633220067972;900.693764120199;568.7345655915597;653.2748648999607;665.1618960110247;673.1854456108115;677.3226773226774;14676.779848302283;14707.63230919225;14730.4844330449;14764.557063288119;14776.482823101247;12380.504779808796;13628.296462942133;14180.43278268868;14254.216018119;14620.342084606218;11730.824037639442;11828.349440780754;11846.585369512111;11982.281063136239;12003.099767517471;3111.222194451387;3759.586445490994;3789.2800367930004;3792.962070379316;3923.6468717815533;110709.89290107077;111442.44278778635;111505.44247278792;113315;114461.54769106147;10901.473793668893;10953.452327383598;10992.670219893416;11006.449677516091;11104.555817767361;22649.4337641557;22765.430864228525;22766.65850012218;22768.316950491513;22773.544529109324;7933.246341597539;7966.960165199137;7987.480813747088;8193.426460147852;8310.127436619068;3936.4292177634657;3946.9802650987135;3979.5821438749094;4027.577104404054;4063.6952228582973;3550.7336949728356;3613.313470440608;3622.3660859349507;3660.7254455915913;3663.084228942764;647.4917190005917;654.5385503220218;661.6493258572964;668.0313545359248;674.3829396102609;501.14054396709815;545.9109077390552;574.8821491594213;656.0290769660833;664.6078813500059;7726.34326082278;9324.78294988228;9620.230381569458;9721.270904682036;9723.756906077404;569.7122952908837;590.9645421274737;601.750273636436;629.4177885455935;648.597869321024;4333.458317710286;4585.220512512881;4622.745748983838;4651.09303685778;4657.74382408964;116527.41736291433;143759;154364;154738;155043.2247838776;96773.29040386259;106812.79561613164;125945.11082333772;136210;137540.8106635204;112749;113558.43220784009;114115;114733.42633286613;115904.42047789707;7853.664876971043;8764.649414023368;8885.200331970154;9078.409903356262;9103.135202155785;48923;58300.12549811841;60907.390926091946;61107.694461527404;62589.37410625836;90551.54724226336;96936.51531742296;97062;98239;98370.50814745881;10778.568857245622;11304.604338848101;11429.771404571695;11637.359945203096;12115.394230288626;12128.878711212776;13778.331257783313;14550.92724536413;14914.925425372803;15038.624034399227;13492.797608036084;13589.52436664712;13664;13896.37466313998;13952.511662091778;13690.31548422555;13836.584902452943;13870.514531991332;13891.235982020682;13936.721265574835;11074.5570177192;11669.35818529989;13876;13878.930605346908;14138.293085345485;11272.887271127513;11319.037881779812;11368.848077297855;11479;11597.304161750322;9056.554730222671;10396;10591.841122383017;11144.88855111471;11636.592719255124 08:23:25 INFO - PROCESS | 1662 | __end_tp_report 08:23:25 INFO - PROCESS | 1662 | __start_cc_report 08:23:25 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,371 08:23:25 INFO - PROCESS | 1662 | __end_cc_report 08:23:25 INFO - PROCESS | 1662 | __startTimestamp1476285805545__endTimestamp 08:23:25 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | ------- Summary: start ------- 08:23:25 INFO - PROCESS | 1662 | Number of tests: 6 08:23:25 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5662.76 Median:5790.86 stddev:657.84 (11.4%) stddev-sans-first:634.28 08:23:25 INFO - PROCESS | 1662 | Values: 3346.8 3363.0 3404.9 3405.2 3428.7 5540.3 5588.4 5594.5 5607.8 5646.4 5543.8 5633.3 5654.1 5664.1 5729.0 5133.1 5141.1 5185.5 5204.6 5244.7 5263.2 5281.2 5284.4 5291.5 5340.6 5116.5 5235.0 5280.0 5288.6 5313.3 5159.3 5214.0 5275.3 5278.5 5291.5 5101.7 5181.3 5208.6 5214.3 5255.0 4537.8 5548.8 5584.5 5609.4 5738.3 6111.6 6132.2 6133.5 6134.1 6176.0 6235.6 6264.5 6265.3 6268.0 6284.9 5692.6 5735.5 5780.9 5781.7 5852.0 6265.8 6268.3 6284.0 6284.1 6309.0 6110.4 6126.8 6127.8 6128.6 6143.7 6103.3 6110.9 6130.7 6136.8 6212.6 4768.5 5188.2 6190.1 6190.3 6193.4 6120.4 6135.0 6196.0 6197.1 6217.5 4789.0 4867.5 4876.6 4918.3 4925.0 3500.1 3734.5 3987.2 4493.1 4507.3 5704.8 5754.3 5783.7 5784.3 5804.3 6089.5 6098.1 6180.5 6180.5 6192.2 5720.7 5780.8 5793.1 5845.0 5891.9 4711.0 5374.9 5378.5 5386.1 5397.3 5623.7 5926.3 6251.6 6256.9 6279.9 6114.0 6132.8 6138.7 6145.9 6211.2 6262.7 6271.5 6290.0 6293.7 6322.2 5639.0 5652.7 6126.3 6156.2 6184.1 6180.1 6226.9 6227.5 6236.2 6242.8 5686.1 5767.8 6096.7 6127.7 6129.9 5713.9 5790.9 6025.4 6085.7 6098.7 5544.1 5715.1 5908.9 5921.7 6009.4 5253.2 6035.5 6056.0 6058.9 6100.3 5721.9 5928.0 5928.1 5931.6 5938.1 08:23:25 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:71034.39 Median:38869.01 stddev:160557.44 (413.1%) stddev-sans-first:160920.42 08:23:25 INFO - PROCESS | 1662 | Values: 468.7 496.8 502.8 518.5 548.9 36934.4 39260.8 39344.2 41004.4 41157.6 39529.8 39938.4 40038.8 40056.4 40122.0 9377.6 9534.8 9548.7 9550.4 9564.5 7223.5 8849.3 9403.4 9496.7 9507.4 7732.8 9482.7 9509.0 9517.0 9536.4 8121.7 9355.4 9485.1 9487.1 9517.6 8439.6 8931.6 9321.7 9428.7 9442.6 38692.9 38709.2 38823.0 38865.2 38872.8 3276.5 3338.4 3339.6 3341.4 3343.3 29158.9 29186.9 29766.0 29962.1 30000.6 2396.6 3027.9 3068.3 3074.2 3158.4 457833.0 461459.0 464640.0 465088.0 465576.3 57491.0 58044.7 58449.7 58639.0 58684.8 60707.7 61229.0 61390.0 61393.4 61408.0 16191.8 16221.0 16433.6 16439.8 16445.2 12652.9 12750.1 12751.9 12772.3 12787.9 16864.5 18121.5 18157.7 18235.8 18315.5 6542.9 7581.2 7597.7 7616.7 7705.4 767.2 778.2 870.4 954.8 1021.9 22127.7 22731.0 29319.3 30150.4 32383.7 1021.8 1021.9 1023.6 1055.0 1092.1 12259.3 12385.4 13560.1 14384.9 15242.4 689400.0 691328.0 691625.0 699846.0 699996.0 47205.8 51428.0 51972.2 52018.7 52134.0 1256.0 1318.9 1324.9 1325.9 1331.1 25104.6 27545.9 28290.9 28722.6 28934.4 47326.3 47941.8 47983.0 48035.0 48035.5 612621.0 614592.0 616388.0 616846.0 617378.0 24972.6 25033.1 25381.6 25719.4 26347.8 43525.3 43922.0 44956.6 45062.5 45148.3 37192.6 37351.4 37725.6 38598.0 38612.8 37064.0 37275.9 37354.8 37866.6 38607.0 36487.5 36766.8 36887.3 37165.1 37430.3 29811.7 31629.5 32586.9 32919.2 33121.5 24177.6 24280.4 24851.6 24882.8 25158.4 08:23:25 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33394.08 Median:2150.60 stddev:96963.63 (4508.7%) stddev-sans-first:97203.82 08:23:25 INFO - PROCESS | 1662 | Values: 315.8 351.4 375.9 385.1 401.6 14881.9 18949.7 19917.8 20214.4 21175.7 19379.7 19797.4 20250.8 20255.2 20328.7 5704.9 5725.8 6016.5 6034.9 6073.7 5951.3 6040.6 6075.6 6087.3 6100.1 5882.6 6051.2 6053.0 6078.1 6115.1 5020.4 6042.7 6079.9 6111.0 6115.3 3899.6 5339.2 5371.2 5378.0 5411.2 12640.0 12838.0 12844.2 18594.6 24067.3 1795.0 2016.5 2028.0 2060.4 2229.2 9942.5 9943.2 10183.2 11334.7 13457.5 1488.2 1530.5 1865.0 1868.6 1874.0 108163.0 114466.9 115168.4 115614.0 117201.0 9334.7 11209.8 11231.4 11408.0 11470.3 14363.9 14373.9 14382.4 14445.5 14464.8 8212.7 8440.7 8503.3 8595.1 8631.4 7270.7 8270.6 8426.5 8434.2 8660.5 3730.5 4443.1 4536.9 4603.1 4627.7 1917.0 1917.1 1933.9 1941.9 1958.0 803.6 833.5 842.2 876.3 922.2 19572.0 20339.4 20419.4 20475.2 20629.5 878.0 878.5 883.2 887.4 896.4 10363.1 10389.2 10805.4 10865.8 11512.7 564882.0 577716.0 580913.1 595526.0 597579.0 86215.0 90175.0 96216.1 100290.0 124912.4 953.6 992.3 1000.3 1004.6 1012.1 18501.0 18752.4 19163.8 19180.8 19216.0 55086.7 55108.7 56080.7 56499.7 56931.7 79064.6 80963.6 81575.0 83024.0 83821.0 6420.2 7578.6 7718.9 7766.7 7907.1 9447.7 11763.4 11855.6 11950.2 11955.2 9978.4 10110.9 10193.5 10361.3 10379.2 9895.8 9901.1 9960.8 10253.9 10272.4 9857.4 10123.1 10210.4 10321.7 10562.5 7600.7 7733.0 7742.7 7831.8 7849.1 9030.6 10221.8 10252.4 10330.8 10472.1 08:23:25 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5526.16 Median:4891.34 stddev:3147.07 (64.3%) stddev-sans-first:3139.70 08:23:25 INFO - PROCESS | 1662 | Values: 1277.7 1433.7 1562.6 1755.5 1767.4 7683.7 8018.2 8160.4 8724.9 8728.7 8205.4 8532.1 8547.0 8635.1 8636.0 2257.5 2297.3 2303.8 2304.1 2305.0 2247.0 2286.4 2296.7 2311.4 2312.4 2272.5 2279.4 2283.5 2292.6 2293.6 2242.9 2252.6 2257.8 2259.0 2267.9 2247.6 2248.6 2252.6 2255.4 2262.4 8662.1 8664.8 8665.6 8670.7 8675.7 5041.6 5160.6 5223.3 5228.9 5231.7 3257.7 3311.4 3442.7 3446.5 3450.1 1040.2 1043.6 1053.1 1066.5 1068.1 11338.7 11384.7 11467.0 11487.8 11519.8 3981.5 4002.8 4495.3 4545.9 4624.2 9726.4 9794.0 9805.4 9807.4 9840.6 4785.3 5191.8 5205.2 5206.2 5210.1 3682.9 4231.0 4378.3 4554.4 4761.9 3339.2 3753.0 3791.9 3917.1 3920.5 1263.4 1279.8 1279.9 1286.4 1290.2 787.8 823.9 841.8 857.0 882.1 5469.5 5556.5 5854.0 5889.6 5952.6 344.4 407.8 425.5 429.9 431.1 3466.3 3837.7 3867.3 3902.2 3954.8 10035.8 10405.1 10470.3 10492.8 10542.1 10396.5 10451.5 10460.2 10474.3 10504.0 7523.5 7868.0 7871.0 8000.0 8005.6 5509.4 5700.3 5829.9 5893.5 5984.6 7027.6 9937.7 9947.6 9978.9 9989.0 8565.7 9818.1 9961.2 9986.3 10142.8 7348.3 7367.3 7400.1 7428.4 7474.1 6819.0 8173.4 8181.7 8182.9 8212.8 6621.9 7240.4 7380.8 7464.0 7482.7 7236.9 7276.7 7286.5 7314.0 7359.8 6814.2 6832.2 6923.8 6941.9 6969.0 4861.7 4879.4 4909.7 4948.4 4989.1 4790.4 4876.2 4903.3 4972.1 4978.9 08:23:25 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4320.68 Median:4725.80 stddev:1340.40 (28.4%) stddev-sans-first:1309.67 08:23:25 INFO - PROCESS | 1662 | Values: 295.6 300.3 311.7 313.5 318.1 4065.4 4803.4 5002.8 5341.0 5352.8 5113.5 5281.6 5362.5 5391.7 5403.1 3371.7 3931.8 4074.5 4092.8 4115.7 3749.0 4017.8 4116.4 4126.3 4130.9 3919.0 4024.7 4025.7 4049.3 4123.9 3336.2 3668.4 4003.4 4059.6 4073.3 3513.2 3517.6 3519.3 3527.9 3533.8 4313.4 4827.0 5126.6 5135.9 5238.7 3623.2 4142.7 4600.8 4602.8 4640.1 4400.7 4658.5 4704.5 5694.9 5705.1 3158.1 3488.7 3499.8 3549.2 3846.7 5129.0 5813.0 5950.7 5995.3 6014.3 3900.3 3915.7 3922.3 3925.1 3935.8 3320.9 3322.7 3682.3 3957.1 3971.4 4599.9 4606.1 4612.8 4645.3 4665.2 4763.9 5295.3 5746.3 5775.5 5818.6 1096.3 1128.9 1251.7 1296.9 1356.3 807.9 844.9 864.7 902.4 904.8 5327.0 5327.5 5333.1 5334.6 5341.7 5116.7 5356.5 5714.7 5788.5 5797.4 4731.6 5048.4 5208.7 5313.5 5373.4 2198.5 2205.9 2470.1 2528.4 2594.5 4441.9 4638.6 4660.3 4716.9 4998.9 4661.6 4666.9 4748.3 4914.4 4997.6 4702.7 4767.7 5041.2 5095.3 5122.0 4270.2 4835.5 4848.9 4872.8 4909.4 3945.7 4124.8 4376.9 4558.0 4788.3 4583.5 4625.9 4828.5 5009.6 5036.0 3246.5 3246.9 3409.5 3420.3 3425.2 4108.3 4720.0 4791.8 4875.7 5557.7 4872.0 4917.5 5687.3 5722.3 5724.9 5282.2 5335.5 5350.4 5668.0 5736.8 4940.8 5327.5 5336.8 5594.4 5615.0 5106.7 5393.6 5721.9 5769.2 5771.8 5616.2 5618.6 5618.9 5647.8 5668.5 08:23:25 INFO - PROCESS | 1662 | 08:23:25 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:24643.87 Median:3617.84 stddev:39400.65 (1089.1%) stddev-sans-first:39470.15 08:23:25 INFO - PROCESS | 1662 | Values: 694.4 722.6 731.8 746.8 790.2 14548.0 15637.2 15710.8 15712.5 15721.9 14241.2 14403.7 14405.8 14409.6 14410.4 662.9 687.9 818.7 834.3 846.8 814.1 853.4 855.0 855.6 855.6 900.1 903.5 906.1 906.8 907.6 842.6 879.7 885.9 894.5 900.7 568.7 653.3 665.2 673.2 677.3 14676.8 14707.6 14730.5 14764.6 14776.5 12380.5 13628.3 14180.4 14254.2 14620.3 11730.8 11828.3 11846.6 11982.3 12003.1 3111.2 3759.6 3789.3 3793.0 3923.6 110709.9 111442.4 111505.4 113315.0 114461.5 10901.5 10953.5 10992.7 11006.4 11104.6 22649.4 22765.4 22766.7 22768.3 22773.5 7933.2 7967.0 7987.5 8193.4 8310.1 3936.4 3947.0 3979.6 4027.6 4063.7 3550.7 3613.3 3622.4 3660.7 3663.1 647.5 654.5 661.6 668.0 674.4 501.1 545.9 574.9 656.0 664.6 7726.3 9324.8 9620.2 9721.3 9723.8 569.7 591.0 601.8 629.4 648.6 4333.5 4585.2 4622.7 4651.1 4657.7 116527.4 143759.0 154364.0 154738.0 155043.2 96773.3 106812.8 125945.1 136210.0 137540.8 112749.0 113558.4 114115.0 114733.4 115904.4 7853.7 8764.6 8885.2 9078.4 9103.1 48923.0 58300.1 60907.4 61107.7 62589.4 90551.5 96936.5 97062.0 98239.0 98370.5 10778.6 11304.6 11429.8 11637.4 12115.4 12128.9 13778.3 14550.9 14914.9 15038.6 13492.8 13589.5 13664.0 13896.4 13952.5 13690.3 13836.6 13870.5 13891.2 13936.7 11074.6 11669.4 13876.0 13878.9 14138.3 11272.9 11319.0 11368.8 11479.0 11597.3 9056.6 10396.0 10591.8 11144.9 11636.6 08:23:25 INFO - PROCESS | 1662 | -------- Summary: end -------- 08:23:25 INFO - PROCESS | 1662 | 08:23:26 INFO - TEST-INFO | 1662: exit 0 08:23:26 INFO - TEST-OK | dromaeo_css | took 1115158ms 08:23:26 INFO - TEST-START | kraken 08:23:26 INFO - operating with platform_type : mac_ 08:23:26 INFO - Initialising browser for kraken test... 08:23:26 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpf6BAZq/profile http://localhost:49272/getInfo.html) 08:23:28 INFO - PROCESS | 1669 | 2016-10-12 08:23:28.788 plugin-container[1670:10196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 08:23:28 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:28 INFO - PROCESS | 1669 | 2016-10-12 08:23:28.790 plugin-container[1670:10196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:23:28 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:28 INFO - PROCESS | 1669 | 2016-10-12 08:23:28.790 plugin-container[1670:10196] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:23:28 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 08:23:28 INFO - PROCESS | 1669 | colorDepth:24 08:23:28 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 08:23:28 INFO - PROCESS | 1669 | __metrics 08:23:28 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 08:23:29 INFO - TEST-INFO | 1669: exit 0 08:23:29 INFO - Browser initialized. 08:23:29 INFO - Running cycle 1/1 for kraken test... 08:23:29 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpf6BAZq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:23:31 INFO - PROCESS | 1671 | 2016-10-12 08:23:31.858 plugin-container[1672:10311] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:23:31 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:31 INFO - PROCESS | 1671 | 2016-10-12 08:23:31.858 plugin-container[1672:10311] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 08:23:31 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:31 INFO - PROCESS | 1671 | 2016-10-12 08:23:31.858 plugin-container[1672:10311] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:23:41 INFO - PROCESS | 1671 | RSS: Main: 238989312 08:23:41 INFO - PROCESS | 1671 | 08:23:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 08:23:45 INFO - PROCESS | 1671 | RSS: Main: 252657664 08:23:45 INFO - PROCESS | 1671 | 08:23:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 08:23:49 INFO - PROCESS | 1671 | RSS: Main: 261787648 08:23:49 INFO - PROCESS | 1671 | 08:23:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 08:23:53 INFO - PROCESS | 1671 | RSS: Main: 261304320 08:23:53 INFO - PROCESS | 1671 | 08:23:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 08:23:55 INFO - PROCESS | 1671 | RSS: Main: 263409664 08:23:55 INFO - PROCESS | 1671 | 08:23:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:23:58 INFO - PROCESS | 1671 | RSS: Main: 263651328 08:23:58 INFO - PROCESS | 1671 | 08:24:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 08:24:01 INFO - PROCESS | 1671 | RSS: Main: 263999488 08:24:01 INFO - PROCESS | 1671 | 08:24:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 08:24:05 INFO - PROCESS | 1671 | RSS: Main: 264257536 08:24:05 INFO - PROCESS | 1671 | 08:24:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 08:24:08 INFO - PROCESS | 1671 | RSS: Main: 264257536 08:24:08 INFO - PROCESS | 1671 | 08:24:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:24:11 INFO - PROCESS | 1671 | RSS: Main: 264413184 08:24:11 INFO - PROCESS | 1671 | 08:24:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:24:13 INFO - PROCESS | 1671 | RSS: Main: 264601600 08:24:13 INFO - PROCESS | 1671 | 08:24:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:24:16 INFO - PROCESS | 1671 | RSS: Main: 264347648 08:24:16 INFO - PROCESS | 1671 | 08:24:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:24:20 INFO - PROCESS | 1671 | RSS: Main: 264531968 08:24:20 INFO - PROCESS | 1671 | 08:24:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:24:23 INFO - PROCESS | 1671 | RSS: Main: 264667136 08:24:23 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 08:24:25 INFO - PROCESS | 1671 | RSS: Main: 266018816 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | __start_tp_report 08:24:25 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 08:24:25 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 08:24:25 INFO - PROCESS | 1671 | |i|pagename|runs| 08:24:25 INFO - PROCESS | 1671 | |0;ai-astar;107;118;118;118;116;120;155;170;166;117 08:24:25 INFO - PROCESS | 1671 | |1;audio-beat-detection;151;144;135;180;108;153;140;149;143;115 08:24:25 INFO - PROCESS | 1671 | |2;audio-dft;203;231;196;197;242;220;201;196;192;198 08:24:25 INFO - PROCESS | 1671 | |3;audio-fft;78;60;60;85;62;60;57;58;79;60 08:24:25 INFO - PROCESS | 1671 | |4;audio-oscillator;82;72;91;74;70;69;91;70;74;101 08:24:25 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;92;128;88;87;89;85;89;86;124;132 08:24:25 INFO - PROCESS | 1671 | |6;imaging-darkroom;99;99;97;130;123;98;98;130;97;144 08:24:25 INFO - PROCESS | 1671 | |7;imaging-desaturate;92;101;92;89;93;86;80;120;101;146 08:24:25 INFO - PROCESS | 1671 | |8;json-parse-financial;57;56;55;58;57;67;56;55;52;79 08:24:25 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;66;79;98;122;114;74;72;72;70;71 08:24:25 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;89;86;91;97;106;86;90;95;90;88 08:24:25 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;145;181;169;150;174;161;165;150;191;152 08:24:25 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;177;175;175;174;179;174;178;178;176;176 08:24:25 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;71;73;80;70;69;72;69;71;70;72 08:24:25 INFO - PROCESS | 1671 | __end_tp_report 08:24:25 INFO - PROCESS | 1671 | __start_cc_report 08:24:25 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,969 08:24:25 INFO - PROCESS | 1671 | __end_cc_report 08:24:25 INFO - PROCESS | 1671 | __startTimestamp1476285865903__endTimestamp 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | ------- Summary: start ------- 08:24:25 INFO - PROCESS | 1671 | Number of tests: 14 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:130.50 Median:119.00 stddev:23.44 (19.7%) stddev-sans-first:23.27 08:24:25 INFO - PROCESS | 1671 | Values: 107.0 118.0 118.0 118.0 116.0 120.0 155.0 170.0 166.0 117.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:146.50 stddev:20.10 (13.7%) stddev-sans-first:21.05 08:24:25 INFO - PROCESS | 1671 | Values: 151.0 144.0 135.0 180.0 108.0 153.0 140.0 149.0 143.0 115.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:207.60 Median:202.00 stddev:17.21 (8.5%) stddev-sans-first:18.18 08:24:25 INFO - PROCESS | 1671 | Values: 203.0 231.0 196.0 197.0 242.0 220.0 201.0 196.0 192.0 198.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:65.90 Median:61.00 stddev:10.43 (17.1%) stddev-sans-first:10.10 08:24:25 INFO - PROCESS | 1671 | Values: 78.0 60.0 60.0 85.0 62.0 60.0 57.0 58.0 79.0 60.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:74.00 stddev:11.26 (15.2%) stddev-sans-first:11.90 08:24:25 INFO - PROCESS | 1671 | Values: 82.0 72.0 91.0 74.0 70.0 69.0 91.0 70.0 74.0 101.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:100.00 Median:87.50 stddev:19.50 (22.3%) stddev-sans-first:20.47 08:24:25 INFO - PROCESS | 1671 | Values: 92.0 128.0 88.0 87.0 89.0 85.0 89.0 86.0 124.0 132.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:111.50 Median:97.50 stddev:18.17 (18.6%) stddev-sans-first:18.70 08:24:25 INFO - PROCESS | 1671 | Values: 99.0 99.0 97.0 130.0 123.0 98.0 98.0 130.0 97.0 144.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:100.00 Median:87.50 stddev:19.47 (22.3%) stddev-sans-first:20.44 08:24:25 INFO - PROCESS | 1671 | Values: 92.0 101.0 92.0 89.0 93.0 86.0 80.0 120.0 101.0 146.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:57.00 stddev:7.97 (14.0%) stddev-sans-first:8.41 08:24:25 INFO - PROCESS | 1671 | Values: 57.0 56.0 55.0 58.0 57.0 67.0 56.0 55.0 52.0 79.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:83.80 Median:72.00 stddev:20.12 (27.9%) stddev-sans-first:20.28 08:24:25 INFO - PROCESS | 1671 | Values: 66.0 79.0 98.0 122.0 114.0 74.0 72.0 72.0 70.0 71.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:90.00 stddev:6.11 (6.8%) stddev-sans-first:6.39 08:24:25 INFO - PROCESS | 1671 | Values: 89.0 86.0 91.0 97.0 106.0 86.0 90.0 95.0 90.0 88.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:163.80 Median:167.00 stddev:15.09 (9.0%) stddev-sans-first:14.39 08:24:25 INFO - PROCESS | 1671 | Values: 145.0 181.0 169.0 150.0 174.0 161.0 165.0 150.0 191.0 152.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:176.50 stddev:1.75 (1.0%) stddev-sans-first:1.83 08:24:25 INFO - PROCESS | 1671 | Values: 177.0 175.0 175.0 174.0 179.0 174.0 178.0 178.0 176.0 176.0 08:24:25 INFO - PROCESS | 1671 | 08:24:25 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:71.50 stddev:3.20 (4.5%) stddev-sans-first:3.38 08:24:25 INFO - PROCESS | 1671 | Values: 71.0 73.0 80.0 70.0 69.0 72.0 69.0 71.0 70.0 72.0 08:24:25 INFO - PROCESS | 1671 | -------- Summary: end -------- 08:24:25 INFO - PROCESS | 1671 | 08:24:26 INFO - TEST-INFO | 1671: exit 0 08:24:26 INFO - TEST-OK | kraken | took 60667ms 08:24:26 INFO - SUITE-END | took 1175s 08:24:27 INFO - Completed test suite (00:19:36) 08:24:27 INFO - javascript benchmark 08:24:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7100.1563085937005, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.84939177737, 3362.966370336296, 3404.897853064409, 3405.233822389962, 3428.657134286573, 5540.252065971093, 5588.441155884409, 5594.49649531542, 5607.803726869555, 5646.378898321182, 5543.750531226095, 5633.324001119871, 5654.077613611361, 5664.1220610805285, 5728.971355143229, 5133.101707201244, 5141.082009190412, 5185.507376799198, 5204.609654275925, 5244.711540865251, 5263.1578947368425, 5281.234221037967, 5284.44513326101, 5291.4708529147165, 5340.572754179676, 5116.539511443951, 5235.005348983681, 5280.023195708768, 5288.576913846883, 5313.282706834567, 5159.329287192641, 5214.0, 5275.26146339509, 5278.5249327560705, 5291.4708529147165, 5101.7194054327, 5181.326427564431, 5208.6093542984045, 5214.322138122057, 5255.0, 4537.750423726694, 5548.805791797267, 5584.469475399871, 5609.35492418373, 5738.311402631711, 6111.560547330611, 6132.20254427538, 6133.518290279305, 6134.110553969649, 6176.0, 6235.563510554263, 6264.498840092781, 6265.342139075423, 6267.96866015667, 6284.87430251397, 5692.6299790513485, 5735.541156707537, 5780.913286300709, 5781.682007489587, 5852.034414321589, 6265.780697675651, 6268.27914789805, 6284.0, 6284.057391391328, 6308.990561510136, 6110.388961103854, 6126.754929802758, 6127.785527506516, 6128.601640893327, 6143.723532441051, 6103.277060575968, 6110.908336374958, 6130.662813545208, 6136.7948077883175, 6212.596181248205, 4768.499307572724, 5188.247704082887, 6190.071489276645, 6190.3190649028575, 6193.4425901669065, 6120.357362476965, 6135.0, 6195.96902015496, 6197.070439434121, 6217.533684973645, 4789.0, 4867.5132486751045, 4876.561109500162, 4918.262260660929, 4924.975375123101, 3500.072480792592, 3734.514513113278, 3987.2424239394422, 4493.101379724003, 4507.301368287921, 5704.771809127588, 5754.280714910637, 5783.739731712083, 5784.334801497882, 5804.2744656917885, 6089.512838972872, 6098.129681996382, 6180.47465965398, 6180.47465965398, 6192.164057852222, 5720.656760594377, 5780.814932938738, 5793.131030345482, 5844.970775146097, 5891.852703682356, 4710.95289047112, 5374.892502149935, 5378.542823860016, 5386.084365657847, 5397.298351214317, 5623.746931388016, 5926.288845338587, 6251.562390632612, 6256.937430625636, 6279.905801412982, 6113.9932453606225, 6132.753252973555, 6138.694953005908, 6145.893739127, 6211.192544969125, 6262.749490020348, 6271.529635277464, 6290.0, 6293.653849038347, 6322.241331040304, 5639.0, 5652.717364131695, 6126.326104128453, 6156.230471191101, 6184.072389141665, 6180.072989051499, 6226.937730622817, 6227.532935029982, 6236.189295391751, 6242.781502647567, 5686.061799802987, 5767.769289228384, 6096.664683442454, 6127.662978536223, 6129.938700612936, 5713.857153571194, 5790.855228619318, 6025.367336429612, 6085.695715214133, 6098.664573448503, 5544.112941929272, 5715.085586306171, 5908.911366329595, 5921.6743079131065, 6009.399060093956, 5253.212018197301, 6035.456808887221, 6056.0, 6058.90911636317, 6100.267967843887, 5721.885562288648, 5928.021876390348, 5928.081147422156, 5931.6441013539325, 5938.079597662369], "value": 5621.170239417518, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.67427138139004, 496.75907185015285, 502.83155143027085, 518.4504425309171, 548.9011977843987, 36934.44598331024, 39260.803695981514, 39344.21311573767, 41004.38493422601, 41157.58842411575, 39529.802350988204, 39938.40092398616, 40038.797866117355, 40056.39915401264, 40122.0, 9377.624895004208, 9534.80930381392, 9548.665796697116, 9550.379225350365, 9564.521773911276, 7223.530470519399, 8849.292056635486, 9403.388779729295, 9496.715098547054, 9507.429554226768, 7732.806679833021, 9482.66810661627, 9509.0, 9516.952415237914, 9536.427814331162, 8121.675132994673, 9355.438673679568, 9485.098915603006, 9487.051294870527, 9517.5717092731, 8439.57802109892, 8931.598078086501, 9321.72034838956, 9428.669996550156, 9442.57508412123, 38692.87189204593, 38709.22581548381, 38823.0, 38865.22269554621, 38872.80563597192, 3276.459384201604, 3338.432466480704, 3339.6159441664036, 3341.381844358777, 3343.314620502791, 29158.854205728836, 29186.85406572975, 29765.95819146341, 29962.101136965925, 30000.64997075137, 2396.604560247557, 3027.8637461314293, 3068.2789544457037, 3074.200707815962, 3158.4156930967843, 457833.0, 461459.0, 464639.9999999966, 465088.0, 465576.3442365567, 57491.0, 58044.70977645085, 58449.70775146182, 58639.0, 58684.82630347455, 60707.69646151741, 61229.0, 61390.0, 61393.38606613966, 61408.0, 16191.757123643154, 16221.0, 16433.589160271087, 16439.753403698953, 16445.177741112897, 12652.873471265353, 12750.107492475623, 12751.872481275068, 12772.297523636289, 12787.872121278668, 16864.494065178063, 18121.54696132581, 18157.7276340855, 18235.81764182341, 18315.450536483928, 6542.901856472156, 7581.166071732105, 7597.696092156252, 7616.695332186761, 7705.383569314441, 767.2021098058069, 778.2256654628568, 870.3602851903846, 954.7660823098247, 1021.8773747150241, 22127.668084978737, 22731.0, 29319.267018324714, 30150.396992060036, 32383.67616323854, 1021.7701017271203, 1021.9284650074424, 1023.6417253961054, 1054.9578016879393, 1092.071739021841, 12259.264444133381, 12385.380730963416, 13560.050796444155, 14384.85615143856, 15242.390304387922, 689400.0, 691328.0, 691625.0, 699846.0, 699996.0, 47205.76397117993, 51428.0, 51972.22041669378, 52018.69953251199, 52134.0, 1256.0, 1318.854925958144, 1324.920504769717, 1325.8740419660116, 1331.0815537279245, 25104.62343064892, 27545.862270688518, 28290.907636625605, 28722.569161462176, 28934.42131157407, 47326.29010564776, 47941.76029119972, 47983.04033919372, 48035.03929921452, 48035.5196448045, 612621.0, 614592.0, 616388.0, 616846.0, 617378.0, 24972.625410619217, 25033.12384066549, 25381.619275711248, 25719.357016074748, 26347.814348353986, 43525.3471197926, 43922.0, 44956.550434496545, 45062.549374505834, 45148.32277515905, 37192.628073718915, 37351.43972840355, 37725.62274377221, 38598.0, 38612.80693596514, 37064.0, 37275.88172354834, 37354.8132259337, 37866.62133378631, 38607.0, 36487.45268821023, 36766.816165919, 36887.26225475422, 37165.07087322839, 37430.251394972496, 29811.70188298089, 31629.525557117122, 32586.85945991879, 32919.17702057468, 33121.50317745187, 24177.63733543963, 24280.392990175387, 24851.62722559127, 24882.751172488766, 25158.371040724127], "value": 19218.373545411043, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.81366993473847, 351.3710458279678, 375.8590528551793, 385.0662144300075, 401.5764116208044, 14881.925590372046, 18949.715754263696, 19917.800821991794, 20214.39356819294, 21175.68236476454, 19379.709304360447, 19797.406077817654, 20250.797492025074, 20255.189792408324, 20328.695069573932, 5704.857378565527, 5725.828225153252, 6016.548758843082, 6034.8793024139495, 6073.665948372838, 5951.285845698522, 6040.607360521574, 6075.635461872302, 6087.3303936566945, 6100.145979562864, 5882.588218824684, 6051.243594550681, 6052.9697351513405, 6078.088286756978, 6115.113308570255, 5020.397552293712, 6042.697865106749, 6079.908801367983, 6110.9694451527685, 6115.266168059839, 3899.629535194152, 5339.225812257201, 5371.248025276425, 5377.973110134424, 5411.18832175173, 12639.988800895906, 12838.0, 12844.229346239255, 18594.628107437777, 24067.2779816604, 1794.9891544446805, 2016.536196674773, 2028.0, 2060.3818854343785, 2229.2086309360106, 9942.502874856229, 9943.204543636492, 10183.18534517237, 11334.71663208418, 13457.461701531927, 1488.2409970914803, 1530.4643374818725, 1864.962700745991, 1868.5702288473592, 1873.9974113849025, 108163.0, 114466.85533144645, 115168.42415787952, 115614.0, 117201.0, 9334.673286435078, 11209.775804483865, 11231.38227397501, 11408.0, 11470.254433461798, 14363.928180359031, 14373.85626143746, 14382.424703011762, 14445.49440769568, 14464.783028254584, 8212.671493140328, 8440.66237350499, 8503.319734421231, 8595.054544000155, 8631.438956467935, 7270.672819723125, 8270.59399902018, 8426.496837962282, 8434.198751118634, 8660.523671198018, 3730.4891518934874, 4443.111377724468, 4536.877063178652, 4603.125406172883, 4627.6529260305615, 1916.980830191708, 1917.0606402862497, 1933.9033048347526, 1941.8737782044122, 1957.9706304405445, 803.5620586780219, 833.5248908122434, 842.1578421578422, 876.268315956171, 922.2115091596722, 19572.0, 20339.389818305473, 20419.38741837747, 20475.18099276042, 20629.484262893548, 878.0288252872342, 878.5036454403241, 883.1830556734994, 887.3921363865772, 896.4307664632875, 10363.06732394088, 10389.168866490814, 10805.351678899291, 10865.80821753595, 11512.712182195344, 564882.0, 577716.0, 580913.0954345202, 595526.0, 597579.0, 86215.0, 90175.0, 96216.14060329909, 100290.0, 124912.37543812222, 953.561361773578, 992.2905122837062, 1000.2698030437821, 1004.5830980143305, 1012.1346248957183, 18500.9824459656, 18752.437426877215, 19163.808361916203, 19180.80819191846, 19216.03919803976, 55086.72456637691, 55108.72445637746, 56080.71959640176, 56499.71750141388, 56931.71534142303, 79064.60467697625, 80963.59518202371, 81575.0, 83024.0, 83821.0, 6420.229572451336, 7578.621068946641, 7718.884216736641, 7766.650500727594, 7907.051153861574, 9447.669331573637, 11763.418068554549, 11855.64433066975, 11950.223235490013, 11955.163138580216, 9978.351407158512, 10110.898891010997, 10193.541290641791, 10361.274710770474, 10379.169666426516, 9895.752606184902, 9901.108900199017, 9960.750981225527, 10253.897461025294, 10272.435016074187, 9857.408555486694, 10123.139533139765, 10210.438425886647, 10321.690349289533, 10562.524686389284, 7600.695972161052, 7733.0, 7742.690292388242, 7831.843363132592, 7849.136594974662, 9030.593623286837, 10221.846672300071, 10252.38485690861, 10330.845037324596, 10472.057514823702], "value": 9105.862942004047, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1277.6869666931602, 1433.741926453238, 1562.6249700071987, 1755.4645833020923, 1767.4079183473536, 7683.731069412572, 8018.198180181994, 8160.387970902184, 8724.8691269631, 8728.694495692653, 8205.425620206588, 8532.14678532148, 8546.957265213665, 8635.093315201908, 8636.0, 2257.548490301942, 2297.2878407693584, 2303.8272129590264, 2304.0553373117027, 2304.9539009219807, 2246.9662955055687, 2286.3941055620276, 2296.6555016747457, 2311.3528212100637, 2312.3756585721844, 2272.488690044743, 2279.3959600705743, 2283.5318759654315, 2292.621717416624, 2293.564222797663, 2242.921497747571, 2252.6170551006285, 2257.8419510634344, 2258.9661155082686, 2267.9319620411334, 2247.5617254635354, 2248.5952528545017, 2252.6395776675818, 2255.391044418014, 2262.366537369539, 8662.09048049952, 8664.826703465895, 8665.566721663954, 8670.696525621574, 8675.739727808175, 5041.59667226621, 5160.56135228506, 5223.268742376071, 5228.92156617651, 5231.686098834082, 3257.7068063874367, 3311.3542859142476, 3442.7417943654327, 3446.5002574626537, 3450.0857272822723, 1040.2198351236573, 1043.6347278452556, 1053.1101219469535, 1066.5094056733913, 1068.0814499530397, 11338.71653208668, 11384.71538211543, 11467.0, 11487.827682584768, 11519.769604607945, 3981.462502562175, 4002.8198731057173, 4495.303227999665, 4545.863624091283, 4624.156143971433, 9726.36778609388, 9793.971632978577, 9805.362651427635, 9807.411555306562, 9840.557174927002, 4785.306130611042, 5191.84424467259, 5205.193195054772, 5206.245094461357, 5210.088234559013, 3682.9447558286647, 4231.0, 4378.343248512749, 4554.407926969473, 4761.880952976203, 3339.1819004343606, 3753.0, 3791.8672846450795, 3917.0990672144994, 3920.4707364506, 1263.3746295583744, 1279.8336216291823, 1279.8656141105237, 1286.408252203978, 1290.2323117744927, 787.8424315136996, 823.8887750153773, 841.7584063506401, 857.0358346859783, 882.1222883231226, 5469.453054694499, 5556.527695145877, 5854.0, 5889.558283128783, 5952.553558483044, 344.41105709236984, 407.80425395810175, 425.4511679932914, 429.8538496911065, 431.1162117658787, 3466.2720828625766, 3837.6929845612285, 3867.3425517662067, 3902.180542086194, 3954.762714237155, 10035.849462258071, 10405.0635442809, 10470.267081304235, 10492.842607360895, 10542.051215390498, 10396.48017599117, 10451.477426128664, 10460.163186945027, 10474.266801323987, 10504.0, 7523.473356864967, 7867.9771629687775, 7870.9606451967375, 8000.039995200613, 8005.639746211435, 5509.421510741315, 5700.315962084576, 5829.912551311649, 5893.469587737124, 5984.581079324405, 7027.61348125858, 9937.65218217359, 9947.552360143956, 9978.900210998088, 9988.950055249677, 8565.700200492953, 9818.067283608047, 9961.153301969123, 9986.300958932805, 10142.768590909987, 7348.301911318416, 7367.336939675455, 7400.074990626172, 7428.368588670023, 7474.065741782278, 6819.0, 8173.427860049739, 8181.713640022571, 8182.918170818216, 8212.83574328498, 6621.9337806621315, 7240.420766338786, 7380.815479613053, 7464.0, 7482.700691972478, 7236.878283865799, 7276.672549735381, 7286.48994570375, 7314.0, 7359.77920662381, 6814.2163651180745, 6832.214295355899, 6923.792286231421, 6941.861162776615, 6969.024336592983, 4861.6596838220985, 4879.365682461257, 4909.729964851825, 4948.38145231846, 4989.12690279207, 4790.353302304145, 4876.21980483121, 4903.31353610502, 4972.105021096238, 4978.850634480971], "value": 4348.308880178241, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.5906070092921, 300.3107867444214, 311.67430035612784, 313.4827534567961, 318.077575032406, 4065.4308396824467, 4803.423589169296, 5002.7982091461445, 5340.973295133524, 5352.759125839337, 5113.463086375933, 5281.566981462051, 5362.490563396481, 5391.67903863553, 5403.054900235912, 3371.7134043606325, 3931.842726290959, 4074.514546126875, 4092.7953602319913, 4115.691323150776, 3749.0, 4017.8392864285393, 4116.403121547374, 4126.319157339036, 4130.8967275818195, 3919.0006548330134, 4024.657904078142, 4025.7181997260204, 4049.2711311963835, 4123.865936867355, 3336.249343897628, 3668.3763760160705, 4003.4195041718935, 4059.594040595917, 4073.348264277701, 3513.1744040150543, 3517.6306487818665, 3519.296140771856, 3527.9294414111832, 3533.805640689761, 4313.360922849329, 4827.034593081397, 5126.641135120543, 5135.87160320995, 5238.711870847102, 3623.2391197848483, 4142.71001029928, 4600.769961501945, 4602.792874320664, 4640.141573808856, 4400.73595584266, 4658.487566367697, 4704.506026867164, 5694.857628559278, 5705.11570706537, 3158.136699347396, 3488.6511348865165, 3499.7900125992523, 3549.183687751806, 3846.74996125251, 5128.974355128201, 5812.970935145339, 5950.672713000783, 5995.280566332024, 6014.278286605591, 3900.2979463696806, 3915.686745060388, 3922.2939870822956, 3925.1364699766004, 3935.7995811277833, 3320.900372988814, 3322.6511216321937, 3682.3371793076967, 3957.149212919179, 3971.4439978403047, 4599.9080018399445, 4606.055758569485, 4612.762810037129, 4645.349651048856, 4665.160271151158, 4763.856674398119, 5295.338082739658, 5746.281714785652, 5775.5090817281, 5818.6217895836635, 1096.270979798425, 1128.90968722502, 1251.6683078984076, 1296.9027322950817, 1356.2540602668494, 807.935365170785, 844.9239568438747, 864.6843901975734, 902.386377263454, 904.8416527107861, 5326.978131510685, 5327.547158491494, 5333.12003519415, 5334.626576139711, 5341.65279256847, 5116.744162791845, 5356.464353564613, 5714.657120572779, 5788.5369170466265, 5797.449242322057, 4731.645126615517, 5048.419431765394, 5208.739563021833, 5313.521783039545, 5373.447612384086, 2198.4723666320288, 2205.9448513787283, 2470.0980245211445, 2528.3805467660172, 2594.4811037792515, 4441.933935855436, 4638.628909687284, 4660.347551342815, 4716.858494245178, 4998.925016124761, 4661.603763680125, 4666.859994200179, 4748.335233067373, 4914.361133052752, 4997.625178111656, 4702.670813043054, 4767.69010014348, 5041.219085685736, 5095.286659867547, 5121.974390128025, 4270.231358355526, 4835.540623640749, 4848.878778030577, 4872.829450969199, 4909.4354149273295, 3945.6843452523153, 4124.8143833526965, 4376.9124617508105, 4557.986215163294, 4788.281757736368, 4583.49581546023, 4625.884352891205, 4828.5412885775795, 5009.618507507345, 5036.0, 3246.464333384965, 3246.8863589774246, 3409.5168601658356, 3420.298838738003, 3425.246445781924, 4108.322126849036, 4719.961608446137, 4791.808327666994, 4875.731834749123, 5557.72211389437, 4871.95128048715, 4917.459079501324, 5687.326956330518, 5722.313322401172, 5724.942750572441, 5282.177648711188, 5335.493128152819, 5350.411454739898, 5667.971660141673, 5736.770529178953, 4940.75296235194, 5327.51048063519, 5336.786528538815, 5594.4125866785025, 5614.9719251403485, 5106.66806657566, 5393.649412788156, 5721.914171287517, 5769.1634712967225, 5771.826845194651, 5616.1856530803625, 5618.578606604603, 5618.859528511656, 5647.830565083054, 5668.51817595509], "value": 3898.063804405519, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.3715937076944, 722.584513904505, 731.798755342281, 746.7685017644534, 790.1584812175035, 14548.0, 15637.218139093056, 15710.842891571081, 15712.528624141294, 15721.934222826554, 14241.216733079702, 14403.711925761478, 14405.783913241283, 14409.63975900603, 14410.351534180962, 662.8972509261048, 687.9002544631026, 818.6684392820918, 834.3450391442703, 846.8137009857821, 814.1248158229927, 853.4452605806214, 854.9953804279977, 855.6192494340021, 855.6192494340021, 900.0774206438388, 903.5030733096803, 906.1436942089728, 906.8050369170627, 907.587047893207, 842.5576572299531, 879.7316818370394, 885.9379843410964, 894.4633220067972, 900.693764120199, 568.7345655915597, 653.2748648999607, 665.1618960110247, 673.1854456108115, 677.3226773226774, 14676.779848302283, 14707.63230919225, 14730.4844330449, 14764.557063288119, 14776.482823101247, 12380.504779808796, 13628.296462942133, 14180.43278268868, 14254.216018119, 14620.342084606218, 11730.824037639442, 11828.349440780754, 11846.585369512111, 11982.281063136239, 12003.099767517471, 3111.222194451387, 3759.586445490994, 3789.2800367930004, 3792.962070379316, 3923.6468717815533, 110709.89290107077, 111442.44278778635, 111505.44247278792, 113315.0, 114461.54769106147, 10901.473793668893, 10953.452327383598, 10992.670219893416, 11006.449677516091, 11104.555817767361, 22649.4337641557, 22765.430864228525, 22766.65850012218, 22768.316950491513, 22773.544529109324, 7933.246341597539, 7966.960165199137, 7987.480813747088, 8193.426460147852, 8310.127436619068, 3936.4292177634657, 3946.9802650987135, 3979.5821438749094, 4027.577104404054, 4063.6952228582973, 3550.7336949728356, 3613.313470440608, 3622.3660859349507, 3660.7254455915913, 3663.084228942764, 647.4917190005917, 654.5385503220218, 661.6493258572964, 668.0313545359248, 674.3829396102609, 501.14054396709815, 545.9109077390552, 574.8821491594213, 656.0290769660833, 664.6078813500059, 7726.34326082278, 9324.78294988228, 9620.230381569458, 9721.270904682036, 9723.756906077404, 569.7122952908837, 590.9645421274737, 601.750273636436, 629.4177885455935, 648.597869321024, 4333.458317710286, 4585.220512512881, 4622.745748983838, 4651.09303685778, 4657.74382408964, 116527.41736291433, 143759.0, 154364.0, 154738.0, 155043.2247838776, 96773.29040386259, 106812.79561613164, 125945.11082333772, 136210.0, 137540.8106635204, 112749.0, 113558.43220784009, 114115.0, 114733.42633286613, 115904.42047789707, 7853.664876971043, 8764.649414023368, 8885.200331970154, 9078.409903356262, 9103.135202155785, 48923.0, 58300.12549811841, 60907.390926091946, 61107.694461527404, 62589.37410625836, 90551.54724226336, 96936.51531742296, 97062.0, 98239.0, 98370.50814745881, 10778.568857245622, 11304.604338848101, 11429.771404571695, 11637.359945203096, 12115.394230288626, 12128.878711212776, 13778.331257783313, 14550.92724536413, 14914.925425372803, 15038.624034399227, 13492.797608036084, 13589.52436664712, 13664.0, 13896.37466313998, 13952.511662091778, 13690.31548422555, 13836.584902452943, 13870.514531991332, 13891.235982020682, 13936.721265574835, 11074.5570177192, 11669.35818529989, 13876.0, 13878.930605346908, 14138.293085345485, 11272.887271127513, 11319.037881779812, 11368.848077297855, 11479.0, 11597.304161750322, 9056.554730222671, 10396.0, 10591.841122383017, 11144.88855111471, 11636.592719255124], "value": 7682.902050147399, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 118.0, 116.0, 120.0, 155.0, 170.0, 166.0, 117.0], "value": 130.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 144.0, 135.0, 180.0, 108.0, 153.0, 140.0, 149.0, 143.0, 115.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 231.0, 196.0, 197.0, 242.0, 220.0, 201.0, 196.0, 192.0, 198.0], "value": 207.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 60.0, 60.0, 85.0, 62.0, 60.0, 57.0, 58.0, 79.0, 60.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 72.0, 91.0, 74.0, 70.0, 69.0, 91.0, 70.0, 74.0, 101.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 128.0, 88.0, 87.0, 89.0, 85.0, 89.0, 86.0, 124.0, 132.0], "value": 100.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 97.0, 130.0, 123.0, 98.0, 98.0, 130.0, 97.0, 144.0], "value": 111.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 101.0, 92.0, 89.0, 93.0, 86.0, 80.0, 120.0, 101.0, 146.0], "value": 100.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 58.0, 57.0, 67.0, 56.0, 55.0, 52.0, 79.0], "value": 59.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 98.0, 122.0, 114.0, 74.0, 72.0, 72.0, 70.0, 71.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 91.0, 97.0, 106.0, 86.0, 90.0, 95.0, 90.0, 88.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 181.0, 169.0, 150.0, 174.0, 161.0, 165.0, 150.0, 191.0, 152.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 175.0, 175.0, 174.0, 179.0, 174.0, 178.0, 178.0, 176.0, 176.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 80.0, 70.0, 69.0, 72.0, 69.0, 71.0, 70.0, 72.0], "value": 71.7, "unit": "score"}]}]} 08:24:27 INFO - Return code: 0 08:24:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:24:27 ERROR - # TBPL SUCCESS # 08:24:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:24:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:24:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:24:27 INFO - 2016-10-12 08:24:27.243 screenresolution[1676:10709] starting screenresolution argv=screenresolution get 08:24:27 INFO - 2016-10-12 08:24:27.246 screenresolution[1676:10709] Display 0: 1600x1200x32@60 08:24:27 INFO - 2016-10-12 08:24:27.252 screenresolution[1677:10712] starting screenresolution argv=screenresolution list 08:24:27 INFO - Available Modes on Display 0 08:24:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:24:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:24:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:24:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:24:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:24:27 INFO - Intel Iris: 08:24:27 INFO - Chipset Model: Intel Iris 08:24:27 INFO - Type: GPU 08:24:27 INFO - Bus: Built-In 08:24:27 INFO - VRAM (Dynamic, Max): 1536 MB 08:24:27 INFO - Vendor: Intel (0x8086) 08:24:27 INFO - Device ID: 0x0a2e 08:24:27 INFO - Revision ID: 0x0009 08:24:27 INFO - Displays: 08:24:27 INFO - Display: 08:24:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:24:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:24:27 INFO - Display Serial Number: 1600x1200 60 08:24:27 INFO - Main Display: Yes 08:24:27 INFO - Mirror: Off 08:24:27 INFO - Online: Yes 08:24:27 INFO - Rotation: Supported 08:24:27 INFO - Return code: 0 08:24:27 INFO - Running post-action listener: _resource_record_post_action 08:24:27 INFO - [mozharness: 2016-10-12 15:24:27.494631Z] Finished run-tests step (success) 08:24:27 INFO - Running post-run listener: _resource_record_post_run 08:24:27 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 34095616; Write bytes: 712776704; Read time: 317; Write time: 1922 08:24:27 INFO - TinderboxPrint: CPU usage
54.5% 08:24:27 INFO - TinderboxPrint: I/O read bytes / time
34,095,616 / 317 08:24:27 INFO - TinderboxPrint: I/O write bytes / time
712,776,704 / 1,922 08:24:27 INFO - TinderboxPrint: CPU idle
2,202.1 (45.5%) 08:24:27 INFO - TinderboxPrint: CPU system
977.8 (20.2%) 08:24:27 INFO - TinderboxPrint: CPU user
1,661.8 (34.3%) 08:24:27 INFO - TinderboxPrint: Swap in / out
437,379,072 / 0 08:24:27 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405918208; Write bytes: 401180672; Read time: 29894; Write time: 766 08:24:27 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 30036480; Write bytes: 300594176; Read time: 286; Write time: 1124 08:24:27 INFO - Running post-run listener: _upload_blobber_files 08:24:27 INFO - Blob upload gear active. 08:24:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:24:27 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 08:24:27 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:24:27 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:24:28 INFO - (blobuploader) - INFO - Open directory for files ... 08:24:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:24:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:24:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:24:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:24:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:24:29 INFO - (blobuploader) - INFO - Done attempting. 08:24:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:24:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:24:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:24:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:24:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:24:30 INFO - (blobuploader) - INFO - Done attempting. 08:24:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:24:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:24:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:24:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:24:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:24:32 INFO - (blobuploader) - INFO - Done attempting. 08:24:32 INFO - (blobuploader) - INFO - Iteration through files over. 08:24:32 INFO - Return code: 0 08:24:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:24:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:24:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70c24ab2eeee21d714381f522d8c4d6aadf40dc22bab5850fcd9a2359474a4f9a79bd7afe055a5b9a23c258a762374f6fb3b669abece2d3e16d316cdd97a3932", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/23a9d3a8ac6fb01f1e6ed4cfbb5e1cc9ead6eb60656d0c78b2aa5b71cacfa91aa6f248f08b0c69dec62fc553cd25d83e119cabfe406df2383ea512809bb1d581", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e7809e9e2bc308d2c9da585814d02977ac6af01d7517a6679fe4b501e6ae0e4f15e7f846e13c807a2823694e1af910fd18fa465349a8eb35a05efa360fd069d"} 08:24:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:24:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:24:32 INFO - Contents: 08:24:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70c24ab2eeee21d714381f522d8c4d6aadf40dc22bab5850fcd9a2359474a4f9a79bd7afe055a5b9a23c258a762374f6fb3b669abece2d3e16d316cdd97a3932", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/23a9d3a8ac6fb01f1e6ed4cfbb5e1cc9ead6eb60656d0c78b2aa5b71cacfa91aa6f248f08b0c69dec62fc553cd25d83e119cabfe406df2383ea512809bb1d581", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e7809e9e2bc308d2c9da585814d02977ac6af01d7517a6679fe4b501e6ae0e4f15e7f846e13c807a2823694e1af910fd18fa465349a8eb35a05efa360fd069d"} 08:24:32 INFO - Running post-run listener: copy_logs_to_upload_dir 08:24:32 INFO - Copying logs to upload dir... 08:24:32 INFO - mkdir: /builds/slave/test/build/upload/logs 08:24:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.840169 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-12 08:24:32.308974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 08:24:32.312204) ========= 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.yOES5JZtgj/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.MelvKhXabP/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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/Autoland/sha512/70c24ab2eeee21d714381f522d8c4d6aadf40dc22bab5850fcd9a2359474a4f9a79bd7afe055a5b9a23c258a762374f6fb3b669abece2d3e16d316cdd97a3932", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/23a9d3a8ac6fb01f1e6ed4cfbb5e1cc9ead6eb60656d0c78b2aa5b71cacfa91aa6f248f08b0c69dec62fc553cd25d83e119cabfe406df2383ea512809bb1d581", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e7809e9e2bc308d2c9da585814d02977ac6af01d7517a6679fe4b501e6ae0e4f15e7f846e13c807a2823694e1af910fd18fa465349a8eb35a05efa360fd069d"} build_url:https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/KFM76eaIRJy5MtYeaYhkAA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70c24ab2eeee21d714381f522d8c4d6aadf40dc22bab5850fcd9a2359474a4f9a79bd7afe055a5b9a23c258a762374f6fb3b669abece2d3e16d316cdd97a3932", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/23a9d3a8ac6fb01f1e6ed4cfbb5e1cc9ead6eb60656d0c78b2aa5b71cacfa91aa6f248f08b0c69dec62fc553cd25d83e119cabfe406df2383ea512809bb1d581", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e7809e9e2bc308d2c9da585814d02977ac6af01d7517a6679fe4b501e6ae0e4f15e7f846e13c807a2823694e1af910fd18fa465349a8eb35a05efa360fd069d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 08:24:32.345212) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:24:32.345612) ========= 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.yOES5JZtgj/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.MelvKhXabP/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.005297 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:24:32.394711) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 08:24:32.395104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 08:24:32.395451) ========= ========= Total master_lag: 0.17 =========