builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0245 starttime: 1476016134.6 results: success (0) buildid: 20161009030202 builduid: 4f63f7e89c234062b084102fce7a9a05 revision: 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:54.601234) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:54.601698) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:54.602094) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:54.634993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:54.635375) ========= 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.idIIcE5jai/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.X0lCEWOeuU/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-09 05:28:54-- 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% 60.0M=0s 2016-10-09 05:28:55 (60.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.729319 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:55.388350) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:55.388720) ========= 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.idIIcE5jai/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.X0lCEWOeuU/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.026766 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:55.441997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:55.442370) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 --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 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.idIIcE5jai/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.X0lCEWOeuU/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-09 05:28:55,508 truncating revision to first 12 chars 2016-10-09 05:28:55,508 Setting DEBUG logging. 2016-10-09 05:28:55,508 attempt 1/10 2016-10-09 05:28:55,508 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7a7ba250bb2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-09 05:28:56,127 unpacking tar archive at: mozilla-central-7a7ba250bb2f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.889622 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:56.356074) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:56.356429) ========= script_repo_revision: 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:56.356933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:56.357246) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-09 05:28:56.375882) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-09 05:28:56.376222) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.idIIcE5jai/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.X0lCEWOeuU/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:28:56 INFO - MultiFileLogger online at 20161009 05:28:56 in /builds/slave/test 05:28:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:28:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:28:56 INFO - {'append_to_log': False, 05:28:56 INFO - 'base_work_dir': '/builds/slave/test', 05:28:56 INFO - 'blob_upload_branch': 'Firefox', 05:28:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:28:56 INFO - 'branch': 'Firefox', 05:28:56 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:56 INFO - 'config_files': ('talos/mac_config.py',), 05:28:56 INFO - 'default_actions': ('clobber', 05:28:56 INFO - 'read-buildbot-config', 05:28:56 INFO - 'download-and-extract', 05:28:56 INFO - 'populate-webroot', 05:28:56 INFO - 'create-virtualenv', 05:28:56 INFO - 'install', 05:28:56 INFO - 'run-tests'), 05:28:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:56 INFO - 'download_minidump_stackwalk': True, 05:28:56 INFO - 'download_symbols': 'ondemand', 05:28:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:56 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:56 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:56 INFO - 'installer_path': 'installer.exe', 05:28:56 INFO - 'log_level': 'info', 05:28:56 INFO - 'log_name': 'talos', 05:28:56 INFO - 'log_to_console': True, 05:28:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:28:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:28:56 INFO - 'opt_config_files': (), 05:28:56 INFO - 'pip_index': False, 05:28:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:28:56 INFO - 'cmd': ('bash', 05:28:56 INFO - '-c', 05:28:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:28:56 INFO - 'enabled': True, 05:28:56 INFO - 'halt_on_failure': False, 05:28:56 INFO - 'name': 'check_screen_resolution'},), 05:28:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:28:56 INFO - 'cmd': ('bash', 05:28:56 INFO - '-c', 05:28:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:28:56 INFO - 'enabled': True, 05:28:56 INFO - 'halt_on_failure': False, 05:28:56 INFO - 'name': 'check_screen_resolution'},), 05:28:56 INFO - 'run_cmd_checks_enabled': True, 05:28:56 INFO - 'sps_profile': False, 05:28:56 INFO - 'sps_profile_interval': 0, 05:28:56 INFO - 'suite': 'dromaeojs-e10s', 05:28:56 INFO - 'system_bits': '32', 05:28:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:56 INFO - 'title': 't-yosemite-r7-0245', 05:28:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:56 INFO - 'use_talos_json': True, 05:28:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:28:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:56 INFO - 'work_dir': 'build'} 05:28:56 INFO - [mozharness: 2016-10-09 12:28:56.560263Z] Running clobber step. 05:28:56 INFO - Running pre-action listener: _resource_record_pre_action 05:28:56 INFO - Running main action method: clobber 05:28:56 INFO - rmtree: /builds/slave/test/build 05:28:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:28:58 INFO - Running post-action listener: _resource_record_post_action 05:28:58 INFO - [mozharness: 2016-10-09 12:28:58.797394Z] Finished clobber step (success) 05:28:58 INFO - [mozharness: 2016-10-09 12:28:58.797521Z] Running read-buildbot-config step. 05:28:58 INFO - Running pre-action listener: _resource_record_pre_action 05:28:58 INFO - Running main action method: read_buildbot_config 05:28:58 INFO - Using buildbot properties: 05:28:58 INFO - { 05:28:58 INFO - "project": "", 05:28:58 INFO - "product": "firefox", 05:28:58 INFO - "script_repo_revision": "production", 05:28:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:28:58 INFO - "repository": "", 05:28:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:28:58 INFO - "buildid": "20161009030202", 05:28:58 INFO - "stage_platform": "macosx64", 05:28:58 INFO - "basedir": "/builds/slave/test", 05:28:58 INFO - "nightly_build": "True", 05:28:58 INFO - "buildnumber": 43, 05:28:58 INFO - "slavename": "t-yosemite-r7-0245", 05:28:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:28:58 INFO - "platform": "yosemite_r7", 05:28:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:28:58 INFO - "branch": "mozilla-central", 05:28:58 INFO - "repo_path": "mozilla-central", 05:28:58 INFO - "revision": "7a7ba250bb2f5a7cc7acf4b97145425c5292e894", 05:28:58 INFO - "pgo_build": "False", 05:28:58 INFO - "builduid": "4f63f7e89c234062b084102fce7a9a05", 05:28:58 INFO - "slavebuilddir": "test" 05:28:58 INFO - } 05:28:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:28:58 INFO - Running post-action listener: _resource_record_post_action 05:28:58 INFO - [mozharness: 2016-10-09 12:28:58.801136Z] Finished read-buildbot-config step (success) 05:28:58 INFO - [mozharness: 2016-10-09 12:28:58.801237Z] Running download-and-extract step. 05:28:58 INFO - Running pre-action listener: _resource_record_pre_action 05:28:58 INFO - Running main action method: download_and_extract 05:28:58 INFO - mkdir: /builds/slave/test/build/tests 05:28:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:58 INFO - trying https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:28:58 INFO - Downloading https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/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 05:28:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/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 05:29:00 INFO - Downloaded 1199 bytes. 05:29:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:29:00 INFO - Using the following test package requirements: 05:29:00 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:29:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:29:00 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:29:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:29:00 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:29:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:29:00 INFO - u'jsshell-mac.zip'], 05:29:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:29:00 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:29:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:29:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:29:00 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:29:00 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:29:00 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:29:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:29:00 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:29:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:29:00 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:29:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:29:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:29:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:29:00 INFO - Fetch https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:29:00 INFO - Http code: 200 05:29:00 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 05:29:00 INFO - x-amz-cf-id: KQLa0DrzK5LzG3P2JCYzLDhX1V2uNvRTMQthWqxqiEeyWpaMuCBxSw== 05:29:00 INFO - x-amz-version-id: w7KuQuaset8wzc_JaDAGmKNwfI7UDVJc 05:29:01 INFO - Expected file size: 16504659 05:29:01 INFO - Obtained file size: 16504659 05:29:07 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 05:29:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:29:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:29:07 INFO - Fetch https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:29:08 INFO - Http code: 200 05:29:08 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 05:29:08 INFO - x-amz-cf-id: cJmcwRFnwlHBRkcXhOn3rPP--e-HQkhTeamtfwTHTYhnxnC_vvYQmg== 05:29:08 INFO - x-amz-version-id: w7KuQuaset8wzc_JaDAGmKNwfI7UDVJc 05:29:08 INFO - Expected file size: 16504659 05:29:08 INFO - Obtained file size: 16504659 05:29:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:29:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:29:15 INFO - Fetch https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:29:16 INFO - Http code: 200 05:29:16 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 05:29:16 INFO - x-amz-cf-id: WYx4OTNtB3KocQWO_CUdBE56jwh845oVnlxUBjwwunXZaG_bTZ_eBw== 05:29:16 INFO - x-amz-version-id: rmj63gSsnFdnOXEw7Fc0ZbHCBBO3mWCP 05:29:16 INFO - Expected file size: 14409006 05:29:16 INFO - Obtained file size: 14409006 05:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:16 INFO - trying https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:29:16 INFO - Downloading https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:29:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/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 05:29:19 INFO - Downloaded 111842526 bytes. 05:29:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:29:19 INFO - mkdir: /builds/slave/test/properties 05:29:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:29:19 INFO - Writing to file /builds/slave/test/properties/build_url 05:29:19 INFO - Contents: 05:29:19 INFO - build_url:https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:29:20 INFO - Running post-action listener: _resource_record_post_action 05:29:20 INFO - Running post-action listener: set_extra_try_arguments 05:29:20 INFO - [mozharness: 2016-10-09 12:29:20.148159Z] Finished download-and-extract step (success) 05:29:20 INFO - [mozharness: 2016-10-09 12:29:20.148261Z] Running populate-webroot step. 05:29:20 INFO - Running pre-action listener: _resource_record_pre_action 05:29:20 INFO - Running main action method: populate_webroot 05:29:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:29:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:29:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:29:20 INFO - '--tppagecycles', 05:29:20 INFO - '7'], 05:29:20 INFO - 'tests': ['tsvgm']}}, 05:29:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:29:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:29:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:29:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:29:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:20 INFO - 'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:20 INFO - 'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['damp', 'tps']}, 05:29:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:20 INFO - 'tests': ['damp', 'tps']}, 05:29:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['dromaeo_dom']}, 05:29:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:29:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['basic_compositor_video']}, 05:29:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:29:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['a11yr', 05:29:20 INFO - 'ts_paint', 05:29:20 INFO - 'tpaint', 05:29:20 INFO - 'sessionrestore', 05:29:20 INFO - 'sessionrestore_no_auto_restore', 05:29:20 INFO - 'tabpaint']}, 05:29:20 INFO - 'other-e10s': {'tests': ['a11yr', 05:29:20 INFO - 'ts_paint', 05:29:20 INFO - 'tpaint', 05:29:20 INFO - 'sessionrestore', 05:29:20 INFO - 'sessionrestore_no_auto_restore', 05:29:20 INFO - 'tabpaint']}, 05:29:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:29:20 INFO - 'ts_paint', 05:29:20 INFO - 'tpaint', 05:29:20 INFO - 'sessionrestore', 05:29:20 INFO - 'sessionrestore_no_auto_restore', 05:29:20 INFO - 'tabpaint']}, 05:29:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:29:20 INFO - 'ts_paint', 05:29:20 INFO - 'tpaint', 05:29:20 INFO - 'sessionrestore', 05:29:20 INFO - 'sessionrestore_no_auto_restore', 05:29:20 INFO - 'tabpaint']}, 05:29:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['a11yr', 05:29:20 INFO - 'ts_paint', 05:29:20 INFO - 'tpaint', 05:29:20 INFO - 'sessionrestore', 05:29:20 INFO - 'sessionrestore_no_auto_restore', 05:29:20 INFO - 'tabpaint']}, 05:29:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['a11yr', 05:29:20 INFO - 'ts_paint', 05:29:20 INFO - 'tpaint', 05:29:20 INFO - 'sessionrestore', 05:29:20 INFO - 'sessionrestore_no_auto_restore', 05:29:20 INFO - 'tabpaint']}, 05:29:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['tsvgx', 05:29:20 INFO - 'tsvgr_opacity', 05:29:20 INFO - 'tart', 05:29:20 INFO - 'tscrollx', 05:29:20 INFO - 'cart']}, 05:29:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:29:20 INFO - 'tsvgr_opacity', 05:29:20 INFO - 'tart', 05:29:20 INFO - 'tscrollx', 05:29:20 INFO - 'cart']}, 05:29:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:20 INFO - 'talos_options': ['--disable-e10s'], 05:29:20 INFO - 'tests': ['tp5o']}, 05:29:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:20 INFO - 'tests': ['tp5o']}, 05:29:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:20 INFO - 'talos_options': ['--disable-e10s', 05:29:20 INFO - '--xperf_path', 05:29:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:20 INFO - 'tests': ['tp5n']}, 05:29:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:20 INFO - 'talos_options': ['--xperf_path', 05:29:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:20 INFO - 'tests': ['tp5n']}}, 05:29:20 INFO - 'talos.zip': {'path': '', 05:29:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:29:20 INFO - Running post-action listener: _resource_record_post_action 05:29:20 INFO - [mozharness: 2016-10-09 12:29:20.166893Z] Finished populate-webroot step (success) 05:29:20 INFO - [mozharness: 2016-10-09 12:29:20.167009Z] Running create-virtualenv step. 05:29:20 INFO - Running pre-action listener: _resource_record_pre_action 05:29:20 INFO - Running main action method: create_virtualenv 05:29:20 INFO - Creating virtualenv /builds/slave/test/build/venv 05:29:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:29:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:29:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:29:20 INFO - Using real prefix '/tools/python27' 05:29:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:29:21 INFO - Installing distribute.............................................................................................................................................................................................done. 05:29:23 INFO - Installing pip.................done. 05:29:23 INFO - Return code: 0 05:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:29:24 INFO - Reading from file tmpfile_stdout 05:29:24 INFO - Output received: 05:29:24 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) 05:29:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:24 INFO - 'HOME': '/Users/cltbld', 05:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:24 INFO - 'LOGNAME': 'cltbld', 05:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:24 INFO - 'MOZ_NO_REMOTE': '1', 05:29:24 INFO - 'NO_EM_RESTART': '1', 05:29:24 INFO - 'PAGER': '/bin/cat', 05:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:24 INFO - 'PWD': '/builds/slave/test', 05:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:24 INFO - 'SHELL': '/bin/bash', 05:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:24 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:24 INFO - 'USER': 'cltbld', 05:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:24 INFO - 'XPC_FLAGS': '0x0', 05:29:24 INFO - 'XPC_SERVICE_NAME': '0', 05:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:29:24 INFO - Cleaning up... 05:29:24 INFO - Return code: 0 05:29:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:24 INFO - 'HOME': '/Users/cltbld', 05:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:24 INFO - 'LOGNAME': 'cltbld', 05:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:24 INFO - 'MOZ_NO_REMOTE': '1', 05:29:24 INFO - 'NO_EM_RESTART': '1', 05:29:24 INFO - 'PAGER': '/bin/cat', 05:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:24 INFO - 'PWD': '/builds/slave/test', 05:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:24 INFO - 'SHELL': '/bin/bash', 05:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:24 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:24 INFO - 'USER': 'cltbld', 05:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:24 INFO - 'XPC_FLAGS': '0x0', 05:29:24 INFO - 'XPC_SERVICE_NAME': '0', 05:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:24 INFO - Downloading/unpacking psutil>=3.1.1 05:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:28 INFO - Installing collected packages: psutil 05:29:28 INFO - Running setup.py install for psutil 05:29:28 INFO - building 'psutil._psutil_osx' extension 05:29:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:29:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:29:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:29:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:29:29 INFO - building 'psutil._psutil_posix' extension 05:29:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:29:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:29:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:29:29 INFO - ^ 05:29:29 INFO - 1 warning generated. 05:29:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:29:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:29 INFO - Successfully installed psutil 05:29:29 INFO - Cleaning up... 05:29:29 INFO - Return code: 0 05:29:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:29: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')]} 05:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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')]} 05:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:29: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 mozsystemmonitor==0.3 05:29:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:29 INFO - 'HOME': '/Users/cltbld', 05:29:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:29 INFO - 'LOGNAME': 'cltbld', 05:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:29 INFO - 'MOZ_NO_REMOTE': '1', 05:29:29 INFO - 'NO_EM_RESTART': '1', 05:29:29 INFO - 'PAGER': '/bin/cat', 05:29:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:29 INFO - 'PWD': '/builds/slave/test', 05:29:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:29 INFO - 'SHELL': '/bin/bash', 05:29:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:29 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:29 INFO - 'USER': 'cltbld', 05:29:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:29 INFO - 'XPC_FLAGS': '0x0', 05:29:29 INFO - 'XPC_SERVICE_NAME': '0', 05:29:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:29:32 INFO - Installing collected packages: mozsystemmonitor 05:29:32 INFO - Running setup.py install for mozsystemmonitor 05:29:32 INFO - Successfully installed mozsystemmonitor 05:29:32 INFO - Cleaning up... 05:29:32 INFO - Return code: 0 05:29:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:29: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')]} 05:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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')]} 05:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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', '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 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:29: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 jsonschema==2.5.1 05:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:32 INFO - 'HOME': '/Users/cltbld', 05:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:32 INFO - 'LOGNAME': 'cltbld', 05:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:32 INFO - 'MOZ_NO_REMOTE': '1', 05:29:32 INFO - 'NO_EM_RESTART': '1', 05:29:32 INFO - 'PAGER': '/bin/cat', 05:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:32 INFO - 'PWD': '/builds/slave/test', 05:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:32 INFO - 'SHELL': '/bin/bash', 05:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:32 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:32 INFO - 'USER': 'cltbld', 05:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:32 INFO - 'XPC_FLAGS': '0x0', 05:29:32 INFO - 'XPC_SERVICE_NAME': '0', 05:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:32 INFO - Downloading/unpacking jsonschema==2.5.1 05:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:29:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:35 INFO - Downloading functools32-3.2.3-2.tar.gz 05:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:29:35 INFO - warning: no files found matching '*.txt' 05:29:35 INFO - no previously-included directories found matching 'build' 05:29:35 INFO - no previously-included directories found matching 'dist' 05:29:35 INFO - no previously-included directories found matching '.git*' 05:29:35 INFO - Installing collected packages: jsonschema, functools32 05:29:35 INFO - Running setup.py install for functools32 05:29:35 INFO - warning: no files found matching '*.txt' 05:29:35 INFO - no previously-included directories found matching 'build' 05:29:35 INFO - no previously-included directories found matching 'dist' 05:29:35 INFO - no previously-included directories found matching '.git*' 05:29:36 INFO - Successfully installed jsonschema functools32 05:29:36 INFO - Cleaning up... 05:29:36 INFO - Return code: 0 05:29:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:36 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 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:29:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:36 INFO - 'HOME': '/Users/cltbld', 05:29:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:36 INFO - 'LOGNAME': 'cltbld', 05:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:36 INFO - 'MOZ_NO_REMOTE': '1', 05:29:36 INFO - 'NO_EM_RESTART': '1', 05:29:36 INFO - 'PAGER': '/bin/cat', 05:29:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:36 INFO - 'PWD': '/builds/slave/test', 05:29:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:36 INFO - 'SHELL': '/bin/bash', 05:29:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:36 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:36 INFO - 'USER': 'cltbld', 05:29:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:36 INFO - 'XPC_FLAGS': '0x0', 05:29:36 INFO - 'XPC_SERVICE_NAME': '0', 05:29:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:29:36 INFO - Cleaning up... 05:29:36 INFO - Return code: 0 05:29:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:36 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 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:29:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:36 INFO - 'HOME': '/Users/cltbld', 05:29:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:36 INFO - 'LOGNAME': 'cltbld', 05:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:36 INFO - 'MOZ_NO_REMOTE': '1', 05:29:36 INFO - 'NO_EM_RESTART': '1', 05:29:36 INFO - 'PAGER': '/bin/cat', 05:29:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:36 INFO - 'PWD': '/builds/slave/test', 05:29:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:36 INFO - 'SHELL': '/bin/bash', 05:29:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:36 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:36 INFO - 'USER': 'cltbld', 05:29:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:36 INFO - 'XPC_FLAGS': '0x0', 05:29:36 INFO - 'XPC_SERVICE_NAME': '0', 05:29:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:36 INFO - Downloading/unpacking blobuploader==1.2.4 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:39 INFO - Downloading blobuploader-1.2.4.tar.gz 05:29:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:29:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:29:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:40 INFO - Downloading docopt-0.6.1.tar.gz 05:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:29:40 INFO - Installing collected packages: blobuploader, requests, docopt 05:29:40 INFO - Running setup.py install for blobuploader 05:29:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:29:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:29:40 INFO - Running setup.py install for requests 05:29:41 INFO - Running setup.py install for docopt 05:29:41 INFO - Successfully installed blobuploader requests docopt 05:29:41 INFO - Cleaning up... 05:29:41 INFO - Return code: 0 05:29:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:41 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 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:41 INFO - 'HOME': '/Users/cltbld', 05:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:41 INFO - 'LOGNAME': 'cltbld', 05:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:41 INFO - 'MOZ_NO_REMOTE': '1', 05:29:41 INFO - 'NO_EM_RESTART': '1', 05:29:41 INFO - 'PAGER': '/bin/cat', 05:29:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:41 INFO - 'PWD': '/builds/slave/test', 05:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:41 INFO - 'SHELL': '/bin/bash', 05:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:41 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:41 INFO - 'USER': 'cltbld', 05:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:41 INFO - 'XPC_FLAGS': '0x0', 05:29:41 INFO - 'XPC_SERVICE_NAME': '0', 05:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:29:41 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-jVayB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:29:41 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-VT7CwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:29:41 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-y0wzJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:29:41 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-rRDlT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-QVqmn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-IurzME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-KXCaW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-4Rs71h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-LQ1SYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-WtS9Tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-RFfkVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-SlzNxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-XRndRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:29:42 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-jHeA52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:43 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-AHLhfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:29:43 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-eH8i1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:29:43 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-oem89t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:29:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:29:43 INFO - Running setup.py install for manifestparser 05:29:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:29:43 INFO - Running setup.py install for mozcrash 05:29:43 INFO - Running setup.py install for mozdebug 05:29:43 INFO - Running setup.py install for mozdevice 05:29:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:29:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:29:43 INFO - Running setup.py install for mozfile 05:29:44 INFO - Running setup.py install for mozhttpd 05:29:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:29:44 INFO - Running setup.py install for mozinfo 05:29:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:29:44 INFO - Running setup.py install for mozInstall 05:29:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:29:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:29:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:29:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:29:44 INFO - Running setup.py install for mozleak 05:29:44 INFO - Running setup.py install for mozlog 05:29:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:29:44 INFO - Running setup.py install for moznetwork 05:29:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:29:44 INFO - Running setup.py install for mozprocess 05:29:45 INFO - Running setup.py install for mozprofile 05:29:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:29:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:29:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:29:45 INFO - Running setup.py install for mozrunner 05:29:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:29:45 INFO - Running setup.py install for mozscreenshot 05:29:45 INFO - Running setup.py install for moztest 05:29:45 INFO - Running setup.py install for mozversion 05:29:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:29:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:29:45 INFO - Cleaning up... 05:29:45 INFO - Return code: 0 05:29:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:45 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 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:45 INFO - 'HOME': '/Users/cltbld', 05:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:45 INFO - 'LOGNAME': 'cltbld', 05:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:45 INFO - 'MOZ_NO_REMOTE': '1', 05:29:45 INFO - 'NO_EM_RESTART': '1', 05:29:45 INFO - 'PAGER': '/bin/cat', 05:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:45 INFO - 'PWD': '/builds/slave/test', 05:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:45 INFO - 'SHELL': '/bin/bash', 05:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:45 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:45 INFO - 'USER': 'cltbld', 05:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:45 INFO - 'XPC_FLAGS': '0x0', 05:29:45 INFO - 'XPC_SERVICE_NAME': '0', 05:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:29:46 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-7gogS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:29:46 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-msobMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:29:46 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-6LBtRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:29:46 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-HPwV96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:29:46 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-yfN8wE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:29:46 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-H3Bhcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:29:46 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-F5ElcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:29:46 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-N_7JiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Pie9YE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-i6VrAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:29:47 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)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-OAlSoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ipMpuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-X56ISI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-0uVgPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-8qa0kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-EUHAVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:29:47 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-79_LBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:29:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:50 INFO - Downloading blessings-1.6.tar.gz 05:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:29:50 INFO - Installing collected packages: blessings 05:29:50 INFO - Running setup.py install for blessings 05:29:50 INFO - Successfully installed blessings 05:29:50 INFO - Cleaning up... 05:29:50 INFO - Return code: 0 05:29:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:29:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:29:51 INFO - Reading from file tmpfile_stdout 05:29:51 INFO - Current package versions: 05:29:51 INFO - blessings == 1.6 05:29:51 INFO - blobuploader == 1.2.4 05:29:51 INFO - docopt == 0.6.1 05:29:51 INFO - functools32 == 3.2.3-2 05:29:51 INFO - jsonschema == 2.5.1 05:29:51 INFO - manifestparser == 1.1 05:29:51 INFO - mozInstall == 1.12 05:29:51 INFO - mozcrash == 0.17 05:29:51 INFO - mozdebug == 0.1 05:29:51 INFO - mozdevice == 0.48 05:29:51 INFO - mozfile == 1.2 05:29:51 INFO - mozhttpd == 0.7 05:29:51 INFO - mozinfo == 0.9 05:29:51 INFO - mozleak == 0.1 05:29:51 INFO - mozlog == 3.4 05:29:51 INFO - moznetwork == 0.27 05:29:51 INFO - mozprocess == 0.23 05:29:51 INFO - mozprofile == 0.28 05:29:51 INFO - mozrunner == 6.12 05:29:51 INFO - mozscreenshot == 0.1 05:29:51 INFO - mozsystemmonitor == 0.3 05:29:51 INFO - moztest == 0.7 05:29:51 INFO - mozversion == 1.4 05:29:51 INFO - psutil == 3.1.1 05:29:51 INFO - requests == 1.2.3 05:29:51 INFO - wsgiref == 0.1.2 05:29:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 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 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:51 INFO - 'HOME': '/Users/cltbld', 05:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:51 INFO - 'LOGNAME': 'cltbld', 05:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:51 INFO - 'MOZ_NO_REMOTE': '1', 05:29:51 INFO - 'NO_EM_RESTART': '1', 05:29:51 INFO - 'PAGER': '/bin/cat', 05:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:51 INFO - 'PWD': '/builds/slave/test', 05:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:51 INFO - 'SHELL': '/bin/bash', 05:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:51 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:51 INFO - 'USER': 'cltbld', 05:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:51 INFO - 'XPC_FLAGS': '0x0', 05:29:51 INFO - 'XPC_SERVICE_NAME': '0', 05:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:29:51 INFO - Cleaning up... 05:29:51 INFO - Return code: 0 05:29:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ca17490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c98d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea84c0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, '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.idIIcE5jai/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.X0lCEWOeuU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:51 INFO - 'HOME': '/Users/cltbld', 05:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:51 INFO - 'LOGNAME': 'cltbld', 05:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:51 INFO - 'MOZ_NO_REMOTE': '1', 05:29:51 INFO - 'NO_EM_RESTART': '1', 05:29:51 INFO - 'PAGER': '/bin/cat', 05:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:51 INFO - 'PWD': '/builds/slave/test', 05:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:51 INFO - 'SHELL': '/bin/bash', 05:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:29:51 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:51 INFO - 'USER': 'cltbld', 05:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:51 INFO - 'XPC_FLAGS': '0x0', 05:29:51 INFO - 'XPC_SERVICE_NAME': '0', 05:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:29:51 INFO - Cleaning up... 05:29:51 INFO - Return code: 0 05:29:51 INFO - Running post-action listener: _resource_record_post_action 05:29:51 INFO - Running post-action listener: _start_resource_monitoring 05:29:51 INFO - Starting resource monitoring. 05:29:51 INFO - [mozharness: 2016-10-09 12:29:51.713768Z] Finished create-virtualenv step (success) 05:29:51 INFO - [mozharness: 2016-10-09 12:29:51.714741Z] Running install step. 05:29:51 INFO - Running pre-action listener: _resource_record_pre_action 05:29:51 INFO - Running main action method: install 05:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:29:51 INFO - Reading from file tmpfile_stdout 05:29:51 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:29:52 INFO - Reading from file tmpfile_stdout 05:29:52 INFO - Output received: 05:29:52 INFO - Usage: mozinstall [options] installer 05:29:52 INFO - Options: 05:29:52 INFO - -h, --help show this help message and exit 05:29:52 INFO - -d DEST, --destination=DEST 05:29:52 INFO - Directory to install application into. [default: 05:29:52 INFO - "/builds/slave/test"] 05:29:52 INFO - --app=APP Application being installed. [default: firefox] 05:29:52 INFO - mkdir: /builds/slave/test/build/application 05:29:52 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'] 05:29:52 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 05:30:25 INFO - Reading from file tmpfile_stdout 05:30:25 INFO - Output received: 05:30:25 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:30:25 INFO - Running post-action listener: _resource_record_post_action 05:30:25 INFO - [mozharness: 2016-10-09 12:30:25.959143Z] Finished install step (success) 05:30:25 INFO - [mozharness: 2016-10-09 12:30:25.959305Z] Running run-tests step. 05:30:25 INFO - Running pre-action listener: _resource_record_pre_action 05:30:25 INFO - Running main action method: run_tests 05:30:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:25 INFO - 2016-10-09 05:30:25.973 screenresolution[1651:8165] starting screenresolution argv=screenresolution get 05:30:25 INFO - 2016-10-09 05:30:25.976 screenresolution[1651:8165] Display 0: 1600x1200x32@60 05:30:25 INFO - 2016-10-09 05:30:25.982 screenresolution[1652:8167] starting screenresolution argv=screenresolution list 05:30:25 INFO - Available Modes on Display 0 05:30:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:26 INFO - Intel Iris: 05:30:26 INFO - Chipset Model: Intel Iris 05:30:26 INFO - Type: GPU 05:30:26 INFO - Bus: Built-In 05:30:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:26 INFO - Vendor: Intel (0x8086) 05:30:26 INFO - Device ID: 0x0a2e 05:30:26 INFO - Revision ID: 0x0009 05:30:26 INFO - Displays: 05:30:26 INFO - Display: 05:30:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:26 INFO - Display Serial Number: 1600x1200 60 05:30:26 INFO - Main Display: Yes 05:30:26 INFO - Mirror: Off 05:30:26 INFO - Online: Yes 05:30:26 INFO - Rotation: Supported 05:30:26 INFO - Return code: 0 05:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:30:26 INFO - Python 2.7.3 05:30:26 INFO - Return code: 0 05:30:26 INFO - grabbing minidump binary from tooltool 05:30: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')]} 05:30:26 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 0x10c82bd30>, '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 0x10c651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c823b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:30:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:30:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:30:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:30:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:30:26 INFO - Return code: 0 05:30:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:30:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:30:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:30:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:30:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0245 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.idIIcE5jai/Render', 05:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:26 INFO - 'HOME': '/Users/cltbld', 05:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:26 INFO - 'LOGNAME': 'cltbld', 05:30:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:30:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:26 INFO - 'MOZ_NO_REMOTE': '1', 05:30:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:30:26 INFO - 'NO_EM_RESTART': '1', 05:30:26 INFO - 'PAGER': '/bin/cat', 05:30:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:26 INFO - 'PWD': '/builds/slave/test', 05:30:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:26 INFO - 'SHELL': '/bin/bash', 05:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X0lCEWOeuU/Listeners', 05:30:26 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:26 INFO - 'USER': 'cltbld', 05:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:26 INFO - 'XPC_FLAGS': '0x0', 05:30:26 INFO - 'XPC_SERVICE_NAME': '0', 05:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:30:26 INFO - mozversion application_buildid: 20161009030202 05:30:26 INFO - mozversion application_changeset: 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 05:30:26 INFO - mozversion application_display_name: Nightly 05:30:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:30:26 INFO - mozversion application_name: Firefox 05:30:26 INFO - mozversion application_remotingname: firefox 05:30:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:30:26 INFO - mozversion application_vendor: Mozilla 05:30:26 INFO - mozversion application_version: 52.0a1 05:30:26 INFO - mozversion platform_buildid: 20161009030202 05:30:26 INFO - mozversion platform_changeset: 7a7ba250bb2f5a7cc7acf4b97145425c5292e894 05:30:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:30:26 INFO - mozversion platform_version: 52.0a1 05:30:26 INFO - using testdate: 1476016226 05:30:26 INFO - actual date: 1476016226 05:30:26 INFO - starting webserver on 'localhost:49267' 05:30:26 INFO - SUITE-START | Running 2 tests 05:30:26 INFO - TEST-START | dromaeo_css 05:30:26 INFO - operating with platform_type : mac_ 05:30:26 INFO - Initialising browser for dromaeo_css test... 05:30:26 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpRjLUtN/profile http://localhost:49267/getInfo.html) 05:30:29 INFO - PROCESS | 1662 | 2016-10-09 05:30:29.577 plugin-container[1666:8356] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e47, name = 'com.apple.tsm.portname' 05:30:29 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:29 INFO - PROCESS | 1662 | 2016-10-09 05:30:29.578 plugin-container[1666:8356] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9603, name = 'com.apple.CFPasteboardClient' 05:30:29 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:29 INFO - PROCESS | 1662 | 2016-10-09 05:30:29.578 plugin-container[1666:8356] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:30:29 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 05:30:29 INFO - PROCESS | 1662 | colorDepth:24 05:30:29 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 05:30:29 INFO - PROCESS | 1662 | __metrics 05:30:29 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 05:30:30 INFO - TEST-INFO | 1662: exit 0 05:30:30 INFO - Browser initialized. 05:30:30 INFO - Running cycle 1/1 for dromaeo_css test... 05:30:30 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpRjLUtN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:32 INFO - PROCESS | 1667 | 2016-10-09 05:30:32.686 plugin-container[1668:8481] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a13, name = 'com.apple.tsm.portname' 05:30:32 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:32 INFO - PROCESS | 1667 | 2016-10-09 05:30:32.687 plugin-container[1668:8481] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 05:30:32 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:32 INFO - PROCESS | 1667 | 2016-10-09 05:30:32.687 plugin-container[1668:8481] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:30:42 INFO - PROCESS | 1667 | RSS: Main: 239046656 05:30:42 INFO - PROCESS | 1667 | 05:31:32 INFO - PROCESS | 1667 | 1476016292052 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:33:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 05:33:45 INFO - PROCESS | 1667 | RSS: Main: 252157952 05:33:45 INFO - PROCESS | 1667 | 05:36:48 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 05:36:48 INFO - PROCESS | 1667 | RSS: Main: 265793536 05:36:48 INFO - PROCESS | 1667 | 05:39:50 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 05:39:51 INFO - PROCESS | 1667 | RSS: Main: 269463552 05:39:51 INFO - PROCESS | 1667 | 05:42:53 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 05:42:53 INFO - PROCESS | 1667 | RSS: Main: 270057472 05:42:53 INFO - PROCESS | 1667 | 05:45:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 05:45:57 INFO - PROCESS | 1667 | RSS: Main: 275255296 05:45:57 INFO - PROCESS | 1667 | 05:48:59 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 05:49:00 INFO - PROCESS | 1667 | RSS: Main: 278126592 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - PROCESS | 1667 | __start_tp_report 05:49:00 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 05:49:00 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 05:49:00 INFO - PROCESS | 1667 | |i|pagename|runs| 05:49:00 INFO - PROCESS | 1667 | |0;dojo.html;2659.4149287156824;3060.2502386915194;3079.5996520452336;3366.882159124431;3412.76110672253;5590.580706447013;5653.180288858113;5714.428557144289;5733.226014488043;5756.453136951994;5611.2144299798065;5612.382637909826;5717;5720.570957178207;5838.445347691973;5132.538071573557;5137.666051706646;5153.999999999991;5281.762320695559;5285.392179899322;5286.3392075990505;5314.415414304423;5325.014872248627;5333.876626151584;5340.786368545254;4319.978400107995;5075.213341932006;5148.819791307324;5235.58115350771;5243.370795504564;5190.636655434121;5193.221016847466;5218.582513398919;5219.4780521947505;5245.763940622681;4060.330045542482;4269.786510674454;5125.2312153176945;5163.66436181647;5183.067047931371;5541.5289700375515;5550.111982082888;5551.2228288039705;5599;5699.68651724155;5095.8534329775875;6093.451589356979;6164.599301045418;6166.105914642351;6170.259568851767;6178.876422471525;6245.063240513914;6254.6872656366995;6259.280182778994;6272.1219029335925;5745.482906538432;5775.797847075416;5822.301323841166;5858.648481091105;5917.260342457193;6260.092286618506;6268.811935641938;6269.404406581368;6272.843178920563;6274.811755647338;6131.356207598227;6162.075688646739;6168.1056246843955;6169.383061693795;6183.227096612924;6077.392260773976;6121.357257487989;6135.783919773617;6136.304638670889;6230.875382492325;6112.777056696867;6178.4439400453275;6189.783357582463;6191.071339299051;6191.535634827406;5829.8251052468495;5891.646501209942;6170.784022559189;6171.876562468815;6175.166352542439;4815.662903596714;4842.7336496493035;4882.926756098661;4921.827736029221;4927.975360123177;4331.306990881444;4368.628666563378;4377.518472967971;4464.0402313502755;4494.955050449454;5611.80358687444;5776.480116789425;5779.3642699303045;5788.421157884262;5803.477687008105;6083.66539840313;6109.2528441424865;6231.22109736283;6238.781642642576;6268.122462855204;5614.9719251403485;5640.520555752807;5742.908847318996;5781.103928891029;5877.617954832922;5376.623636345496;5377.7311134443125;5378.11261141915;5396.08266594672;5410.323709536308;6266.185395898504;6286.65423401699;6287.371262873768;6306.716197771111;6308.148399965946;6077.878442431215;6094.7734293265175;6134.957077260823;6153;6160.199174107337;6244.094606282155;6269.592476489013;6273.71768270438;6292.402221788922;6294.685265736786;5976.61152025117;6030.668313242813;6085.2393450818645;6093;6161.383861613803;5187.273781670494;6156.322804491592;6208.503319734501;6237.126802247598;6253.593516421418;4562.771861406983;6096.390360963868;6136.171616831671;6144.41628045335;6154.169187159676;5951;6027.36712645166;6153.630782153085;6161.907571386343;6162.198914141133;5833.445822646841;5841.795537156179;5943.583949123693;6002.609830361012;6030.547708921936;6016.2479690038745;6075.27096748393;6104.847378815565;6112.358202388863;6118.174046503843;5879.176915231786;5927.614705044159;5931.051031835058;5935.0503919372695;5958.314793798769 05:49:00 INFO - PROCESS | 1667 | |1;ext.html;470.088029223307;518.2226660009985;532.6058716549752;553.3083645443198;554.2323881424228;38340.61659383406;40033.799831000804;40036.39945400814;40113;40152.79923600382;40756.79621601895;41311.58688413115;41404.58595414045;41407;41409.37885931706;8353.749387518385;9907.900920990805;9910.702678919644;9918.20654347654;9956.253281003917;9780.608775648965;9845.45849978251;9862.9506852466;9869.111779939803;9901;8363.83272334553;9826;9856.718626578535;9859.753506162368;9873.753156171082;9806.234877722007;9845.901540984605;9849.655262065866;9854.211663066937;9889.159421449185;9456.243500519942;9474.763130921714;9543.522823858779;9545.665901693408;9562.856557151648;39036.80481597602;39319.82040538789;39324.60675393238;39347.60652393468;39488;3304.867805287786;3326.600807903043;3355.3457075870215;3405.8126803025825;3412.3175364926865;30305.848470757504;30642;30968;31065.91269305586;31220.598663643617;3153.59003329568;3229.660885607001;3305.636379998198;3311.784733992283;3364.9495257571157;497262;498178;502802;504091;505981;46626.76686616613;55635;55764;56338;56388.718056410275;60192.699036505415;60306.69846650738;60338.39661603416;60385;60405;15419.229038548028;15657.373705051898;15838;15856.60358491047;15869.285882135331;12790.424430900632;12860.228386296852;12867.22796632205;12882.22706637586;12895;14094.436222551189;15444.45944391941;15464.690706185813;15480.225988700746;15493.922530387501;6841.418479429304;6907.620080895599;6966.582005079711;6991.510594258455;7028.226895041541;789.5854676294877;845.3913182508584;956.1967946924617;967.2890425537224;1082.5399205337858;29169.708302917123;29823.254418639706;30085.69914300873;30102.849485752868;30478.847605761828;864.6714248585497;1024.8872624011353;1088.8638920134983;1136.2443974756695;1228.8402507674125;13432.731345373038;14867.851321486864;15398.999065060736;15545.68908621844;15793.842061579237;658564.1435856472;668094;672832;673727;674349;56948;57157.714211428676;57160.142597861064;57284.14073788896;57338.13992790028;1037.3555233819372;1119.6249256499002;1224.9326287054118;1225.8345123408403;1373.3888419653158;24271.752443321995;25018.499630006932;29156.708432916246;29833.701662983094;30044.699553005066;48849.75575122102;49346.259806103655;49405.50594494009;49411;49510;594080;602477;607382;610651;612658;25374.74625253724;26103.347416314744;26145.215643530726;39621.80189099036;39724.20551588894;46100.7694961523;46515;46948.295775562714;47118.79410579834;47159.056818862744;34197.316053679286;38574.035649109;38867.80566097151;39405;40538.59461405466;37985.2402951945;38256;38713.22573548457;39505;40109.997250068984;36870;37441.43837842489;38218.426723599725;38282.61717382791;38420.6157938417;32659;33217.501737473474;33864.984050478524;34623.82688086543;34949;25407.745922540536;25460.363490912136;25473.61789573195;25604;26033 05:49:00 INFO - PROCESS | 1667 | |2;jquery.html;323.82189795612413;375.53996354465784;382.9444730514074;383.23927004895285;404.9250888585612;19566.413007609757;19600;20170.394888153343;20288.087036083358;20692.586148277027;19506.902465487652;19516.219351225933;20228.797712022875;20685.896570517165;21038.053287602055;4377.430933978594;5318.760655770499;5589.993801115818;5592.888142237153;6107.083937409402;6102.969485152568;6174.413430724074;6185.319614842364;6187.969060154715;6211.285702144243;5904.852378690545;6130.203073600425;6175.351588083254;6176.505879529627;6176.9691151544175;6089.360617135203;6096.72564734588;6105.389461053904;6150.323464418932;6152.107944348045;5915.733791979328;5965.880682386328;5970.0746384310505;5975.372585878507;5981.252343457068;12962.675933101653;13319.467221311135;13394.93302533481;15280.770788438182;24239.394015149763;2079.4489460293034;2214.6124428225;2544.0078369435937;2935.949243921091;2994.356213414105;8998.955005224932;9842.65550705722;9847.409155450625;9928.553215105338;10363.222758293083;1814.4647329037966;1838.9172487238106;1884.5349974432602;1921.34674210769;1923.1249781349452;88300.55849720775;118462.99999999914;122650;123043.46521882586;125866.74133258733;9096.408733432305;11500.309981401142;11629.709257268467;11649.941750291195;11742.823857642143;14522.20127892955;14565.053271537316;14581.562553123424;14583.708325833424;14605;8477;8719.215270625675;8743.256823169975;8777.29781617469;8814.86777698335;8618.913810861812;8693.217610414966;8712.564371781385;8857.247133993682;8871.157240062184;4528.252838281646;4561.110583436198;4595.747233902101;4661.533846615379;4700.929486057712;1682.8401301876302;1958.6964020576834;1962.519182800223;1967.3114409956402;1985.7517810273716;869.2958703450225;881.5283823154582;921.2860033473977;947.9952600236954;989.6931951095039;17787.46637600874;20474;20575.38273851787;21795.782042179377;21849.453763655718;862.6161358195543;863.5984267315729;893.4237416866085;905.1762895764822;939.2298315381322;9913.157381622499;10324.896751032393;10841.07850832688;11478.368689722145;11585.594504192313;514809;519459;526653;581778;597640;76483.61758191173;83048;116754;126543;134913;898.869663898731;986.5067466266867;997.5411310797115;1024.4672770159475;1038.8026275007724;19520.90239548793;20129.993500325218;20245.088970996636;20311.390658280274;20314.693875511563;51858.22212666737;52757.7362113187;54143;54982.17526737182;57129.42870571241;64906;66316;84317;84733;85845.71231431409;7181.748638797826;7302.963485182752;7576.8484630308185;8303.4602750821;8467.872981905399;12859.228446293253;12918.354082296035;13154.144980576231;13353.866461335263;13500;10683.732906677084;11018.393988330397;11232.494537745657;11430.885691142983;11476.967072963473;5913.763449462145;10458.68623941283;10848.945755271174;10982;11024.559017639205;8959;9228.215601673934;10160.237982151517;11494.82757758651;12408.875911241134;7027.754028608974;7395.445341599294;7431.182569917413;8032.236937490929;8432.704855330034;10763.267740012112;11328.773424531299;11501.48243329069;11519.654410367702;11558.133140014701 05:49:00 INFO - PROCESS | 1667 | |3;mootools.html;1529.0978322789556;1557.9873082496376;1791.0238919788715;1861.4322631597358;1894.204434137662;7957.522548647071;8276.17238276172;8443.36674749394;9045.050269721674;9050.502222377767;8457.069722330538;8789.780255493615;8840.690575829847;8914.42056266344;8945.955270223656;2325.6278995360744;2362.70466191726;2372.146027430124;2372.822038347122;2386.140989243871;2328.1734984080663;2368.1593034472685;2368.656544801003;2370.466645004877;2374.501354715503;2340.3096086654477;2342.437814924423;2360.3745007573007;2362.11420717231;2369.7156341239074;2326.5230627721276;2334.708161479815;2335.74306826249;2342.8711420871846;2347.8378202789645;2321.100417560324;2331.3355693627236;2339.9532009359887;2340.9648855267183;2343.425860664131;8935.28517718581;8943;8997.685081022133;8999.100089991014;9013.954930225373;5186.636935414523;5273.604479664041;5359.356877174725;5367.007103685831;5374.086405311106;3570.357335679577;3586.6413358664186;3638.745287829853;3640.872569460056;3664.047347689593;1075.93544387337;1080.0603474976742;1082.4587706146926;1093.2128867215672;1107.9889201107967;11590.188686791927;11670.76658466826;11733.823992640117;11768.823467647991;11786.882131178665;4493.550644935481;4599.6090332321455;4772.785224664898;4819.59033482151;4821.252705830531;9925.50372481373;10045.146162576262;10046.849297260547;10054.346467479736;10055.648052318134;5325.653832500875;5339.466053394707;5349.22436246742;5352.678839269656;5354.277172581729;4353.934690979638;4511.70673906195;4705.364775755229;4838.879028024327;4895.7062576245535;3900.278448486982;3949.782761948063;3975.662068724133;3994.08136128692;4058.1680755445063;1202.5790973159499;1205.9095567832449;1207.2454715802623;1208.987910120905;1214.9247915594763;817.4073796497491;862.8490014247482;872.9476231426135;911.1935936696071;956.7177682583655;5624.943750562442;5858.443447872445;5940.940590594038;6046.244219472566;6237.282712488031;818.4843548564367;882.1399135842502;910.1535571918047;927.7726956895604;977.3354119199013;3515.086077619786;3523.94714079289;3543;3608.1881576645046;3625.7643253188457;8550.615222314886;9718.173955213744;10005.849912251322;10006.949965250273;10893.509792059376;9166.633334666672;9907.306488545733;10215.94892025535;10914.672559823219;10955.17836162291;8145.226203510657;8216.178382161735;8240.546769927592;8357.791055223668;8372.790680233044;5831.533477321719;5844.649321040752;5867.589268751317;5965.254343207099;6034.668093254738;9856.507174641383;9909.662195603501;10011;10035.24735644815;10035.24735644844;9916.405015699082;10148.289619726549;10373.170146388422;10379.169666426818;10382.169426446017;5953.970230148821;6513.120728701565;6757.93242067573;6849.794506164823;7178.774579880253;8280.089190188954;8542;8553.144685531397;8556.315494760529;8579.742607721779;7390;7465.253474652492;7593.924060759543;7652.464327497244;7717.691292348468;7320.633968301457;7406.222346653739;7439.9256007439235;7578.280063393969;7613.010308659839;6800;6891.862162756616;6957.652117394211;6965.33829286217;6974.37230649244;4974.825881094289;5119.513646203604;5174.404943431554;5196.636235463481;5226.151747297808;4959.801607935642;5123.974380128076;5163.199704045879;5231.895362092813;5377.865553361197 05:49:00 INFO - PROCESS | 1667 | |4;prototype.html;272.55845530241015;302.975761939045;308.5079298518862;315.1506689471873;319.28480204342264;4301.537477257732;5364.7317634118235;5524.254225679532;5700.942990570093;5758.395368486306;4781.163296423129;5072.731817045738;5277.70972596507;5478.561715062795;5550;3389.711874490659;3979.3036218661764;4185.28850095483;4255.5530020683345;4257.808398622069;3784.9621503785024;3896.6687831534355;4137.386419296477;4176.394422808691;4264.104538047014;4143.751374917515;4146.44023056888;4148.274052040896;4149.356849688304;4176.895577610554;4093.7953102345064;4141.254574176647;4171.97786542296;4180.728252663567;4196.118815048843;3194.0417874637515;3605.5312809334882;3618.7105031597407;3625.456181572759;3626.637336266352;5291.576673866081;5370.758315875795;5393.83818485446;5414.0477451460265;5424.349078110652;4173;4776.283557466373;4793.041391721669;4796.25658023007;4799.512151233129;4576.004148219041;4822.614190864722;5104.744762761847;5897.292324920994;5900.321463031763;3713.6472035156617;3842.3275926712713;3923.9993801580413;3938.369860822255;3949.5063117110362;6136.447719705248;6139.447449729546;6182.69086545671;6192;6245.843853903643;3787.280416720869;3851.9792255052434;3973.006748312922;3985.7608543487486;4010.7794071326357;3590.317839610466;3927.434283598667;3962;3990.2618015666612;3998.9400158997637;3626.6917312028245;4422.137683151755;4670.416197975253;4744.197205062054;4759.738214398241;5452.372977107604;5885.911711324334;5956.76172953077;5960.642361458326;5986.6707331097205;1124.7413094988199;1212.4180393411036;1267.473998290699;1352.3373546962114;1372.7254549090221;733.9797681222998;744.0839837418669;785.1049803224329;828.8147948433798;840.8612578924409;4598.562761736285;4605.447346318463;4683.81264749413;4684.236300582605;4690.202665546865;4688.953110468851;4825.010872771073;5055.219629203923;5103.800606857386;5113.207452844815;4170.895727606834;4357.716748411343;4638.512956139624;4640.234361330343;4670.299455081697;2271.602964177019;2294.621387471082;2621.7509336612993;2627.2643659774913;2700.648915640954;4556.681032327705;4792.760361981887;4890.975545122323;5024.794049428111;5296.292595183387;4326.935095973563;4473.038296766211;4641.466231383367;4869.220924652039;5186.236679528978;4460.487043989982;4478.909552408827;4649.558291962326;5168.61235407346;5176.456472070378;4008.7995600219288;4509.977450112729;4533.909321813611;5047.974760126176;5068.898622027465;4465.1962646723905;4481.058977614737;4910.8772280693265;5008.474110218521;5040.773165207501;4938.839372747473;5166.354205724285;5208;5225.719698673849;5261.342332208474;3446.844891979917;3458.083607843974;3463.913402164966;3492.336456073338;3500.6697454966948;4893.461719210813;5006.5994720423005;5051.242313652834;5093.745312734422;5095.949040509696;5121.948780512147;5169.586433085269;5588.267637032533;5863.853403664943;5887.293524777054;5357.383900851452;5501.449855014466;5848.94151058501;5871.412858714094;5917.19525544713;5485.820548582074;5688.829335119953;5785.334686511105;5803.7678492861505;5870.9412905870395;4549.499555048876;5146.639735218499;5248.504176309733;6004.969975150097;6037.879242415039;5891.610749991284;5894.852628684317;5896.262967129109;5896.616719913192;5914.0375948115725 05:49:00 INFO - PROCESS | 1667 | |5;yui.html;716.8996340512332;718.8382613911871;724.5037149552556;774.7791879314397;809.259527532308;15242.847571524282;15971.600709982256;15989.360425582963;16003.439879604217;16004.479574440424;13623.93188034061;13654.931725341385;13677.726445471084;13684.17894926305;13727;684.8938414545728;720.4488566246825;839.7186942374312;851.5571902610639;871.9825603487926;892.0588778838323;892.2861710631502;893.9942564614809;894.7136916186823;899.4558292233203;938.211902002318;945.7541039329755;948.8766460360143;950.9952450237739;953.9046095390474;928;935.5696379665362;937.9671711490133;941.4727752458645;943.4999450291323;688.944884409251;699.8425354295293;704.9672230182756;706.3607435271088;706.8197609609568;14609.196494192814;14705.191214483199;14727.484538041117;14732.779008314883;14739.189344586044;13671.949207618838;13677.24775137367;14614.34235459407;14705.485308014167;14786.18675972821;8965;9162.725118246462;11881.821772673331;12005.819912701316;12089;3626.764260323097;3814.9799486885863;3815.656590906804;3838.596947320547;3968.8015599219925;85357.71963420554;112377;115812.15596674292;117068.82931170665;117773.41113294465;11024.750455300877;11090.168237382228;11214.495347709373;11238.719032024264;11286.830697539543;22841.20055798039;22863.971121299583;22917.31248062561;22927.885360573426;22937.082516699145;7880.133185349607;7899.763007109796;8005.799855003672;8054.234847689578;8212;4324.459442569679;4384.694727655022;4420.9336859947125;4426.9778651106535;4435.379046933367;3512.4906888501023;3605.8737944172353;3616.945745813815;3626.8367923443507;3775.6601905828607;656.8357910522369;678.1184460201719;679.0696745458753;683.0710234081645;686.5057158845623;546.9863253418696;629.9622022678655;660.3990368764497;675.5239801034656;683.7846078485261;9723.409039802142;9723.902760972442;9727.416355018722;9754.122129008423;9873.210143188671;545.8799064205868;590.7636945221946;632.6678493790797;638.581728967527;688.3838964127078;4496.033352829158;4581.954180458153;4781.402324709411;4789.060371095256;4845.781939812717;134974.3251283757;151761;152363.23818381058;155482.2225888886;159767.20116399575;114926.42536787262;134008.32995834958;136306;137407.31296343455;138583;110330;111030.88969110367;116307;118893.16602509693;119287;9004.864927025968;9022.060257044766;9078.819753432275;9109.954450227706;9135.360524763202;56399.43600563942;65675;66127.66936165288;67423.31441713996;68311.31688683249;85126;86110.56944715236;86981;91542.16915661782;93961;13170.60488185356;13237.27195004284;13546.525871594447;13768.173909565458;14021.369038393093;14961.551153465414;15077.170755608548;15190.088594683912;15248.085114893142;15596.766048509508;13525.323733813073;13669.863301367257;13725.382357793724;13948.930255348658;14306.141631502143;13732.244726540137;14026.29868506591;14196.787048194492;14396.136231826124;14489.203093829941;13344.933275333562;13598.184108953494;13700.862991370357;13752.449902003807;13788.379522921694;10996.65591783134;11299.491522881655;11425.942870285928;11544.769104618028;11816.291022538675;10983.82177496245;11356.488957997075;11591.88408115908;11724.296542207494;11748.765024699629 05:49:00 INFO - PROCESS | 1667 | __end_tp_report 05:49:00 INFO - PROCESS | 1667 | __start_cc_report 05:49:00 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,362 05:49:00 INFO - PROCESS | 1667 | __end_cc_report 05:49:00 INFO - PROCESS | 1667 | __startTimestamp1476017339999__endTimestamp 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - PROCESS | 1667 | ------- Summary: start ------- 05:49:00 INFO - PROCESS | 1667 | Number of tests: 6 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5677.28 Median:5917.26 stddev:689.37 (11.7%) stddev-sans-first:649.56 05:49:00 INFO - PROCESS | 1667 | Values: 2659.4 3060.3 3079.6 3366.9 3412.8 5590.6 5653.2 5714.4 5733.2 5756.5 5611.2 5612.4 5717.0 5720.6 5838.4 5132.5 5137.7 5154.0 5281.8 5285.4 5286.3 5314.4 5325.0 5333.9 5340.8 4320.0 5075.2 5148.8 5235.6 5243.4 5190.6 5193.2 5218.6 5219.5 5245.8 4060.3 4269.8 5125.2 5163.7 5183.1 5541.5 5550.1 5551.2 5599.0 5699.7 5095.9 6093.5 6164.6 6166.1 6170.3 6178.9 6245.1 6254.7 6259.3 6272.1 5745.5 5775.8 5822.3 5858.6 5917.3 6260.1 6268.8 6269.4 6272.8 6274.8 6131.4 6162.1 6168.1 6169.4 6183.2 6077.4 6121.4 6135.8 6136.3 6230.9 6112.8 6178.4 6189.8 6191.1 6191.5 5829.8 5891.6 6170.8 6171.9 6175.2 4815.7 4842.7 4882.9 4921.8 4928.0 4331.3 4368.6 4377.5 4464.0 4495.0 5611.8 5776.5 5779.4 5788.4 5803.5 6083.7 6109.3 6231.2 6238.8 6268.1 5615.0 5640.5 5742.9 5781.1 5877.6 5376.6 5377.7 5378.1 5396.1 5410.3 6266.2 6286.7 6287.4 6306.7 6308.1 6077.9 6094.8 6135.0 6153.0 6160.2 6244.1 6269.6 6273.7 6292.4 6294.7 5976.6 6030.7 6085.2 6093.0 6161.4 5187.3 6156.3 6208.5 6237.1 6253.6 4562.8 6096.4 6136.2 6144.4 6154.2 5951.0 6027.4 6153.6 6161.9 6162.2 5833.4 5841.8 5943.6 6002.6 6030.5 6016.2 6075.3 6104.8 6112.4 6118.2 5879.2 5927.6 5931.1 5935.1 5958.3 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:71763.59 Median:40073.20 stddev:159587.37 (398.2%) stddev-sans-first:159945.28 05:49:00 INFO - PROCESS | 1667 | Values: 470.1 518.2 532.6 553.3 554.2 38340.6 40033.8 40036.4 40113.0 40152.8 40756.8 41311.6 41404.6 41407.0 41409.4 8353.7 9907.9 9910.7 9918.2 9956.3 9780.6 9845.5 9863.0 9869.1 9901.0 8363.8 9826.0 9856.7 9859.8 9873.8 9806.2 9845.9 9849.7 9854.2 9889.2 9456.2 9474.8 9543.5 9545.7 9562.9 39036.8 39319.8 39324.6 39347.6 39488.0 3304.9 3326.6 3355.3 3405.8 3412.3 30305.8 30642.0 30968.0 31065.9 31220.6 3153.6 3229.7 3305.6 3311.8 3364.9 497262.0 498178.0 502802.0 504091.0 505981.0 46626.8 55635.0 55764.0 56338.0 56388.7 60192.7 60306.7 60338.4 60385.0 60405.0 15419.2 15657.4 15838.0 15856.6 15869.3 12790.4 12860.2 12867.2 12882.2 12895.0 14094.4 15444.5 15464.7 15480.2 15493.9 6841.4 6907.6 6966.6 6991.5 7028.2 789.6 845.4 956.2 967.3 1082.5 29169.7 29823.3 30085.7 30102.8 30478.8 864.7 1024.9 1088.9 1136.2 1228.8 13432.7 14867.9 15399.0 15545.7 15793.8 658564.1 668094.0 672832.0 673727.0 674349.0 56948.0 57157.7 57160.1 57284.1 57338.1 1037.4 1119.6 1224.9 1225.8 1373.4 24271.8 25018.5 29156.7 29833.7 30044.7 48849.8 49346.3 49405.5 49411.0 49510.0 594080.0 602477.0 607382.0 610651.0 612658.0 25374.7 26103.3 26145.2 39621.8 39724.2 46100.8 46515.0 46948.3 47118.8 47159.1 34197.3 38574.0 38867.8 39405.0 40538.6 37985.2 38256.0 38713.2 39505.0 40110.0 36870.0 37441.4 38218.4 38282.6 38420.6 32659.0 33217.5 33865.0 34623.8 34949.0 25407.7 25460.4 25473.6 25604.0 26033.0 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:32734.24 Median:4452.84 stddev:91734.38 (2060.1%) stddev-sans-first:91959.44 05:49:00 INFO - PROCESS | 1667 | Values: 323.8 375.5 382.9 383.2 404.9 19566.4 19600.0 20170.4 20288.1 20692.6 19506.9 19516.2 20228.8 20685.9 21038.1 4377.4 5318.8 5590.0 5592.9 6107.1 6103.0 6174.4 6185.3 6188.0 6211.3 5904.9 6130.2 6175.4 6176.5 6177.0 6089.4 6096.7 6105.4 6150.3 6152.1 5915.7 5965.9 5970.1 5975.4 5981.3 12962.7 13319.5 13394.9 15280.8 24239.4 2079.4 2214.6 2544.0 2935.9 2994.4 8999.0 9842.7 9847.4 9928.6 10363.2 1814.5 1838.9 1884.5 1921.3 1923.1 88300.6 118463.0 122650.0 123043.5 125866.7 9096.4 11500.3 11629.7 11649.9 11742.8 14522.2 14565.1 14581.6 14583.7 14605.0 8477.0 8719.2 8743.3 8777.3 8814.9 8618.9 8693.2 8712.6 8857.2 8871.2 4528.3 4561.1 4595.7 4661.5 4700.9 1682.8 1958.7 1962.5 1967.3 1985.8 869.3 881.5 921.3 948.0 989.7 17787.5 20474.0 20575.4 21795.8 21849.5 862.6 863.6 893.4 905.2 939.2 9913.2 10324.9 10841.1 11478.4 11585.6 514809.0 519459.0 526653.0 581778.0 597640.0 76483.6 83048.0 116754.0 126543.0 134913.0 898.9 986.5 997.5 1024.5 1038.8 19520.9 20130.0 20245.1 20311.4 20314.7 51858.2 52757.7 54143.0 54982.2 57129.4 64906.0 66316.0 84317.0 84733.0 85845.7 7181.7 7303.0 7576.8 8303.5 8467.9 12859.2 12918.4 13154.1 13353.9 13500.0 10683.7 11018.4 11232.5 11430.9 11477.0 5913.8 10458.7 10848.9 10982.0 11024.6 8959.0 9228.2 10160.2 11494.8 12408.9 7027.8 7395.4 7431.2 8032.2 8432.7 10763.3 11328.8 11501.5 11519.7 11558.1 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5683.56 Median:5143.59 stddev:3167.96 (61.6%) stddev-sans-first:3161.46 05:49:00 INFO - PROCESS | 1667 | Values: 1529.1 1558.0 1791.0 1861.4 1894.2 7957.5 8276.2 8443.4 9045.1 9050.5 8457.1 8789.8 8840.7 8914.4 8946.0 2325.6 2362.7 2372.1 2372.8 2386.1 2328.2 2368.2 2368.7 2370.5 2374.5 2340.3 2342.4 2360.4 2362.1 2369.7 2326.5 2334.7 2335.7 2342.9 2347.8 2321.1 2331.3 2340.0 2341.0 2343.4 8935.3 8943.0 8997.7 8999.1 9014.0 5186.6 5273.6 5359.4 5367.0 5374.1 3570.4 3586.6 3638.7 3640.9 3664.0 1075.9 1080.1 1082.5 1093.2 1108.0 11590.2 11670.8 11733.8 11768.8 11786.9 4493.6 4599.6 4772.8 4819.6 4821.3 9925.5 10045.1 10046.8 10054.3 10055.6 5325.7 5339.5 5349.2 5352.7 5354.3 4353.9 4511.7 4705.4 4838.9 4895.7 3900.3 3949.8 3975.7 3994.1 4058.2 1202.6 1205.9 1207.2 1209.0 1214.9 817.4 862.8 872.9 911.2 956.7 5624.9 5858.4 5940.9 6046.2 6237.3 818.5 882.1 910.2 927.8 977.3 3515.1 3523.9 3543.0 3608.2 3625.8 8550.6 9718.2 10005.8 10006.9 10893.5 9166.6 9907.3 10215.9 10914.7 10955.2 8145.2 8216.2 8240.5 8357.8 8372.8 5831.5 5844.6 5867.6 5965.3 6034.7 9856.5 9909.7 10011.0 10035.2 10035.2 9916.4 10148.3 10373.2 10379.2 10382.2 5954.0 6513.1 6757.9 6849.8 7178.8 8280.1 8542.0 8553.1 8556.3 8579.7 7390.0 7465.3 7593.9 7652.5 7717.7 7320.6 7406.2 7439.9 7578.3 7613.0 6800.0 6891.9 6957.7 6965.3 6974.4 4974.8 5119.5 5174.4 5196.6 5226.2 4959.8 5124.0 5163.2 5231.9 5377.9 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4406.30 Median:4751.97 stddev:1366.89 (28.8%) stddev-sans-first:1335.05 05:49:00 INFO - PROCESS | 1667 | Values: 272.6 303.0 308.5 315.2 319.3 4301.5 5364.7 5524.3 5700.9 5758.4 4781.2 5072.7 5277.7 5478.6 5550.0 3389.7 3979.3 4185.3 4255.6 4257.8 3785.0 3896.7 4137.4 4176.4 4264.1 4143.8 4146.4 4148.3 4149.4 4176.9 4093.8 4141.3 4172.0 4180.7 4196.1 3194.0 3605.5 3618.7 3625.5 3626.6 5291.6 5370.8 5393.8 5414.0 5424.3 4173.0 4776.3 4793.0 4796.3 4799.5 4576.0 4822.6 5104.7 5897.3 5900.3 3713.6 3842.3 3924.0 3938.4 3949.5 6136.4 6139.4 6182.7 6192.0 6245.8 3787.3 3852.0 3973.0 3985.8 4010.8 3590.3 3927.4 3962.0 3990.3 3998.9 3626.7 4422.1 4670.4 4744.2 4759.7 5452.4 5885.9 5956.8 5960.6 5986.7 1124.7 1212.4 1267.5 1352.3 1372.7 734.0 744.1 785.1 828.8 840.9 4598.6 4605.4 4683.8 4684.2 4690.2 4689.0 4825.0 5055.2 5103.8 5113.2 4170.9 4357.7 4638.5 4640.2 4670.3 2271.6 2294.6 2621.8 2627.3 2700.6 4556.7 4792.8 4891.0 5024.8 5296.3 4326.9 4473.0 4641.5 4869.2 5186.2 4460.5 4478.9 4649.6 5168.6 5176.5 4008.8 4510.0 4533.9 5048.0 5068.9 4465.2 4481.1 4910.9 5008.5 5040.8 4938.8 5166.4 5208.0 5225.7 5261.3 3446.8 3458.1 3463.9 3492.3 3500.7 4893.5 5006.6 5051.2 5093.7 5095.9 5121.9 5169.6 5588.3 5863.9 5887.3 5357.4 5501.4 5848.9 5871.4 5917.2 5485.8 5688.8 5785.3 5803.8 5870.9 4549.5 5146.6 5248.5 6005.0 6037.9 5891.6 5894.9 5896.3 5896.6 5914.0 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:25237.02 Median:3626.80 stddev:40330.97 (1112.0%) stddev-sans-first:40402.09 05:49:00 INFO - PROCESS | 1667 | Values: 716.9 718.8 724.5 774.8 809.3 15242.8 15971.6 15989.4 16003.4 16004.5 13623.9 13654.9 13677.7 13684.2 13727.0 684.9 720.4 839.7 851.6 872.0 892.1 892.3 894.0 894.7 899.5 938.2 945.8 948.9 951.0 953.9 928.0 935.6 938.0 941.5 943.5 688.9 699.8 705.0 706.4 706.8 14609.2 14705.2 14727.5 14732.8 14739.2 13671.9 13677.2 14614.3 14705.5 14786.2 8965.0 9162.7 11881.8 12005.8 12089.0 3626.8 3815.0 3815.7 3838.6 3968.8 85357.7 112377.0 115812.2 117068.8 117773.4 11024.8 11090.2 11214.5 11238.7 11286.8 22841.2 22864.0 22917.3 22927.9 22937.1 7880.1 7899.8 8005.8 8054.2 8212.0 4324.5 4384.7 4420.9 4427.0 4435.4 3512.5 3605.9 3616.9 3626.8 3775.7 656.8 678.1 679.1 683.1 686.5 547.0 630.0 660.4 675.5 683.8 9723.4 9723.9 9727.4 9754.1 9873.2 545.9 590.8 632.7 638.6 688.4 4496.0 4582.0 4781.4 4789.1 4845.8 134974.3 151761.0 152363.2 155482.2 159767.2 114926.4 134008.3 136306.0 137407.3 138583.0 110330.0 111030.9 116307.0 118893.2 119287.0 9004.9 9022.1 9078.8 9110.0 9135.4 56399.4 65675.0 66127.7 67423.3 68311.3 85126.0 86110.6 86981.0 91542.2 93961.0 13170.6 13237.3 13546.5 13768.2 14021.4 14961.6 15077.2 15190.1 15248.1 15596.8 13525.3 13669.9 13725.4 13948.9 14306.1 13732.2 14026.3 14196.8 14396.1 14489.2 13344.9 13598.2 13700.9 13752.4 13788.4 10996.7 11299.5 11425.9 11544.8 11816.3 10983.8 11356.5 11591.9 11724.3 11748.8 05:49:00 INFO - PROCESS | 1667 | -------- Summary: end -------- 05:49:00 INFO - PROCESS | 1667 | 05:49:00 INFO - TEST-INFO | 1667: exit 0 05:49:00 INFO - TEST-OK | dromaeo_css | took 1114191ms 05:49:00 INFO - TEST-START | kraken 05:49:00 INFO - operating with platform_type : mac_ 05:49:00 INFO - Initialising browser for kraken test... 05:49:00 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpvSjHgF/profile http://localhost:49267/getInfo.html) 05:49:03 INFO - PROCESS | 1675 | 2016-10-09 05:49:03.517 plugin-container[1677:10650] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b27, name = 'com.apple.tsm.portname' 05:49:03 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:03 INFO - PROCESS | 1675 | 2016-10-09 05:49:03.517 plugin-container[1677:10650] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9203, name = 'com.apple.CFPasteboardClient' 05:49:03 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:03 INFO - PROCESS | 1675 | 2016-10-09 05:49:03.517 plugin-container[1677:10650] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:49:03 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 05:49:03 INFO - PROCESS | 1675 | colorDepth:24 05:49:03 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 05:49:03 INFO - PROCESS | 1675 | __metrics 05:49:03 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 05:49:04 INFO - TEST-INFO | 1675: exit 0 05:49:04 INFO - Browser initialized. 05:49:04 INFO - Running cycle 1/1 for kraken test... 05:49:04 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpvSjHgF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:06 INFO - PROCESS | 1678 | 2016-10-09 05:49:06.738 plugin-container[1679:10767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1f, name = 'com.apple.tsm.portname' 05:49:06 INFO - PROCESS | 1678 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:06 INFO - PROCESS | 1678 | 2016-10-09 05:49:06.739 plugin-container[1679:10767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 05:49:06 INFO - PROCESS | 1678 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:06 INFO - PROCESS | 1678 | 2016-10-09 05:49:06.739 plugin-container[1679:10767] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:49:16 INFO - PROCESS | 1678 | RSS: Main: 239312896 05:49:16 INFO - PROCESS | 1678 | 05:49:19 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 05:49:20 INFO - PROCESS | 1678 | RSS: Main: 252829696 05:49:20 INFO - PROCESS | 1678 | 05:49:23 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 05:49:23 INFO - PROCESS | 1678 | RSS: Main: 260243456 05:49:23 INFO - PROCESS | 1678 | 05:49:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 05:49:27 INFO - PROCESS | 1678 | RSS: Main: 261246976 05:49:27 INFO - PROCESS | 1678 | 05:49:29 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 05:49:30 INFO - PROCESS | 1678 | RSS: Main: 263405568 05:49:30 INFO - PROCESS | 1678 | 05:49:32 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:49:32 INFO - PROCESS | 1678 | RSS: Main: 263774208 05:49:32 INFO - PROCESS | 1678 | 05:49:35 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 05:49:36 INFO - PROCESS | 1678 | RSS: Main: 264392704 05:49:36 INFO - PROCESS | 1678 | 05:49:39 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 05:49:39 INFO - PROCESS | 1678 | RSS: Main: 264892416 05:49:39 INFO - PROCESS | 1678 | 05:49:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 05:49:43 INFO - PROCESS | 1678 | RSS: Main: 265334784 05:49:43 INFO - PROCESS | 1678 | 05:49:44 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:49:45 INFO - PROCESS | 1678 | RSS: Main: 264687616 05:49:45 INFO - PROCESS | 1678 | 05:49:47 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:49:48 INFO - PROCESS | 1678 | RSS: Main: 264785920 05:49:48 INFO - PROCESS | 1678 | 05:49:50 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:49:50 INFO - PROCESS | 1678 | RSS: Main: 264851456 05:49:50 INFO - PROCESS | 1678 | 05:49:53 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:54 INFO - PROCESS | 1678 | RSS: Main: 265089024 05:49:54 INFO - PROCESS | 1678 | 05:49:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:58 INFO - PROCESS | 1678 | RSS: Main: 265207808 05:49:58 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 05:50:00 INFO - PROCESS | 1678 | RSS: Main: 266559488 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | __start_tp_report 05:50:00 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 05:50:00 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 05:50:00 INFO - PROCESS | 1678 | |i|pagename|runs| 05:50:00 INFO - PROCESS | 1678 | |0;ai-astar;114;117;118;119;151;164;96;139;174;176 05:50:00 INFO - PROCESS | 1678 | |1;audio-beat-detection;155;118;110;146;111;138;144;141;139;114 05:50:00 INFO - PROCESS | 1678 | |2;audio-dft;199;244;210;198;195;200;193;198;200;194 05:50:00 INFO - PROCESS | 1678 | |3;audio-fft;80;62;58;89;60;59;60;60;80;59 05:50:00 INFO - PROCESS | 1678 | |4;audio-oscillator;103;100;117;86;71;70;86;71;72;73 05:50:00 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;88;86;89;86;88;84;88;85;88;87 05:50:00 INFO - PROCESS | 1678 | |6;imaging-darkroom;95;102;151;123;95;94;93;94;95;94 05:50:00 INFO - PROCESS | 1678 | |7;imaging-desaturate;149;96;138;152;92;90;88;90;91;95 05:50:00 INFO - PROCESS | 1678 | |8;json-parse-financial;55;54;54;57;57;55;57;57;58;56 05:50:00 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;66;73;73;73;75;73;70;69;69;67 05:50:00 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;106;108;100;86;87;93;90;91;90;94 05:50:00 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;138;135;154;140;130;149;168;150;216;173 05:50:00 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;161;173;174;235;176;254;241;182;162;238 05:50:00 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;70;86;94;86;69;70;70;79;87;92 05:50:00 INFO - PROCESS | 1678 | __end_tp_report 05:50:00 INFO - PROCESS | 1678 | __start_cc_report 05:50:00 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,930 05:50:00 INFO - PROCESS | 1678 | __end_cc_report 05:50:00 INFO - PROCESS | 1678 | __startTimestamp1476017400570__endTimestamp 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | ------- Summary: start ------- 05:50:00 INFO - PROCESS | 1678 | Number of tests: 14 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:136.80 Median:157.50 stddev:28.11 (17.9%) stddev-sans-first:28.58 05:50:00 INFO - PROCESS | 1678 | Values: 114.0 117.0 118.0 119.0 151.0 164.0 96.0 139.0 174.0 176.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:140.00 stddev:16.59 (11.9%) stddev-sans-first:15.29 05:50:00 INFO - PROCESS | 1678 | Values: 155.0 118.0 110.0 146.0 111.0 138.0 144.0 141.0 139.0 114.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:203.10 Median:199.50 stddev:15.13 (7.6%) stddev-sans-first:15.97 05:50:00 INFO - PROCESS | 1678 | Values: 199.0 244.0 210.0 198.0 195.0 200.0 193.0 198.0 200.0 194.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.00 stddev:11.56 (18.9%) stddev-sans-first:11.21 05:50:00 INFO - PROCESS | 1678 | Values: 80.0 62.0 58.0 89.0 60.0 59.0 60.0 60.0 80.0 59.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:84.90 Median:71.50 stddev:16.68 (23.3%) stddev-sans-first:16.36 05:50:00 INFO - PROCESS | 1678 | Values: 103.0 100.0 117.0 86.0 71.0 70.0 86.0 71.0 72.0 73.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:1.60 (1.8%) stddev-sans-first:1.64 05:50:00 INFO - PROCESS | 1678 | Values: 88.0 86.0 89.0 86.0 88.0 84.0 88.0 85.0 88.0 87.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:94.00 stddev:18.96 (20.2%) stddev-sans-first:19.86 05:50:00 INFO - PROCESS | 1678 | Values: 95.0 102.0 151.0 123.0 95.0 94.0 93.0 94.0 95.0 94.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:108.10 Median:90.50 stddev:26.71 (29.5%) stddev-sans-first:23.89 05:50:00 INFO - PROCESS | 1678 | Values: 149.0 96.0 138.0 152.0 92.0 90.0 88.0 90.0 91.0 95.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:1.41 (2.5%) stddev-sans-first:1.45 05:50:00 INFO - PROCESS | 1678 | Values: 55.0 54.0 54.0 57.0 57.0 55.0 57.0 57.0 58.0 56.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:73.00 stddev:3.01 (4.1%) stddev-sans-first:2.65 05:50:00 INFO - PROCESS | 1678 | Values: 66.0 73.0 73.0 73.0 75.0 73.0 70.0 69.0 69.0 67.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:90.00 stddev:7.66 (8.5%) stddev-sans-first:6.91 05:50:00 INFO - PROCESS | 1678 | Values: 106.0 108.0 100.0 86.0 87.0 93.0 90.0 91.0 90.0 94.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:152.00 stddev:25.42 (16.7%) stddev-sans-first:26.18 05:50:00 INFO - PROCESS | 1678 | Values: 138.0 135.0 154.0 140.0 130.0 149.0 168.0 150.0 216.0 173.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.60 Median:208.50 stddev:37.32 (17.9%) stddev-sans-first:36.88 05:50:00 INFO - PROCESS | 1678 | Values: 161.0 173.0 174.0 235.0 176.0 254.0 241.0 182.0 162.0 238.0 05:50:00 INFO - PROCESS | 1678 | 05:50:00 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.30 Median:86.00 stddev:9.90 (11.5%) stddev-sans-first:9.77 05:50:00 INFO - PROCESS | 1678 | Values: 70.0 86.0 94.0 86.0 69.0 70.0 70.0 79.0 87.0 92.0 05:50:00 INFO - PROCESS | 1678 | -------- Summary: end -------- 05:50:00 INFO - PROCESS | 1678 | 05:50:01 INFO - TEST-INFO | 1678: exit 0 05:50:01 INFO - TEST-OK | kraken | took 60636ms 05:50:01 INFO - SUITE-END | took 1174s 05:50:01 INFO - Completed test suite (00:19:35) 05:50:01 INFO - javascript benchmark 05:50:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7286.586563249427, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2659.4149287156824, 3060.2502386915194, 3079.5996520452336, 3366.882159124431, 3412.76110672253, 5590.580706447013, 5653.180288858113, 5714.428557144289, 5733.226014488043, 5756.453136951994, 5611.2144299798065, 5612.382637909826, 5717.0, 5720.570957178207, 5838.445347691973, 5132.538071573557, 5137.666051706646, 5153.999999999991, 5281.762320695559, 5285.392179899322, 5286.3392075990505, 5314.415414304423, 5325.014872248627, 5333.876626151584, 5340.786368545254, 4319.978400107995, 5075.213341932006, 5148.819791307324, 5235.58115350771, 5243.370795504564, 5190.636655434121, 5193.221016847466, 5218.582513398919, 5219.4780521947505, 5245.763940622681, 4060.330045542482, 4269.786510674454, 5125.2312153176945, 5163.66436181647, 5183.067047931371, 5541.5289700375515, 5550.111982082888, 5551.2228288039705, 5599.0, 5699.68651724155, 5095.8534329775875, 6093.451589356979, 6164.599301045418, 6166.105914642351, 6170.259568851767, 6178.876422471525, 6245.063240513914, 6254.6872656366995, 6259.280182778994, 6272.1219029335925, 5745.482906538432, 5775.797847075416, 5822.301323841166, 5858.648481091105, 5917.260342457193, 6260.092286618506, 6268.811935641938, 6269.404406581368, 6272.843178920563, 6274.811755647338, 6131.356207598227, 6162.075688646739, 6168.1056246843955, 6169.383061693795, 6183.227096612924, 6077.392260773976, 6121.357257487989, 6135.783919773617, 6136.304638670889, 6230.875382492325, 6112.777056696867, 6178.4439400453275, 6189.783357582463, 6191.071339299051, 6191.535634827406, 5829.8251052468495, 5891.646501209942, 6170.784022559189, 6171.876562468815, 6175.166352542439, 4815.662903596714, 4842.7336496493035, 4882.926756098661, 4921.827736029221, 4927.975360123177, 4331.306990881444, 4368.628666563378, 4377.518472967971, 4464.0402313502755, 4494.955050449454, 5611.80358687444, 5776.480116789425, 5779.3642699303045, 5788.421157884262, 5803.477687008105, 6083.66539840313, 6109.2528441424865, 6231.22109736283, 6238.781642642576, 6268.122462855204, 5614.9719251403485, 5640.520555752807, 5742.908847318996, 5781.103928891029, 5877.617954832922, 5376.623636345496, 5377.7311134443125, 5378.11261141915, 5396.08266594672, 5410.323709536308, 6266.185395898504, 6286.65423401699, 6287.371262873768, 6306.716197771111, 6308.148399965946, 6077.878442431215, 6094.7734293265175, 6134.957077260823, 6153.0, 6160.199174107337, 6244.094606282155, 6269.592476489013, 6273.71768270438, 6292.402221788922, 6294.685265736786, 5976.61152025117, 6030.668313242813, 6085.2393450818645, 6093.0, 6161.383861613803, 5187.273781670494, 6156.322804491592, 6208.503319734501, 6237.126802247598, 6253.593516421418, 4562.771861406983, 6096.390360963868, 6136.171616831671, 6144.41628045335, 6154.169187159676, 5951.0, 6027.36712645166, 6153.630782153085, 6161.907571386343, 6162.198914141133, 5833.445822646841, 5841.795537156179, 5943.583949123693, 6002.609830361012, 6030.547708921936, 6016.2479690038745, 6075.27096748393, 6104.847378815565, 6112.358202388863, 6118.174046503843, 5879.176915231786, 5927.614705044159, 5931.051031835058, 5935.0503919372695, 5958.314793798769], "value": 5630.288109279593, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.088029223307, 518.2226660009985, 532.6058716549752, 553.3083645443198, 554.2323881424228, 38340.61659383406, 40033.799831000804, 40036.39945400814, 40113.0, 40152.79923600382, 40756.79621601895, 41311.58688413115, 41404.58595414045, 41407.0, 41409.37885931706, 8353.749387518385, 9907.900920990805, 9910.702678919644, 9918.20654347654, 9956.253281003917, 9780.608775648965, 9845.45849978251, 9862.9506852466, 9869.111779939803, 9901.0, 8363.83272334553, 9826.0, 9856.718626578535, 9859.753506162368, 9873.753156171082, 9806.234877722007, 9845.901540984605, 9849.655262065866, 9854.211663066937, 9889.159421449185, 9456.243500519942, 9474.763130921714, 9543.522823858779, 9545.665901693408, 9562.856557151648, 39036.80481597602, 39319.82040538789, 39324.60675393238, 39347.60652393468, 39488.0, 3304.867805287786, 3326.600807903043, 3355.3457075870215, 3405.8126803025825, 3412.3175364926865, 30305.848470757504, 30642.0, 30968.0, 31065.91269305586, 31220.598663643617, 3153.59003329568, 3229.660885607001, 3305.636379998198, 3311.784733992283, 3364.9495257571157, 497262.0, 498178.0, 502802.0, 504091.0, 505981.0, 46626.76686616613, 55635.0, 55764.0, 56338.0, 56388.718056410275, 60192.699036505415, 60306.69846650738, 60338.39661603416, 60385.0, 60405.0, 15419.229038548028, 15657.373705051898, 15838.0, 15856.60358491047, 15869.285882135331, 12790.424430900632, 12860.228386296852, 12867.22796632205, 12882.22706637586, 12895.0, 14094.436222551189, 15444.45944391941, 15464.690706185813, 15480.225988700746, 15493.922530387501, 6841.418479429304, 6907.620080895599, 6966.582005079711, 6991.510594258455, 7028.226895041541, 789.5854676294877, 845.3913182508584, 956.1967946924617, 967.2890425537224, 1082.5399205337858, 29169.708302917123, 29823.254418639706, 30085.69914300873, 30102.849485752868, 30478.847605761828, 864.6714248585497, 1024.8872624011353, 1088.8638920134983, 1136.2443974756695, 1228.8402507674125, 13432.731345373038, 14867.851321486864, 15398.999065060736, 15545.68908621844, 15793.842061579237, 658564.1435856472, 668094.0, 672832.0, 673727.0, 674349.0, 56948.0, 57157.714211428676, 57160.142597861064, 57284.14073788896, 57338.13992790028, 1037.3555233819372, 1119.6249256499002, 1224.9326287054118, 1225.8345123408403, 1373.3888419653158, 24271.752443321995, 25018.499630006932, 29156.708432916246, 29833.701662983094, 30044.699553005066, 48849.75575122102, 49346.259806103655, 49405.50594494009, 49411.0, 49510.0, 594080.0, 602477.0, 607382.0, 610651.0, 612658.0, 25374.74625253724, 26103.347416314744, 26145.215643530726, 39621.80189099036, 39724.20551588894, 46100.7694961523, 46515.0, 46948.295775562714, 47118.79410579834, 47159.056818862744, 34197.316053679286, 38574.035649109, 38867.80566097151, 39405.0, 40538.59461405466, 37985.2402951945, 38256.0, 38713.22573548457, 39505.0, 40109.997250068984, 36870.0, 37441.43837842489, 38218.426723599725, 38282.61717382791, 38420.6157938417, 32659.0, 33217.501737473474, 33864.984050478524, 34623.82688086543, 34949.0, 25407.745922540536, 25460.363490912136, 25473.61789573195, 25604.0, 26033.0], "value": 19721.283070433008, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.82189795612413, 375.53996354465784, 382.9444730514074, 383.23927004895285, 404.9250888585612, 19566.413007609757, 19600.0, 20170.394888153343, 20288.087036083358, 20692.586148277027, 19506.902465487652, 19516.219351225933, 20228.797712022875, 20685.896570517165, 21038.053287602055, 4377.430933978594, 5318.760655770499, 5589.993801115818, 5592.888142237153, 6107.083937409402, 6102.969485152568, 6174.413430724074, 6185.319614842364, 6187.969060154715, 6211.285702144243, 5904.852378690545, 6130.203073600425, 6175.351588083254, 6176.505879529627, 6176.9691151544175, 6089.360617135203, 6096.72564734588, 6105.389461053904, 6150.323464418932, 6152.107944348045, 5915.733791979328, 5965.880682386328, 5970.0746384310505, 5975.372585878507, 5981.252343457068, 12962.675933101653, 13319.467221311135, 13394.93302533481, 15280.770788438182, 24239.394015149763, 2079.4489460293034, 2214.6124428225, 2544.0078369435937, 2935.949243921091, 2994.356213414105, 8998.955005224932, 9842.65550705722, 9847.409155450625, 9928.553215105338, 10363.222758293083, 1814.4647329037966, 1838.9172487238106, 1884.5349974432602, 1921.34674210769, 1923.1249781349452, 88300.55849720775, 118462.99999999914, 122650.0, 123043.46521882586, 125866.74133258733, 9096.408733432305, 11500.309981401142, 11629.709257268467, 11649.941750291195, 11742.823857642143, 14522.20127892955, 14565.053271537316, 14581.562553123424, 14583.708325833424, 14605.0, 8477.0, 8719.215270625675, 8743.256823169975, 8777.29781617469, 8814.86777698335, 8618.913810861812, 8693.217610414966, 8712.564371781385, 8857.247133993682, 8871.157240062184, 4528.252838281646, 4561.110583436198, 4595.747233902101, 4661.533846615379, 4700.929486057712, 1682.8401301876302, 1958.6964020576834, 1962.519182800223, 1967.3114409956402, 1985.7517810273716, 869.2958703450225, 881.5283823154582, 921.2860033473977, 947.9952600236954, 989.6931951095039, 17787.46637600874, 20474.0, 20575.38273851787, 21795.782042179377, 21849.453763655718, 862.6161358195543, 863.5984267315729, 893.4237416866085, 905.1762895764822, 939.2298315381322, 9913.157381622499, 10324.896751032393, 10841.07850832688, 11478.368689722145, 11585.594504192313, 514809.0, 519459.0, 526653.0, 581778.0, 597640.0, 76483.61758191173, 83048.0, 116754.0, 126543.0, 134913.0, 898.869663898731, 986.5067466266867, 997.5411310797115, 1024.4672770159475, 1038.8026275007724, 19520.90239548793, 20129.993500325218, 20245.088970996636, 20311.390658280274, 20314.693875511563, 51858.22212666737, 52757.7362113187, 54143.0, 54982.17526737182, 57129.42870571241, 64906.0, 66316.0, 84317.0, 84733.0, 85845.71231431409, 7181.748638797826, 7302.963485182752, 7576.8484630308185, 8303.4602750821, 8467.872981905399, 12859.228446293253, 12918.354082296035, 13154.144980576231, 13353.866461335263, 13500.0, 10683.732906677084, 11018.393988330397, 11232.494537745657, 11430.885691142983, 11476.967072963473, 5913.763449462145, 10458.68623941283, 10848.945755271174, 10982.0, 11024.559017639205, 8959.0, 9228.215601673934, 10160.237982151517, 11494.82757758651, 12408.875911241134, 7027.754028608974, 7395.445341599294, 7431.182569917413, 8032.236937490929, 8432.704855330034, 10763.267740012112, 11328.773424531299, 11501.48243329069, 11519.654410367702, 11558.133140014701], "value": 9372.167044536349, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1529.0978322789556, 1557.9873082496376, 1791.0238919788715, 1861.4322631597358, 1894.204434137662, 7957.522548647071, 8276.17238276172, 8443.36674749394, 9045.050269721674, 9050.502222377767, 8457.069722330538, 8789.780255493615, 8840.690575829847, 8914.42056266344, 8945.955270223656, 2325.6278995360744, 2362.70466191726, 2372.146027430124, 2372.822038347122, 2386.140989243871, 2328.1734984080663, 2368.1593034472685, 2368.656544801003, 2370.466645004877, 2374.501354715503, 2340.3096086654477, 2342.437814924423, 2360.3745007573007, 2362.11420717231, 2369.7156341239074, 2326.5230627721276, 2334.708161479815, 2335.74306826249, 2342.8711420871846, 2347.8378202789645, 2321.100417560324, 2331.3355693627236, 2339.9532009359887, 2340.9648855267183, 2343.425860664131, 8935.28517718581, 8943.0, 8997.685081022133, 8999.100089991014, 9013.954930225373, 5186.636935414523, 5273.604479664041, 5359.356877174725, 5367.007103685831, 5374.086405311106, 3570.357335679577, 3586.6413358664186, 3638.745287829853, 3640.872569460056, 3664.047347689593, 1075.93544387337, 1080.0603474976742, 1082.4587706146926, 1093.2128867215672, 1107.9889201107967, 11590.188686791927, 11670.76658466826, 11733.823992640117, 11768.823467647991, 11786.882131178665, 4493.550644935481, 4599.6090332321455, 4772.785224664898, 4819.59033482151, 4821.252705830531, 9925.50372481373, 10045.146162576262, 10046.849297260547, 10054.346467479736, 10055.648052318134, 5325.653832500875, 5339.466053394707, 5349.22436246742, 5352.678839269656, 5354.277172581729, 4353.934690979638, 4511.70673906195, 4705.364775755229, 4838.879028024327, 4895.7062576245535, 3900.278448486982, 3949.782761948063, 3975.662068724133, 3994.08136128692, 4058.1680755445063, 1202.5790973159499, 1205.9095567832449, 1207.2454715802623, 1208.987910120905, 1214.9247915594763, 817.4073796497491, 862.8490014247482, 872.9476231426135, 911.1935936696071, 956.7177682583655, 5624.943750562442, 5858.443447872445, 5940.940590594038, 6046.244219472566, 6237.282712488031, 818.4843548564367, 882.1399135842502, 910.1535571918047, 927.7726956895604, 977.3354119199013, 3515.086077619786, 3523.94714079289, 3543.0, 3608.1881576645046, 3625.7643253188457, 8550.615222314886, 9718.173955213744, 10005.849912251322, 10006.949965250273, 10893.509792059376, 9166.633334666672, 9907.306488545733, 10215.94892025535, 10914.672559823219, 10955.17836162291, 8145.226203510657, 8216.178382161735, 8240.546769927592, 8357.791055223668, 8372.790680233044, 5831.533477321719, 5844.649321040752, 5867.589268751317, 5965.254343207099, 6034.668093254738, 9856.507174641383, 9909.662195603501, 10011.0, 10035.24735644815, 10035.24735644844, 9916.405015699082, 10148.289619726549, 10373.170146388422, 10379.169666426818, 10382.169426446017, 5953.970230148821, 6513.120728701565, 6757.93242067573, 6849.794506164823, 7178.774579880253, 8280.089190188954, 8542.0, 8553.144685531397, 8556.315494760529, 8579.742607721779, 7390.0, 7465.253474652492, 7593.924060759543, 7652.464327497244, 7717.691292348468, 7320.633968301457, 7406.222346653739, 7439.9256007439235, 7578.280063393969, 7613.010308659839, 6800.0, 6891.862162756616, 6957.652117394211, 6965.33829286217, 6974.37230649244, 4974.825881094289, 5119.513646203604, 5174.404943431554, 5196.636235463481, 5226.151747297808, 4959.801607935642, 5123.974380128076, 5163.199704045879, 5231.895362092813, 5377.865553361197], "value": 4571.371448585684, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.55845530241015, 302.975761939045, 308.5079298518862, 315.1506689471873, 319.28480204342264, 4301.537477257732, 5364.7317634118235, 5524.254225679532, 5700.942990570093, 5758.395368486306, 4781.163296423129, 5072.731817045738, 5277.70972596507, 5478.561715062795, 5550.0, 3389.711874490659, 3979.3036218661764, 4185.28850095483, 4255.5530020683345, 4257.808398622069, 3784.9621503785024, 3896.6687831534355, 4137.386419296477, 4176.394422808691, 4264.104538047014, 4143.751374917515, 4146.44023056888, 4148.274052040896, 4149.356849688304, 4176.895577610554, 4093.7953102345064, 4141.254574176647, 4171.97786542296, 4180.728252663567, 4196.118815048843, 3194.0417874637515, 3605.5312809334882, 3618.7105031597407, 3625.456181572759, 3626.637336266352, 5291.576673866081, 5370.758315875795, 5393.83818485446, 5414.0477451460265, 5424.349078110652, 4173.0, 4776.283557466373, 4793.041391721669, 4796.25658023007, 4799.512151233129, 4576.004148219041, 4822.614190864722, 5104.744762761847, 5897.292324920994, 5900.321463031763, 3713.6472035156617, 3842.3275926712713, 3923.9993801580413, 3938.369860822255, 3949.5063117110362, 6136.447719705248, 6139.447449729546, 6182.69086545671, 6192.0, 6245.843853903643, 3787.280416720869, 3851.9792255052434, 3973.006748312922, 3985.7608543487486, 4010.7794071326357, 3590.317839610466, 3927.434283598667, 3962.0, 3990.2618015666612, 3998.9400158997637, 3626.6917312028245, 4422.137683151755, 4670.416197975253, 4744.197205062054, 4759.738214398241, 5452.372977107604, 5885.911711324334, 5956.76172953077, 5960.642361458326, 5986.6707331097205, 1124.7413094988199, 1212.4180393411036, 1267.473998290699, 1352.3373546962114, 1372.7254549090221, 733.9797681222998, 744.0839837418669, 785.1049803224329, 828.8147948433798, 840.8612578924409, 4598.562761736285, 4605.447346318463, 4683.81264749413, 4684.236300582605, 4690.202665546865, 4688.953110468851, 4825.010872771073, 5055.219629203923, 5103.800606857386, 5113.207452844815, 4170.895727606834, 4357.716748411343, 4638.512956139624, 4640.234361330343, 4670.299455081697, 2271.602964177019, 2294.621387471082, 2621.7509336612993, 2627.2643659774913, 2700.648915640954, 4556.681032327705, 4792.760361981887, 4890.975545122323, 5024.794049428111, 5296.292595183387, 4326.935095973563, 4473.038296766211, 4641.466231383367, 4869.220924652039, 5186.236679528978, 4460.487043989982, 4478.909552408827, 4649.558291962326, 5168.61235407346, 5176.456472070378, 4008.7995600219288, 4509.977450112729, 4533.909321813611, 5047.974760126176, 5068.898622027465, 4465.1962646723905, 4481.058977614737, 4910.8772280693265, 5008.474110218521, 5040.773165207501, 4938.839372747473, 5166.354205724285, 5208.0, 5225.719698673849, 5261.342332208474, 3446.844891979917, 3458.083607843974, 3463.913402164966, 3492.336456073338, 3500.6697454966948, 4893.461719210813, 5006.5994720423005, 5051.242313652834, 5093.745312734422, 5095.949040509696, 5121.948780512147, 5169.586433085269, 5588.267637032533, 5863.853403664943, 5887.293524777054, 5357.383900851452, 5501.449855014466, 5848.94151058501, 5871.412858714094, 5917.19525544713, 5485.820548582074, 5688.829335119953, 5785.334686511105, 5803.7678492861505, 5870.9412905870395, 4549.499555048876, 5146.639735218499, 5248.504176309733, 6004.969975150097, 6037.879242415039, 5891.610749991284, 5894.852628684317, 5896.262967129109, 5896.616719913192, 5914.0375948115725], "value": 3965.6985142419053, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.8996340512332, 718.8382613911871, 724.5037149552556, 774.7791879314397, 809.259527532308, 15242.847571524282, 15971.600709982256, 15989.360425582963, 16003.439879604217, 16004.479574440424, 13623.93188034061, 13654.931725341385, 13677.726445471084, 13684.17894926305, 13727.0, 684.8938414545728, 720.4488566246825, 839.7186942374312, 851.5571902610639, 871.9825603487926, 892.0588778838323, 892.2861710631502, 893.9942564614809, 894.7136916186823, 899.4558292233203, 938.211902002318, 945.7541039329755, 948.8766460360143, 950.9952450237739, 953.9046095390474, 928.0, 935.5696379665362, 937.9671711490133, 941.4727752458645, 943.4999450291323, 688.944884409251, 699.8425354295293, 704.9672230182756, 706.3607435271088, 706.8197609609568, 14609.196494192814, 14705.191214483199, 14727.484538041117, 14732.779008314883, 14739.189344586044, 13671.949207618838, 13677.24775137367, 14614.34235459407, 14705.485308014167, 14786.18675972821, 8965.0, 9162.725118246462, 11881.821772673331, 12005.819912701316, 12089.0, 3626.764260323097, 3814.9799486885863, 3815.656590906804, 3838.596947320547, 3968.8015599219925, 85357.71963420554, 112377.0, 115812.15596674292, 117068.82931170665, 117773.41113294465, 11024.750455300877, 11090.168237382228, 11214.495347709373, 11238.719032024264, 11286.830697539543, 22841.20055798039, 22863.971121299583, 22917.31248062561, 22927.885360573426, 22937.082516699145, 7880.133185349607, 7899.763007109796, 8005.799855003672, 8054.234847689578, 8212.0, 4324.459442569679, 4384.694727655022, 4420.9336859947125, 4426.9778651106535, 4435.379046933367, 3512.4906888501023, 3605.8737944172353, 3616.945745813815, 3626.8367923443507, 3775.6601905828607, 656.8357910522369, 678.1184460201719, 679.0696745458753, 683.0710234081645, 686.5057158845623, 546.9863253418696, 629.9622022678655, 660.3990368764497, 675.5239801034656, 683.7846078485261, 9723.409039802142, 9723.902760972442, 9727.416355018722, 9754.122129008423, 9873.210143188671, 545.8799064205868, 590.7636945221946, 632.6678493790797, 638.581728967527, 688.3838964127078, 4496.033352829158, 4581.954180458153, 4781.402324709411, 4789.060371095256, 4845.781939812717, 134974.3251283757, 151761.0, 152363.23818381058, 155482.2225888886, 159767.20116399575, 114926.42536787262, 134008.32995834958, 136306.0, 137407.31296343455, 138583.0, 110330.0, 111030.88969110367, 116307.0, 118893.16602509693, 119287.0, 9004.864927025968, 9022.060257044766, 9078.819753432275, 9109.954450227706, 9135.360524763202, 56399.43600563942, 65675.0, 66127.66936165288, 67423.31441713996, 68311.31688683249, 85126.0, 86110.56944715236, 86981.0, 91542.16915661782, 93961.0, 13170.60488185356, 13237.27195004284, 13546.525871594447, 13768.173909565458, 14021.369038393093, 14961.551153465414, 15077.170755608548, 15190.088594683912, 15248.085114893142, 15596.766048509508, 13525.323733813073, 13669.863301367257, 13725.382357793724, 13948.930255348658, 14306.141631502143, 13732.244726540137, 14026.29868506591, 14196.787048194492, 14396.136231826124, 14489.203093829941, 13344.933275333562, 13598.184108953494, 13700.862991370357, 13752.449902003807, 13788.379522921694, 10996.65591783134, 11299.491522881655, 11425.942870285928, 11544.769104618028, 11816.291022538675, 10983.82177496245, 11356.488957997075, 11591.88408115908, 11724.296542207494, 11748.765024699629], "value": 7932.783930312688, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 118.0, 119.0, 151.0, 164.0, 96.0, 139.0, 174.0, 176.0], "value": 136.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 118.0, 110.0, 146.0, 111.0, 138.0, 144.0, 141.0, 139.0, 114.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 244.0, 210.0, 198.0, 195.0, 200.0, 193.0, 198.0, 200.0, 194.0], "value": 203.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 62.0, 58.0, 89.0, 60.0, 59.0, 60.0, 60.0, 80.0, 59.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 100.0, 117.0, 86.0, 71.0, 70.0, 86.0, 71.0, 72.0, 73.0], "value": 84.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 84.0, 88.0, 85.0, 88.0, 87.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 151.0, 123.0, 95.0, 94.0, 93.0, 94.0, 95.0, 94.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 96.0, 138.0, 152.0, 92.0, 90.0, 88.0, 90.0, 91.0, 95.0], "value": 108.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 57.0, 57.0, 55.0, 57.0, 57.0, 58.0, 56.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 73.0, 73.0, 75.0, 73.0, 70.0, 69.0, 69.0, 67.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 108.0, 100.0, 86.0, 87.0, 93.0, 90.0, 91.0, 90.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 154.0, 140.0, 130.0, 149.0, 168.0, 150.0, 216.0, 173.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 173.0, 174.0, 235.0, 176.0, 254.0, 241.0, 182.0, 162.0, 238.0], "value": 199.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 86.0, 94.0, 86.0, 69.0, 70.0, 70.0, 79.0, 87.0, 92.0], "value": 80.3, "unit": "score"}]}]} 05:50:01 INFO - Return code: 0 05:50:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:50:01 ERROR - # TBPL SUCCESS # 05:50:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:50:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:50:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:50:01 INFO - 2016-10-09 05:50:01.859 screenresolution[1683:11149] starting screenresolution argv=screenresolution get 05:50:01 INFO - 2016-10-09 05:50:01.861 screenresolution[1683:11149] Display 0: 1600x1200x32@60 05:50:01 INFO - 2016-10-09 05:50:01.867 screenresolution[1684:11152] starting screenresolution argv=screenresolution list 05:50:01 INFO - Available Modes on Display 0 05:50:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:50:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:50:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:50:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:50:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:50:02 INFO - Intel Iris: 05:50:02 INFO - Chipset Model: Intel Iris 05:50:02 INFO - Type: GPU 05:50:02 INFO - Bus: Built-In 05:50:02 INFO - VRAM (Dynamic, Max): 1536 MB 05:50:02 INFO - Vendor: Intel (0x8086) 05:50:02 INFO - Device ID: 0x0a2e 05:50:02 INFO - Revision ID: 0x0009 05:50:02 INFO - Displays: 05:50:02 INFO - Display: 05:50:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:50:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:50:02 INFO - Display Serial Number: 1600x1200 60 05:50:02 INFO - Main Display: Yes 05:50:02 INFO - Mirror: Off 05:50:02 INFO - Online: Yes 05:50:02 INFO - Rotation: Supported 05:50:02 INFO - Return code: 0 05:50:02 INFO - Running post-action listener: _resource_record_post_action 05:50:02 INFO - [mozharness: 2016-10-09 12:50:02.101668Z] Finished run-tests step (success) 05:50:02 INFO - Running post-run listener: _resource_record_post_run 05:50:02 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 33468416; Write bytes: 710684672; Read time: 203; Write time: 2024 05:50:02 INFO - TinderboxPrint: CPU usage
54.7% 05:50:02 INFO - TinderboxPrint: I/O read bytes / time
33,468,416 / 203 05:50:02 INFO - TinderboxPrint: I/O write bytes / time
710,684,672 / 2,024 05:50:02 INFO - TinderboxPrint: CPU idle
2,190.6 (45.3%) 05:50:02 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 05:50:02 INFO - TinderboxPrint: CPU user
1,673.0 (34.6%) 05:50:02 INFO - TinderboxPrint: Swap in / out
437,981,184 / 0 05:50:02 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 406456832; Write bytes: 399746560; Read time: 29915; Write time: 824 05:50:02 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 31363072; Write bytes: 296178176; Read time: 184; Write time: 1150 05:50:02 INFO - Running post-run listener: _upload_blobber_files 05:50:02 INFO - Blob upload gear active. 05:50:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:50:02 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:50:02 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:50:02 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:50:02 INFO - (blobuploader) - INFO - Open directory for files ... 05:50:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:50:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:50:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:04 INFO - (blobuploader) - INFO - Done attempting. 05:50:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:50:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:50:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:05 INFO - (blobuploader) - INFO - Done attempting. 05:50:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:50:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:50:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:07 INFO - (blobuploader) - INFO - Done attempting. 05:50:07 INFO - (blobuploader) - INFO - Iteration through files over. 05:50:07 INFO - Return code: 0 05:50:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:50:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:50:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b684cedcd957ebb780a77aac2e9ba3425860a69e1bff25c0d726ed51fe261c6fb6bb06fc3bb82252335639fd9dfc0114cc7495ee437374c33c9dc1fc2e8bbca8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b64e143f0a6416c92d8a5f0ae2c45f8c68f947070aa8213c5436365c203e83cf369a52aa8760d717ef32fb8d40aa0a5e417f590f18cdea87f2974a12abc98fc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3531d59da97c59918d4767dc54ce71ef8835b5fc99e19bfc1b827798d2fd868c1e0c000d52f76512c9825f35feaa010fc186d1cd4d56574bc84558b86a6c6612"} 05:50:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:50:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:50:07 INFO - Contents: 05:50:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b684cedcd957ebb780a77aac2e9ba3425860a69e1bff25c0d726ed51fe261c6fb6bb06fc3bb82252335639fd9dfc0114cc7495ee437374c33c9dc1fc2e8bbca8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b64e143f0a6416c92d8a5f0ae2c45f8c68f947070aa8213c5436365c203e83cf369a52aa8760d717ef32fb8d40aa0a5e417f590f18cdea87f2974a12abc98fc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3531d59da97c59918d4767dc54ce71ef8835b5fc99e19bfc1b827798d2fd868c1e0c000d52f76512c9825f35feaa010fc186d1cd4d56574bc84558b86a6c6612"} 05:50:07 INFO - Running post-run listener: copy_logs_to_upload_dir 05:50:07 INFO - Copying logs to upload dir... 05:50:07 INFO - mkdir: /builds/slave/test/build/upload/logs 05:50:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.640220 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-09 05:50:08.073196) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-09 05:50:08.076287) ========= 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.idIIcE5jai/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.X0lCEWOeuU/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b684cedcd957ebb780a77aac2e9ba3425860a69e1bff25c0d726ed51fe261c6fb6bb06fc3bb82252335639fd9dfc0114cc7495ee437374c33c9dc1fc2e8bbca8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b64e143f0a6416c92d8a5f0ae2c45f8c68f947070aa8213c5436365c203e83cf369a52aa8760d717ef32fb8d40aa0a5e417f590f18cdea87f2974a12abc98fc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3531d59da97c59918d4767dc54ce71ef8835b5fc99e19bfc1b827798d2fd868c1e0c000d52f76512c9825f35feaa010fc186d1cd4d56574bc84558b86a6c6612"} build_url:https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011239 build_url: 'https://queue.taskcluster.net/v1/task/fFXZG63wS1eGbSjzGqBa7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b684cedcd957ebb780a77aac2e9ba3425860a69e1bff25c0d726ed51fe261c6fb6bb06fc3bb82252335639fd9dfc0114cc7495ee437374c33c9dc1fc2e8bbca8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b64e143f0a6416c92d8a5f0ae2c45f8c68f947070aa8213c5436365c203e83cf369a52aa8760d717ef32fb8d40aa0a5e417f590f18cdea87f2974a12abc98fc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3531d59da97c59918d4767dc54ce71ef8835b5fc99e19bfc1b827798d2fd868c1e0c000d52f76512c9825f35feaa010fc186d1cd4d56574bc84558b86a6c6612"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-09 05:50:08.108927) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 05:50:08.109289) ========= 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.idIIcE5jai/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.X0lCEWOeuU/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.005317 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 05:50:08.161057) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-09 05:50:08.161779) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-09 05:50:08.162483) ========= ========= Total master_lag: 0.20 =========