builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0115 starttime: 1476099832.29 results: success (0) buildid: 20161010025617 builduid: 51199ecd55ba4e65b809b921601ec53a revision: 7be6b348c431d69f96f0765af3a0c0a0fe56d4bf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:52.285408) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:52.285958) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:52.286569) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:52.359745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:52.360290) ========= 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.4U3raE7Kur/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.Wr3zojW4mN/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-10 04:43:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.12M=0.001s 2016-10-10 04:43:52 (9.12 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152255 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:52.535094) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:52.535470) ========= 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.4U3raE7Kur/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.Wr3zojW4mN/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.020997 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:52.585401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 04:43:52.585791) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7be6b348c431d69f96f0765af3a0c0a0fe56d4bf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7be6b348c431d69f96f0765af3a0c0a0fe56d4bf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4U3raE7Kur/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.Wr3zojW4mN/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-10 04:43:52,659 truncating revision to first 12 chars 2016-10-10 04:43:52,659 Setting DEBUG logging. 2016-10-10 04:43:52,659 attempt 1/10 2016-10-10 04:43:52,659 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7be6b348c431?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 04:43:53,416 unpacking tar archive at: mozilla-central-7be6b348c431/testing/mozharness/ program finished with exit code 0 elapsedTime=1.107068 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 04:43:53.716067) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:53.716804) ========= script_repo_revision: 7be6b348c431d69f96f0765af3a0c0a0fe56d4bf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:53.717737) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:53.718324) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 04:43:53.742171) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-10 04:43:53.742859) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4U3raE7Kur/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.Wr3zojW4mN/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:43:53 INFO - MultiFileLogger online at 20161010 04:43:53 in /builds/slave/test 04:43:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:43:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:43:53 INFO - {'append_to_log': False, 04:43:53 INFO - 'base_work_dir': '/builds/slave/test', 04:43:53 INFO - 'blob_upload_branch': 'Firefox', 04:43:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:43:53 INFO - 'branch': 'Firefox', 04:43:53 INFO - 'buildbot_json_path': 'buildprops.json', 04:43:53 INFO - 'config_files': ('talos/mac_config.py',), 04:43:53 INFO - 'default_actions': ('clobber', 04:43:53 INFO - 'read-buildbot-config', 04:43:53 INFO - 'download-and-extract', 04:43:53 INFO - 'populate-webroot', 04:43:53 INFO - 'create-virtualenv', 04:43:53 INFO - 'install', 04:43:53 INFO - 'run-tests'), 04:43:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:43:53 INFO - 'download_minidump_stackwalk': True, 04:43:53 INFO - 'download_symbols': 'ondemand', 04:43:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:43:53 INFO - 'tooltool.py': '/tools/tooltool.py', 04:43:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:43:53 INFO - '/tools/misc-python/virtualenv.py')}, 04:43:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:43:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:43:53 INFO - 'installer_path': 'installer.exe', 04:43:53 INFO - 'log_level': 'info', 04:43:53 INFO - 'log_name': 'talos', 04:43:53 INFO - 'log_to_console': True, 04:43:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:43:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:43:53 INFO - 'opt_config_files': (), 04:43:53 INFO - 'pip_index': False, 04:43:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:43:53 INFO - 'cmd': ('bash', 04:43:53 INFO - '-c', 04:43:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:43:53 INFO - 'enabled': True, 04:43:53 INFO - 'halt_on_failure': False, 04:43:53 INFO - 'name': 'check_screen_resolution'},), 04:43:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:43:53 INFO - 'cmd': ('bash', 04:43:53 INFO - '-c', 04:43:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:43:53 INFO - 'enabled': True, 04:43:53 INFO - 'halt_on_failure': False, 04:43:53 INFO - 'name': 'check_screen_resolution'},), 04:43:53 INFO - 'run_cmd_checks_enabled': True, 04:43:53 INFO - 'sps_profile': False, 04:43:53 INFO - 'sps_profile_interval': 0, 04:43:53 INFO - 'suite': 'dromaeojs', 04:43:53 INFO - 'system_bits': '32', 04:43:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:43:53 INFO - 'title': 't-yosemite-r7-0115', 04:43:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:43:53 INFO - 'use_talos_json': True, 04:43:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:43:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:43:53 INFO - 'work_dir': 'build'} 04:43:53 INFO - [mozharness: 2016-10-10 11:43:53.947024Z] Running clobber step. 04:43:53 INFO - Running pre-action listener: _resource_record_pre_action 04:43:53 INFO - Running main action method: clobber 04:43:53 INFO - rmtree: /builds/slave/test/build 04:43:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:43:55 INFO - Running post-action listener: _resource_record_post_action 04:43:55 INFO - [mozharness: 2016-10-10 11:43:55.540068Z] Finished clobber step (success) 04:43:55 INFO - [mozharness: 2016-10-10 11:43:55.540231Z] Running read-buildbot-config step. 04:43:55 INFO - Running pre-action listener: _resource_record_pre_action 04:43:55 INFO - Running main action method: read_buildbot_config 04:43:55 INFO - Using buildbot properties: 04:43:55 INFO - { 04:43:55 INFO - "project": "", 04:43:55 INFO - "product": "firefox", 04:43:55 INFO - "script_repo_revision": "production", 04:43:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:43:55 INFO - "repository": "", 04:43:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:43:55 INFO - "buildid": "20161010025617", 04:43:55 INFO - "pgo_build": "False", 04:43:55 INFO - "basedir": "/builds/slave/test", 04:43:55 INFO - "buildnumber": 2, 04:43:55 INFO - "slavename": "t-yosemite-r7-0115", 04:43:55 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:43:55 INFO - "platform": "yosemite_r7", 04:43:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:43:55 INFO - "branch": "mozilla-central", 04:43:55 INFO - "repo_path": "mozilla-central", 04:43:55 INFO - "revision": "7be6b348c431d69f96f0765af3a0c0a0fe56d4bf", 04:43:55 INFO - "stage_platform": "macosx64", 04:43:55 INFO - "builduid": "51199ecd55ba4e65b809b921601ec53a", 04:43:55 INFO - "slavebuilddir": "test" 04:43:55 INFO - } 04:43:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:43:55 INFO - Running post-action listener: _resource_record_post_action 04:43:55 INFO - [mozharness: 2016-10-10 11:43:55.543728Z] Finished read-buildbot-config step (success) 04:43:55 INFO - [mozharness: 2016-10-10 11:43:55.543859Z] Running download-and-extract step. 04:43:55 INFO - Running pre-action listener: _resource_record_pre_action 04:43:55 INFO - Running main action method: download_and_extract 04:43:55 INFO - mkdir: /builds/slave/test/build/tests 04:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:55 INFO - trying https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:43:55 INFO - Downloading https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/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 04:43:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/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 04:43:56 INFO - Downloaded 1199 bytes. 04:43:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:43:56 INFO - Using the following test package requirements: 04:43:56 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:43:56 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:43:56 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:43:56 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:43:56 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:43:56 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:43:56 INFO - u'jsshell-mac.zip'], 04:43:56 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:43:56 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:43:56 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:43:56 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:43:56 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:43:56 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:43:56 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:43:56 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:43:56 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:43:56 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:43:56 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:43:56 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:43:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:43:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:43:56 INFO - Fetch https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:43:56 INFO - Http code: 200 04:43:56 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 04:43:56 INFO - x-amz-cf-id: flM8W84TzZTEWNZvolMvxP7a56FG_A-vjqPQp-c6vXMFtj-DVTieOw== 04:43:56 INFO - x-amz-version-id: .7xrtbvI3u_SRRubNo62ibXgU2vzS.NY 04:43:57 INFO - Expected file size: 16497195 04:43:57 INFO - Obtained file size: 16497195 04:44:03 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 04:44:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:44:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:03 INFO - Fetch https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:44:04 INFO - Http code: 200 04:44:04 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 04:44:04 INFO - x-amz-cf-id: tduPyKaD-8--LQuVSCSN9R3FzzBjbFbhvpMe72Xz_FALrqUOz42veA== 04:44:04 INFO - x-amz-version-id: .7xrtbvI3u_SRRubNo62ibXgU2vzS.NY 04:44:04 INFO - Expected file size: 16497195 04:44:04 INFO - Obtained file size: 16497195 04:44:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:44:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:44:11 INFO - Fetch https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:44:12 INFO - Http code: 200 04:44:12 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 04:44:12 INFO - x-amz-cf-id: a-nhkDEIeJxkjLbWSw4pFkPOHU4odUau44Zv0b2_Zyr6z-w6Q0swsg== 04:44:12 INFO - x-amz-version-id: HH8Fk6AeTZbe6uUdhZbG4f2hR_Cgk0E0 04:44:12 INFO - Expected file size: 14409006 04:44:12 INFO - Obtained file size: 14409006 04:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:13 INFO - trying https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:44:13 INFO - Downloading https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/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 04:44:16 INFO - Downloaded 111714611 bytes. 04:44:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:44:16 INFO - mkdir: /builds/slave/test/properties 04:44:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:44:16 INFO - Writing to file /builds/slave/test/properties/build_url 04:44:16 INFO - Contents: 04:44:16 INFO - build_url:https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:44:16 INFO - Running post-action listener: _resource_record_post_action 04:44:16 INFO - Running post-action listener: set_extra_try_arguments 04:44:16 INFO - [mozharness: 2016-10-10 11:44:16.877392Z] Finished download-and-extract step (success) 04:44:16 INFO - [mozharness: 2016-10-10 11:44:16.877541Z] Running populate-webroot step. 04:44:16 INFO - Running pre-action listener: _resource_record_pre_action 04:44:16 INFO - Running main action method: populate_webroot 04:44:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:44:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:44:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:44:16 INFO - '--tppagecycles', 04:44:16 INFO - '7'], 04:44:16 INFO - 'tests': ['tsvgm']}}, 04:44:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:44:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:44:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:44:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:44:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:16 INFO - 'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:16 INFO - 'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['damp', 'tps']}, 04:44:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:16 INFO - 'tests': ['damp', 'tps']}, 04:44:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['dromaeo_dom']}, 04:44:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:44:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['basic_compositor_video']}, 04:44:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:44:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['a11yr', 04:44:16 INFO - 'ts_paint', 04:44:16 INFO - 'tpaint', 04:44:16 INFO - 'sessionrestore', 04:44:16 INFO - 'sessionrestore_no_auto_restore', 04:44:16 INFO - 'tabpaint']}, 04:44:16 INFO - 'other-e10s': {'tests': ['a11yr', 04:44:16 INFO - 'ts_paint', 04:44:16 INFO - 'tpaint', 04:44:16 INFO - 'sessionrestore', 04:44:16 INFO - 'sessionrestore_no_auto_restore', 04:44:16 INFO - 'tabpaint']}, 04:44:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:44:16 INFO - 'ts_paint', 04:44:16 INFO - 'tpaint', 04:44:16 INFO - 'sessionrestore', 04:44:16 INFO - 'sessionrestore_no_auto_restore', 04:44:16 INFO - 'tabpaint']}, 04:44:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:44:16 INFO - 'ts_paint', 04:44:16 INFO - 'tpaint', 04:44:16 INFO - 'sessionrestore', 04:44:16 INFO - 'sessionrestore_no_auto_restore', 04:44:16 INFO - 'tabpaint']}, 04:44:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['a11yr', 04:44:16 INFO - 'ts_paint', 04:44:16 INFO - 'tpaint', 04:44:16 INFO - 'sessionrestore', 04:44:16 INFO - 'sessionrestore_no_auto_restore', 04:44:16 INFO - 'tabpaint']}, 04:44:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['a11yr', 04:44:16 INFO - 'ts_paint', 04:44:16 INFO - 'tpaint', 04:44:16 INFO - 'sessionrestore', 04:44:16 INFO - 'sessionrestore_no_auto_restore', 04:44:16 INFO - 'tabpaint']}, 04:44:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['tsvgx', 04:44:16 INFO - 'tsvgr_opacity', 04:44:16 INFO - 'tart', 04:44:16 INFO - 'tscrollx', 04:44:16 INFO - 'cart']}, 04:44:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:44:16 INFO - 'tsvgr_opacity', 04:44:16 INFO - 'tart', 04:44:16 INFO - 'tscrollx', 04:44:16 INFO - 'cart']}, 04:44:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:16 INFO - 'talos_options': ['--disable-e10s'], 04:44:16 INFO - 'tests': ['tp5o']}, 04:44:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:16 INFO - 'tests': ['tp5o']}, 04:44:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:16 INFO - 'talos_options': ['--disable-e10s', 04:44:16 INFO - '--xperf_path', 04:44:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:16 INFO - 'tests': ['tp5n']}, 04:44:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:16 INFO - 'talos_options': ['--xperf_path', 04:44:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:16 INFO - 'tests': ['tp5n']}}, 04:44:16 INFO - 'talos.zip': {'path': '', 04:44:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:44:16 INFO - Running post-action listener: _resource_record_post_action 04:44:16 INFO - [mozharness: 2016-10-10 11:44:16.896186Z] Finished populate-webroot step (success) 04:44:16 INFO - [mozharness: 2016-10-10 11:44:16.896323Z] Running create-virtualenv step. 04:44:16 INFO - Running pre-action listener: _resource_record_pre_action 04:44:16 INFO - Running main action method: create_virtualenv 04:44:16 INFO - Creating virtualenv /builds/slave/test/build/venv 04:44:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:44:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:44:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:44:17 INFO - Using real prefix '/tools/python27' 04:44:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:44:17 INFO - Installing distribute.............................................................................................................................................................................................done. 04:44:20 INFO - Installing pip.................done. 04:44:20 INFO - Return code: 0 04:44:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:44:20 INFO - Reading from file tmpfile_stdout 04:44:20 INFO - Output received: 04:44:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:44:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:20 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 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:20 INFO - 'HOME': '/Users/cltbld', 04:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:20 INFO - 'LOGNAME': 'cltbld', 04:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:20 INFO - 'MOZ_NO_REMOTE': '1', 04:44:20 INFO - 'NO_EM_RESTART': '1', 04:44:20 INFO - 'PAGER': '/bin/cat', 04:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:20 INFO - 'PWD': '/builds/slave/test', 04:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:20 INFO - 'SHELL': '/bin/bash', 04:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:20 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:20 INFO - 'USER': 'cltbld', 04:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:20 INFO - 'XPC_FLAGS': '0x0', 04:44:20 INFO - 'XPC_SERVICE_NAME': '0', 04:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:44:21 INFO - Cleaning up... 04:44:21 INFO - Return code: 0 04:44:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:21 INFO - 'HOME': '/Users/cltbld', 04:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:21 INFO - 'LOGNAME': 'cltbld', 04:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:21 INFO - 'MOZ_NO_REMOTE': '1', 04:44:21 INFO - 'NO_EM_RESTART': '1', 04:44:21 INFO - 'PAGER': '/bin/cat', 04:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:21 INFO - 'PWD': '/builds/slave/test', 04:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:21 INFO - 'SHELL': '/bin/bash', 04:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:21 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:21 INFO - 'USER': 'cltbld', 04:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:21 INFO - 'XPC_FLAGS': '0x0', 04:44:21 INFO - 'XPC_SERVICE_NAME': '0', 04:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:21 INFO - Downloading/unpacking psutil>=3.1.1 04:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:44:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:44:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:44:25 INFO - Installing collected packages: psutil 04:44:25 INFO - Running setup.py install for psutil 04:44:25 INFO - building 'psutil._psutil_osx' extension 04:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:44:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:44:25 INFO - building 'psutil._psutil_posix' extension 04:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:44:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:44:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:44:25 INFO - ^ 04:44:25 INFO - 1 warning generated. 04:44:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:44:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:44:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:44:25 INFO - Successfully installed psutil 04:44:25 INFO - Cleaning up... 04:44:26 INFO - Return code: 0 04:44:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:26 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 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:44:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:26 INFO - 'HOME': '/Users/cltbld', 04:44:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:26 INFO - 'LOGNAME': 'cltbld', 04:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:26 INFO - 'MOZ_NO_REMOTE': '1', 04:44:26 INFO - 'NO_EM_RESTART': '1', 04:44:26 INFO - 'PAGER': '/bin/cat', 04:44:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:26 INFO - 'PWD': '/builds/slave/test', 04:44:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:26 INFO - 'SHELL': '/bin/bash', 04:44:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:26 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:26 INFO - 'USER': 'cltbld', 04:44:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:26 INFO - 'XPC_FLAGS': '0x0', 04:44:26 INFO - 'XPC_SERVICE_NAME': '0', 04:44:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:44:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:44:28 INFO - Installing collected packages: mozsystemmonitor 04:44:28 INFO - Running setup.py install for mozsystemmonitor 04:44:29 INFO - Successfully installed mozsystemmonitor 04:44:29 INFO - Cleaning up... 04:44:29 INFO - Return code: 0 04:44:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:29 INFO - 'HOME': '/Users/cltbld', 04:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:29 INFO - 'LOGNAME': 'cltbld', 04:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:29 INFO - 'MOZ_NO_REMOTE': '1', 04:44:29 INFO - 'NO_EM_RESTART': '1', 04:44:29 INFO - 'PAGER': '/bin/cat', 04:44:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:29 INFO - 'PWD': '/builds/slave/test', 04:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:29 INFO - 'SHELL': '/bin/bash', 04:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:29 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:29 INFO - 'USER': 'cltbld', 04:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:29 INFO - 'XPC_FLAGS': '0x0', 04:44:29 INFO - 'XPC_SERVICE_NAME': '0', 04:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:29 INFO - Downloading/unpacking jsonschema==2.5.1 04:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:44:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:32 INFO - Downloading functools32-3.2.3-2.tar.gz 04:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:44:32 INFO - warning: no files found matching '*.txt' 04:44:32 INFO - no previously-included directories found matching 'build' 04:44:32 INFO - no previously-included directories found matching 'dist' 04:44:32 INFO - no previously-included directories found matching '.git*' 04:44:32 INFO - Installing collected packages: jsonschema, functools32 04:44:32 INFO - Running setup.py install for functools32 04:44:32 INFO - warning: no files found matching '*.txt' 04:44:32 INFO - no previously-included directories found matching 'build' 04:44:32 INFO - no previously-included directories found matching 'dist' 04:44:32 INFO - no previously-included directories found matching '.git*' 04:44:32 INFO - Successfully installed jsonschema functools32 04:44:32 INFO - Cleaning up... 04:44:32 INFO - Return code: 0 04:44:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:32 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 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:44:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:32 INFO - 'HOME': '/Users/cltbld', 04:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:32 INFO - 'LOGNAME': 'cltbld', 04:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:32 INFO - 'MOZ_NO_REMOTE': '1', 04:44:32 INFO - 'NO_EM_RESTART': '1', 04:44:32 INFO - 'PAGER': '/bin/cat', 04:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:32 INFO - 'PWD': '/builds/slave/test', 04:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:32 INFO - 'SHELL': '/bin/bash', 04:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:32 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:32 INFO - 'USER': 'cltbld', 04:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:32 INFO - 'XPC_FLAGS': '0x0', 04:44:32 INFO - 'XPC_SERVICE_NAME': '0', 04:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:44:32 INFO - Cleaning up... 04:44:32 INFO - Return code: 0 04:44:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:32 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 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:44:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:32 INFO - 'HOME': '/Users/cltbld', 04:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:32 INFO - 'LOGNAME': 'cltbld', 04:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:32 INFO - 'MOZ_NO_REMOTE': '1', 04:44:32 INFO - 'NO_EM_RESTART': '1', 04:44:32 INFO - 'PAGER': '/bin/cat', 04:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:32 INFO - 'PWD': '/builds/slave/test', 04:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:32 INFO - 'SHELL': '/bin/bash', 04:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:32 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:32 INFO - 'USER': 'cltbld', 04:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:32 INFO - 'XPC_FLAGS': '0x0', 04:44:32 INFO - 'XPC_SERVICE_NAME': '0', 04:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:33 INFO - Downloading/unpacking blobuploader==1.2.4 04:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:35 INFO - Downloading blobuploader-1.2.4.tar.gz 04:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:44:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:44:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:36 INFO - Downloading docopt-0.6.1.tar.gz 04:44:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:44:36 INFO - Installing collected packages: blobuploader, requests, docopt 04:44:36 INFO - Running setup.py install for blobuploader 04:44:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:44:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:44:37 INFO - Running setup.py install for requests 04:44:37 INFO - Running setup.py install for docopt 04:44:37 INFO - Successfully installed blobuploader requests docopt 04:44:37 INFO - Cleaning up... 04:44:37 INFO - Return code: 0 04:44:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:37 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 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:37 INFO - 'HOME': '/Users/cltbld', 04:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:37 INFO - 'LOGNAME': 'cltbld', 04:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:37 INFO - 'MOZ_NO_REMOTE': '1', 04:44:37 INFO - 'NO_EM_RESTART': '1', 04:44:37 INFO - 'PAGER': '/bin/cat', 04:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:37 INFO - 'PWD': '/builds/slave/test', 04:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:37 INFO - 'SHELL': '/bin/bash', 04:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:37 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:37 INFO - 'USER': 'cltbld', 04:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:37 INFO - 'XPC_FLAGS': '0x0', 04:44:37 INFO - 'XPC_SERVICE_NAME': '0', 04:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-JHjAtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Ur2dqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-V1fla_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-zPMqxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-eHg154-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-pxlzPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-tZMcuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-lG8ofX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:44:38 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-qFByWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:44:39 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-u4RCDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:44:39 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-2glQ6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:44:39 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-kpVo2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:44:39 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7cdKFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:44:39 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-0I0bLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:39 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-712iVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:44:39 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Ha5Bd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:44:39 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-AWGBK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:44:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:44:39 INFO - Running setup.py install for manifestparser 04:44:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:44:40 INFO - Running setup.py install for mozcrash 04:44:40 INFO - Running setup.py install for mozdebug 04:44:40 INFO - Running setup.py install for mozdevice 04:44:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:44:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:44:40 INFO - Running setup.py install for mozfile 04:44:40 INFO - Running setup.py install for mozhttpd 04:44:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:44:40 INFO - Running setup.py install for mozinfo 04:44:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:44:40 INFO - Running setup.py install for mozInstall 04:44:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Running setup.py install for mozleak 04:44:41 INFO - Running setup.py install for mozlog 04:44:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Running setup.py install for moznetwork 04:44:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Running setup.py install for mozprocess 04:44:41 INFO - Running setup.py install for mozprofile 04:44:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:44:41 INFO - Running setup.py install for mozrunner 04:44:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:44:42 INFO - Running setup.py install for mozscreenshot 04:44:42 INFO - Running setup.py install for moztest 04:44:42 INFO - Running setup.py install for mozversion 04:44:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:44:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:44:42 INFO - Cleaning up... 04:44:42 INFO - Return code: 0 04:44:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:42 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 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:42 INFO - 'HOME': '/Users/cltbld', 04:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:42 INFO - 'LOGNAME': 'cltbld', 04:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:42 INFO - 'MOZ_NO_REMOTE': '1', 04:44:42 INFO - 'NO_EM_RESTART': '1', 04:44:42 INFO - 'PAGER': '/bin/cat', 04:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:42 INFO - 'PWD': '/builds/slave/test', 04:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:42 INFO - 'SHELL': '/bin/bash', 04:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:42 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:42 INFO - 'USER': 'cltbld', 04:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:42 INFO - 'XPC_FLAGS': '0x0', 04:44:42 INFO - 'XPC_SERVICE_NAME': '0', 04:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:44:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-9Qt5lW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:44:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-u4Jpbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:44:42 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)) 04:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Pwma6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-JCYnmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:44:43 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)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-XOggoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ZgQ0f0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-JXxN6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-C_xfQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-QYDW58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-TjjLh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-gwyiRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:44:43 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-skpF9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:44:44 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xSVFvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:44:44 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7D3oPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:44:44 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)) 04:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:44 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-asAtBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:44:44 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-V9T_Kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:44:44 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-zf5HW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:44:44 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)) 04:44:44 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)) 04:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:44:44 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)) 04:44:44 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)) 04:44:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:47 INFO - Downloading blessings-1.6.tar.gz 04:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:44:47 INFO - Installing collected packages: blessings 04:44:47 INFO - Running setup.py install for blessings 04:44:47 INFO - Successfully installed blessings 04:44:47 INFO - Cleaning up... 04:44:47 INFO - Return code: 0 04:44:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:44:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:44:47 INFO - Reading from file tmpfile_stdout 04:44:47 INFO - Current package versions: 04:44:47 INFO - blessings == 1.6 04:44:47 INFO - blobuploader == 1.2.4 04:44:47 INFO - docopt == 0.6.1 04:44:47 INFO - functools32 == 3.2.3-2 04:44:47 INFO - jsonschema == 2.5.1 04:44:47 INFO - manifestparser == 1.1 04:44:47 INFO - mozInstall == 1.12 04:44:47 INFO - mozcrash == 0.17 04:44:47 INFO - mozdebug == 0.1 04:44:47 INFO - mozdevice == 0.48 04:44:47 INFO - mozfile == 1.2 04:44:47 INFO - mozhttpd == 0.7 04:44:47 INFO - mozinfo == 0.9 04:44:47 INFO - mozleak == 0.1 04:44:47 INFO - mozlog == 3.4 04:44:47 INFO - moznetwork == 0.27 04:44:47 INFO - mozprocess == 0.23 04:44:47 INFO - mozprofile == 0.28 04:44:47 INFO - mozrunner == 6.12 04:44:47 INFO - mozscreenshot == 0.1 04:44:47 INFO - mozsystemmonitor == 0.3 04:44:47 INFO - moztest == 0.7 04:44:47 INFO - mozversion == 1.4 04:44:47 INFO - psutil == 3.1.1 04:44:47 INFO - requests == 1.2.3 04:44:47 INFO - wsgiref == 0.1.2 04:44:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:47 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 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:47 INFO - 'HOME': '/Users/cltbld', 04:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:47 INFO - 'LOGNAME': 'cltbld', 04:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:47 INFO - 'MOZ_NO_REMOTE': '1', 04:44:47 INFO - 'NO_EM_RESTART': '1', 04:44:47 INFO - 'PAGER': '/bin/cat', 04:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:47 INFO - 'PWD': '/builds/slave/test', 04:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:47 INFO - 'SHELL': '/bin/bash', 04:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:47 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:47 INFO - 'USER': 'cltbld', 04:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:47 INFO - 'XPC_FLAGS': '0x0', 04:44:47 INFO - 'XPC_SERVICE_NAME': '0', 04:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:44:48 INFO - Cleaning up... 04:44:48 INFO - Return code: 0 04:44:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:44: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')]} 04:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100e95490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e0b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f986ad87ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, '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.4U3raE7Kur/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.Wr3zojW4mN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:44: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', 'jsonschema'] in /builds/slave/test/build 04:44: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 jsonschema 04:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:48 INFO - 'HOME': '/Users/cltbld', 04:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:48 INFO - 'LOGNAME': 'cltbld', 04:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:48 INFO - 'MOZ_NO_REMOTE': '1', 04:44:48 INFO - 'NO_EM_RESTART': '1', 04:44:48 INFO - 'PAGER': '/bin/cat', 04:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:48 INFO - 'PWD': '/builds/slave/test', 04:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:48 INFO - 'SHELL': '/bin/bash', 04:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:44:48 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:48 INFO - 'USER': 'cltbld', 04:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:48 INFO - 'XPC_FLAGS': '0x0', 04:44:48 INFO - 'XPC_SERVICE_NAME': '0', 04:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:44:48 INFO - Cleaning up... 04:44:48 INFO - Return code: 0 04:44:48 INFO - Running post-action listener: _resource_record_post_action 04:44:48 INFO - Running post-action listener: _start_resource_monitoring 04:44:48 INFO - Starting resource monitoring. 04:44:48 INFO - [mozharness: 2016-10-10 11:44:48.331356Z] Finished create-virtualenv step (success) 04:44:48 INFO - [mozharness: 2016-10-10 11:44:48.332423Z] Running install step. 04:44:48 INFO - Running pre-action listener: _resource_record_pre_action 04:44:48 INFO - Running main action method: install 04:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:44:48 INFO - Reading from file tmpfile_stdout 04:44:48 INFO - Detecting whether we're running mozinstall >=1.0... 04:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:44:48 INFO - Reading from file tmpfile_stdout 04:44:48 INFO - Output received: 04:44:48 INFO - Usage: mozinstall [options] installer 04:44:48 INFO - Options: 04:44:48 INFO - -h, --help show this help message and exit 04:44:48 INFO - -d DEST, --destination=DEST 04:44:48 INFO - Directory to install application into. [default: 04:44:48 INFO - "/builds/slave/test"] 04:44:48 INFO - --app=APP Application being installed. [default: firefox] 04:44:48 INFO - mkdir: /builds/slave/test/build/application 04:44:48 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'] 04:44:48 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 04:45:22 INFO - Reading from file tmpfile_stdout 04:45:22 INFO - Output received: 04:45:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:45:22 INFO - Running post-action listener: _resource_record_post_action 04:45:22 INFO - [mozharness: 2016-10-10 11:45:22.941240Z] Finished install step (success) 04:45:22 INFO - [mozharness: 2016-10-10 11:45:22.941372Z] Running run-tests step. 04:45:22 INFO - Running pre-action listener: _resource_record_pre_action 04:45:22 INFO - Running main action method: run_tests 04:45:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:45:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:45:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:45:22 INFO - 2016-10-10 04:45:22.956 screenresolution[2230:22343] starting screenresolution argv=screenresolution get 04:45:22 INFO - 2016-10-10 04:45:22.958 screenresolution[2230:22343] Display 0: 1600x1200x32@60 04:45:22 INFO - 2016-10-10 04:45:22.964 screenresolution[2231:22345] starting screenresolution argv=screenresolution list 04:45:22 INFO - Available Modes on Display 0 04:45:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:45:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:45:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:45:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:45:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:45:23 INFO - Intel Iris: 04:45:23 INFO - Chipset Model: Intel Iris 04:45:23 INFO - Type: GPU 04:45:23 INFO - Bus: Built-In 04:45:23 INFO - VRAM (Dynamic, Max): 1536 MB 04:45:23 INFO - Vendor: Intel (0x8086) 04:45:23 INFO - Device ID: 0x0a2e 04:45:23 INFO - Revision ID: 0x0009 04:45:23 INFO - Displays: 04:45:23 INFO - Display: 04:45:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:45:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:45:23 INFO - Display Serial Number: 1600x1200 60 04:45:23 INFO - Main Display: Yes 04:45:23 INFO - Mirror: Off 04:45:23 INFO - Online: Yes 04:45:23 INFO - Rotation: Supported 04:45:23 INFO - Return code: 0 04:45:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:45:23 INFO - Python 2.7.3 04:45:23 INFO - Return code: 0 04:45:23 INFO - grabbing minidump binary from tooltool 04:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:23 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 0x100ca9d30>, '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 0x100acfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ca1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:45:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:45:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:45:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:45:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:45:23 INFO - Return code: 0 04:45:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:45:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:45:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:45:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:45:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:45:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0115 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4U3raE7Kur/Render', 04:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:23 INFO - 'HOME': '/Users/cltbld', 04:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:23 INFO - 'LOGNAME': 'cltbld', 04:45:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:45:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:23 INFO - 'MOZ_NO_REMOTE': '1', 04:45:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:45:23 INFO - 'NO_EM_RESTART': '1', 04:45:23 INFO - 'PAGER': '/bin/cat', 04:45:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:23 INFO - 'PWD': '/builds/slave/test', 04:45:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:23 INFO - 'SHELL': '/bin/bash', 04:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wr3zojW4mN/Listeners', 04:45:23 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 04:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:23 INFO - 'USER': 'cltbld', 04:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:23 INFO - 'XPC_FLAGS': '0x0', 04:45:23 INFO - 'XPC_SERVICE_NAME': '0', 04:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:45:23 INFO - mozversion application_buildid: 20161010025617 04:45:23 INFO - mozversion application_changeset: 7be6b348c431d69f96f0765af3a0c0a0fe56d4bf 04:45:23 INFO - mozversion application_display_name: Nightly 04:45:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:45:23 INFO - mozversion application_name: Firefox 04:45:23 INFO - mozversion application_remotingname: firefox 04:45:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:45:23 INFO - mozversion application_vendor: Mozilla 04:45:23 INFO - mozversion application_version: 52.0a1 04:45:23 INFO - mozversion platform_buildid: 20161010025617 04:45:23 INFO - mozversion platform_changeset: 7be6b348c431d69f96f0765af3a0c0a0fe56d4bf 04:45:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:45:23 INFO - mozversion platform_version: 52.0a1 04:45:23 INFO - using testdate: 1476099923 04:45:23 INFO - actual date: 1476099923 04:45:23 INFO - starting webserver on 'localhost:53340' 04:45:23 INFO - SUITE-START | Running 2 tests 04:45:23 INFO - TEST-START | dromaeo_css 04:45:23 INFO - operating with platform_type : mac_ 04:45:23 INFO - Initialising browser for dromaeo_css test... 04:45:23 INFO - TEST-INFO | started process 2241 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp9e5Cvt/profile http://localhost:53340/getInfo.html) 04:45:25 INFO - PROCESS | 2241 | __metrics Screen width/height:1600/1200 04:45:25 INFO - PROCESS | 2241 | colorDepth:24 04:45:25 INFO - PROCESS | 2241 | Browser inner width/height: 1024/689 04:45:25 INFO - PROCESS | 2241 | __metrics 04:45:25 INFO - PROCESS | 2241 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:45:25 INFO - PROCESS | 2241 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:45:26 INFO - TEST-INFO | 2241: exit 0 04:45:26 INFO - Browser initialized. 04:45:26 INFO - Running cycle 1/1 for dromaeo_css test... 04:45:26 INFO - TEST-INFO | started process 2243 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp9e5Cvt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:38 INFO - PROCESS | 2243 | RSS: Main: 234704896 04:45:38 INFO - PROCESS | 2243 | 04:46:30 INFO - PROCESS | 2243 | 1476099990087 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:48:43 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:53340/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53340/tests/dromaeo/cssquery-ext.html) 04:48:44 INFO - PROCESS | 2243 | RSS: Main: 380469248 04:48:44 INFO - PROCESS | 2243 | 04:51:47 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:53340/tests/dromaeo/cssquery-ext.html (next: http://localhost:53340/tests/dromaeo/cssquery-jquery.html) 04:51:48 INFO - PROCESS | 2243 | RSS: Main: 369115136 04:51:48 INFO - PROCESS | 2243 | 04:54:50 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:53340/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53340/tests/dromaeo/cssquery-mootools.html) 04:54:51 INFO - PROCESS | 2243 | RSS: Main: 324202496 04:54:51 INFO - PROCESS | 2243 | 04:57:53 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:53340/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53340/tests/dromaeo/cssquery-prototype.html) 04:57:54 INFO - PROCESS | 2243 | RSS: Main: 346349568 04:57:54 INFO - PROCESS | 2243 | 05:00:57 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:53340/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53340/tests/dromaeo/cssquery-yui.html) 05:00:58 INFO - PROCESS | 2243 | RSS: Main: 325050368 05:00:58 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:53340/tests/dromaeo/cssquery-yui.html (next: http://localhost:53340/tests/dromaeo/cssquery-dojo.html) 05:04:01 INFO - PROCESS | 2243 | RSS: Main: 438943744 05:04:01 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | __start_tp_report 05:04:01 INFO - PROCESS | 2243 | _x_x_mozilla_page_load 05:04:01 INFO - PROCESS | 2243 | _x_x_mozilla_page_load_details 05:04:01 INFO - PROCESS | 2243 | |i|pagename|runs| 05:04:01 INFO - PROCESS | 2243 | |0;dojo.html;2764.9585256221153;2989.656189538203;3316.9834150829242;3324.9168770780743;3356.976122282703;5509.559235261179;5580.637258578189;5634.802781902634;5702.344230413503;5723.914141287879;4190.853320133796;5608.803691870786;5637.689927054021;5765.164051212571;5863.413658634145;5123;5171.896562068757;5201.68789872607;5209.947900521003;5238.52853243208;5093.745312734367;5253.474652534755;5272.051030814471;5280.102382594949;5335.946640533603;5258.894822103574;5265.473452654742;5278.049951008817;5293.841184764444;5329.973350133263;5106.719130447824;5134.7689353978785;5166.199239117943;5170.663906846043;5210.114280572312;5111.87220319495;5153.201253805666;5170.793168273264;5177.197534382177;5177.792888284464;5696.544276457915;5705.77176912923;5708.20085188074;5711.685857277848;5737.426257374229;6097.131491098847;6109.114178444101;6177.227846519185;6179.721912513947;6241.812745617639;5297;6197.845053873644;6217.875642487125;6258.123862659231;6266.216722909637;5678.176664383683;5769.221155144044;5792.739326730307;5802.3037235531165;5808.215890854681;5525.392206857243;6174.691265436711;6177.598456100429;6228.688565571703;6268.780592679234;6089.5737298388685;6114;6124.479419249325;6134.171886795315;6136.9079463808075;6202.937970620236;6205.937940620626;6212.223472065992;6219.782307619212;6227.875442491125;6072.635641861502;6092.055523033665;6113.476306051762;6130.785422510191;6135.851732731318;6233;6234.407731265523;6237.781677641261;6241.438270555672;6248.125262463259;4834.347363105936;4928.310036594879;4932.482089380564;4940.8764780880765;4972.57733092684;4390.121975604892;4398.428204333416;4419.292913133948;4465.352523884018;4476.753778542212;5635.633683810539;5719.28508936383;5734.369219385864;5747.051736463437;5779.190913272145;6208.348123447064;6258.687065646699;6260.154879091356;6273.811785646438;6286.277078136073;4799.688020278671;5731.996900542388;5749.798757043483;5754.88490230193;5765.596408251382;5261.237120617489;5363.275957745733;5391.245225668409;5421.132618780976;5421.484958928895;6163.9383606164265;6258.186435763321;6265.4361107500545;6344.238691357066;6353.142325786144;6263.498920086473;6277.5605707600025;6285.2457705075685;6286.7799627014665;6289.842753931005;6154.230721159855;6272.937270627235;6332.335104813928;6334.968325158345;6350.301466838551;6061.363556826646;6106.460667179974;6107.370879189235;6113.816585502442;6192.380761923771;6054.8183554493435;6244.65654389013;6252.187215662117;6256.31180570146;6264.5301602379095;5862.443067908542;5865.794697185749;5896.616719913192;6057.273127224761;6091.0558863376245;5994;6038.305594856648;6063.333033366238;6087.726052327567;6099.4815440688035;5925.200097986716;5935.762569497172;5948.910766338422;5957.791477298274;5981.222441082631;6088.117223002551;6098.024316109401;6109.144719739329;6109.877802444015;6122;5724.141378793048;5728.083506638918;5865.208196893364;5893.941060589339;5926.851828704317 05:04:01 INFO - PROCESS | 2243 | |1;ext.html;458.2621978614431;461.2182350915199;498.0636403561304;509.4726957598886;535.3976776126857;29367.55948660769;38620;39826.40260396093;39947.20105597886;39974.60025399745;40425.59574404255;40974.18051638966;41367.758967230955;41451.37822932658;41471.58528414715;8385.245327920486;8615.741527754162;9781;9801.460919649417;9820;9620.230381569458;9712.174465170434;9719.125278724914;9728.124468797807;9756.121949024586;9691.030896910288;9746.658866939659;9762.414255144678;9764.511774411287;9773.853392199087;9642.325037247396;9656.082672146134;9698.612055517771;9722.513874306293;9723.562439690231;9607.13535781776;9699.175570076552;9703.708888733348;9714.90285097147;9716.271279654055;39822;40165;40171.79914100411;40184.59815401867;40198;3349.815760133192;3647.8905632831056;3933.9213215735813;3936.193080418507;4073.775942323171;29703.70296297031;30736.69263307383;31179.688203118134;31446.842765786023;31461.213469663213;3201.0876900083363;3301.108700650811;3323.6842499962463;3393.5079413484905;3394.932101357984;483492;483894.86893614085;483994;486940;497587;57983.710081449324;58229.70885145632;58257;58296;58445.70777146087;61100.388996110356;61179;61204.081938770956;61269.69365153235;61295.38704612897;13381.46474141043;15932.283047262901;16143;16165.515034548982;16203;12517.186382885084;12602.432890519949;12604.936975315064;12609.747805044031;12618.81071783924;14310.928445357707;15766.9211653941;16025.278862450985;16059.678806423806;16100;5559.305086864142;6631.668416579152;6658.6337751424135;6729.158855143107;6771.424428923498;764.2930289482318;863.9870401943975;926.8424367857345;1030.5105075089273;1041.1982773264544;27840.582391264146;28976.71023289782;29015.85492072526;29787.404251915275;29918.401631967674;923.4274749655257;1011.079917275276;1036;1123.230587047875;1159.5419809175328;13534.796978045337;15073.547793566211;15241;15321.004134731207;15518;624015;628440.8577957172;628531;628644;628884;55597.444025560035;55826.72086639541;55838.441615583324;55897.44102559004;55977;1110.7278716714295;1251.2054845173197;1310.2269660900115;1316.5457917018614;1361.8297712785902;26194.345141371618;29003.709962900946;30615;30818;31590.368192636477;47660;48312;48373.75813120912;48435;48542.51457485521;520336;522632;523203;523948;524355;24593.87703061473;25368.873155634104;25420.872895635403;25497.362565936;25525;45936.31095533503;46037.01187097763;46301;46724.299135512316;47493.52506474891;38273.425898610985;38448.19744398371;38592;39333.3300006496;39715.60284397119;37836.86489405322;38825.58462698971;38967.41548876826;39249.60750392459;39265.80367098146;33370.33259334848;36596.63403365932;36752.44871326879;37402.43896341503;37479.43780843235;32369.028929132164;33926.491102633976;33970.15074623154;34139.4879076819;34316.82841585776;22675.319740407813;24883.75116248887;25023.873925673754;25145.748542514342;25570.360740981625 05:04:01 INFO - PROCESS | 2243 | |2;jquery.html;308.6496826102374;351.96832285094337;367.6433859156617;374.6178897524525;403.7819577428189;14415.423383064677;14447.699707026366;15849.920750396246;16655;21082.156713731434;19255.807441925575;19660.213591456326;20588.897055514703;20639.071241794118;20898.059587318607;4843.297721830332;5314.176302673091;5515.779368825242;5619.887602247973;5634.661920284796;4560.8859778505475;5539.695316757577;6132.41742034506;6173.32093469718;6206.906896396557;6163.445289923906;6169.938300616981;6171.783987560436;6174.722137503823;6176.505879529649;5317.494837990384;5488.313960754886;5537.889242215173;5545.889082218333;5551.694656793875;5104.234364845266;5374.758135883894;5382.434844341327;5383.703896285702;5386.461353864622;13099.2140471572;13379.063465557414;19861.10625021863;24109.276721698378;25787.484250315076;2140.886738895781;2282.0757593174626;2372.905083796646;2449.39989702524;2554.386947132675;9305.973205990178;9443.197328227108;9686.41881487106;9993.70018899427;10300.712410948632;1549.628089258581;1807.8463330616914;1870.1303893689499;1876.8780029298189;1900.6483800496815;114620.85379146268;120550.79449205483;123243.3837830814;124378.13432798516;127652;11318.547258109747;11334.546618135348;11487.13846461502;11517.596884109016;11718;14405.495807646683;14405.85594144066;14443.78334324986;14454.349554270084;14465.638359041108;8360.247577718035;8500.914990850013;8566.100559441293;8581.283743251375;8680.34897382699;8507.064222935473;8518.233358997595;8660.8700869487;8692.261157801659;8704;4417.702794546882;4498.212812757754;4505.062822986082;4508.188526065274;4602.102589994919;1622.4689484404132;1855.1280897977929;1897.5825318429922;1904.7714274286898;1915.9521011974812;708.8198150080162;724.8695234857671;781.6638845296463;885.8272636835756;939.0468674295596;18581.977991210344;19195.904020479808;20840.255638872834;20969.68545471819;21105.472363191042;831.4138532334689;884.3853521802414;892.8616064510011;927.6150397584927;944.2729098594044;8518.222482586787;10422.739431514272;10698;10898.128149748001;10910.322230466387;531247;590295;598077;606699.4305484083;611516;86166;129107;133207;134330;138834;941.2940294778916;948.9572969216401;971.7813491964251;973.5910917414561;978.3933960944261;18035.188416521025;18168.818311817242;18177;18249.90875045616;18300.633987320445;49376.25935610897;55175;55914;56056;56059;66478.66760666166;78290;79177.20822791928;81186.59406702929;82018.76971845547;6957.2694867038235;7805.102413222552;7903.407244456574;7995.840083198187;8289.95855020721;10650.680479585624;12926.095173337777;13080;13097.934510327388;13335.466581337027;10682.342686779884;10727.463626818784;11277.492512836778;11347.375894325562;11368.602098926829;9870.703878883358;10681.626143084955;11153.72115697114;11359.659210223706;11561.826572601249;11225.270357426742;11566.991075838012;11669.183157178915;11770.234934729215;11886.108541859223;6926.792196234121;8074.434789564674;8133.308668763221;8193.869593905747;8293.1706829319;9298.767530811783;9853.950730246303;9940.502974851084;11316.49075791575;11457.885421145682 05:04:01 INFO - PROCESS | 2243 | |3;mootools.html;1359.2116572388013;1524.1997951075687;1720.5956600198951;1720.7590937268776;1896.6206758648266;8375.748727538175;8852.468851868893;8896.822063558724;8905;8919.286457083434;7331.413486921047;7568.772936811891;7796.844063118734;8042.638081286342;8651.610677519511;2132.402927180388;2164.4697049222964;2269.7503274639857;2361.527694461106;2363.869987150706;2343.9179628713;2344.132670911765;2351.412146963259;2355.1521452277166;2364.8344615876895;2331.079223706635;2339.263132113387;2378.0725517048363;2380.250221180331;2380.8095352371856;2341.157183413987;2348.9530209395803;2351.776581224781;2366.810655147601;2367.43181636406;2289.461976435553;2303.573838839803;2324.5467133908896;2328.639060945556;2330.3358542815217;8640.956795215983;8791.824163516758;8792.47245165292;8800.16398442147;8835.911640883638;5209.6874187548865;5376.38171610262;5387.1111266640955;5388.0570900092325;5394.136938089926;3426.7087297579737;3447.913802154941;3667.889963301105;3674.8530058797614;3680.7975561344115;1048.119579553179;1079.9298045627008;1083.8428427877914;1101.895319944604;1104.3318792130726;11617.244879082833;11661.883381166164;11669.066474682006;11705.882941170565;11717.765644687059;4406.713563618355;4566.452025756864;4758.191107511731;4766.856994290177;4772.7136371817805;9934.45360505179;9936.453495057842;9941.30410871232;9958.153556947596;9962.402255864672;5250.868728281747;5251.186066159675;5323.733813309319;5352.785888564492;5359.464053594609;4780.282957556395;4825.131476334293;4900.828471003498;4901.754912254373;4902.093112774148;3021.108772911952;3166.920826979298;3762.398016317376;3817.594915203404;3884.864029758945;1065.7548763784373;1282.7947528395412;1285.4729560879994;1295.1322613848745;1297.4485843516468;805.6092794994423;854.2141230068352;889.4440974391006;892.4466830564961;921.8064206516573;5678.006348888928;5718.771249150071;5824.097264923944;5838.79564215259;6001.669908155093;873.5239294584467;922.2891536200386;951.743029382063;970.6214576315243;992.5235886774389;3834.789086600208;3846.249981253629;3916.4712763776524;3964.4251583520227;3966.2662407454136;9102.317326200562;10309.639162629423;10667.519961601747;10749.40878251688;10814;10747.83878241827;10750.0324970753;10750.462476876124;10773.192010599236;10774;7610.885836712454;7907.48601340911;8197.672093116327;8283.37874659403;8309.792255193668;4738.620910327235;5493.587980901369;6046.274447066205;6069.726862291119;6140.508759299156;9812.901870981199;9813.558389872616;10118.494075296352;10145.985401459795;10152.695419137437;9796.804063918824;9910.702678919644;10138.695839124837;10216.642417515352;10224.795504090025;7427.554346739213;7592.8861067082935;7603.353714934293;7606.543607383574;7608.31525162738;8048.261742431595;8244.299234564889;8322.833543328978;8331.708390206313;8352.123027082069;7490.363319117936;7506.887396689163;7511.887321690288;7523.172451030492;7548.509346892434;6303.810885673437;7391.445641576795;7402.814929626587;7406.555606663617;7443.404527637667;6718.395344419026;6942.132233470817;6959.930400695928;7045.027392330156;7068.11648543932;4942.1104201244125;5004.274380214922;5014.699118052783;5046.949530504648;5109.20807274873;4954.529319714621;5088.313077734607;5111.667741596784;5147.279380886754;5205.245239440335 05:04:01 INFO - PROCESS | 2243 | |4;prototype.html;275.5797408951349;292.6765923654362;309.4939773470375;313.64401404405993;316.702299838152;5370.167624018279;5396.676199428032;5411.809401931569;5415.972920135398;5432.728363581825;4015;4343.022819865529;5043.974780126104;5260.763265653045;5277.577793776498;3624.655657712526;3911.8239679214503;4065.857694980676;4068.6338229559333;4075.1645912587996;4071.6539094176883;4080.8369614659678;4085.3445260580565;4120.958790412103;4132.359484279941;3129.092563156682;3418.1112910643287;3822.4075268333454;3955.683545316382;4060.7563546187325;3371.393149233137;4005.218982298453;4027.254957832781;4038.273110840062;4068.65416439603;3458.412069948115;3502.2995400919917;3502.807345595991;3507.7719948203285;3511.227529943408;4494.505604383515;4948.822518092028;5113.309703190096;5156.561692256163;5182.196759502283;3645.270945810822;4253.27838141626;4558.475775285851;4569.223232050559;4582.243929751587;4921.434035085975;5556.388797232301;5577.2191893135;5595.664260144405;5599.1041433370465;3800.8289626966853;3810.504634397538;3838.539375274957;3840.7311488195837;3841.5774264830848;5533.833984980457;5629.634073785191;5705.71471426427;5822.243108395882;5822.719001819593;3523.418635925095;3719.758215716024;3750.1749615084636;3752.3433399155037;3892.8053597320586;3901.1612503311358;3922.9408059823686;3930.272899513599;3936.8622098226997;3955.110100227483;3602.693771029492;4284.700070995063;4591.747453890068;4594.6783725139585;4622.514635963175;4473.217186992263;5302.92045619316;5558;5563.916541251884;5662.745176467069;1233.8766123387768;1311.7769979103384;1338.872807083345;1345.186162371751;1347.7708789505807;744.9776506704808;766.4826242286434;807.2653884964772;820.6389188756928;821.3552361396395;3868.5551161616213;4117.691173162025;4324.459442569679;4402.471703395613;4407.603315701603;4211.536730959592;4348.8258170294475;4672.766361681903;4711.607642497465;4740.3837501124635;3733.831977561016;3915;4069.491313585802;4126.834926602963;4312.892177695583;2230.8661480311234;2363.4564050268536;2415.997361095126;2493.551160791075;2540.6697129373065;3983.0483063299353;4427.468703755506;4801.830791677476;4866.78099485524;4878.463369029404;4028.9436756870527;4757.976210118927;4776.5701086902345;4818.1209328361965;4846.75766211688;4766.594839438617;4865.956298354699;5417.864553386197;6077.6353418795015;6123.805857857764;5152.562032227303;5683.71581420919;5686.545076393962;5911.527077833849;5940.851478713067;5122.487751224848;5401.784598465314;5543.113101903676;5652.434756524314;5804;4709.576138147583;4970.428400733817;5266.946610677803;5849.970750146393;5921.822345329647;3534.469829525489;3669.8348574313686;3752.180192606529;3769.2273084018257;3876.4687948260844;4398.560143985575;5034.421041580265;5492.978861626169;5565.610407271614;5834.970825145847;4936.7284799336385;5320.419596495619;5341.679499230058;5684.9431505684415;5708.971455142698;4821.831235906727;5649.406812284651;5658.830235092953;5662.03657439802;5667.57111947266;5178.886539394049;5528.391876893619;5547.694876781816;5563.721813909369;5610.663360198401;5315.3621565412395;5618.803341883015;5637.9718101409235;5674.914876276776;5702.60081794287;4231.555686652857;5352.35771707382;5451.48210919962;5477.616566840283;5509.696966666872 05:04:01 INFO - PROCESS | 2243 | |5;yui.html;638.9266033064456;714.4712912444791;752.1538269446869;770.9383249340053;824.8432797768423;15145.318460669268;15838.128902910335;15839.683206335867;15853.20733963303;15877;14164.787528187086;14289.499867504639;14290.78563821541;14296.571102866905;14313.642158946006;642.8360768004152;669.3206395508563;670.088679396021;828.7886588919832;855.9315254779634;820.5281962871343;839.7606682095604;848.101012926297;854.5171977832536;856.2293935458107;892.2371372476537;893.9362159030765;894.061235702513;895.6014573514789;895.847705889997;683.962382068986;886.2909672262165;889.0753616239103;890.6481939633841;890.8708237305555;530.6948504609865;645.4578154350369;664.3588936676131;668.3750693101965;668.8729141463156;14637.92681036588;14688.118712877154;14690;14693.77959330611;14697.191654459;13225.470981160839;13634.250116243602;13657.453701851913;13773;14018.36917338712;12027.639170824888;12193.878061219362;12241.93879030608;12258.26450412978;12769.48922043117;4407.016842420923;4429.557044295577;4463.799129039201;4523.050159466516;4540.318952157169;119872.40063799713;120412.34639496898;120562;120725;121940;10569.260151789302;10630.468476576294;10709.98255165758;10791.305781984862;10818.404987725593;21146.154153833675;21161.576768464543;21192.682109768364;21199.68200476994;21310.573788524143;7446.776596702108;7628.504147230523;7636.427267954926;7699.730509432255;7798.220177982156;4136.296829538986;4159.812808423628;4209.34755112958;4229.069604686964;4350.891227719332;3452.896413107611;3615.8553657853918;3619.8009109498703;3622.094476380905;3622.2755448909797;673.5958424944994;674.1472037872096;678.9355011273921;692.8025512728848;749.2619769527012;521.8512723873678;605.3643674142133;643.2474005413677;663.9601623902581;673.3170797106212;8773.736787896245;9328.440293582407;9439.808824039827;9504.429734215832;9639.566219520138;530.7823792245161;626.2985456288985;632.7279269913889;657.9572327798678;680.6154522694661;4342.131573685276;4397.428334316518;4525.954740452619;4536.478304994967;4560.794764235618;75114;92884;128574;139213.82358529262;165357;131018;135729;135775;138315;139447.97627666531;115684;116308;116758;118535;118726.4063679676;8504.489730616182;8556.74329770108;8703.086175951687;8850.690225842065;9087.59105840252;46713.29930050984;62509.68745156245;63165;64356.67821660862;66251;77013.2298677006;89318;99158;99394.04241703011;101528;11221.438928053727;11780.646580602595;12180.695482613006;12756.61730148097;13432.261225632685;15204.923975380494;15249.923750381176;15301.923490382476;15781.526554203392;15866.52400427989;13964.4414223434;14022.929885350508;14113.435462581792;14313.856861431252;14651.047681900642;13880.861191388361;13987;14244.21656808844;14297.714045719236;14521.92739036298;13574.524891628746;13706.794398084236;13721.862781372058;13726.450941962608;13752.449902003807;10558.260921735406;10919.689637243582;11245.69285774829;11376.260543064674;12024.158308918642;9557.52212389364;10562.366258024544;10840.512176952214;11202.887971120184;11477.311361318347 05:04:01 INFO - PROCESS | 2243 | __end_tp_report 05:04:01 INFO - PROCESS | 2243 | __start_cc_report 05:04:01 INFO - PROCESS | 2243 | _x_x_mozilla_cycle_collect,1626 05:04:01 INFO - PROCESS | 2243 | __end_cc_report 05:04:01 INFO - PROCESS | 2243 | __startTimestamp1476101041848__endTimestamp 05:04:01 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | ------- Summary: start ------- 05:04:01 INFO - PROCESS | 2243 | Number of tests: 6 05:04:01 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | [#0] dojo.html Cycles:165 Average:5697.39 Median:5893.94 stddev:667.31 (11.3%) stddev-sans-first:628.45 05:04:01 INFO - PROCESS | 2243 | Values: 2765.0 2989.7 3317.0 3324.9 3357.0 5509.6 5580.6 5634.8 5702.3 5723.9 4190.9 5608.8 5637.7 5765.2 5863.4 5123.0 5171.9 5201.7 5209.9 5238.5 5093.7 5253.5 5272.1 5280.1 5335.9 5258.9 5265.5 5278.0 5293.8 5330.0 5106.7 5134.8 5166.2 5170.7 5210.1 5111.9 5153.2 5170.8 5177.2 5177.8 5696.5 5705.8 5708.2 5711.7 5737.4 6097.1 6109.1 6177.2 6179.7 6241.8 5297.0 6197.8 6217.9 6258.1 6266.2 5678.2 5769.2 5792.7 5802.3 5808.2 5525.4 6174.7 6177.6 6228.7 6268.8 6089.6 6114.0 6124.5 6134.2 6136.9 6202.9 6205.9 6212.2 6219.8 6227.9 6072.6 6092.1 6113.5 6130.8 6135.9 6233.0 6234.4 6237.8 6241.4 6248.1 4834.3 4928.3 4932.5 4940.9 4972.6 4390.1 4398.4 4419.3 4465.4 4476.8 5635.6 5719.3 5734.4 5747.1 5779.2 6208.3 6258.7 6260.2 6273.8 6286.3 4799.7 5732.0 5749.8 5754.9 5765.6 5261.2 5363.3 5391.2 5421.1 5421.5 6163.9 6258.2 6265.4 6344.2 6353.1 6263.5 6277.6 6285.2 6286.8 6289.8 6154.2 6272.9 6332.3 6335.0 6350.3 6061.4 6106.5 6107.4 6113.8 6192.4 6054.8 6244.7 6252.2 6256.3 6264.5 5862.4 5865.8 5896.6 6057.3 6091.1 5994.0 6038.3 6063.3 6087.7 6099.5 5925.2 5935.8 5948.9 5957.8 5981.2 6088.1 6098.0 6109.1 6109.9 6122.0 5724.1 5728.1 5865.2 5893.9 5926.9 05:04:01 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | [#1] ext.html Cycles:180 Average:67726.63 Median:40069.80 stddev:146655.20 (366.0%) stddev-sans-first:146979.64 05:04:01 INFO - PROCESS | 2243 | Values: 458.3 461.2 498.1 509.5 535.4 29367.6 38620.0 39826.4 39947.2 39974.6 40425.6 40974.2 41367.8 41451.4 41471.6 8385.2 8615.7 9781.0 9801.5 9820.0 9620.2 9712.2 9719.1 9728.1 9756.1 9691.0 9746.7 9762.4 9764.5 9773.9 9642.3 9656.1 9698.6 9722.5 9723.6 9607.1 9699.2 9703.7 9714.9 9716.3 39822.0 40165.0 40171.8 40184.6 40198.0 3349.8 3647.9 3933.9 3936.2 4073.8 29703.7 30736.7 31179.7 31446.8 31461.2 3201.1 3301.1 3323.7 3393.5 3394.9 483492.0 483894.9 483994.0 486940.0 497587.0 57983.7 58229.7 58257.0 58296.0 58445.7 61100.4 61179.0 61204.1 61269.7 61295.4 13381.5 15932.3 16143.0 16165.5 16203.0 12517.2 12602.4 12604.9 12609.7 12618.8 14310.9 15766.9 16025.3 16059.7 16100.0 5559.3 6631.7 6658.6 6729.2 6771.4 764.3 864.0 926.8 1030.5 1041.2 27840.6 28976.7 29015.9 29787.4 29918.4 923.4 1011.1 1036.0 1123.2 1159.5 13534.8 15073.5 15241.0 15321.0 15518.0 624015.0 628440.9 628531.0 628644.0 628884.0 55597.4 55826.7 55838.4 55897.4 55977.0 1110.7 1251.2 1310.2 1316.5 1361.8 26194.3 29003.7 30615.0 30818.0 31590.4 47660.0 48312.0 48373.8 48435.0 48542.5 520336.0 522632.0 523203.0 523948.0 524355.0 24593.9 25368.9 25420.9 25497.4 25525.0 45936.3 46037.0 46301.0 46724.3 47493.5 38273.4 38448.2 38592.0 39333.3 39715.6 37836.9 38825.6 38967.4 39249.6 39265.8 33370.3 36596.6 36752.4 37402.4 37479.4 32369.0 33926.5 33970.2 34139.5 34316.8 22675.3 24883.8 25023.9 25145.7 25570.4 05:04:01 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | [#2] jquery.html Cycles:180 Average:34368.51 Median:4457.96 stddev:98422.68 (2207.8%) stddev-sans-first:98665.55 05:04:01 INFO - PROCESS | 2243 | Values: 308.6 352.0 367.6 374.6 403.8 14415.4 14447.7 15849.9 16655.0 21082.2 19255.8 19660.2 20588.9 20639.1 20898.1 4843.3 5314.2 5515.8 5619.9 5634.7 4560.9 5539.7 6132.4 6173.3 6206.9 6163.4 6169.9 6171.8 6174.7 6176.5 5317.5 5488.3 5537.9 5545.9 5551.7 5104.2 5374.8 5382.4 5383.7 5386.5 13099.2 13379.1 19861.1 24109.3 25787.5 2140.9 2282.1 2372.9 2449.4 2554.4 9306.0 9443.2 9686.4 9993.7 10300.7 1549.6 1807.8 1870.1 1876.9 1900.6 114620.9 120550.8 123243.4 124378.1 127652.0 11318.5 11334.5 11487.1 11517.6 11718.0 14405.5 14405.9 14443.8 14454.3 14465.6 8360.2 8500.9 8566.1 8581.3 8680.3 8507.1 8518.2 8660.9 8692.3 8704.0 4417.7 4498.2 4505.1 4508.2 4602.1 1622.5 1855.1 1897.6 1904.8 1916.0 708.8 724.9 781.7 885.8 939.0 18582.0 19195.9 20840.3 20969.7 21105.5 831.4 884.4 892.9 927.6 944.3 8518.2 10422.7 10698.0 10898.1 10910.3 531247.0 590295.0 598077.0 606699.4 611516.0 86166.0 129107.0 133207.0 134330.0 138834.0 941.3 949.0 971.8 973.6 978.4 18035.2 18168.8 18177.0 18249.9 18300.6 49376.3 55175.0 55914.0 56056.0 56059.0 66478.7 78290.0 79177.2 81186.6 82018.8 6957.3 7805.1 7903.4 7995.8 8290.0 10650.7 12926.1 13080.0 13097.9 13335.5 10682.3 10727.5 11277.5 11347.4 11368.6 9870.7 10681.6 11153.7 11359.7 11561.8 11225.3 11567.0 11669.2 11770.2 11886.1 6926.8 8074.4 8133.3 8193.9 8293.2 9298.8 9854.0 9940.5 11316.5 11457.9 05:04:01 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | [#3] mootools.html Cycles:180 Average:5667.04 Median:5067.63 stddev:3179.66 (62.7%) stddev-sans-first:3172.10 05:04:01 INFO - PROCESS | 2243 | Values: 1359.2 1524.2 1720.6 1720.8 1896.6 8375.7 8852.5 8896.8 8905.0 8919.3 7331.4 7568.8 7796.8 8042.6 8651.6 2132.4 2164.5 2269.8 2361.5 2363.9 2343.9 2344.1 2351.4 2355.2 2364.8 2331.1 2339.3 2378.1 2380.3 2380.8 2341.2 2349.0 2351.8 2366.8 2367.4 2289.5 2303.6 2324.5 2328.6 2330.3 8641.0 8791.8 8792.5 8800.2 8835.9 5209.7 5376.4 5387.1 5388.1 5394.1 3426.7 3447.9 3667.9 3674.9 3680.8 1048.1 1079.9 1083.8 1101.9 1104.3 11617.2 11661.9 11669.1 11705.9 11717.8 4406.7 4566.5 4758.2 4766.9 4772.7 9934.5 9936.5 9941.3 9958.2 9962.4 5250.9 5251.2 5323.7 5352.8 5359.5 4780.3 4825.1 4900.8 4901.8 4902.1 3021.1 3166.9 3762.4 3817.6 3884.9 1065.8 1282.8 1285.5 1295.1 1297.4 805.6 854.2 889.4 892.4 921.8 5678.0 5718.8 5824.1 5838.8 6001.7 873.5 922.3 951.7 970.6 992.5 3834.8 3846.2 3916.5 3964.4 3966.3 9102.3 10309.6 10667.5 10749.4 10814.0 10747.8 10750.0 10750.5 10773.2 10774.0 7610.9 7907.5 8197.7 8283.4 8309.8 4738.6 5493.6 6046.3 6069.7 6140.5 9812.9 9813.6 10118.5 10146.0 10152.7 9796.8 9910.7 10138.7 10216.6 10224.8 7427.6 7592.9 7603.4 7606.5 7608.3 8048.3 8244.3 8322.8 8331.7 8352.1 7490.4 7506.9 7511.9 7523.2 7548.5 6303.8 7391.4 7402.8 7406.6 7443.4 6718.4 6942.1 6959.9 7045.0 7068.1 4942.1 5004.3 5014.7 5046.9 5109.2 4954.5 5088.3 5111.7 5147.3 5205.2 05:04:01 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | [#4] prototype.html Cycles:180 Average:4330.11 Median:4647.64 stddev:1355.93 (29.2%) stddev-sans-first:1325.14 05:04:01 INFO - PROCESS | 2243 | Values: 275.6 292.7 309.5 313.6 316.7 5370.2 5396.7 5411.8 5416.0 5432.7 4015.0 4343.0 5044.0 5260.8 5277.6 3624.7 3911.8 4065.9 4068.6 4075.2 4071.7 4080.8 4085.3 4121.0 4132.4 3129.1 3418.1 3822.4 3955.7 4060.8 3371.4 4005.2 4027.3 4038.3 4068.7 3458.4 3502.3 3502.8 3507.8 3511.2 4494.5 4948.8 5113.3 5156.6 5182.2 3645.3 4253.3 4558.5 4569.2 4582.2 4921.4 5556.4 5577.2 5595.7 5599.1 3800.8 3810.5 3838.5 3840.7 3841.6 5533.8 5629.6 5705.7 5822.2 5822.7 3523.4 3719.8 3750.2 3752.3 3892.8 3901.2 3922.9 3930.3 3936.9 3955.1 3602.7 4284.7 4591.7 4594.7 4622.5 4473.2 5302.9 5558.0 5563.9 5662.7 1233.9 1311.8 1338.9 1345.2 1347.8 745.0 766.5 807.3 820.6 821.4 3868.6 4117.7 4324.5 4402.5 4407.6 4211.5 4348.8 4672.8 4711.6 4740.4 3733.8 3915.0 4069.5 4126.8 4312.9 2230.9 2363.5 2416.0 2493.6 2540.7 3983.0 4427.5 4801.8 4866.8 4878.5 4028.9 4758.0 4776.6 4818.1 4846.8 4766.6 4866.0 5417.9 6077.6 6123.8 5152.6 5683.7 5686.5 5911.5 5940.9 5122.5 5401.8 5543.1 5652.4 5804.0 4709.6 4970.4 5266.9 5850.0 5921.8 3534.5 3669.8 3752.2 3769.2 3876.5 4398.6 5034.4 5493.0 5565.6 5835.0 4936.7 5320.4 5341.7 5684.9 5709.0 4821.8 5649.4 5658.8 5662.0 5667.6 5178.9 5528.4 5547.7 5563.7 5610.7 5315.4 5618.8 5638.0 5674.9 5702.6 4231.6 5352.4 5451.5 5477.6 5509.7 05:04:01 INFO - PROCESS | 2243 | 05:04:01 INFO - PROCESS | 2243 | [#5] yui.html Cycles:180 Average:24764.17 Median:3620.95 stddev:39498.31 (1090.8%) stddev-sans-first:39567.58 05:04:01 INFO - PROCESS | 2243 | Values: 638.9 714.5 752.2 770.9 824.8 15145.3 15838.1 15839.7 15853.2 15877.0 14164.8 14289.5 14290.8 14296.6 14313.6 642.8 669.3 670.1 828.8 855.9 820.5 839.8 848.1 854.5 856.2 892.2 893.9 894.1 895.6 895.8 684.0 886.3 889.1 890.6 890.9 530.7 645.5 664.4 668.4 668.9 14637.9 14688.1 14690.0 14693.8 14697.2 13225.5 13634.3 13657.5 13773.0 14018.4 12027.6 12193.9 12241.9 12258.3 12769.5 4407.0 4429.6 4463.8 4523.1 4540.3 119872.4 120412.3 120562.0 120725.0 121940.0 10569.3 10630.5 10710.0 10791.3 10818.4 21146.2 21161.6 21192.7 21199.7 21310.6 7446.8 7628.5 7636.4 7699.7 7798.2 4136.3 4159.8 4209.3 4229.1 4350.9 3452.9 3615.9 3619.8 3622.1 3622.3 673.6 674.1 678.9 692.8 749.3 521.9 605.4 643.2 664.0 673.3 8773.7 9328.4 9439.8 9504.4 9639.6 530.8 626.3 632.7 658.0 680.6 4342.1 4397.4 4526.0 4536.5 4560.8 75114.0 92884.0 128574.0 139213.8 165357.0 131018.0 135729.0 135775.0 138315.0 139448.0 115684.0 116308.0 116758.0 118535.0 118726.4 8504.5 8556.7 8703.1 8850.7 9087.6 46713.3 62509.7 63165.0 64356.7 66251.0 77013.2 89318.0 99158.0 99394.0 101528.0 11221.4 11780.6 12180.7 12756.6 13432.3 15204.9 15249.9 15301.9 15781.5 15866.5 13964.4 14022.9 14113.4 14313.9 14651.0 13880.9 13987.0 14244.2 14297.7 14521.9 13574.5 13706.8 13721.9 13726.5 13752.4 10558.3 10919.7 11245.7 11376.3 12024.2 9557.5 10562.4 10840.5 11202.9 11477.3 05:04:01 INFO - PROCESS | 2243 | -------- Summary: end -------- 05:04:01 INFO - PROCESS | 2243 | 05:04:02 INFO - TEST-INFO | 2243: exit 0 05:04:02 INFO - TEST-OK | dromaeo_css | took 1118928ms 05:04:02 INFO - TEST-START | kraken 05:04:02 INFO - operating with platform_type : mac_ 05:04:02 INFO - Initialising browser for kraken test... 05:04:02 INFO - TEST-INFO | started process 2245 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpiG9LlE/profile http://localhost:53340/getInfo.html) 05:04:04 INFO - PROCESS | 2245 | __metrics Screen width/height:1600/1200 05:04:04 INFO - PROCESS | 2245 | colorDepth:24 05:04:04 INFO - PROCESS | 2245 | Browser inner width/height: 1024/689 05:04:04 INFO - PROCESS | 2245 | __metrics 05:04:04 INFO - PROCESS | 2245 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:04:04 INFO - PROCESS | 2245 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:04:05 INFO - TEST-INFO | 2245: exit 0 05:04:05 INFO - Browser initialized. 05:04:05 INFO - Running cycle 1/1 for kraken test... 05:04:05 INFO - TEST-INFO | started process 2246 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpiG9LlE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:18 INFO - PROCESS | 2246 | RSS: Main: 234971136 05:04:18 INFO - PROCESS | 2246 | 05:04:20 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53340/tests/kraken/driver.html?testName=audio-beat-detection) 05:04:21 INFO - PROCESS | 2246 | RSS: Main: 295694336 05:04:21 INFO - PROCESS | 2246 | 05:04:24 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53340/tests/kraken/driver.html?testName=audio-dft) 05:04:24 INFO - PROCESS | 2246 | RSS: Main: 294678528 05:04:24 INFO - PROCESS | 2246 | 05:04:28 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53340/tests/kraken/driver.html?testName=audio-fft) 05:04:29 INFO - PROCESS | 2246 | RSS: Main: 385945600 05:04:29 INFO - PROCESS | 2246 | 05:04:31 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53340/tests/kraken/driver.html?testName=audio-oscillator) 05:04:31 INFO - PROCESS | 2246 | RSS: Main: 287690752 05:04:31 INFO - PROCESS | 2246 | 05:04:33 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53340/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:04:34 INFO - PROCESS | 2246 | RSS: Main: 288792576 05:04:34 INFO - PROCESS | 2246 | 05:04:37 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53340/tests/kraken/driver.html?testName=imaging-darkroom) 05:04:37 INFO - PROCESS | 2246 | RSS: Main: 326180864 05:04:37 INFO - PROCESS | 2246 | 05:04:40 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53340/tests/kraken/driver.html?testName=imaging-desaturate) 05:04:41 INFO - PROCESS | 2246 | RSS: Main: 327778304 05:04:41 INFO - PROCESS | 2246 | 05:04:44 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53340/tests/kraken/driver.html?testName=json-parse-financial) 05:04:44 INFO - PROCESS | 2246 | RSS: Main: 325042176 05:04:44 INFO - PROCESS | 2246 | 05:04:46 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53340/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:04:47 INFO - PROCESS | 2246 | RSS: Main: 302092288 05:04:47 INFO - PROCESS | 2246 | 05:04:48 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53340/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:04:49 INFO - PROCESS | 2246 | RSS: Main: 302379008 05:04:49 INFO - PROCESS | 2246 | 05:04:51 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53340/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:04:52 INFO - PROCESS | 2246 | RSS: Main: 312037376 05:04:52 INFO - PROCESS | 2246 | 05:04:55 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53340/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:04:56 INFO - PROCESS | 2246 | RSS: Main: 325210112 05:04:56 INFO - PROCESS | 2246 | 05:04:59 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53340/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:04:59 INFO - PROCESS | 2246 | RSS: Main: 318459904 05:04:59 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | Cycle 1(1): loaded http://localhost:53340/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53340/tests/kraken/driver.html?testName=ai-astar) 05:05:01 INFO - PROCESS | 2246 | RSS: Main: 362717184 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | __start_tp_report 05:05:01 INFO - PROCESS | 2246 | _x_x_mozilla_page_load 05:05:01 INFO - PROCESS | 2246 | _x_x_mozilla_page_load_details 05:05:01 INFO - PROCESS | 2246 | |i|pagename|runs| 05:05:01 INFO - PROCESS | 2246 | |0;ai-astar;113;113;113;112;115;152;165;119;113;111 05:05:01 INFO - PROCESS | 2246 | |1;audio-beat-detection;152;112;111;148;114;114;112;114;142;112 05:05:01 INFO - PROCESS | 2246 | |2;audio-dft;181;185;184;185;183;184;184;180;242;182 05:05:01 INFO - PROCESS | 2246 | |3;audio-fft;80;63;82;112;90;58;59;60;101;80 05:05:01 INFO - PROCESS | 2246 | |4;audio-oscillator;90;71;95;72;68;67;91;67;68;89 05:05:01 INFO - PROCESS | 2246 | |5;imaging-gaussian-blur;87;83;86;84;86;83;86;84;87;83 05:05:01 INFO - PROCESS | 2246 | |6;imaging-darkroom;93;101;89;135;146;93;92;92;113;107 05:05:01 INFO - PROCESS | 2246 | |7;imaging-desaturate;83;86;85;86;94;86;85;86;85;85 05:05:01 INFO - PROCESS | 2246 | |8;json-parse-financial;56;54;53;54;54;55;54;54;55;54 05:05:01 INFO - PROCESS | 2246 | |9;json-stringify-tinderbox;65;72;69;76;72;69;69;70;70;70 05:05:01 INFO - PROCESS | 2246 | |10;stanford-crypto-aes;87;89;88;89;86;86;89;92;85;87 05:05:01 INFO - PROCESS | 2246 | |11;stanford-crypto-ccm;151;159;155;149;149;159;154;148;184;147 05:05:01 INFO - PROCESS | 2246 | |12;stanford-crypto-pbkdf2;174;171;172;170;171;172;171;194;171;171 05:05:01 INFO - PROCESS | 2246 | |13;stanford-crypto-sha256-iterative;69;70;70;68;68;67;68;67;67;67 05:05:01 INFO - PROCESS | 2246 | __end_tp_report 05:05:01 INFO - PROCESS | 2246 | __start_cc_report 05:05:01 INFO - PROCESS | 2246 | _x_x_mozilla_cycle_collect,2005 05:05:01 INFO - PROCESS | 2246 | __end_cc_report 05:05:01 INFO - PROCESS | 2246 | __startTimestamp1476101101611__endTimestamp 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | ------- Summary: start ------- 05:05:01 INFO - PROCESS | 2246 | Number of tests: 14 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#0] ai-astar Cycles:10 Average:122.60 Median:114.00 stddev:19.29 (16.9%) stddev-sans-first:20.14 05:05:01 INFO - PROCESS | 2246 | Values: 113.0 113.0 113.0 112.0 115.0 152.0 165.0 119.0 113.0 111.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:114.00 stddev:16.92 (14.8%) stddev-sans-first:14.36 05:05:01 INFO - PROCESS | 2246 | Values: 152.0 112.0 111.0 148.0 114.0 114.0 112.0 114.0 142.0 112.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#2] audio-dft Cycles:10 Average:189.00 Median:184.00 stddev:18.70 (10.2%) stddev-sans-first:19.61 05:05:01 INFO - PROCESS | 2246 | Values: 181.0 185.0 184.0 185.0 183.0 184.0 184.0 180.0 242.0 182.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#3] audio-fft Cycles:10 Average:78.50 Median:71.50 stddev:18.74 (26.2%) stddev-sans-first:19.87 05:05:01 INFO - PROCESS | 2246 | Values: 80.0 63.0 82.0 112.0 90.0 58.0 59.0 60.0 101.0 80.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:80.50 stddev:11.78 (14.6%) stddev-sans-first:11.64 05:05:01 INFO - PROCESS | 2246 | Values: 90.0 71.0 95.0 72.0 68.0 67.0 91.0 67.0 68.0 89.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.58 05:05:01 INFO - PROCESS | 2246 | Values: 87.0 83.0 86.0 84.0 86.0 83.0 86.0 84.0 87.0 83.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#6] imaging-darkroom Cycles:10 Average:106.10 Median:90.50 stddev:19.82 (21.9%) stddev-sans-first:20.45 05:05:01 INFO - PROCESS | 2246 | Values: 93.0 101.0 89.0 135.0 146.0 93.0 92.0 92.0 113.0 107.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.00 stddev:2.92 (3.4%) stddev-sans-first:2.88 05:05:01 INFO - PROCESS | 2246 | Values: 83.0 86.0 85.0 86.0 94.0 86.0 85.0 86.0 85.0 85.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.60 05:05:01 INFO - PROCESS | 2246 | Values: 56.0 54.0 53.0 54.0 54.0 55.0 54.0 54.0 55.0 54.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.00 stddev:2.82 (4.0%) stddev-sans-first:2.28 05:05:01 INFO - PROCESS | 2246 | Values: 65.0 72.0 69.0 76.0 72.0 69.0 69.0 70.0 70.0 70.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:88.50 stddev:2.04 (2.3%) stddev-sans-first:2.15 05:05:01 INFO - PROCESS | 2246 | Values: 87.0 89.0 88.0 89.0 86.0 86.0 89.0 92.0 85.0 87.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:154.50 stddev:10.92 (7.1%) stddev-sans-first:11.46 05:05:01 INFO - PROCESS | 2246 | Values: 151.0 159.0 155.0 149.0 149.0 159.0 154.0 148.0 184.0 147.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:171.50 stddev:7.21 (4.2%) stddev-sans-first:7.65 05:05:01 INFO - PROCESS | 2246 | Values: 174.0 171.0 172.0 170.0 171.0 172.0 171.0 194.0 171.0 171.0 05:05:01 INFO - PROCESS | 2246 | 05:05:01 INFO - PROCESS | 2246 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.20 (1.8%) stddev-sans-first:1.22 05:05:01 INFO - PROCESS | 2246 | Values: 69.0 70.0 70.0 68.0 68.0 67.0 68.0 67.0 67.0 67.0 05:05:01 INFO - PROCESS | 2246 | -------- Summary: end -------- 05:05:01 INFO - PROCESS | 2246 | 05:05:02 INFO - TEST-INFO | 2246: exit 0 05:05:02 INFO - TEST-OK | kraken | took 60047ms 05:05:02 INFO - SUITE-END | took 1178s 05:05:02 INFO - Completed test suite (00:19:39) 05:05:02 INFO - javascript benchmark 05:05:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2764.9585256221153, 2989.656189538203, 3316.9834150829242, 3324.9168770780743, 3356.976122282703, 5509.559235261179, 5580.637258578189, 5634.802781902634, 5702.344230413503, 5723.914141287879, 4190.853320133796, 5608.803691870786, 5637.689927054021, 5765.164051212571, 5863.413658634145, 5123.0, 5171.896562068757, 5201.68789872607, 5209.947900521003, 5238.52853243208, 5093.745312734367, 5253.474652534755, 5272.051030814471, 5280.102382594949, 5335.946640533603, 5258.894822103574, 5265.473452654742, 5278.049951008817, 5293.841184764444, 5329.973350133263, 5106.719130447824, 5134.7689353978785, 5166.199239117943, 5170.663906846043, 5210.114280572312, 5111.87220319495, 5153.201253805666, 5170.793168273264, 5177.197534382177, 5177.792888284464, 5696.544276457915, 5705.77176912923, 5708.20085188074, 5711.685857277848, 5737.426257374229, 6097.131491098847, 6109.114178444101, 6177.227846519185, 6179.721912513947, 6241.812745617639, 5297.0, 6197.845053873644, 6217.875642487125, 6258.123862659231, 6266.216722909637, 5678.176664383683, 5769.221155144044, 5792.739326730307, 5802.3037235531165, 5808.215890854681, 5525.392206857243, 6174.691265436711, 6177.598456100429, 6228.688565571703, 6268.780592679234, 6089.5737298388685, 6114.0, 6124.479419249325, 6134.171886795315, 6136.9079463808075, 6202.937970620236, 6205.937940620626, 6212.223472065992, 6219.782307619212, 6227.875442491125, 6072.635641861502, 6092.055523033665, 6113.476306051762, 6130.785422510191, 6135.851732731318, 6233.0, 6234.407731265523, 6237.781677641261, 6241.438270555672, 6248.125262463259, 4834.347363105936, 4928.310036594879, 4932.482089380564, 4940.8764780880765, 4972.57733092684, 4390.121975604892, 4398.428204333416, 4419.292913133948, 4465.352523884018, 4476.753778542212, 5635.633683810539, 5719.28508936383, 5734.369219385864, 5747.051736463437, 5779.190913272145, 6208.348123447064, 6258.687065646699, 6260.154879091356, 6273.811785646438, 6286.277078136073, 4799.688020278671, 5731.996900542388, 5749.798757043483, 5754.88490230193, 5765.596408251382, 5261.237120617489, 5363.275957745733, 5391.245225668409, 5421.132618780976, 5421.484958928895, 6163.9383606164265, 6258.186435763321, 6265.4361107500545, 6344.238691357066, 6353.142325786144, 6263.498920086473, 6277.5605707600025, 6285.2457705075685, 6286.7799627014665, 6289.842753931005, 6154.230721159855, 6272.937270627235, 6332.335104813928, 6334.968325158345, 6350.301466838551, 6061.363556826646, 6106.460667179974, 6107.370879189235, 6113.816585502442, 6192.380761923771, 6054.8183554493435, 6244.65654389013, 6252.187215662117, 6256.31180570146, 6264.5301602379095, 5862.443067908542, 5865.794697185749, 5896.616719913192, 6057.273127224761, 6091.0558863376245, 5994.0, 6038.305594856648, 6063.333033366238, 6087.726052327567, 6099.4815440688035, 5925.200097986716, 5935.762569497172, 5948.910766338422, 5957.791477298274, 5981.222441082631, 6088.117223002551, 6098.024316109401, 6109.144719739329, 6109.877802444015, 6122.0, 5724.141378793048, 5728.083506638918, 5865.208196893364, 5893.941060589339, 5926.851828704317], "value": 5652.571474782529, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.2621978614431, 461.2182350915199, 498.0636403561304, 509.4726957598886, 535.3976776126857, 29367.55948660769, 38620.0, 39826.40260396093, 39947.20105597886, 39974.60025399745, 40425.59574404255, 40974.18051638966, 41367.758967230955, 41451.37822932658, 41471.58528414715, 8385.245327920486, 8615.741527754162, 9781.0, 9801.460919649417, 9820.0, 9620.230381569458, 9712.174465170434, 9719.125278724914, 9728.124468797807, 9756.121949024586, 9691.030896910288, 9746.658866939659, 9762.414255144678, 9764.511774411287, 9773.853392199087, 9642.325037247396, 9656.082672146134, 9698.612055517771, 9722.513874306293, 9723.562439690231, 9607.13535781776, 9699.175570076552, 9703.708888733348, 9714.90285097147, 9716.271279654055, 39822.0, 40165.0, 40171.79914100411, 40184.59815401867, 40198.0, 3349.815760133192, 3647.8905632831056, 3933.9213215735813, 3936.193080418507, 4073.775942323171, 29703.70296297031, 30736.69263307383, 31179.688203118134, 31446.842765786023, 31461.213469663213, 3201.0876900083363, 3301.108700650811, 3323.6842499962463, 3393.5079413484905, 3394.932101357984, 483492.0, 483894.86893614085, 483994.0, 486940.0, 497587.0, 57983.710081449324, 58229.70885145632, 58257.0, 58296.0, 58445.70777146087, 61100.388996110356, 61179.0, 61204.081938770956, 61269.69365153235, 61295.38704612897, 13381.46474141043, 15932.283047262901, 16143.0, 16165.515034548982, 16203.0, 12517.186382885084, 12602.432890519949, 12604.936975315064, 12609.747805044031, 12618.81071783924, 14310.928445357707, 15766.9211653941, 16025.278862450985, 16059.678806423806, 16100.0, 5559.305086864142, 6631.668416579152, 6658.6337751424135, 6729.158855143107, 6771.424428923498, 764.2930289482318, 863.9870401943975, 926.8424367857345, 1030.5105075089273, 1041.1982773264544, 27840.582391264146, 28976.71023289782, 29015.85492072526, 29787.404251915275, 29918.401631967674, 923.4274749655257, 1011.079917275276, 1036.0, 1123.230587047875, 1159.5419809175328, 13534.796978045337, 15073.547793566211, 15241.0, 15321.004134731207, 15518.0, 624015.0, 628440.8577957172, 628531.0, 628644.0, 628884.0, 55597.444025560035, 55826.72086639541, 55838.441615583324, 55897.44102559004, 55977.0, 1110.7278716714295, 1251.2054845173197, 1310.2269660900115, 1316.5457917018614, 1361.8297712785902, 26194.345141371618, 29003.709962900946, 30615.0, 30818.0, 31590.368192636477, 47660.0, 48312.0, 48373.75813120912, 48435.0, 48542.51457485521, 520336.0, 522632.0, 523203.0, 523948.0, 524355.0, 24593.87703061473, 25368.873155634104, 25420.872895635403, 25497.362565936, 25525.0, 45936.31095533503, 46037.01187097763, 46301.0, 46724.299135512316, 47493.52506474891, 38273.425898610985, 38448.19744398371, 38592.0, 39333.3300006496, 39715.60284397119, 37836.86489405322, 38825.58462698971, 38967.41548876826, 39249.60750392459, 39265.80367098146, 33370.33259334848, 36596.63403365932, 36752.44871326879, 37402.43896341503, 37479.43780843235, 32369.028929132164, 33926.491102633976, 33970.15074623154, 34139.4879076819, 34316.82841585776, 22675.319740407813, 24883.75116248887, 25023.873925673754, 25145.748542514342, 25570.360740981625], "value": 19458.570681267833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.6496826102374, 351.96832285094337, 367.6433859156617, 374.6178897524525, 403.7819577428189, 14415.423383064677, 14447.699707026366, 15849.920750396246, 16655.0, 21082.156713731434, 19255.807441925575, 19660.213591456326, 20588.897055514703, 20639.071241794118, 20898.059587318607, 4843.297721830332, 5314.176302673091, 5515.779368825242, 5619.887602247973, 5634.661920284796, 4560.8859778505475, 5539.695316757577, 6132.41742034506, 6173.32093469718, 6206.906896396557, 6163.445289923906, 6169.938300616981, 6171.783987560436, 6174.722137503823, 6176.505879529649, 5317.494837990384, 5488.313960754886, 5537.889242215173, 5545.889082218333, 5551.694656793875, 5104.234364845266, 5374.758135883894, 5382.434844341327, 5383.703896285702, 5386.461353864622, 13099.2140471572, 13379.063465557414, 19861.10625021863, 24109.276721698378, 25787.484250315076, 2140.886738895781, 2282.0757593174626, 2372.905083796646, 2449.39989702524, 2554.386947132675, 9305.973205990178, 9443.197328227108, 9686.41881487106, 9993.70018899427, 10300.712410948632, 1549.628089258581, 1807.8463330616914, 1870.1303893689499, 1876.8780029298189, 1900.6483800496815, 114620.85379146268, 120550.79449205483, 123243.3837830814, 124378.13432798516, 127652.0, 11318.547258109747, 11334.546618135348, 11487.13846461502, 11517.596884109016, 11718.0, 14405.495807646683, 14405.85594144066, 14443.78334324986, 14454.349554270084, 14465.638359041108, 8360.247577718035, 8500.914990850013, 8566.100559441293, 8581.283743251375, 8680.34897382699, 8507.064222935473, 8518.233358997595, 8660.8700869487, 8692.261157801659, 8704.0, 4417.702794546882, 4498.212812757754, 4505.062822986082, 4508.188526065274, 4602.102589994919, 1622.4689484404132, 1855.1280897977929, 1897.5825318429922, 1904.7714274286898, 1915.9521011974812, 708.8198150080162, 724.8695234857671, 781.6638845296463, 885.8272636835756, 939.0468674295596, 18581.977991210344, 19195.904020479808, 20840.255638872834, 20969.68545471819, 21105.472363191042, 831.4138532334689, 884.3853521802414, 892.8616064510011, 927.6150397584927, 944.2729098594044, 8518.222482586787, 10422.739431514272, 10698.0, 10898.128149748001, 10910.322230466387, 531247.0, 590295.0, 598077.0, 606699.4305484083, 611516.0, 86166.0, 129107.0, 133207.0, 134330.0, 138834.0, 941.2940294778916, 948.9572969216401, 971.7813491964251, 973.5910917414561, 978.3933960944261, 18035.188416521025, 18168.818311817242, 18177.0, 18249.90875045616, 18300.633987320445, 49376.25935610897, 55175.0, 55914.0, 56056.0, 56059.0, 66478.66760666166, 78290.0, 79177.20822791928, 81186.59406702929, 82018.76971845547, 6957.2694867038235, 7805.102413222552, 7903.407244456574, 7995.840083198187, 8289.95855020721, 10650.680479585624, 12926.095173337777, 13080.0, 13097.934510327388, 13335.466581337027, 10682.342686779884, 10727.463626818784, 11277.492512836778, 11347.375894325562, 11368.602098926829, 9870.703878883358, 10681.626143084955, 11153.72115697114, 11359.659210223706, 11561.826572601249, 11225.270357426742, 11566.991075838012, 11669.183157178915, 11770.234934729215, 11886.108541859223, 6926.792196234121, 8074.434789564674, 8133.308668763221, 8193.869593905747, 8293.1706829319, 9298.767530811783, 9853.950730246303, 9940.502974851084, 11316.49075791575, 11457.885421145682], "value": 9252.590170903712, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.2116572388013, 1524.1997951075687, 1720.5956600198951, 1720.7590937268776, 1896.6206758648266, 8375.748727538175, 8852.468851868893, 8896.822063558724, 8905.0, 8919.286457083434, 7331.413486921047, 7568.772936811891, 7796.844063118734, 8042.638081286342, 8651.610677519511, 2132.402927180388, 2164.4697049222964, 2269.7503274639857, 2361.527694461106, 2363.869987150706, 2343.9179628713, 2344.132670911765, 2351.412146963259, 2355.1521452277166, 2364.8344615876895, 2331.079223706635, 2339.263132113387, 2378.0725517048363, 2380.250221180331, 2380.8095352371856, 2341.157183413987, 2348.9530209395803, 2351.776581224781, 2366.810655147601, 2367.43181636406, 2289.461976435553, 2303.573838839803, 2324.5467133908896, 2328.639060945556, 2330.3358542815217, 8640.956795215983, 8791.824163516758, 8792.47245165292, 8800.16398442147, 8835.911640883638, 5209.6874187548865, 5376.38171610262, 5387.1111266640955, 5388.0570900092325, 5394.136938089926, 3426.7087297579737, 3447.913802154941, 3667.889963301105, 3674.8530058797614, 3680.7975561344115, 1048.119579553179, 1079.9298045627008, 1083.8428427877914, 1101.895319944604, 1104.3318792130726, 11617.244879082833, 11661.883381166164, 11669.066474682006, 11705.882941170565, 11717.765644687059, 4406.713563618355, 4566.452025756864, 4758.191107511731, 4766.856994290177, 4772.7136371817805, 9934.45360505179, 9936.453495057842, 9941.30410871232, 9958.153556947596, 9962.402255864672, 5250.868728281747, 5251.186066159675, 5323.733813309319, 5352.785888564492, 5359.464053594609, 4780.282957556395, 4825.131476334293, 4900.828471003498, 4901.754912254373, 4902.093112774148, 3021.108772911952, 3166.920826979298, 3762.398016317376, 3817.594915203404, 3884.864029758945, 1065.7548763784373, 1282.7947528395412, 1285.4729560879994, 1295.1322613848745, 1297.4485843516468, 805.6092794994423, 854.2141230068352, 889.4440974391006, 892.4466830564961, 921.8064206516573, 5678.006348888928, 5718.771249150071, 5824.097264923944, 5838.79564215259, 6001.669908155093, 873.5239294584467, 922.2891536200386, 951.743029382063, 970.6214576315243, 992.5235886774389, 3834.789086600208, 3846.249981253629, 3916.4712763776524, 3964.4251583520227, 3966.2662407454136, 9102.317326200562, 10309.639162629423, 10667.519961601747, 10749.40878251688, 10814.0, 10747.83878241827, 10750.0324970753, 10750.462476876124, 10773.192010599236, 10774.0, 7610.885836712454, 7907.48601340911, 8197.672093116327, 8283.37874659403, 8309.792255193668, 4738.620910327235, 5493.587980901369, 6046.274447066205, 6069.726862291119, 6140.508759299156, 9812.901870981199, 9813.558389872616, 10118.494075296352, 10145.985401459795, 10152.695419137437, 9796.804063918824, 9910.702678919644, 10138.695839124837, 10216.642417515352, 10224.795504090025, 7427.554346739213, 7592.8861067082935, 7603.353714934293, 7606.543607383574, 7608.31525162738, 8048.261742431595, 8244.299234564889, 8322.833543328978, 8331.708390206313, 8352.123027082069, 7490.363319117936, 7506.887396689163, 7511.887321690288, 7523.172451030492, 7548.509346892434, 6303.810885673437, 7391.445641576795, 7402.814929626587, 7406.555606663617, 7443.404527637667, 6718.395344419026, 6942.132233470817, 6959.930400695928, 7045.027392330156, 7068.11648543932, 4942.1104201244125, 5004.274380214922, 5014.699118052783, 5046.949530504648, 5109.20807274873, 4954.529319714621, 5088.313077734607, 5111.667741596784, 5147.279380886754, 5205.245239440335], "value": 4553.830941434937, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.5797408951349, 292.6765923654362, 309.4939773470375, 313.64401404405993, 316.702299838152, 5370.167624018279, 5396.676199428032, 5411.809401931569, 5415.972920135398, 5432.728363581825, 4015.0, 4343.022819865529, 5043.974780126104, 5260.763265653045, 5277.577793776498, 3624.655657712526, 3911.8239679214503, 4065.857694980676, 4068.6338229559333, 4075.1645912587996, 4071.6539094176883, 4080.8369614659678, 4085.3445260580565, 4120.958790412103, 4132.359484279941, 3129.092563156682, 3418.1112910643287, 3822.4075268333454, 3955.683545316382, 4060.7563546187325, 3371.393149233137, 4005.218982298453, 4027.254957832781, 4038.273110840062, 4068.65416439603, 3458.412069948115, 3502.2995400919917, 3502.807345595991, 3507.7719948203285, 3511.227529943408, 4494.505604383515, 4948.822518092028, 5113.309703190096, 5156.561692256163, 5182.196759502283, 3645.270945810822, 4253.27838141626, 4558.475775285851, 4569.223232050559, 4582.243929751587, 4921.434035085975, 5556.388797232301, 5577.2191893135, 5595.664260144405, 5599.1041433370465, 3800.8289626966853, 3810.504634397538, 3838.539375274957, 3840.7311488195837, 3841.5774264830848, 5533.833984980457, 5629.634073785191, 5705.71471426427, 5822.243108395882, 5822.719001819593, 3523.418635925095, 3719.758215716024, 3750.1749615084636, 3752.3433399155037, 3892.8053597320586, 3901.1612503311358, 3922.9408059823686, 3930.272899513599, 3936.8622098226997, 3955.110100227483, 3602.693771029492, 4284.700070995063, 4591.747453890068, 4594.6783725139585, 4622.514635963175, 4473.217186992263, 5302.92045619316, 5558.0, 5563.916541251884, 5662.745176467069, 1233.8766123387768, 1311.7769979103384, 1338.872807083345, 1345.186162371751, 1347.7708789505807, 744.9776506704808, 766.4826242286434, 807.2653884964772, 820.6389188756928, 821.3552361396395, 3868.5551161616213, 4117.691173162025, 4324.459442569679, 4402.471703395613, 4407.603315701603, 4211.536730959592, 4348.8258170294475, 4672.766361681903, 4711.607642497465, 4740.3837501124635, 3733.831977561016, 3915.0, 4069.491313585802, 4126.834926602963, 4312.892177695583, 2230.8661480311234, 2363.4564050268536, 2415.997361095126, 2493.551160791075, 2540.6697129373065, 3983.0483063299353, 4427.468703755506, 4801.830791677476, 4866.78099485524, 4878.463369029404, 4028.9436756870527, 4757.976210118927, 4776.5701086902345, 4818.1209328361965, 4846.75766211688, 4766.594839438617, 4865.956298354699, 5417.864553386197, 6077.6353418795015, 6123.805857857764, 5152.562032227303, 5683.71581420919, 5686.545076393962, 5911.527077833849, 5940.851478713067, 5122.487751224848, 5401.784598465314, 5543.113101903676, 5652.434756524314, 5804.0, 4709.576138147583, 4970.428400733817, 5266.946610677803, 5849.970750146393, 5921.822345329647, 3534.469829525489, 3669.8348574313686, 3752.180192606529, 3769.2273084018257, 3876.4687948260844, 4398.560143985575, 5034.421041580265, 5492.978861626169, 5565.610407271614, 5834.970825145847, 4936.7284799336385, 5320.419596495619, 5341.679499230058, 5684.9431505684415, 5708.971455142698, 4821.831235906727, 5649.406812284651, 5658.830235092953, 5662.03657439802, 5667.57111947266, 5178.886539394049, 5528.391876893619, 5547.694876781816, 5563.721813909369, 5610.663360198401, 5315.3621565412395, 5618.803341883015, 5637.9718101409235, 5674.914876276776, 5702.60081794287, 4231.555686652857, 5352.35771707382, 5451.48210919962, 5477.616566840283, 5509.696966666872], "value": 3901.1133578807485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.9266033064456, 714.4712912444791, 752.1538269446869, 770.9383249340053, 824.8432797768423, 15145.318460669268, 15838.128902910335, 15839.683206335867, 15853.20733963303, 15877.0, 14164.787528187086, 14289.499867504639, 14290.78563821541, 14296.571102866905, 14313.642158946006, 642.8360768004152, 669.3206395508563, 670.088679396021, 828.7886588919832, 855.9315254779634, 820.5281962871343, 839.7606682095604, 848.101012926297, 854.5171977832536, 856.2293935458107, 892.2371372476537, 893.9362159030765, 894.061235702513, 895.6014573514789, 895.847705889997, 683.962382068986, 886.2909672262165, 889.0753616239103, 890.6481939633841, 890.8708237305555, 530.6948504609865, 645.4578154350369, 664.3588936676131, 668.3750693101965, 668.8729141463156, 14637.92681036588, 14688.118712877154, 14690.0, 14693.77959330611, 14697.191654459, 13225.470981160839, 13634.250116243602, 13657.453701851913, 13773.0, 14018.36917338712, 12027.639170824888, 12193.878061219362, 12241.93879030608, 12258.26450412978, 12769.48922043117, 4407.016842420923, 4429.557044295577, 4463.799129039201, 4523.050159466516, 4540.318952157169, 119872.40063799713, 120412.34639496898, 120562.0, 120725.0, 121940.0, 10569.260151789302, 10630.468476576294, 10709.98255165758, 10791.305781984862, 10818.404987725593, 21146.154153833675, 21161.576768464543, 21192.682109768364, 21199.68200476994, 21310.573788524143, 7446.776596702108, 7628.504147230523, 7636.427267954926, 7699.730509432255, 7798.220177982156, 4136.296829538986, 4159.812808423628, 4209.34755112958, 4229.069604686964, 4350.891227719332, 3452.896413107611, 3615.8553657853918, 3619.8009109498703, 3622.094476380905, 3622.2755448909797, 673.5958424944994, 674.1472037872096, 678.9355011273921, 692.8025512728848, 749.2619769527012, 521.8512723873678, 605.3643674142133, 643.2474005413677, 663.9601623902581, 673.3170797106212, 8773.736787896245, 9328.440293582407, 9439.808824039827, 9504.429734215832, 9639.566219520138, 530.7823792245161, 626.2985456288985, 632.7279269913889, 657.9572327798678, 680.6154522694661, 4342.131573685276, 4397.428334316518, 4525.954740452619, 4536.478304994967, 4560.794764235618, 75114.0, 92884.0, 128574.0, 139213.82358529262, 165357.0, 131018.0, 135729.0, 135775.0, 138315.0, 139447.97627666531, 115684.0, 116308.0, 116758.0, 118535.0, 118726.4063679676, 8504.489730616182, 8556.74329770108, 8703.086175951687, 8850.690225842065, 9087.59105840252, 46713.29930050984, 62509.68745156245, 63165.0, 64356.67821660862, 66251.0, 77013.2298677006, 89318.0, 99158.0, 99394.04241703011, 101528.0, 11221.438928053727, 11780.646580602595, 12180.695482613006, 12756.61730148097, 13432.261225632685, 15204.923975380494, 15249.923750381176, 15301.923490382476, 15781.526554203392, 15866.52400427989, 13964.4414223434, 14022.929885350508, 14113.435462581792, 14313.856861431252, 14651.047681900642, 13880.861191388361, 13987.0, 14244.21656808844, 14297.714045719236, 14521.92739036298, 13574.524891628746, 13706.794398084236, 13721.862781372058, 13726.450941962608, 13752.449902003807, 10558.260921735406, 10919.689637243582, 11245.69285774829, 11376.260543064674, 12024.158308918642, 9557.52212389364, 10562.366258024544, 10840.512176952214, 11202.887971120184, 11477.311361318347], "value": 7778.050796633597, "unit": "score"}], "name": "dromaeo_css", "value": 7211.362152241695, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 115.0, 152.0, 165.0, 119.0, 113.0, 111.0], "value": 122.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 112.0, 111.0, 148.0, 114.0, 114.0, 112.0, 114.0, 142.0, 112.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 184.0, 185.0, 183.0, 184.0, 184.0, 180.0, 242.0, 182.0], "value": 189.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 63.0, 82.0, 112.0, 90.0, 58.0, 59.0, 60.0, 101.0, 80.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 95.0, 72.0, 68.0, 67.0, 91.0, 67.0, 68.0, 89.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 84.0, 86.0, 83.0, 86.0, 84.0, 87.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 101.0, 89.0, 135.0, 146.0, 93.0, 92.0, 92.0, 113.0, 107.0], "value": 106.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 86.0, 94.0, 86.0, 85.0, 86.0, 85.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 54.0, 54.0, 55.0, 54.0, 54.0, 55.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 69.0, 76.0, 72.0, 69.0, 69.0, 70.0, 70.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 88.0, 89.0, 86.0, 86.0, 89.0, 92.0, 85.0, 87.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 159.0, 155.0, 149.0, 149.0, 159.0, 154.0, 148.0, 184.0, 147.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 172.0, 170.0, 171.0, 172.0, 171.0, 194.0, 171.0, 171.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 67.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1477.7, "alertThreshold": 2.0}]} 05:05:02 INFO - Return code: 0 05:05:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:05:02 INFO - # TBPL SUCCESS # 05:05:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:02 INFO - 2016-10-10 05:05:02.942 screenresolution[2250:24431] starting screenresolution argv=screenresolution get 05:05:02 INFO - 2016-10-10 05:05:02.945 screenresolution[2250:24431] Display 0: 1600x1200x32@60 05:05:02 INFO - 2016-10-10 05:05:02.951 screenresolution[2251:24434] starting screenresolution argv=screenresolution list 05:05:02 INFO - Available Modes on Display 0 05:05:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:03 INFO - Intel Iris: 05:05:03 INFO - Chipset Model: Intel Iris 05:05:03 INFO - Type: GPU 05:05:03 INFO - Bus: Built-In 05:05:03 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:03 INFO - Vendor: Intel (0x8086) 05:05:03 INFO - Device ID: 0x0a2e 05:05:03 INFO - Revision ID: 0x0009 05:05:03 INFO - Displays: 05:05:03 INFO - Display: 05:05:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:03 INFO - Display Serial Number: 1600x1200 60 05:05:03 INFO - Main Display: Yes 05:05:03 INFO - Mirror: Off 05:05:03 INFO - Online: Yes 05:05:03 INFO - Rotation: Supported 05:05:03 INFO - Return code: 0 05:05:03 INFO - Running post-action listener: _resource_record_post_action 05:05:03 INFO - [mozharness: 2016-10-10 12:05:03.186811Z] Finished run-tests step (success) 05:05:03 INFO - Running post-run listener: _resource_record_post_run 05:05:03 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 507904; Write bytes: 709945856; Read time: 4; Write time: 2073 05:05:03 INFO - TinderboxPrint: CPU usage
53.4% 05:05:03 INFO - TinderboxPrint: I/O read bytes / time
507,904 / 4 05:05:03 INFO - TinderboxPrint: I/O write bytes / time
709,945,856 / 2,073 05:05:03 INFO - TinderboxPrint: CPU idle
2,263.3 (46.6%) 05:05:03 INFO - TinderboxPrint: CPU system
969.0 (19.9%) 05:05:03 INFO - TinderboxPrint: CPU user
1,625.5 (33.5%) 05:05:03 INFO - TinderboxPrint: Swap in / out
407,511,040 / 0 05:05:03 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395698688; Write bytes: 391602688; Read time: 29655; Write time: 771 05:05:03 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 499712; Write bytes: 297293824; Read time: 4; Write time: 1254 05:05:03 INFO - Running post-run listener: _upload_blobber_files 05:05:03 INFO - Blob upload gear active. 05:05:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:05:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:05:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:05:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:05:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:05:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:05 INFO - (blobuploader) - INFO - Done attempting. 05:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:06 INFO - (blobuploader) - INFO - Done attempting. 05:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:05:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:08 INFO - (blobuploader) - INFO - Done attempting. 05:05:08 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:08 INFO - Return code: 0 05:05:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a7eaa5f868616d7bda4c7ba80fd62227fdf77702b95f4282afafb0159fadbfe3942dac85d702c1dd6f0d00068d8377e883cb5fae1521c7df66a2dbb725784b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99ee272e70958be3a0279173a4eb4167ac681c95275d0760d7ce7baa3e3d65052a5b2ba44df79da4caf6ec075853e7ef6ab2e6706aa77e671d8ec2b8ba52d3a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5ca90ba83153484faa33530a6dea922e3d7c085d40a390a156093af67ecbf860cd43433cdc3b75f56f4c27bcae822cae92749e300701aa5749e4feb65fbe40b"} 05:05:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:08 INFO - Contents: 05:05:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a7eaa5f868616d7bda4c7ba80fd62227fdf77702b95f4282afafb0159fadbfe3942dac85d702c1dd6f0d00068d8377e883cb5fae1521c7df66a2dbb725784b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99ee272e70958be3a0279173a4eb4167ac681c95275d0760d7ce7baa3e3d65052a5b2ba44df79da4caf6ec075853e7ef6ab2e6706aa77e671d8ec2b8ba52d3a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5ca90ba83153484faa33530a6dea922e3d7c085d40a390a156093af67ecbf860cd43433cdc3b75f56f4c27bcae822cae92749e300701aa5749e4feb65fbe40b"} 05:05:08 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:08 INFO - Copying logs to upload dir... 05:05:08 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.168889 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-10 05:05:08.955651) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 05:05:08.959324) ========= 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.4U3raE7Kur/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.Wr3zojW4mN/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a7eaa5f868616d7bda4c7ba80fd62227fdf77702b95f4282afafb0159fadbfe3942dac85d702c1dd6f0d00068d8377e883cb5fae1521c7df66a2dbb725784b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99ee272e70958be3a0279173a4eb4167ac681c95275d0760d7ce7baa3e3d65052a5b2ba44df79da4caf6ec075853e7ef6ab2e6706aa77e671d8ec2b8ba52d3a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5ca90ba83153484faa33530a6dea922e3d7c085d40a390a156093af67ecbf860cd43433cdc3b75f56f4c27bcae822cae92749e300701aa5749e4feb65fbe40b"} build_url:https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010913 build_url: 'https://queue.taskcluster.net/v1/task/bE7YhZ3MQ9G-MmLo3Esg0A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a7eaa5f868616d7bda4c7ba80fd62227fdf77702b95f4282afafb0159fadbfe3942dac85d702c1dd6f0d00068d8377e883cb5fae1521c7df66a2dbb725784b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99ee272e70958be3a0279173a4eb4167ac681c95275d0760d7ce7baa3e3d65052a5b2ba44df79da4caf6ec075853e7ef6ab2e6706aa77e671d8ec2b8ba52d3a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5ca90ba83153484faa33530a6dea922e3d7c085d40a390a156093af67ecbf860cd43433cdc3b75f56f4c27bcae822cae92749e300701aa5749e4feb65fbe40b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 05:05:08.991829) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 05:05:08.992201) ========= 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.4U3raE7Kur/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.Wr3zojW4mN/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.005201 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 05:05:09.041174) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 05:05:09.041520) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 05:05:09.041886) ========= ========= Total master_lag: 0.18 =========