builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1476460071.51 results: success (0) buildid: 20161014060935 builduid: da086c0f6df84e7e888670314500305a revision: a06e83406d1baafc01c867415ebb532f0f214119 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:51.508345) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:51.514246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:51.514557) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:51.539495) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-14 08:47:51.539880) ========= 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.cdFR66HUsr/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.DqxJMrglSr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-14 08:47:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 55.6M=0s 2016-10-14 08:47:52 (55.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.614502 ========= master_lag: 1.56 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-14 08:47:53.717810) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:53.719780) ========= 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.cdFR66HUsr/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.DqxJMrglSr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.025863 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:53.776538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-14 08:47:53.779434) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a06e83406d1baafc01c867415ebb532f0f214119 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a06e83406d1baafc01c867415ebb532f0f214119 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cdFR66HUsr/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.DqxJMrglSr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-14 08:47:53,854 truncating revision to first 12 chars 2016-10-14 08:47:53,854 Setting DEBUG logging. 2016-10-14 08:47:53,854 attempt 1/10 2016-10-14 08:47:53,854 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a06e83406d1b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 08:47:54,804 unpacking tar archive at: autoland-a06e83406d1b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.228010 ========= master_lag: 0.88 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-14 08:47:55.887249) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:55.887582) ========= script_repo_revision: a06e83406d1baafc01c867415ebb532f0f214119 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:55.888038) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:55.888300) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 08:47:55.931401) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-14 08:47:55.931797) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cdFR66HUsr/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.DqxJMrglSr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:47:56 INFO - MultiFileLogger online at 20161014 08:47:56 in /builds/slave/test 08:47:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 08:47:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:47:56 INFO - {'append_to_log': False, 08:47:56 INFO - 'base_work_dir': '/builds/slave/test', 08:47:56 INFO - 'blob_upload_branch': 'Autoland', 08:47:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:47:56 INFO - 'branch': 'Autoland', 08:47:56 INFO - 'buildbot_json_path': 'buildprops.json', 08:47:56 INFO - 'config_files': ('talos/mac_config.py',), 08:47:56 INFO - 'default_actions': ('clobber', 08:47:56 INFO - 'read-buildbot-config', 08:47:56 INFO - 'download-and-extract', 08:47:56 INFO - 'populate-webroot', 08:47:56 INFO - 'create-virtualenv', 08:47:56 INFO - 'install', 08:47:56 INFO - 'run-tests'), 08:47:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:47:56 INFO - 'download_minidump_stackwalk': True, 08:47:56 INFO - 'download_symbols': 'ondemand', 08:47:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:47:56 INFO - 'tooltool.py': '/tools/tooltool.py', 08:47:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:47:56 INFO - '/tools/misc-python/virtualenv.py')}, 08:47:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:47:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:47:56 INFO - 'installer_path': 'installer.exe', 08:47:56 INFO - 'log_level': 'info', 08:47:56 INFO - 'log_name': 'talos', 08:47:56 INFO - 'log_to_console': True, 08:47:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:47:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:47:56 INFO - 'opt_config_files': (), 08:47:56 INFO - 'pip_index': False, 08:47:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:47:56 INFO - 'cmd': ('bash', 08:47:56 INFO - '-c', 08:47:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:47:56 INFO - 'enabled': True, 08:47:56 INFO - 'halt_on_failure': False, 08:47:56 INFO - 'name': 'check_screen_resolution'},), 08:47:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:47:56 INFO - 'cmd': ('bash', 08:47:56 INFO - '-c', 08:47:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:47:56 INFO - 'enabled': True, 08:47:56 INFO - 'halt_on_failure': False, 08:47:56 INFO - 'name': 'check_screen_resolution'},), 08:47:56 INFO - 'run_cmd_checks_enabled': True, 08:47:56 INFO - 'sps_profile': False, 08:47:56 INFO - 'sps_profile_interval': 0, 08:47:56 INFO - 'suite': 'dromaeojs', 08:47:56 INFO - 'system_bits': '32', 08:47:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:47:56 INFO - 'title': 't-yosemite-r7-0077', 08:47:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:47:56 INFO - 'use_talos_json': True, 08:47:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:47:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:47:56 INFO - 'work_dir': 'build'} 08:47:56 INFO - [mozharness: 2016-10-14 15:47:56.140213Z] Running clobber step. 08:47:56 INFO - Running pre-action listener: _resource_record_pre_action 08:47:56 INFO - Running main action method: clobber 08:47:56 INFO - rmtree: /builds/slave/test/build 08:47:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:47:58 INFO - Running post-action listener: _resource_record_post_action 08:47:58 INFO - [mozharness: 2016-10-14 15:47:58.282313Z] Finished clobber step (success) 08:47:58 INFO - [mozharness: 2016-10-14 15:47:58.282385Z] Running read-buildbot-config step. 08:47:58 INFO - Running pre-action listener: _resource_record_pre_action 08:47:58 INFO - Running main action method: read_buildbot_config 08:47:58 INFO - Using buildbot properties: 08:47:58 INFO - { 08:47:58 INFO - "project": "", 08:47:58 INFO - "product": "firefox", 08:47:58 INFO - "script_repo_revision": "production", 08:47:58 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 08:47:58 INFO - "repository": "", 08:47:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 08:47:58 INFO - "buildid": "20161014060935", 08:47:58 INFO - "pgo_build": "False", 08:47:58 INFO - "basedir": "/builds/slave/test", 08:47:58 INFO - "buildnumber": 252, 08:47:58 INFO - "slavename": "t-yosemite-r7-0077", 08:47:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:47:58 INFO - "platform": "yosemite_r7", 08:47:58 INFO - "scheduler": "tests-autoland-macosx64-talos", 08:47:58 INFO - "branch": "autoland", 08:47:58 INFO - "repo_path": "integration/autoland", 08:47:58 INFO - "revision": "a06e83406d1baafc01c867415ebb532f0f214119", 08:47:58 INFO - "stage_platform": "macosx64", 08:47:58 INFO - "builduid": "da086c0f6df84e7e888670314500305a", 08:47:58 INFO - "slavebuilddir": "test" 08:47:58 INFO - } 08:47:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:47:58 INFO - Running post-action listener: _resource_record_post_action 08:47:58 INFO - [mozharness: 2016-10-14 15:47:58.285360Z] Finished read-buildbot-config step (success) 08:47:58 INFO - [mozharness: 2016-10-14 15:47:58.285469Z] Running download-and-extract step. 08:47:58 INFO - Running pre-action listener: _resource_record_pre_action 08:47:58 INFO - Running main action method: download_and_extract 08:47:58 INFO - mkdir: /builds/slave/test/build/tests 08:47: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')]} 08:47:58 INFO - trying https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:47:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:47:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:47:59 INFO - Downloaded 1199 bytes. 08:47:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:47:59 INFO - Using the following test package requirements: 08:47:59 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:47:59 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:47:59 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:47:59 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:47:59 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:47:59 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:47:59 INFO - u'jsshell-mac.zip'], 08:47:59 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:47:59 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:47:59 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:47:59 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:47:59 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:47:59 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:47:59 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:47:59 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:47:59 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:47:59 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:47:59 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:47:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:47:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:47:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:47:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:47:59 INFO - Http code: 200 08:47:59 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 08:47:59 INFO - x-amz-cf-id: mNOyVf4na3VBBEaqGCnArJUg1xpmn2_ag4khvihGXF-lDodb7OhtuQ== 08:47:59 INFO - x-amz-version-id: imTwkK0anuwX9akS_.OUhhUkp15bn14n 08:48:00 INFO - Expected file size: 16516723 08:48:00 INFO - Obtained file size: 16516723 08:48:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:48:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:48:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:48:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:48:07 INFO - Http code: 200 08:48:07 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 08:48:07 INFO - x-amz-cf-id: yeXgk0KtMH2gObxxqKMthIrzDFh_KxajLo_5F2WCPtuqxIzwzVjuVw== 08:48:07 INFO - x-amz-version-id: imTwkK0anuwX9akS_.OUhhUkp15bn14n 08:48:07 INFO - Expected file size: 16516723 08:48:07 INFO - Obtained file size: 16516723 08:48:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:48:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:48:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:48:14 INFO - Http code: 200 08:48:14 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 08:48:14 INFO - x-amz-cf-id: eu-GYE60-4axuwKRfnVzOlFiHIfIJSRHRaUPuHExltTDXEXf0VPl9A== 08:48:14 INFO - x-amz-version-id: 6Carg936DimQnhd6DLp091DS5NwtxcCx 08:48:15 INFO - Expected file size: 14409006 08:48:15 INFO - Obtained file size: 14409006 08:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:15 INFO - trying https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:48:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:48:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:48:18 INFO - Downloaded 111956353 bytes. 08:48:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:48:18 INFO - mkdir: /builds/slave/test/properties 08:48:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:48:18 INFO - Writing to file /builds/slave/test/properties/build_url 08:48:18 INFO - Contents: 08:48:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:48:19 INFO - Running post-action listener: _resource_record_post_action 08:48:19 INFO - Running post-action listener: set_extra_try_arguments 08:48:19 INFO - [mozharness: 2016-10-14 15:48:19.567232Z] Finished download-and-extract step (success) 08:48:19 INFO - [mozharness: 2016-10-14 15:48:19.567383Z] Running populate-webroot step. 08:48:19 INFO - Running pre-action listener: _resource_record_pre_action 08:48:19 INFO - Running main action method: populate_webroot 08:48:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:48:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:48:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:48:19 INFO - '--tppagecycles', 08:48:19 INFO - '7'], 08:48:19 INFO - 'tests': ['tsvgm']}}, 08:48:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:48:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:48:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:48:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:48:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:19 INFO - 'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:19 INFO - 'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['damp', 'tps']}, 08:48:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:19 INFO - 'tests': ['damp', 'tps']}, 08:48:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['dromaeo_dom']}, 08:48:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:48:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['basic_compositor_video']}, 08:48:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:48:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other-e10s': {'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['tsvgx', 08:48:19 INFO - 'tsvgr_opacity', 08:48:19 INFO - 'tart', 08:48:19 INFO - 'tscrollx', 08:48:19 INFO - 'cart']}, 08:48:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:48:19 INFO - 'tsvgr_opacity', 08:48:19 INFO - 'tart', 08:48:19 INFO - 'tscrollx', 08:48:19 INFO - 'cart']}, 08:48:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:19 INFO - 'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['tp5o']}, 08:48:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:19 INFO - 'tests': ['tp5o']}, 08:48:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:19 INFO - 'talos_options': ['--disable-e10s', 08:48:19 INFO - '--xperf_path', 08:48:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:19 INFO - 'tests': ['tp5n']}, 08:48:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:19 INFO - 'talos_options': ['--xperf_path', 08:48:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:19 INFO - 'tests': ['tp5n']}}, 08:48:19 INFO - 'talos.zip': {'path': '', 08:48:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:48:19 INFO - Running post-action listener: _resource_record_post_action 08:48:19 INFO - [mozharness: 2016-10-14 15:48:19.585944Z] Finished populate-webroot step (success) 08:48:19 INFO - [mozharness: 2016-10-14 15:48:19.586081Z] Running create-virtualenv step. 08:48:19 INFO - Running pre-action listener: _resource_record_pre_action 08:48:19 INFO - Running main action method: create_virtualenv 08:48:19 INFO - Creating virtualenv /builds/slave/test/build/venv 08:48:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:48:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:48:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:48:19 INFO - Using real prefix '/tools/python27' 08:48:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:48:20 INFO - Installing distribute.............................................................................................................................................................................................done. 08:48:23 INFO - Installing pip.................done. 08:48:23 INFO - Return code: 0 08:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:48:23 INFO - Reading from file tmpfile_stdout 08:48:23 INFO - Output received: 08:48:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:48:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:23 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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:23 INFO - 'HOME': '/Users/cltbld', 08:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:23 INFO - 'LOGNAME': 'cltbld', 08:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:23 INFO - 'MOZ_NO_REMOTE': '1', 08:48:23 INFO - 'NO_EM_RESTART': '1', 08:48:23 INFO - 'PAGER': '/bin/cat', 08:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:23 INFO - 'PWD': '/builds/slave/test', 08:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:23 INFO - 'SHELL': '/bin/bash', 08:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:23 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:23 INFO - 'USER': 'cltbld', 08:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:23 INFO - 'XPC_FLAGS': '0x0', 08:48:23 INFO - 'XPC_SERVICE_NAME': '0', 08:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:48:23 INFO - Cleaning up... 08:48:23 INFO - Return code: 0 08:48:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:23 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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:23 INFO - 'HOME': '/Users/cltbld', 08:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:23 INFO - 'LOGNAME': 'cltbld', 08:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:23 INFO - 'MOZ_NO_REMOTE': '1', 08:48:23 INFO - 'NO_EM_RESTART': '1', 08:48:23 INFO - 'PAGER': '/bin/cat', 08:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:23 INFO - 'PWD': '/builds/slave/test', 08:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:23 INFO - 'SHELL': '/bin/bash', 08:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:23 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:23 INFO - 'USER': 'cltbld', 08:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:23 INFO - 'XPC_FLAGS': '0x0', 08:48:23 INFO - 'XPC_SERVICE_NAME': '0', 08:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:24 INFO - Downloading/unpacking psutil>=3.1.1 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:48:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:27 INFO - Installing collected packages: psutil 08:48:27 INFO - Running setup.py install for psutil 08:48:27 INFO - building 'psutil._psutil_osx' extension 08:48:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:48: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 08:48: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:48:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:48:28 INFO - building 'psutil._psutil_posix' extension 08:48:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:48:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:48:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:48:28 INFO - ^ 08:48:28 INFO - 1 warning generated. 08:48:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:48:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:29 INFO - Successfully installed psutil 08:48:29 INFO - Cleaning up... 08:48:29 INFO - Return code: 0 08:48:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48: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 08:48: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 08:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:29 INFO - 'HOME': '/Users/cltbld', 08:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:29 INFO - 'LOGNAME': 'cltbld', 08:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:29 INFO - 'MOZ_NO_REMOTE': '1', 08:48:29 INFO - 'NO_EM_RESTART': '1', 08:48:29 INFO - 'PAGER': '/bin/cat', 08:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:29 INFO - 'PWD': '/builds/slave/test', 08:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:29 INFO - 'SHELL': '/bin/bash', 08:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:29 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:29 INFO - 'USER': 'cltbld', 08:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:29 INFO - 'XPC_FLAGS': '0x0', 08:48:29 INFO - 'XPC_SERVICE_NAME': '0', 08:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:48:31 INFO - Installing collected packages: mozsystemmonitor 08:48:31 INFO - Running setup.py install for mozsystemmonitor 08:48:32 INFO - Successfully installed mozsystemmonitor 08:48:32 INFO - Cleaning up... 08:48:32 INFO - Return code: 0 08:48:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48: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 08:48: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 08:48:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:32 INFO - 'HOME': '/Users/cltbld', 08:48:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:32 INFO - 'LOGNAME': 'cltbld', 08:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:32 INFO - 'MOZ_NO_REMOTE': '1', 08:48:32 INFO - 'NO_EM_RESTART': '1', 08:48:32 INFO - 'PAGER': '/bin/cat', 08:48:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:32 INFO - 'PWD': '/builds/slave/test', 08:48:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:32 INFO - 'SHELL': '/bin/bash', 08:48:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:32 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:32 INFO - 'USER': 'cltbld', 08:48:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:32 INFO - 'XPC_FLAGS': '0x0', 08:48:32 INFO - 'XPC_SERVICE_NAME': '0', 08:48:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:32 INFO - Downloading/unpacking jsonschema==2.5.1 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:48:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:36 INFO - Downloading functools32-3.2.3-2.tar.gz 08:48:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:48:36 INFO - warning: no files found matching '*.txt' 08:48:36 INFO - no previously-included directories found matching 'build' 08:48:36 INFO - no previously-included directories found matching 'dist' 08:48:36 INFO - no previously-included directories found matching '.git*' 08:48:36 INFO - Installing collected packages: jsonschema, functools32 08:48:36 INFO - Running setup.py install for functools32 08:48:36 INFO - warning: no files found matching '*.txt' 08:48:36 INFO - no previously-included directories found matching 'build' 08:48:36 INFO - no previously-included directories found matching 'dist' 08:48:36 INFO - no previously-included directories found matching '.git*' 08:48:36 INFO - Successfully installed jsonschema functools32 08:48:36 INFO - Cleaning up... 08:48:36 INFO - Return code: 0 08:48:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48: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 08:48: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 08:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:36 INFO - 'HOME': '/Users/cltbld', 08:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:36 INFO - 'LOGNAME': 'cltbld', 08:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:36 INFO - 'MOZ_NO_REMOTE': '1', 08:48:36 INFO - 'NO_EM_RESTART': '1', 08:48:36 INFO - 'PAGER': '/bin/cat', 08:48:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:36 INFO - 'PWD': '/builds/slave/test', 08:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:36 INFO - 'SHELL': '/bin/bash', 08:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:36 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:36 INFO - 'USER': 'cltbld', 08:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:36 INFO - 'XPC_FLAGS': '0x0', 08:48:36 INFO - 'XPC_SERVICE_NAME': '0', 08:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:48:37 INFO - Cleaning up... 08:48:37 INFO - Return code: 0 08:48:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:37 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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:48:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:37 INFO - 'HOME': '/Users/cltbld', 08:48:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:37 INFO - 'LOGNAME': 'cltbld', 08:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:37 INFO - 'MOZ_NO_REMOTE': '1', 08:48:37 INFO - 'NO_EM_RESTART': '1', 08:48:37 INFO - 'PAGER': '/bin/cat', 08:48:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:37 INFO - 'PWD': '/builds/slave/test', 08:48:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:37 INFO - 'SHELL': '/bin/bash', 08:48:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:37 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:37 INFO - 'USER': 'cltbld', 08:48:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:37 INFO - 'XPC_FLAGS': '0x0', 08:48:37 INFO - 'XPC_SERVICE_NAME': '0', 08:48:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:37 INFO - Downloading/unpacking blobuploader==1.2.4 08:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:39 INFO - Downloading blobuploader-1.2.4.tar.gz 08:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:48:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:48:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:40 INFO - Downloading docopt-0.6.1.tar.gz 08:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:48:41 INFO - Installing collected packages: blobuploader, requests, docopt 08:48:41 INFO - Running setup.py install for blobuploader 08:48:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:48:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:48:41 INFO - Running setup.py install for requests 08:48:41 INFO - Running setup.py install for docopt 08:48:41 INFO - Successfully installed blobuploader requests docopt 08:48:41 INFO - Cleaning up... 08:48:41 INFO - Return code: 0 08:48:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48: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 08:48: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 08:48:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:41 INFO - 'HOME': '/Users/cltbld', 08:48:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:41 INFO - 'LOGNAME': 'cltbld', 08:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:41 INFO - 'MOZ_NO_REMOTE': '1', 08:48:41 INFO - 'NO_EM_RESTART': '1', 08:48:41 INFO - 'PAGER': '/bin/cat', 08:48:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:41 INFO - 'PWD': '/builds/slave/test', 08:48:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:41 INFO - 'SHELL': '/bin/bash', 08:48:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:41 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:41 INFO - 'USER': 'cltbld', 08:48:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:41 INFO - 'XPC_FLAGS': '0x0', 08:48:41 INFO - 'XPC_SERVICE_NAME': '0', 08:48:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:42 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-wlXEAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:42 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-WghHD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:42 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-vmcscv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:42 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-P_Tukp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:42 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-yp3YCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:42 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fBqrOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-iMVPva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-csqonc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-PLXnkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-TwlDz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-zIdWuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Ve3gk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Inz4C2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-BO4Uju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-2NRV5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-nP5Hmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-T8C4EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:48:44 INFO - Running setup.py install for manifestparser 08:48:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:48:44 INFO - Running setup.py install for mozcrash 08:48:44 INFO - Running setup.py install for mozdebug 08:48:44 INFO - Running setup.py install for mozdevice 08:48:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:48:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:48:44 INFO - Running setup.py install for mozfile 08:48:44 INFO - Running setup.py install for mozhttpd 08:48:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for mozinfo 08:48:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for mozInstall 08:48:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for mozleak 08:48:45 INFO - Running setup.py install for mozlog 08:48:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for moznetwork 08:48:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for mozprocess 08:48:45 INFO - Running setup.py install for mozprofile 08:48:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:48:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:48:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:48:46 INFO - Running setup.py install for mozrunner 08:48:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:48:46 INFO - Running setup.py install for mozscreenshot 08:48:46 INFO - Running setup.py install for moztest 08:48:46 INFO - Running setup.py install for mozversion 08:48:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:48:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:48:46 INFO - Cleaning up... 08:48:46 INFO - Return code: 0 08:48:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:46 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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:46 INFO - 'HOME': '/Users/cltbld', 08:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:46 INFO - 'LOGNAME': 'cltbld', 08:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:46 INFO - 'MOZ_NO_REMOTE': '1', 08:48:46 INFO - 'NO_EM_RESTART': '1', 08:48:46 INFO - 'PAGER': '/bin/cat', 08:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:46 INFO - 'PWD': '/builds/slave/test', 08:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:46 INFO - 'SHELL': '/bin/bash', 08:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:46 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:46 INFO - 'USER': 'cltbld', 08:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:46 INFO - 'XPC_FLAGS': '0x0', 08:48:46 INFO - 'XPC_SERVICE_NAME': '0', 08:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-nZFgHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-P0XgG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-BOf6w1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-NnC7tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-snVZz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5FsMFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-QkVygb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3Au7Tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48: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)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-unHflo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48: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)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-OhCUMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5OKGR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-9ju06v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-oKevHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-8cWnKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-sBvRAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-YFZ2Tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:48 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-kShsEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:51 INFO - Downloading blessings-1.6.tar.gz 08:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:48:51 INFO - Installing collected packages: blessings 08:48:51 INFO - Running setup.py install for blessings 08:48:51 INFO - Successfully installed blessings 08:48:51 INFO - Cleaning up... 08:48:51 INFO - Return code: 0 08:48:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:48:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:48:52 INFO - Reading from file tmpfile_stdout 08:48:52 INFO - Current package versions: 08:48:52 INFO - blessings == 1.6 08:48:52 INFO - blobuploader == 1.2.4 08:48:52 INFO - docopt == 0.6.1 08:48:52 INFO - functools32 == 3.2.3-2 08:48:52 INFO - jsonschema == 2.5.1 08:48:52 INFO - manifestparser == 1.1 08:48:52 INFO - mozInstall == 1.12 08:48:52 INFO - mozcrash == 0.17 08:48:52 INFO - mozdebug == 0.1 08:48:52 INFO - mozdevice == 0.48 08:48:52 INFO - mozfile == 1.2 08:48:52 INFO - mozhttpd == 0.7 08:48:52 INFO - mozinfo == 0.9 08:48:52 INFO - mozleak == 0.1 08:48:52 INFO - mozlog == 3.4 08:48:52 INFO - moznetwork == 0.27 08:48:52 INFO - mozprocess == 0.23 08:48:52 INFO - mozprofile == 0.28 08:48:52 INFO - mozrunner == 6.12 08:48:52 INFO - mozscreenshot == 0.1 08:48:52 INFO - mozsystemmonitor == 0.3 08:48:52 INFO - moztest == 0.7 08:48:52 INFO - mozversion == 1.4 08:48:52 INFO - psutil == 3.1.1 08:48:52 INFO - requests == 1.2.3 08:48:52 INFO - wsgiref == 0.1.2 08:48:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:52 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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:52 INFO - 'HOME': '/Users/cltbld', 08:48:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:52 INFO - 'LOGNAME': 'cltbld', 08:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:52 INFO - 'MOZ_NO_REMOTE': '1', 08:48:52 INFO - 'NO_EM_RESTART': '1', 08:48:52 INFO - 'PAGER': '/bin/cat', 08:48:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:52 INFO - 'PWD': '/builds/slave/test', 08:48:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:52 INFO - 'SHELL': '/bin/bash', 08:48:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:52 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:52 INFO - 'USER': 'cltbld', 08:48:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:52 INFO - 'XPC_FLAGS': '0x0', 08:48:52 INFO - 'XPC_SERVICE_NAME': '0', 08:48:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:48:52 INFO - Cleaning up... 08:48:52 INFO - Return code: 0 08:48:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:52 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 0x10a7ba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a730570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8a5786a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, '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.cdFR66HUsr/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.DqxJMrglSr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:48:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:48:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:52 INFO - 'HOME': '/Users/cltbld', 08:48:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:52 INFO - 'LOGNAME': 'cltbld', 08:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:52 INFO - 'MOZ_NO_REMOTE': '1', 08:48:52 INFO - 'NO_EM_RESTART': '1', 08:48:52 INFO - 'PAGER': '/bin/cat', 08:48:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:52 INFO - 'PWD': '/builds/slave/test', 08:48:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:52 INFO - 'SHELL': '/bin/bash', 08:48:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:48:52 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:48:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:52 INFO - 'USER': 'cltbld', 08:48:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:52 INFO - 'XPC_FLAGS': '0x0', 08:48:52 INFO - 'XPC_SERVICE_NAME': '0', 08:48:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:48:52 INFO - Cleaning up... 08:48:52 INFO - Return code: 0 08:48:52 INFO - Running post-action listener: _resource_record_post_action 08:48:52 INFO - Running post-action listener: _start_resource_monitoring 08:48:52 INFO - Starting resource monitoring. 08:48:52 INFO - [mozharness: 2016-10-14 15:48:52.650903Z] Finished create-virtualenv step (success) 08:48:52 INFO - [mozharness: 2016-10-14 15:48:52.651879Z] Running install step. 08:48:52 INFO - Running pre-action listener: _resource_record_pre_action 08:48:52 INFO - Running main action method: install 08:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:48:52 INFO - Reading from file tmpfile_stdout 08:48:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:48:53 INFO - Reading from file tmpfile_stdout 08:48:53 INFO - Output received: 08:48:53 INFO - Usage: mozinstall [options] installer 08:48:53 INFO - Options: 08:48:53 INFO - -h, --help show this help message and exit 08:48:53 INFO - -d DEST, --destination=DEST 08:48:53 INFO - Directory to install application into. [default: 08:48:53 INFO - "/builds/slave/test"] 08:48:53 INFO - --app=APP Application being installed. [default: firefox] 08:48:53 INFO - mkdir: /builds/slave/test/build/application 08:48:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:48:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:49:27 INFO - Reading from file tmpfile_stdout 08:49:27 INFO - Output received: 08:49:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:49:27 INFO - Running post-action listener: _resource_record_post_action 08:49:27 INFO - [mozharness: 2016-10-14 15:49:27.421540Z] Finished install step (success) 08:49:27 INFO - [mozharness: 2016-10-14 15:49:27.421723Z] Running run-tests step. 08:49:27 INFO - Running pre-action listener: _resource_record_pre_action 08:49:27 INFO - Running main action method: run_tests 08:49:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:49:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:49:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:49:27 INFO - 2016-10-14 08:49:27.436 screenresolution[1638:7630] starting screenresolution argv=screenresolution get 08:49:27 INFO - 2016-10-14 08:49:27.438 screenresolution[1638:7630] Display 0: 1600x1200x32@60 08:49:27 INFO - 2016-10-14 08:49:27.444 screenresolution[1639:7632] starting screenresolution argv=screenresolution list 08:49:27 INFO - Available Modes on Display 0 08:49:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:49:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:49:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:49:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:49:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:49:27 INFO - Intel Iris: 08:49:27 INFO - Chipset Model: Intel Iris 08:49:27 INFO - Type: GPU 08:49:27 INFO - Bus: Built-In 08:49:27 INFO - VRAM (Dynamic, Max): 1536 MB 08:49:27 INFO - Vendor: Intel (0x8086) 08:49:27 INFO - Device ID: 0x0a2e 08:49:27 INFO - Revision ID: 0x0009 08:49:27 INFO - Displays: 08:49:27 INFO - Display: 08:49:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:49:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:49:27 INFO - Display Serial Number: 1600x1200 60 08:49:27 INFO - Main Display: Yes 08:49:27 INFO - Mirror: Off 08:49:27 INFO - Online: Yes 08:49:27 INFO - Rotation: Supported 08:49:27 INFO - Return code: 0 08:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:49:27 INFO - Python 2.7.3 08:49:27 INFO - Return code: 0 08:49:27 INFO - grabbing minidump binary from tooltool 08:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:27 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 0x10a5ced30>, '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 0x10a3f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:49:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:49:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:49:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:49:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:49:27 INFO - Return code: 0 08:49:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:49:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:49:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:49:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:49:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdFR66HUsr/Render', 08:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:27 INFO - 'HOME': '/Users/cltbld', 08:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:27 INFO - 'LOGNAME': 'cltbld', 08:49:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:27 INFO - 'MOZ_NO_REMOTE': '1', 08:49:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:27 INFO - 'NO_EM_RESTART': '1', 08:49:27 INFO - 'PAGER': '/bin/cat', 08:49:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:27 INFO - 'PWD': '/builds/slave/test', 08:49:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:27 INFO - 'SHELL': '/bin/bash', 08:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqxJMrglSr/Listeners', 08:49:27 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:27 INFO - 'USER': 'cltbld', 08:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:27 INFO - 'XPC_FLAGS': '0x0', 08:49:27 INFO - 'XPC_SERVICE_NAME': '0', 08:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:49:28 INFO - mozversion application_buildid: 20161014060935 08:49:28 INFO - mozversion application_changeset: a06e83406d1baafc01c867415ebb532f0f214119 08:49:28 INFO - mozversion application_display_name: Nightly 08:49:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:49:28 INFO - mozversion application_name: Firefox 08:49:28 INFO - mozversion application_remotingname: firefox 08:49:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:49:28 INFO - mozversion application_vendor: Mozilla 08:49:28 INFO - mozversion application_version: 52.0a1 08:49:28 INFO - mozversion platform_buildid: 20161014060935 08:49:28 INFO - mozversion platform_changeset: a06e83406d1baafc01c867415ebb532f0f214119 08:49:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:49:28 INFO - mozversion platform_version: 52.0a1 08:49:28 INFO - using testdate: 1476460168 08:49:28 INFO - actual date: 1476460168 08:49:28 INFO - starting webserver on 'localhost:49275' 08:49:28 INFO - SUITE-START | Running 2 tests 08:49:28 INFO - TEST-START | dromaeo_css 08:49:28 INFO - operating with platform_type : mac_ 08:49:28 INFO - Initialising browser for dromaeo_css test... 08:49:28 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpNZfTdX/profile http://localhost:49275/getInfo.html) 08:49:30 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 08:49:30 INFO - PROCESS | 1649 | colorDepth:24 08:49:30 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 08:49:30 INFO - PROCESS | 1649 | __metrics 08:49:30 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:49:30 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:49:31 INFO - TEST-INFO | 1649: exit 0 08:49:31 INFO - Browser initialized. 08:49:31 INFO - Running cycle 1/1 for dromaeo_css test... 08:49:31 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpNZfTdX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:49:43 INFO - PROCESS | 1651 | RSS: Main: 240418816 08:49:43 INFO - PROCESS | 1651 | 08:50:34 INFO - PROCESS | 1651 | 1476460234273 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:52:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 08:52:49 INFO - PROCESS | 1651 | RSS: Main: 406372352 08:52:49 INFO - PROCESS | 1651 | 08:55:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 08:55:52 INFO - PROCESS | 1651 | RSS: Main: 370257920 08:55:52 INFO - PROCESS | 1651 | 08:58:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 08:58:55 INFO - PROCESS | 1651 | RSS: Main: 360783872 08:58:55 INFO - PROCESS | 1651 | 09:01:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 09:01:58 INFO - PROCESS | 1651 | RSS: Main: 336310272 09:01:58 INFO - PROCESS | 1651 | 09:05:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 09:05:02 INFO - PROCESS | 1651 | RSS: Main: 328916992 09:05:02 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 09:08:06 INFO - PROCESS | 1651 | RSS: Main: 387809280 09:08:06 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | __start_tp_report 09:08:06 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 09:08:06 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 09:08:06 INFO - PROCESS | 1651 | |i|pagename|runs| 09:08:06 INFO - PROCESS | 1651 | |0;dojo.html;2707.9864600677;3251.967480325196;3255.2024753935275;3278.2623909620324;3281.5077738339232;5421.972890135554;5495.093309603915;5512;5545.417731138223;5588.189712491686;5535.197396377523;5552.11166213406;5577.163425486179;5596.888062238752;5599.524040456556;4530.796114174854;4928.383952005999;5023.271625614302;5031.471695471978;5147;4975.751212439363;5012.498750124995;5113.054084994269;5164.8708782280555;5256.448072952342;5165.147750621143;5170.638055336128;5181.637285390024;5205.687658740488;5234.73826308685;4997.225430058311;5040;5047.949520504784;5132.5124113209185;5158.045761534128;4951.504849515056;4963.60291176705;5001.699898006095;5053.494650534917;5073.315102461157;5358.544523715489;5381.192821076831;5461.69960652164;5490.670559766387;5503.3120859892515;5756.251687280668;5972.641641501524;6013.458788709037;6028.110566899751;6053;5912.113183022581;5925.20009798676;6022.578419510636;6097.573080549921;6128.295246046717;4723.220668589713;5624.353199382123;5636.436356364331;5653.773849045992;5656.773729050833;6240.157578726814;6264.404881536247;6277.2153480814895;6277.372262773686;6290.779822706183;5754.539636829044;5818.796342128006;5843.123531470228;5859.414058594191;5942.227510423703;6099.854599951442;6111.799264753458;6124.992521985438;6131.254333328352;6135.575953096713;5964.343922168558;6054.788082417094;6056.394360563909;6072.908906366408;6102.664353460602;5871.882562348815;6097.420745029215;6100.69496525172;6111.113888486234;6119.418655227746;4742.952570474251;4763.523647635278;4799.784009719571;4800.399950006249;4824.927626085611;3886.5336159660455;4213;4275.4441922550495;4299.914001719948;4328.156009578102;5585.385607583163;5589.552835773128;5604.9911016016695;5612.635178713371;5649.858753531113;5948.7323070461935;5994.1608174855555;6061.727222275008;6063.757449701962;6064.272287325549;5594.1608758686525;5596.2165296858475;5644.322681278273;5645.5201307888465;5656.6888821115235;5258.6581872178185;5259.21111833228;5280.709560974184;5290.761915713725;5291.391489978701;6095.740618599498;6187.443130118311;6304.6847657617855;6304.810855674337;6309.873802524015;5970.791022314198;6035.456808887221;6092.75628974836;6100.786472473442;6156.938430615815;6228.439440450381;6277.18396608438;6295.779647712308;6303.275123360872;6318.210223722035;5964.782981096761;6001.639901605918;6007.639541627516;6007.669578173242;6008.24896887889;5949.226600542075;6034.275886893601;6042.425969532887;6076.3012253591405;6114.144019837316;5890.911636325544;5911.763529458945;5911.91132133027;5924.911126333194;5951.672658003851;5883.705814709333;5901.350851406256;5917.142014407973;5919.467247947706;5924.911126333022;5622.634528755618;5699.8860022800145;5701.515371193495;5709.20071190042;5716.5998380113;5929.199558059607;5940.6435613863305;5949.732262048305;5963.7912673056235;5971.940280597139;5619.662820230799;5692.715364231854;5785.190073389645;5806.042003069446;5858.179854820414 09:08:06 INFO - PROCESS | 1651 | |1;ext.html;462.70618157470005;472.1383475157838;520.4509242748903;533.1682575182717;540.3650710415262;26794.464110717774;31394.372112557765;32483.025509234703;32610;32649.347013059723;33007.339853202924;42487;43732;43821.561784382146;46418;9521.666741664043;9673.70978870635;9677.951610241938;9678.709638710814;9699.078587534208;9429.717108486722;9473.147416732492;9600.80798384032;9612.134907858292;9616.134547890688;9577.952110239474;9598.712038638818;9631;9640.951795241013;9714.417134971889;9538.332316737831;9593.088656577615;9604.991475895034;9620.278479114095;9624.230061595055;8456.689213171969;9314.720558383191;9502.47736374499;9554.761130971781;9561.474118923456;41055.384169237484;41430.79284603588;41494;41525;41563;3362.96637033629;3410.488426735985;3418.7265018798435;3439.6216416194197;3516.226430185355;30434.782608695627;31901.52147717786;31960.520592191137;32062;32645.020649380556;3267.18320419895;3289.5723555937816;3305.7024867762016;3362.1930736623035;3368.9663103369144;429418;432081;433508.83245583886;433870;435224;60090.69501177477;60366.69816650976;60534.69732651397;60620.696896515234;60666.393336066954;63696.36303636997;63708.68145659242;63746.0438093429;63775.6811215941;63848.68075659685;16247.918760406123;16485.17574121289;16490.505284841474;16496.6700665986;16523.917380413262;12739.36303184837;12819.102662813513;12823.93588032054;12829.422675979604;12839.03707221962;13654.062550234812;17016.659666806594;17294.221760020828;17294.394696185573;17558.736618950727;5757.337906140848;6817.284579903865;6876.346747059021;6984.895226571605;7199.244079371695;981.7103954333488;1004.1665417703289;1009.3287963504188;1030.4744580264119;1063.8351005648801;25923;29692.703072968994;29986.400271994436;30008;30940.53589196119;1040.6565833275151;1054.8628678271775;1059.5549869055014;1086.147374311162;1090.569225156059;12929.482820687068;15707.293171807296;15717.685646287011;15893.443729469413;15929.591425056473;544380;549744;549883;550321;551309;59096.70451647714;59301;59389.70305148447;59589;59589.40410595925;1222.3093951917137;1253.849538055421;1268.8160216768517;1337.9866201337861;1351.047511504387;29476.705232947395;31527;31668.099914005234;31796.36407271888;32456.83771581127;50174;50483.74758126186;50716;50808;50840.745796270785;472385;474776;474863;475113.62443188945;475813;26356.60465092987;27011.864940675172;27036.05373811907;40459.79770101131;40662.796686017566;47702.52297476981;47851.04297913953;48507.757461212466;48832.290869819386;49230;37945.81027094957;39559;41015;41194.58805411908;41241;38971.415428769156;39678;39723.80138099407;40061.39907901325;40760;34646;36391.90824275276;38952.6104738949;39281.214375712894;39379.409308860966;32513.349733004736;33880.98867868002;34422.13944651404;34484.482732759534;34889.47665785066;24976.625350619393;25041.998320067723;25363.87318063398;25414.364640884127;25430.618540722273 09:08:06 INFO - PROCESS | 1651 | |2;jquery.html;339.77914355668827;353.77358490566024;360.9765365251259;365.2749292654081;386.277660774352;14970.850291497081;15123.773143402857;15995.120268385219;20396.286129985452;21329;17088.743668844978;17191.484255472355;19478.610427791435;20297.28959486418;21343.466413339673;5071.84784456466;5354.544863686591;5367.43641917597;5368.194770784375;5373.623846330758;4666.4400271967415;5345.599080069011;5355.544778693816;5921.851953701171;6002.249718785152;5934.495567876736;5945.256842894638;5948.40515948406;5959.0763431668165;5961.523078153759;5288.2596436498925;5330.227117068042;5339.439358867341;5346.331708536433;5459.235707001003;5179.792808287664;5181.559567436735;5186.896262074775;5188.325517682677;5192.584593232532;13394.732105357838;13483.393247303895;23408;23705.577665339963;24477.38806529833;1914.2630087416305;2175.706279652258;2418.6492958521094;2482.689663792026;2705.5130076586206;9677.90322096777;9848.409095454295;9850.556724947322;10315.277930544866;10436.217283703752;1816.4369045595909;1849.5006348285888;1862.078271255737;1867.5517875709731;1912.7035309527046;102200.48899755454;114301;121228;121843;124940.37529812384;9243.445393276425;11185.832212516818;11390.145739069438;11505.597304094317;11733;14599;14610.63473413152;14611.78082328766;14627.26863656813;14818.481353152803;8228.300594449538;8527.654894903952;8540.74377768668;8675.783105422415;8711.520866352284;8416.915830841614;8422.199891010345;8425.115362886809;8594.65621375138;8657.091005444463;3616.9095772605897;4542.591166795004;4544.795484203219;4585.312203169485;4652.092841895797;1571.5599632103028;1726.9740953885703;1831.8717689761854;1934.8355389792025;1971.763388393402;760.7147319755092;826.4421515477029;867.6616119713317;897.546738896853;976.6630512473185;18830.71753923692;19411.89036658721;21258.468538286666;21383;21671.783282167293;862.9395942284106;917.3349321741817;934.1639232886529;947.8815148106487;975.5951280218771;9058;11060.391678457601;11254.94372528132;11260.774784504263;11311.886881131084;447349;450839;451870;526905;541131.2943435258;74963.5007299851;99109;121301;130592;137008.31495842658;867.5228624256633;941.1858742188095;975.5951280218629;1025.2720568396521;1025.9640912568025;15941.920290398473;18584.81415185885;18843.6231275371;18876.640419947507;18968.956707381225;36766.63233367632;37236.44145337768;54723.72638136943;55233.72383138059;55863.44136558582;72630.49177723106;76907.9236830543;81918.18081819106;84161.15838841778;84727.57636211779;7786.26030527099;7794.688212471665;7884.645190966535;7893.684252629831;8136.75589732309;10760.246782725135;12542.623721288375;12638.43127059303;12764.91522712723;12779.23324600527;10686.198535110054;10780.838287425539;10942.124630029737;11051.668449946514;11108.500117494894;10660.040596346365;10730.83903741429;10776.67669969902;10974.725631859268;11001.449927503432;9185.586648600962;11058.004779569877;11326.603568875049;11360.829587556029;11407.714807129556;7608.771736847903;7791.142974272712;7884.84230315402;7915.881261781193;8061.758147255592;8038.638261278374;9630.566624502055;9637.662681806103;10732.517036733521;10982.286151400134 09:08:06 INFO - PROCESS | 1651 | |3;mootools.html;1513.296317212496;1556.3930067273761;1789.8478629316508;1904.6000339928614;1976.2687805511962;8120.309773669238;8578;8593.65625374985;8613.741587752369;8630.525321107336;7595.316421522062;7843.607819609011;7849.293563579276;8240.876386854186;8398.118197589256;2011.688188330811;2287.942801429965;2305.8155347572156;2330.1844354475966;2335.194357946514;2228.0419419649543;2263.9434014149697;2272.3750968483632;2277.6469647204626;2278.6582012698063;2306.7001289832383;2332.1604222479896;2333.428310064037;2338.3452633262627;2345.4488195274184;2302.8042616377543;2311.410590299463;2314.9189778357845;2316.7219933607907;2319.489712263308;2270.5572413379396;2299.7930186283315;2306.112146823485;2306.7116610423695;2310.1683393978155;8372.497650141011;8563.10087440816;8565.271951884097;8593.742187734379;8607.18231767981;5053.418856831475;5192.7403629818355;5195.5324020837925;5201.55786758126;5208.166693329087;3295.3574053059665;3368.8147151906637;3518.2963407318384;3518.9091381671765;3519.0850378901414;1023.2019025160387;1024.0578667625803;1042.8413184707658;1045.1273186888957;1045.9372437653765;7591.658375373121;9071.228945539637;10989.61536346232;11012.008919197391;11152.219344645879;4296.291111966553;4563.406757121553;4617.8383756568355;4629.537046295411;4636.698614590041;9449.102335278138;9464.810703785886;9472;9480.478573678514;9483.525823708926;5186.262602028287;5189.50699683537;5191.195364718475;5194.091034069098;5196.610254230948;4561.863144105682;4719.292106184032;4754.191787396151;4767.61859051275;4801.399825021872;3618.222082252328;3678.2091850252323;3696.1868388954385;3730.645588669072;3755.080005398695;956.7225504603587;1113.0486771959672;1116.190761697779;1121.775644871029;1124.0557931337553;897.6499165325529;914.533587870191;918.9862152067724;944.6173608023428;968.4915419404728;5529.585680610553;5826.446487583673;5826.650400975955;5838.3784159931165;6027.095935609694;397.6063696940009;422.9640480559187;424.3465063801711;425.97231179973204;427.8224536817248;3843.8462461501786;3917.725759196886;3966.087799806061;3990.7206495545615;4061.3907913813164;8133.59332033396;10132.138768204784;10252.974702529687;10323.07092361676;10330.793384132276;10148.680671512657;10170.135538479164;10252.23108266883;10263.58945642166;10271.126954208976;7695.576743279174;7813.531188128731;7820.865974433676;7904.20957904205;7908.090569584457;5177.637565370538;5728.341240757366;5799.8550036249435;5808.361080281081;5926.8738939601335;9216.631334746535;9233.722988310361;9432.292578056811;9578.377405468622;9631.566579504079;8541.95729021351;8871;9322.860157097784;9376.624935002523;9555.283353748635;6627.701753421011;7272.381847543018;7343.118825740945;7416.592087435243;7435.665395057127;7938.483998540077;7978.281954624112;7981.2018798119725;8041.11547729761;8071.394645401501;7285.470051289289;7295.890561641686;7306.305900939402;7354.705811767683;7398.667059982206;6141.6007959482495;7133.5719856808755;7186;7232.566046037255;7318.597477138808;6683.665816709048;6819.522633415613;6825.010373495915;6837.9316206839285;6853.897191542031;4754.7147171169845;4829.879253018703;4911.754412279443;4954.529319714621;4959.7272150032095;4207.726497777635;4818.229083346788;4918.877028074184;4976.701397916137;5104.132297509506 09:08:06 INFO - PROCESS | 1651 | |4;prototype.html;269.43015522170606;289.9057806212981;300.88716731225793;301.8460585101598;306.98158110513384;5175.896482070356;5215.6349055566125;5222.686638801665;5244.872352444224;5266.605004624649;3762.3821756644634;4347.717398369104;4797.40032495938;4991.625628077891;5255.789768409269;3835.616438356163;3849.5573009104032;3865.729398942061;3884.679209068916;3886.417037444378;3736.7855446979706;3890.9027274318087;3927.7839718815453;3933.2330195611867;3973.5231772187376;3167.6357218919775;3269.623993240772;3339.7976728377885;3645.687552481105;3820.36963900956;3876.8836934891997;3879.0690234343697;3892.7884495291214;3894.104355998122;3895.7078219133678;2943.852807359645;3344.3144155448076;3386.6791951139076;3393.1686736749652;3404.2510647657477;4253.553376895444;4918.8770280743265;4962.776675049631;4972.403311602595;4983.551480366785;4218.8523401681105;4405.383246345515;4443.289073748217;4447.355133505656;4460.219461594208;4432.977835110836;4739.644526660514;5029.817992771696;5357.544608708265;5367.946320536823;3712.2204337089242;3726.739128261023;3748.4069270559903;3754.1740817020213;3756.962430375716;4625.930611040837;4852.6794148941235;5375.301210842605;5528.226048353234;5692.57305702074;3100.240441091947;3761.153740408441;3769.021263836453;3769.9434508482395;3777.792221427848;3202.59967504062;3238.6923242291946;3835.2521258354354;3841.692664586826;3866.2074274773604;4439.445069366329;4450.020995381011;4450.643948484114;4462.642988560908;4468.754218517947;4719.442583947289;5129.589632829365;5380.865478362994;5392.91910621341;5409.080456322435;1290.316132449803;1315.3489022933518;1317.808917706927;1321.7554752370843;1322.1075773852804;735.5476382025123;811.4765975945483;834.837206744679;882;917.4037175315037;4054.6011625988986;4373.846915357947;4531.954680453153;5055.87360315995;5103.2855400244;5230.973845130826;5511.090670039399;5511.862203444946;5516.668999860021;5522.116461366162;4669.649776266793;4974.9751251243515;5026.567428282995;5037.269595908573;5072.949270507248;2868.7131286871145;2870.9282267943468;2884.5528943013865;2892.3058465968015;2898.2754311422145;5637.633553818989;5648.491635752802;5670.461306175906;5704.714764261853;5721.227634269404;5398;5551.694656793915;5559.749811258503;5621.690807005572;5629.605927585029;5618.218981604132;5631.2960879890015;5644.238027866268;5658.490735833795;5708.886767080379;4288.699791014599;4755.667103302736;5436.809711660231;5441.183822426509;5459.181122831607;5339.46605339463;5354.866128346666;5389.137737961907;5406.7296635167295;5424.918626220531;5624.493795558419;5627.352854421794;5627.774889004394;5636.9436305638055;5643.858903527444;3572.3212589607892;3717.9814100930403;3737.7757334560015;3749.1751814600743;3758.590528551793;4510.819567217406;4953.678010929278;4974.776135073858;5078.943028073636;5409.215663728816;4674.275487299474;5289.518934698291;5315.335583052119;5325.603478434642;5359.3032905722;4821.9035619286715;4899.632527560376;5062.31658726082;5285.920711189412;5292.947070529245;4705.541427051183;4989.426215985063;5016.36640763602;5171.793128274978;5182.611304152279;5140.794368225229;5268.6575372600655;5269.262303277467;5369.328833895763;5387.488188622075;4364.781760911878;5255.080360936744;5303.443138470405;5314.362276526842;5354.81388573798 09:08:06 INFO - PROCESS | 1651 | |5;yui.html;710.8613820305039;716.7818282900487;768.8659227639233;779.3905584967042;821.5085884988796;14989.400423983028;15636.687266254668;15873.444429444957;15970.361185552592;15976.121313327776;13981.650458738512;14509.274536273197;14605.92697036516;14624.634384140401;14627.926860365684;760.4106817216646;899.3210126354593;911.7538264668535;913.8263729891332;914.4193437167413;651.1047309948821;699.5697645947756;730.9269073092702;877.4515927545284;907.4056492997064;946.5314669238736;953.4660590069549;953.9427634341927;953.993536818656;954.0793134625086;949.4967667136428;949.6866034208695;951.1487218939011;951.2485136741966;951.4434056077168;546.529984213576;562.9380768115504;688.1776277348571;705.671862583901;707.5683832861951;15293.770593441108;15699.293531791096;15747.370105195778;15749.763753543706;15810.446634367858;13627.977901657416;14073.577792666236;14184.787228191586;14333.21167335796;14371.92814035923;11204.719882002933;11609.071274298036;11878.524859005629;12430.937845310806;12759.61721148367;3800.6009369016133;4447.3996010538485;4466.7766611669285;4477.955220447819;4517.277235642281;103543;104910;105110;105311;106677.46661266721;10587.249071185113;10721.678349649523;10749.570017199381;10816.242862999672;11147;20373.388798336073;20494.38516844497;20516.38450846477;20518.58962820735;20994;7787.182345853717;7795.766127016199;7832.960835195788;7865.646045927947;7955.124936257007;4074.572169922175;4112.09533902541;4129.194807012604;4131.07050913548;4140.855070072533;3319.4854797506423;3441.9827900860337;3443.0531603808654;3444.2767018926306;3707.94438083429;689.1282527602586;690.6926417744156;696.0707455546901;696.4428457234193;704.6124631452681;480.98076076957227;588.2088590845307;604.727872457396;626.3110578363763;674.959502429846;9020.909790902138;9345.579448924815;9510;9658.179054780423;9670.951645241728;602.3043384890459;616.4821549898018;639.8304449320933;684.7055766020559;684.8424862281703;4412.757298348557;4758.571038454814;4785.49752276006;4827.879303017453;4968.27959945813;97870;141627.58372416144;142424;145661;155065;77479;78379;79914.40171196543;80150.5992470034;112082.71462354921;96428.9999999986;113574;114224;115248.42375788068;118286;9523.619055237712;9742.878375030963;9755.29904312129;9793.02022926832;9810.714214404055;50637.72130254074;56923.369119734234;60480;61051.62170399979;65171.34828651653;82775;96032.51983740037;98923.70987657338;101675.49162254142;103576;10810.13518918469;13153.671158221121;13416;13538.07395855191;13652.65868353299;14036.78944815849;14523.419063237774;14621.41514339457;14629.780553291495;15146;13556.525521606696;13798.852008984277;13845.79231311511;13889.861101388857;14306.284685765879;10994.835077474005;13920.443182272596;14276.286185690466;14461.710765784415;14584.48954286595;13307.933460333024;13474.124181928144;13632.454701811816;13796.862031379558;14065.296735162996;10959.945200274267;11174.329540227613;11756.412179390825;11788.587399441321;11804.940975295412;10828.24202305831;11208.71978200519;11420.82868756986;11455.541778328774;11483.655490335303 09:08:06 INFO - PROCESS | 1651 | __end_tp_report 09:08:06 INFO - PROCESS | 1651 | __start_cc_report 09:08:06 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1999 09:08:06 INFO - PROCESS | 1651 | __end_cc_report 09:08:06 INFO - PROCESS | 1651 | __startTimestamp1476461286300__endTimestamp 09:08:06 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | ------- Summary: start ------- 09:08:06 INFO - PROCESS | 1651 | Number of tests: 6 09:08:06 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5580.62 Median:5754.54 stddev:654.82 (11.4%) stddev-sans-first:616.83 09:08:06 INFO - PROCESS | 1651 | Values: 2708.0 3252.0 3255.2 3278.3 3281.5 5422.0 5495.1 5512.0 5545.4 5588.2 5535.2 5552.1 5577.2 5596.9 5599.5 4530.8 4928.4 5023.3 5031.5 5147.0 4975.8 5012.5 5113.1 5164.9 5256.4 5165.1 5170.6 5181.6 5205.7 5234.7 4997.2 5040.0 5047.9 5132.5 5158.0 4951.5 4963.6 5001.7 5053.5 5073.3 5358.5 5381.2 5461.7 5490.7 5503.3 5756.3 5972.6 6013.5 6028.1 6053.0 5912.1 5925.2 6022.6 6097.6 6128.3 4723.2 5624.4 5636.4 5653.8 5656.8 6240.2 6264.4 6277.2 6277.4 6290.8 5754.5 5818.8 5843.1 5859.4 5942.2 6099.9 6111.8 6125.0 6131.3 6135.6 5964.3 6054.8 6056.4 6072.9 6102.7 5871.9 6097.4 6100.7 6111.1 6119.4 4743.0 4763.5 4799.8 4800.4 4824.9 3886.5 4213.0 4275.4 4299.9 4328.2 5585.4 5589.6 5605.0 5612.6 5649.9 5948.7 5994.2 6061.7 6063.8 6064.3 5594.2 5596.2 5644.3 5645.5 5656.7 5258.7 5259.2 5280.7 5290.8 5291.4 6095.7 6187.4 6304.7 6304.8 6309.9 5970.8 6035.5 6092.8 6100.8 6156.9 6228.4 6277.2 6295.8 6303.3 6318.2 5964.8 6001.6 6007.6 6007.7 6008.2 5949.2 6034.3 6042.4 6076.3 6114.1 5890.9 5911.8 5911.9 5924.9 5951.7 5883.7 5901.4 5917.1 5919.5 5924.9 5622.6 5699.9 5701.5 5709.2 5716.6 5929.2 5940.6 5949.7 5963.8 5971.9 5619.7 5692.7 5785.2 5806.0 5858.2 09:08:06 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:63378.42 Median:40711.40 stddev:129673.85 (318.5%) stddev-sans-first:129951.58 09:08:06 INFO - PROCESS | 1651 | Values: 462.7 472.1 520.5 533.2 540.4 26794.5 31394.4 32483.0 32610.0 32649.3 33007.3 42487.0 43732.0 43821.6 46418.0 9521.7 9673.7 9678.0 9678.7 9699.1 9429.7 9473.1 9600.8 9612.1 9616.1 9578.0 9598.7 9631.0 9641.0 9714.4 9538.3 9593.1 9605.0 9620.3 9624.2 8456.7 9314.7 9502.5 9554.8 9561.5 41055.4 41430.8 41494.0 41525.0 41563.0 3363.0 3410.5 3418.7 3439.6 3516.2 30434.8 31901.5 31960.5 32062.0 32645.0 3267.2 3289.6 3305.7 3362.2 3369.0 429418.0 432081.0 433508.8 433870.0 435224.0 60090.7 60366.7 60534.7 60620.7 60666.4 63696.4 63708.7 63746.0 63775.7 63848.7 16247.9 16485.2 16490.5 16496.7 16523.9 12739.4 12819.1 12823.9 12829.4 12839.0 13654.1 17016.7 17294.2 17294.4 17558.7 5757.3 6817.3 6876.3 6984.9 7199.2 981.7 1004.2 1009.3 1030.5 1063.8 25923.0 29692.7 29986.4 30008.0 30940.5 1040.7 1054.9 1059.6 1086.1 1090.6 12929.5 15707.3 15717.7 15893.4 15929.6 544380.0 549744.0 549883.0 550321.0 551309.0 59096.7 59301.0 59389.7 59589.0 59589.4 1222.3 1253.8 1268.8 1338.0 1351.0 29476.7 31527.0 31668.1 31796.4 32456.8 50174.0 50483.7 50716.0 50808.0 50840.7 472385.0 474776.0 474863.0 475113.6 475813.0 26356.6 27011.9 27036.1 40459.8 40662.8 47702.5 47851.0 48507.8 48832.3 49230.0 37945.8 39559.0 41015.0 41194.6 41241.0 38971.4 39678.0 39723.8 40061.4 40760.0 34646.0 36391.9 38952.6 39281.2 39379.4 32513.3 33881.0 34422.1 34484.5 34889.5 24976.6 25042.0 25363.9 25414.4 25430.6 09:08:06 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:30861.02 Median:18565.95 stddev:81966.65 (441.5%) stddev-sans-first:82164.55 09:08:06 INFO - PROCESS | 1651 | Values: 339.8 353.8 361.0 365.3 386.3 14970.9 15123.8 15995.1 20396.3 21329.0 17088.7 17191.5 19478.6 20297.3 21343.5 5071.8 5354.5 5367.4 5368.2 5373.6 4666.4 5345.6 5355.5 5921.9 6002.2 5934.5 5945.3 5948.4 5959.1 5961.5 5288.3 5330.2 5339.4 5346.3 5459.2 5179.8 5181.6 5186.9 5188.3 5192.6 13394.7 13483.4 23408.0 23705.6 24477.4 1914.3 2175.7 2418.6 2482.7 2705.5 9677.9 9848.4 9850.6 10315.3 10436.2 1816.4 1849.5 1862.1 1867.6 1912.7 102200.5 114301.0 121228.0 121843.0 124940.4 9243.4 11185.8 11390.1 11505.6 11733.0 14599.0 14610.6 14611.8 14627.3 14818.5 8228.3 8527.7 8540.7 8675.8 8711.5 8416.9 8422.2 8425.1 8594.7 8657.1 3616.9 4542.6 4544.8 4585.3 4652.1 1571.6 1727.0 1831.9 1934.8 1971.8 760.7 826.4 867.7 897.5 976.7 18830.7 19411.9 21258.5 21383.0 21671.8 862.9 917.3 934.2 947.9 975.6 9058.0 11060.4 11254.9 11260.8 11311.9 447349.0 450839.0 451870.0 526905.0 541131.3 74963.5 99109.0 121301.0 130592.0 137008.3 867.5 941.2 975.6 1025.3 1026.0 15941.9 18584.8 18843.6 18876.6 18969.0 36766.6 37236.4 54723.7 55233.7 55863.4 72630.5 76907.9 81918.2 84161.2 84727.6 7786.3 7794.7 7884.6 7893.7 8136.8 10760.2 12542.6 12638.4 12764.9 12779.2 10686.2 10780.8 10942.1 11051.7 11108.5 10660.0 10730.8 10776.7 10974.7 11001.4 9185.6 11058.0 11326.6 11360.8 11407.7 7608.8 7791.1 7884.8 7915.9 8061.8 8038.6 9630.6 9637.7 10732.5 10982.3 09:08:06 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5440.31 Median:5078.78 stddev:2987.06 (58.8%) stddev-sans-first:2980.86 09:08:06 INFO - PROCESS | 1651 | Values: 1513.3 1556.4 1789.8 1904.6 1976.3 8120.3 8578.0 8593.7 8613.7 8630.5 7595.3 7843.6 7849.3 8240.9 8398.1 2011.7 2287.9 2305.8 2330.2 2335.2 2228.0 2263.9 2272.4 2277.6 2278.7 2306.7 2332.2 2333.4 2338.3 2345.4 2302.8 2311.4 2314.9 2316.7 2319.5 2270.6 2299.8 2306.1 2306.7 2310.2 8372.5 8563.1 8565.3 8593.7 8607.2 5053.4 5192.7 5195.5 5201.6 5208.2 3295.4 3368.8 3518.3 3518.9 3519.1 1023.2 1024.1 1042.8 1045.1 1045.9 7591.7 9071.2 10989.6 11012.0 11152.2 4296.3 4563.4 4617.8 4629.5 4636.7 9449.1 9464.8 9472.0 9480.5 9483.5 5186.3 5189.5 5191.2 5194.1 5196.6 4561.9 4719.3 4754.2 4767.6 4801.4 3618.2 3678.2 3696.2 3730.6 3755.1 956.7 1113.0 1116.2 1121.8 1124.1 897.6 914.5 919.0 944.6 968.5 5529.6 5826.4 5826.7 5838.4 6027.1 397.6 423.0 424.3 426.0 427.8 3843.8 3917.7 3966.1 3990.7 4061.4 8133.6 10132.1 10253.0 10323.1 10330.8 10148.7 10170.1 10252.2 10263.6 10271.1 7695.6 7813.5 7820.9 7904.2 7908.1 5177.6 5728.3 5799.9 5808.4 5926.9 9216.6 9233.7 9432.3 9578.4 9631.6 8542.0 8871.0 9322.9 9376.6 9555.3 6627.7 7272.4 7343.1 7416.6 7435.7 7938.5 7978.3 7981.2 8041.1 8071.4 7285.5 7295.9 7306.3 7354.7 7398.7 6141.6 7133.6 7186.0 7232.6 7318.6 6683.7 6819.5 6825.0 6837.9 6853.9 4754.7 4829.9 4911.8 4954.5 4959.7 4207.7 4818.2 4918.9 4976.7 5104.1 09:08:06 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4333.46 Median:4909.25 stddev:1323.79 (27.0%) stddev-sans-first:1291.88 09:08:06 INFO - PROCESS | 1651 | Values: 269.4 289.9 300.9 301.8 307.0 5175.9 5215.6 5222.7 5244.9 5266.6 3762.4 4347.7 4797.4 4991.6 5255.8 3835.6 3849.6 3865.7 3884.7 3886.4 3736.8 3890.9 3927.8 3933.2 3973.5 3167.6 3269.6 3339.8 3645.7 3820.4 3876.9 3879.1 3892.8 3894.1 3895.7 2943.9 3344.3 3386.7 3393.2 3404.3 4253.6 4918.9 4962.8 4972.4 4983.6 4218.9 4405.4 4443.3 4447.4 4460.2 4433.0 4739.6 5029.8 5357.5 5367.9 3712.2 3726.7 3748.4 3754.2 3757.0 4625.9 4852.7 5375.3 5528.2 5692.6 3100.2 3761.2 3769.0 3769.9 3777.8 3202.6 3238.7 3835.3 3841.7 3866.2 4439.4 4450.0 4450.6 4462.6 4468.8 4719.4 5129.6 5380.9 5392.9 5409.1 1290.3 1315.3 1317.8 1321.8 1322.1 735.5 811.5 834.8 882.0 917.4 4054.6 4373.8 4532.0 5055.9 5103.3 5231.0 5511.1 5511.9 5516.7 5522.1 4669.6 4975.0 5026.6 5037.3 5072.9 2868.7 2870.9 2884.6 2892.3 2898.3 5637.6 5648.5 5670.5 5704.7 5721.2 5398.0 5551.7 5559.7 5621.7 5629.6 5618.2 5631.3 5644.2 5658.5 5708.9 4288.7 4755.7 5436.8 5441.2 5459.2 5339.5 5354.9 5389.1 5406.7 5424.9 5624.5 5627.4 5627.8 5636.9 5643.9 3572.3 3718.0 3737.8 3749.2 3758.6 4510.8 4953.7 4974.8 5078.9 5409.2 4674.3 5289.5 5315.3 5325.6 5359.3 4821.9 4899.6 5062.3 5285.9 5292.9 4705.5 4989.4 5016.4 5171.8 5182.6 5140.8 5268.7 5269.3 5369.3 5387.5 4364.8 5255.1 5303.4 5314.4 5354.8 09:08:06 INFO - PROCESS | 1651 | 09:08:06 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:23322.04 Median:3754.27 stddev:36039.49 (960.0%) stddev-sans-first:36100.60 09:08:06 INFO - PROCESS | 1651 | Values: 710.9 716.8 768.9 779.4 821.5 14989.4 15636.7 15873.4 15970.4 15976.1 13981.7 14509.3 14605.9 14624.6 14627.9 760.4 899.3 911.8 913.8 914.4 651.1 699.6 730.9 877.5 907.4 946.5 953.5 953.9 954.0 954.1 949.5 949.7 951.1 951.2 951.4 546.5 562.9 688.2 705.7 707.6 15293.8 15699.3 15747.4 15749.8 15810.4 13628.0 14073.6 14184.8 14333.2 14371.9 11204.7 11609.1 11878.5 12430.9 12759.6 3800.6 4447.4 4466.8 4478.0 4517.3 103543.0 104910.0 105110.0 105311.0 106677.5 10587.2 10721.7 10749.6 10816.2 11147.0 20373.4 20494.4 20516.4 20518.6 20994.0 7787.2 7795.8 7833.0 7865.6 7955.1 4074.6 4112.1 4129.2 4131.1 4140.9 3319.5 3442.0 3443.1 3444.3 3707.9 689.1 690.7 696.1 696.4 704.6 481.0 588.2 604.7 626.3 675.0 9020.9 9345.6 9510.0 9658.2 9671.0 602.3 616.5 639.8 684.7 684.8 4412.8 4758.6 4785.5 4827.9 4968.3 97870.0 141627.6 142424.0 145661.0 155065.0 77479.0 78379.0 79914.4 80150.6 112082.7 96429.0 113574.0 114224.0 115248.4 118286.0 9523.6 9742.9 9755.3 9793.0 9810.7 50637.7 56923.4 60480.0 61051.6 65171.3 82775.0 96032.5 98923.7 101675.5 103576.0 10810.1 13153.7 13416.0 13538.1 13652.7 14036.8 14523.4 14621.4 14629.8 15146.0 13556.5 13798.9 13845.8 13889.9 14306.3 10994.8 13920.4 14276.3 14461.7 14584.5 13307.9 13474.1 13632.5 13796.9 14065.3 10959.9 11174.3 11756.4 11788.6 11804.9 10828.2 11208.7 11420.8 11455.5 11483.7 09:08:06 INFO - PROCESS | 1651 | -------- Summary: end -------- 09:08:06 INFO - PROCESS | 1651 | 09:08:07 INFO - TEST-INFO | 1651: exit 0 09:08:07 INFO - TEST-OK | dromaeo_css | took 1119039ms 09:08:07 INFO - TEST-START | kraken 09:08:07 INFO - operating with platform_type : mac_ 09:08:07 INFO - Initialising browser for kraken test... 09:08:07 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpAmyWkL/profile http://localhost:49275/getInfo.html) 09:08:09 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 09:08:09 INFO - PROCESS | 1657 | colorDepth:24 09:08:09 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 09:08:09 INFO - PROCESS | 1657 | __metrics 09:08:09 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:08:09 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:10 INFO - TEST-INFO | 1657: exit 0 09:08:10 INFO - Browser initialized. 09:08:10 INFO - Running cycle 1/1 for kraken test... 09:08:10 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpAmyWkL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:22 INFO - PROCESS | 1658 | RSS: Main: 230428672 09:08:22 INFO - PROCESS | 1658 | 09:08:24 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 09:08:25 INFO - PROCESS | 1658 | RSS: Main: 290242560 09:08:25 INFO - PROCESS | 1658 | 09:08:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 09:08:28 INFO - PROCESS | 1658 | RSS: Main: 291229696 09:08:28 INFO - PROCESS | 1658 | 09:08:32 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 09:08:33 INFO - PROCESS | 1658 | RSS: Main: 381677568 09:08:33 INFO - PROCESS | 1658 | 09:08:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 09:08:35 INFO - PROCESS | 1658 | RSS: Main: 286019584 09:08:35 INFO - PROCESS | 1658 | 09:08:37 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:08:38 INFO - PROCESS | 1658 | RSS: Main: 290660352 09:08:38 INFO - PROCESS | 1658 | 09:08:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 09:08:41 INFO - PROCESS | 1658 | RSS: Main: 328007680 09:08:41 INFO - PROCESS | 1658 | 09:08:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 09:08:45 INFO - PROCESS | 1658 | RSS: Main: 328589312 09:08:45 INFO - PROCESS | 1658 | 09:08:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 09:08:48 INFO - PROCESS | 1658 | RSS: Main: 327065600 09:08:48 INFO - PROCESS | 1658 | 09:08:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:08:50 INFO - PROCESS | 1658 | RSS: Main: 302571520 09:08:50 INFO - PROCESS | 1658 | 09:08:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:08:53 INFO - PROCESS | 1658 | RSS: Main: 303755264 09:08:53 INFO - PROCESS | 1658 | 09:08:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:08:56 INFO - PROCESS | 1658 | RSS: Main: 306360320 09:08:56 INFO - PROCESS | 1658 | 09:08:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:00 INFO - PROCESS | 1658 | RSS: Main: 318623744 09:09:00 INFO - PROCESS | 1658 | 09:09:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:04 INFO - PROCESS | 1658 | RSS: Main: 317767680 09:09:04 INFO - PROCESS | 1658 | 09:09:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 09:09:06 INFO - PROCESS | 1658 | RSS: Main: 361930752 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | __start_tp_report 09:09:06 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 09:09:06 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 09:09:06 INFO - PROCESS | 1658 | |i|pagename|runs| 09:09:06 INFO - PROCESS | 1658 | |0;ai-astar;112;110;113;112;110;111;111;111;112;111 09:09:06 INFO - PROCESS | 1658 | |1;audio-beat-detection;150;112;109;150;117;109;107;131;178;114 09:09:06 INFO - PROCESS | 1658 | |2;audio-dft;184;182;181;197;263;184;182;180;182;183 09:09:06 INFO - PROCESS | 1658 | |3;audio-fft;81;58;59;88;60;59;59;58;82;59 09:09:06 INFO - PROCESS | 1658 | |4;audio-oscillator;87;71;93;70;68;70;93;68;69;95 09:09:06 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;89;86;90;86;89;86;90;87;90;86 09:09:06 INFO - PROCESS | 1658 | |6;imaging-darkroom;94;94;95;101;151;133;94;94;95;95 09:09:06 INFO - PROCESS | 1658 | |7;imaging-desaturate;85;95;123;134;81;81;87;85;84;81 09:09:06 INFO - PROCESS | 1658 | |8;json-parse-financial;58;55;55;54;53;55;56;55;56;54 09:09:06 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;64;72;94;108;113;71;70;71;71;71 09:09:06 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;87;85;86;87;87;87;90;85;91;88 09:09:06 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;143;151;166;155;133;153;137;150;220;148 09:09:06 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;172;173;173;174;235;203;171;171;175;235 09:09:06 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;69;68;70;68;69;69;69;67;67;69 09:09:06 INFO - PROCESS | 1658 | __end_tp_report 09:09:06 INFO - PROCESS | 1658 | __start_cc_report 09:09:06 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,2027 09:09:06 INFO - PROCESS | 1658 | __end_cc_report 09:09:06 INFO - PROCESS | 1658 | __startTimestamp1476461346043__endTimestamp 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | ------- Summary: start ------- 09:09:06 INFO - PROCESS | 1658 | Number of tests: 14 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:0.95 (0.9%) stddev-sans-first:0.97 09:09:06 INFO - PROCESS | 1658 | Values: 112.0 110.0 113.0 112.0 110.0 111.0 111.0 111.0 112.0 111.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:124.00 stddev:24.06 (19.4%) stddev-sans-first:24.13 09:09:06 INFO - PROCESS | 1658 | Values: 150.0 112.0 109.0 150.0 117.0 109.0 107.0 131.0 178.0 114.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:191.80 Median:183.50 stddev:25.47 (13.9%) stddev-sans-first:26.86 09:09:06 INFO - PROCESS | 1658 | Values: 184.0 182.0 181.0 197.0 263.0 184.0 182.0 180.0 182.0 183.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:66.30 Median:59.50 stddev:12.13 (20.4%) stddev-sans-first:11.64 09:09:06 INFO - PROCESS | 1658 | Values: 81.0 58.0 59.0 88.0 60.0 59.0 59.0 58.0 82.0 59.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:79.00 stddev:11.91 (15.1%) stddev-sans-first:12.22 09:09:06 INFO - PROCESS | 1658 | Values: 87.0 71.0 93.0 70.0 68.0 70.0 93.0 68.0 69.0 95.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.85 (2.1%) stddev-sans-first:1.92 09:09:06 INFO - PROCESS | 1658 | Values: 89.0 86.0 90.0 86.0 89.0 86.0 90.0 87.0 90.0 86.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:104.60 Median:94.00 stddev:20.27 (21.6%) stddev-sans-first:21.14 09:09:06 INFO - PROCESS | 1658 | Values: 94.0 94.0 95.0 101.0 151.0 133.0 94.0 94.0 95.0 95.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:84.50 stddev:19.03 (22.5%) stddev-sans-first:19.93 09:09:06 INFO - PROCESS | 1658 | Values: 85.0 95.0 123.0 134.0 81.0 81.0 87.0 85.0 84.0 81.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:1.37 (2.5%) stddev-sans-first:0.97 09:09:06 INFO - PROCESS | 1658 | Values: 58.0 55.0 55.0 54.0 53.0 55.0 56.0 55.0 56.0 54.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:71.00 stddev:17.67 (24.9%) stddev-sans-first:17.71 09:09:06 INFO - PROCESS | 1658 | Values: 64.0 72.0 94.0 108.0 113.0 71.0 70.0 71.0 71.0 71.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:87.00 stddev:1.95 (2.2%) stddev-sans-first:2.06 09:09:06 INFO - PROCESS | 1658 | Values: 87.0 85.0 86.0 87.0 87.0 87.0 90.0 85.0 91.0 88.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:155.60 Median:152.00 stddev:24.47 (16.1%) stddev-sans-first:25.52 09:09:06 INFO - PROCESS | 1658 | Values: 143.0 151.0 166.0 155.0 133.0 153.0 137.0 150.0 220.0 148.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:174.50 stddev:26.44 (15.2%) stddev-sans-first:27.39 09:09:06 INFO - PROCESS | 1658 | Values: 172.0 173.0 173.0 174.0 235.0 203.0 171.0 171.0 175.0 235.0 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 09:09:06 INFO - PROCESS | 1658 | Values: 69.0 68.0 70.0 68.0 69.0 69.0 69.0 67.0 67.0 69.0 09:09:06 INFO - PROCESS | 1658 | -------- Summary: end -------- 09:09:06 INFO - PROCESS | 1658 | 09:09:06 INFO - TEST-INFO | 1658: exit 0 09:09:06 INFO - TEST-OK | kraken | took 59876ms 09:09:06 INFO - SUITE-END | took 1178s 09:09:07 INFO - Completed test suite (00:19:39) 09:09:07 INFO - javascript benchmark 09:09:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2707.9864600677, 3251.967480325196, 3255.2024753935275, 3278.2623909620324, 3281.5077738339232, 5421.972890135554, 5495.093309603915, 5512.0, 5545.417731138223, 5588.189712491686, 5535.197396377523, 5552.11166213406, 5577.163425486179, 5596.888062238752, 5599.524040456556, 4530.796114174854, 4928.383952005999, 5023.271625614302, 5031.471695471978, 5147.0, 4975.751212439363, 5012.498750124995, 5113.054084994269, 5164.8708782280555, 5256.448072952342, 5165.147750621143, 5170.638055336128, 5181.637285390024, 5205.687658740488, 5234.73826308685, 4997.225430058311, 5040.0, 5047.949520504784, 5132.5124113209185, 5158.045761534128, 4951.504849515056, 4963.60291176705, 5001.699898006095, 5053.494650534917, 5073.315102461157, 5358.544523715489, 5381.192821076831, 5461.69960652164, 5490.670559766387, 5503.3120859892515, 5756.251687280668, 5972.641641501524, 6013.458788709037, 6028.110566899751, 6053.0, 5912.113183022581, 5925.20009798676, 6022.578419510636, 6097.573080549921, 6128.295246046717, 4723.220668589713, 5624.353199382123, 5636.436356364331, 5653.773849045992, 5656.773729050833, 6240.157578726814, 6264.404881536247, 6277.2153480814895, 6277.372262773686, 6290.779822706183, 5754.539636829044, 5818.796342128006, 5843.123531470228, 5859.414058594191, 5942.227510423703, 6099.854599951442, 6111.799264753458, 6124.992521985438, 6131.254333328352, 6135.575953096713, 5964.343922168558, 6054.788082417094, 6056.394360563909, 6072.908906366408, 6102.664353460602, 5871.882562348815, 6097.420745029215, 6100.69496525172, 6111.113888486234, 6119.418655227746, 4742.952570474251, 4763.523647635278, 4799.784009719571, 4800.399950006249, 4824.927626085611, 3886.5336159660455, 4213.0, 4275.4441922550495, 4299.914001719948, 4328.156009578102, 5585.385607583163, 5589.552835773128, 5604.9911016016695, 5612.635178713371, 5649.858753531113, 5948.7323070461935, 5994.1608174855555, 6061.727222275008, 6063.757449701962, 6064.272287325549, 5594.1608758686525, 5596.2165296858475, 5644.322681278273, 5645.5201307888465, 5656.6888821115235, 5258.6581872178185, 5259.21111833228, 5280.709560974184, 5290.761915713725, 5291.391489978701, 6095.740618599498, 6187.443130118311, 6304.6847657617855, 6304.810855674337, 6309.873802524015, 5970.791022314198, 6035.456808887221, 6092.75628974836, 6100.786472473442, 6156.938430615815, 6228.439440450381, 6277.18396608438, 6295.779647712308, 6303.275123360872, 6318.210223722035, 5964.782981096761, 6001.639901605918, 6007.639541627516, 6007.669578173242, 6008.24896887889, 5949.226600542075, 6034.275886893601, 6042.425969532887, 6076.3012253591405, 6114.144019837316, 5890.911636325544, 5911.763529458945, 5911.91132133027, 5924.911126333194, 5951.672658003851, 5883.705814709333, 5901.350851406256, 5917.142014407973, 5919.467247947706, 5924.911126333022, 5622.634528755618, 5699.8860022800145, 5701.515371193495, 5709.20071190042, 5716.5998380113, 5929.199558059607, 5940.6435613863305, 5949.732262048305, 5963.7912673056235, 5971.940280597139, 5619.662820230799, 5692.715364231854, 5785.190073389645, 5806.042003069446, 5858.179854820414], "value": 5535.690456817958, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.70618157470005, 472.1383475157838, 520.4509242748903, 533.1682575182717, 540.3650710415262, 26794.464110717774, 31394.372112557765, 32483.025509234703, 32610.0, 32649.347013059723, 33007.339853202924, 42487.0, 43732.0, 43821.561784382146, 46418.0, 9521.666741664043, 9673.70978870635, 9677.951610241938, 9678.709638710814, 9699.078587534208, 9429.717108486722, 9473.147416732492, 9600.80798384032, 9612.134907858292, 9616.134547890688, 9577.952110239474, 9598.712038638818, 9631.0, 9640.951795241013, 9714.417134971889, 9538.332316737831, 9593.088656577615, 9604.991475895034, 9620.278479114095, 9624.230061595055, 8456.689213171969, 9314.720558383191, 9502.47736374499, 9554.761130971781, 9561.474118923456, 41055.384169237484, 41430.79284603588, 41494.0, 41525.0, 41563.0, 3362.96637033629, 3410.488426735985, 3418.7265018798435, 3439.6216416194197, 3516.226430185355, 30434.782608695627, 31901.52147717786, 31960.520592191137, 32062.0, 32645.020649380556, 3267.18320419895, 3289.5723555937816, 3305.7024867762016, 3362.1930736623035, 3368.9663103369144, 429418.0, 432081.0, 433508.83245583886, 433870.0, 435224.0, 60090.69501177477, 60366.69816650976, 60534.69732651397, 60620.696896515234, 60666.393336066954, 63696.36303636997, 63708.68145659242, 63746.0438093429, 63775.6811215941, 63848.68075659685, 16247.918760406123, 16485.17574121289, 16490.505284841474, 16496.6700665986, 16523.917380413262, 12739.36303184837, 12819.102662813513, 12823.93588032054, 12829.422675979604, 12839.03707221962, 13654.062550234812, 17016.659666806594, 17294.221760020828, 17294.394696185573, 17558.736618950727, 5757.337906140848, 6817.284579903865, 6876.346747059021, 6984.895226571605, 7199.244079371695, 981.7103954333488, 1004.1665417703289, 1009.3287963504188, 1030.4744580264119, 1063.8351005648801, 25923.0, 29692.703072968994, 29986.400271994436, 30008.0, 30940.53589196119, 1040.6565833275151, 1054.8628678271775, 1059.5549869055014, 1086.147374311162, 1090.569225156059, 12929.482820687068, 15707.293171807296, 15717.685646287011, 15893.443729469413, 15929.591425056473, 544380.0, 549744.0, 549883.0, 550321.0, 551309.0, 59096.70451647714, 59301.0, 59389.70305148447, 59589.0, 59589.40410595925, 1222.3093951917137, 1253.849538055421, 1268.8160216768517, 1337.9866201337861, 1351.047511504387, 29476.705232947395, 31527.0, 31668.099914005234, 31796.36407271888, 32456.83771581127, 50174.0, 50483.74758126186, 50716.0, 50808.0, 50840.745796270785, 472385.0, 474776.0, 474863.0, 475113.62443188945, 475813.0, 26356.60465092987, 27011.864940675172, 27036.05373811907, 40459.79770101131, 40662.796686017566, 47702.52297476981, 47851.04297913953, 48507.757461212466, 48832.290869819386, 49230.0, 37945.81027094957, 39559.0, 41015.0, 41194.58805411908, 41241.0, 38971.415428769156, 39678.0, 39723.80138099407, 40061.39907901325, 40760.0, 34646.0, 36391.90824275276, 38952.6104738949, 39281.214375712894, 39379.409308860966, 32513.349733004736, 33880.98867868002, 34422.13944651404, 34484.482732759534, 34889.47665785066, 24976.625350619393, 25041.998320067723, 25363.87318063398, 25414.364640884127, 25430.618540722273], "value": 19678.61499647563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.77914355668827, 353.77358490566024, 360.9765365251259, 365.2749292654081, 386.277660774352, 14970.850291497081, 15123.773143402857, 15995.120268385219, 20396.286129985452, 21329.0, 17088.743668844978, 17191.484255472355, 19478.610427791435, 20297.28959486418, 21343.466413339673, 5071.84784456466, 5354.544863686591, 5367.43641917597, 5368.194770784375, 5373.623846330758, 4666.4400271967415, 5345.599080069011, 5355.544778693816, 5921.851953701171, 6002.249718785152, 5934.495567876736, 5945.256842894638, 5948.40515948406, 5959.0763431668165, 5961.523078153759, 5288.2596436498925, 5330.227117068042, 5339.439358867341, 5346.331708536433, 5459.235707001003, 5179.792808287664, 5181.559567436735, 5186.896262074775, 5188.325517682677, 5192.584593232532, 13394.732105357838, 13483.393247303895, 23408.0, 23705.577665339963, 24477.38806529833, 1914.2630087416305, 2175.706279652258, 2418.6492958521094, 2482.689663792026, 2705.5130076586206, 9677.90322096777, 9848.409095454295, 9850.556724947322, 10315.277930544866, 10436.217283703752, 1816.4369045595909, 1849.5006348285888, 1862.078271255737, 1867.5517875709731, 1912.7035309527046, 102200.48899755454, 114301.0, 121228.0, 121843.0, 124940.37529812384, 9243.445393276425, 11185.832212516818, 11390.145739069438, 11505.597304094317, 11733.0, 14599.0, 14610.63473413152, 14611.78082328766, 14627.26863656813, 14818.481353152803, 8228.300594449538, 8527.654894903952, 8540.74377768668, 8675.783105422415, 8711.520866352284, 8416.915830841614, 8422.199891010345, 8425.115362886809, 8594.65621375138, 8657.091005444463, 3616.9095772605897, 4542.591166795004, 4544.795484203219, 4585.312203169485, 4652.092841895797, 1571.5599632103028, 1726.9740953885703, 1831.8717689761854, 1934.8355389792025, 1971.763388393402, 760.7147319755092, 826.4421515477029, 867.6616119713317, 897.546738896853, 976.6630512473185, 18830.71753923692, 19411.89036658721, 21258.468538286666, 21383.0, 21671.783282167293, 862.9395942284106, 917.3349321741817, 934.1639232886529, 947.8815148106487, 975.5951280218771, 9058.0, 11060.391678457601, 11254.94372528132, 11260.774784504263, 11311.886881131084, 447349.0, 450839.0, 451870.0, 526905.0, 541131.2943435258, 74963.5007299851, 99109.0, 121301.0, 130592.0, 137008.31495842658, 867.5228624256633, 941.1858742188095, 975.5951280218629, 1025.2720568396521, 1025.9640912568025, 15941.920290398473, 18584.81415185885, 18843.6231275371, 18876.640419947507, 18968.956707381225, 36766.63233367632, 37236.44145337768, 54723.72638136943, 55233.72383138059, 55863.44136558582, 72630.49177723106, 76907.9236830543, 81918.18081819106, 84161.15838841778, 84727.57636211779, 7786.26030527099, 7794.688212471665, 7884.645190966535, 7893.684252629831, 8136.75589732309, 10760.246782725135, 12542.623721288375, 12638.43127059303, 12764.91522712723, 12779.23324600527, 10686.198535110054, 10780.838287425539, 10942.124630029737, 11051.668449946514, 11108.500117494894, 10660.040596346365, 10730.83903741429, 10776.67669969902, 10974.725631859268, 11001.449927503432, 9185.586648600962, 11058.004779569877, 11326.603568875049, 11360.829587556029, 11407.714807129556, 7608.771736847903, 7791.142974272712, 7884.84230315402, 7915.881261781193, 8061.758147255592, 8038.638261278374, 9630.566624502055, 9637.662681806103, 10732.517036733521, 10982.286151400134], "value": 9105.562889278453, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.296317212496, 1556.3930067273761, 1789.8478629316508, 1904.6000339928614, 1976.2687805511962, 8120.309773669238, 8578.0, 8593.65625374985, 8613.741587752369, 8630.525321107336, 7595.316421522062, 7843.607819609011, 7849.293563579276, 8240.876386854186, 8398.118197589256, 2011.688188330811, 2287.942801429965, 2305.8155347572156, 2330.1844354475966, 2335.194357946514, 2228.0419419649543, 2263.9434014149697, 2272.3750968483632, 2277.6469647204626, 2278.6582012698063, 2306.7001289832383, 2332.1604222479896, 2333.428310064037, 2338.3452633262627, 2345.4488195274184, 2302.8042616377543, 2311.410590299463, 2314.9189778357845, 2316.7219933607907, 2319.489712263308, 2270.5572413379396, 2299.7930186283315, 2306.112146823485, 2306.7116610423695, 2310.1683393978155, 8372.497650141011, 8563.10087440816, 8565.271951884097, 8593.742187734379, 8607.18231767981, 5053.418856831475, 5192.7403629818355, 5195.5324020837925, 5201.55786758126, 5208.166693329087, 3295.3574053059665, 3368.8147151906637, 3518.2963407318384, 3518.9091381671765, 3519.0850378901414, 1023.2019025160387, 1024.0578667625803, 1042.8413184707658, 1045.1273186888957, 1045.9372437653765, 7591.658375373121, 9071.228945539637, 10989.61536346232, 11012.008919197391, 11152.219344645879, 4296.291111966553, 4563.406757121553, 4617.8383756568355, 4629.537046295411, 4636.698614590041, 9449.102335278138, 9464.810703785886, 9472.0, 9480.478573678514, 9483.525823708926, 5186.262602028287, 5189.50699683537, 5191.195364718475, 5194.091034069098, 5196.610254230948, 4561.863144105682, 4719.292106184032, 4754.191787396151, 4767.61859051275, 4801.399825021872, 3618.222082252328, 3678.2091850252323, 3696.1868388954385, 3730.645588669072, 3755.080005398695, 956.7225504603587, 1113.0486771959672, 1116.190761697779, 1121.775644871029, 1124.0557931337553, 897.6499165325529, 914.533587870191, 918.9862152067724, 944.6173608023428, 968.4915419404728, 5529.585680610553, 5826.446487583673, 5826.650400975955, 5838.3784159931165, 6027.095935609694, 397.6063696940009, 422.9640480559187, 424.3465063801711, 425.97231179973204, 427.8224536817248, 3843.8462461501786, 3917.725759196886, 3966.087799806061, 3990.7206495545615, 4061.3907913813164, 8133.59332033396, 10132.138768204784, 10252.974702529687, 10323.07092361676, 10330.793384132276, 10148.680671512657, 10170.135538479164, 10252.23108266883, 10263.58945642166, 10271.126954208976, 7695.576743279174, 7813.531188128731, 7820.865974433676, 7904.20957904205, 7908.090569584457, 5177.637565370538, 5728.341240757366, 5799.8550036249435, 5808.361080281081, 5926.8738939601335, 9216.631334746535, 9233.722988310361, 9432.292578056811, 9578.377405468622, 9631.566579504079, 8541.95729021351, 8871.0, 9322.860157097784, 9376.624935002523, 9555.283353748635, 6627.701753421011, 7272.381847543018, 7343.118825740945, 7416.592087435243, 7435.665395057127, 7938.483998540077, 7978.281954624112, 7981.2018798119725, 8041.11547729761, 8071.394645401501, 7285.470051289289, 7295.890561641686, 7306.305900939402, 7354.705811767683, 7398.667059982206, 6141.6007959482495, 7133.5719856808755, 7186.0, 7232.566046037255, 7318.597477138808, 6683.665816709048, 6819.522633415613, 6825.010373495915, 6837.9316206839285, 6853.897191542031, 4754.7147171169845, 4829.879253018703, 4911.754412279443, 4954.529319714621, 4959.7272150032095, 4207.726497777635, 4818.229083346788, 4918.877028074184, 4976.701397916137, 5104.132297509506], "value": 4329.991768140437, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.43015522170606, 289.9057806212981, 300.88716731225793, 301.8460585101598, 306.98158110513384, 5175.896482070356, 5215.6349055566125, 5222.686638801665, 5244.872352444224, 5266.605004624649, 3762.3821756644634, 4347.717398369104, 4797.40032495938, 4991.625628077891, 5255.789768409269, 3835.616438356163, 3849.5573009104032, 3865.729398942061, 3884.679209068916, 3886.417037444378, 3736.7855446979706, 3890.9027274318087, 3927.7839718815453, 3933.2330195611867, 3973.5231772187376, 3167.6357218919775, 3269.623993240772, 3339.7976728377885, 3645.687552481105, 3820.36963900956, 3876.8836934891997, 3879.0690234343697, 3892.7884495291214, 3894.104355998122, 3895.7078219133678, 2943.852807359645, 3344.3144155448076, 3386.6791951139076, 3393.1686736749652, 3404.2510647657477, 4253.553376895444, 4918.8770280743265, 4962.776675049631, 4972.403311602595, 4983.551480366785, 4218.8523401681105, 4405.383246345515, 4443.289073748217, 4447.355133505656, 4460.219461594208, 4432.977835110836, 4739.644526660514, 5029.817992771696, 5357.544608708265, 5367.946320536823, 3712.2204337089242, 3726.739128261023, 3748.4069270559903, 3754.1740817020213, 3756.962430375716, 4625.930611040837, 4852.6794148941235, 5375.301210842605, 5528.226048353234, 5692.57305702074, 3100.240441091947, 3761.153740408441, 3769.021263836453, 3769.9434508482395, 3777.792221427848, 3202.59967504062, 3238.6923242291946, 3835.2521258354354, 3841.692664586826, 3866.2074274773604, 4439.445069366329, 4450.020995381011, 4450.643948484114, 4462.642988560908, 4468.754218517947, 4719.442583947289, 5129.589632829365, 5380.865478362994, 5392.91910621341, 5409.080456322435, 1290.316132449803, 1315.3489022933518, 1317.808917706927, 1321.7554752370843, 1322.1075773852804, 735.5476382025123, 811.4765975945483, 834.837206744679, 882.0, 917.4037175315037, 4054.6011625988986, 4373.846915357947, 4531.954680453153, 5055.87360315995, 5103.2855400244, 5230.973845130826, 5511.090670039399, 5511.862203444946, 5516.668999860021, 5522.116461366162, 4669.649776266793, 4974.9751251243515, 5026.567428282995, 5037.269595908573, 5072.949270507248, 2868.7131286871145, 2870.9282267943468, 2884.5528943013865, 2892.3058465968015, 2898.2754311422145, 5637.633553818989, 5648.491635752802, 5670.461306175906, 5704.714764261853, 5721.227634269404, 5398.0, 5551.694656793915, 5559.749811258503, 5621.690807005572, 5629.605927585029, 5618.218981604132, 5631.2960879890015, 5644.238027866268, 5658.490735833795, 5708.886767080379, 4288.699791014599, 4755.667103302736, 5436.809711660231, 5441.183822426509, 5459.181122831607, 5339.46605339463, 5354.866128346666, 5389.137737961907, 5406.7296635167295, 5424.918626220531, 5624.493795558419, 5627.352854421794, 5627.774889004394, 5636.9436305638055, 5643.858903527444, 3572.3212589607892, 3717.9814100930403, 3737.7757334560015, 3749.1751814600743, 3758.590528551793, 4510.819567217406, 4953.678010929278, 4974.776135073858, 5078.943028073636, 5409.215663728816, 4674.275487299474, 5289.518934698291, 5315.335583052119, 5325.603478434642, 5359.3032905722, 4821.9035619286715, 4899.632527560376, 5062.31658726082, 5285.920711189412, 5292.947070529245, 4705.541427051183, 4989.426215985063, 5016.36640763602, 5171.793128274978, 5182.611304152279, 5140.794368225229, 5268.6575372600655, 5269.262303277467, 5369.328833895763, 5387.488188622075, 4364.781760911878, 5255.080360936744, 5303.443138470405, 5314.362276526842, 5354.81388573798], "value": 3912.145398048929, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.8613820305039, 716.7818282900487, 768.8659227639233, 779.3905584967042, 821.5085884988796, 14989.400423983028, 15636.687266254668, 15873.444429444957, 15970.361185552592, 15976.121313327776, 13981.650458738512, 14509.274536273197, 14605.92697036516, 14624.634384140401, 14627.926860365684, 760.4106817216646, 899.3210126354593, 911.7538264668535, 913.8263729891332, 914.4193437167413, 651.1047309948821, 699.5697645947756, 730.9269073092702, 877.4515927545284, 907.4056492997064, 946.5314669238736, 953.4660590069549, 953.9427634341927, 953.993536818656, 954.0793134625086, 949.4967667136428, 949.6866034208695, 951.1487218939011, 951.2485136741966, 951.4434056077168, 546.529984213576, 562.9380768115504, 688.1776277348571, 705.671862583901, 707.5683832861951, 15293.770593441108, 15699.293531791096, 15747.370105195778, 15749.763753543706, 15810.446634367858, 13627.977901657416, 14073.577792666236, 14184.787228191586, 14333.21167335796, 14371.92814035923, 11204.719882002933, 11609.071274298036, 11878.524859005629, 12430.937845310806, 12759.61721148367, 3800.6009369016133, 4447.3996010538485, 4466.7766611669285, 4477.955220447819, 4517.277235642281, 103543.0, 104910.0, 105110.0, 105311.0, 106677.46661266721, 10587.249071185113, 10721.678349649523, 10749.570017199381, 10816.242862999672, 11147.0, 20373.388798336073, 20494.38516844497, 20516.38450846477, 20518.58962820735, 20994.0, 7787.182345853717, 7795.766127016199, 7832.960835195788, 7865.646045927947, 7955.124936257007, 4074.572169922175, 4112.09533902541, 4129.194807012604, 4131.07050913548, 4140.855070072533, 3319.4854797506423, 3441.9827900860337, 3443.0531603808654, 3444.2767018926306, 3707.94438083429, 689.1282527602586, 690.6926417744156, 696.0707455546901, 696.4428457234193, 704.6124631452681, 480.98076076957227, 588.2088590845307, 604.727872457396, 626.3110578363763, 674.959502429846, 9020.909790902138, 9345.579448924815, 9510.0, 9658.179054780423, 9670.951645241728, 602.3043384890459, 616.4821549898018, 639.8304449320933, 684.7055766020559, 684.8424862281703, 4412.757298348557, 4758.571038454814, 4785.49752276006, 4827.879303017453, 4968.27959945813, 97870.0, 141627.58372416144, 142424.0, 145661.0, 155065.0, 77479.0, 78379.0, 79914.40171196543, 80150.5992470034, 112082.71462354921, 96428.9999999986, 113574.0, 114224.0, 115248.42375788068, 118286.0, 9523.619055237712, 9742.878375030963, 9755.29904312129, 9793.02022926832, 9810.714214404055, 50637.72130254074, 56923.369119734234, 60480.0, 61051.62170399979, 65171.34828651653, 82775.0, 96032.51983740037, 98923.70987657338, 101675.49162254142, 103576.0, 10810.13518918469, 13153.671158221121, 13416.0, 13538.07395855191, 13652.65868353299, 14036.78944815849, 14523.419063237774, 14621.41514339457, 14629.780553291495, 15146.0, 13556.525521606696, 13798.852008984277, 13845.79231311511, 13889.861101388857, 14306.284685765879, 10994.835077474005, 13920.443182272596, 14276.286185690466, 14461.710765784415, 14584.48954286595, 13307.933460333024, 13474.124181928144, 13632.454701811816, 13796.862031379558, 14065.296735162996, 10959.945200274267, 11174.329540227613, 11756.412179390825, 11788.587399441321, 11804.940975295412, 10828.24202305831, 11208.71978200519, 11420.82868756986, 11455.541778328774, 11483.655490335303], "value": 7769.604976765974, "unit": "score"}], "name": "dromaeo_css", "value": 7122.579104415868, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 113.0, 112.0, 110.0, 111.0, 111.0, 111.0, 112.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 109.0, 150.0, 117.0, 109.0, 107.0, 131.0, 178.0, 114.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 181.0, 197.0, 263.0, 184.0, 182.0, 180.0, 182.0, 183.0], "value": 191.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 88.0, 60.0, 59.0, 59.0, 58.0, 82.0, 59.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 93.0, 70.0, 68.0, 70.0, 93.0, 68.0, 69.0, 95.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 89.0, 86.0, 90.0, 87.0, 90.0, 86.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 101.0, 151.0, 133.0, 94.0, 94.0, 95.0, 95.0], "value": 104.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 95.0, 123.0, 134.0, 81.0, 81.0, 87.0, 85.0, 84.0, 81.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 55.0, 54.0, 53.0, 55.0, 56.0, 55.0, 56.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 94.0, 108.0, 113.0, 71.0, 70.0, 71.0, 71.0, 71.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 87.0, 87.0, 87.0, 90.0, 85.0, 91.0, 88.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 151.0, 166.0, 155.0, 133.0, 153.0, 137.0, 150.0, 220.0, 148.0], "value": 155.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 173.0, 174.0, 235.0, 203.0, 171.0, 171.0, 175.0, 235.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 68.0, 69.0, 69.0, 69.0, 67.0, 67.0, 69.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1496.8, "alertThreshold": 2.0}]} 09:09:07 INFO - Return code: 0 09:09:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:07 INFO - # TBPL SUCCESS # 09:09:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:09:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:09:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:09:07 INFO - 2016-10-14 09:09:07.384 screenresolution[1662:9906] starting screenresolution argv=screenresolution get 09:09:07 INFO - 2016-10-14 09:09:07.387 screenresolution[1662:9906] Display 0: 1600x1200x32@60 09:09:07 INFO - 2016-10-14 09:09:07.393 screenresolution[1663:9909] starting screenresolution argv=screenresolution list 09:09:07 INFO - Available Modes on Display 0 09:09:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:09:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:09:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:09:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:09:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:09:07 INFO - Intel Iris: 09:09:07 INFO - Chipset Model: Intel Iris 09:09:07 INFO - Type: GPU 09:09:07 INFO - Bus: Built-In 09:09:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:09:07 INFO - Vendor: Intel (0x8086) 09:09:07 INFO - Device ID: 0x0a2e 09:09:07 INFO - Revision ID: 0x0009 09:09:07 INFO - Displays: 09:09:07 INFO - Display: 09:09:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:09:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:09:07 INFO - Display Serial Number: 1600x1200 60 09:09:07 INFO - Main Display: Yes 09:09:07 INFO - Mirror: Off 09:09:07 INFO - Online: Yes 09:09:07 INFO - Rotation: Supported 09:09:07 INFO - Return code: 0 09:09:07 INFO - Running post-action listener: _resource_record_post_action 09:09:07 INFO - [mozharness: 2016-10-14 16:09:07.629646Z] Finished run-tests step (success) 09:09:07 INFO - Running post-run listener: _resource_record_post_run 09:09:07 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 33020928; Write bytes: 708878848; Read time: 309; Write time: 2044 09:09:07 INFO - TinderboxPrint: CPU usage
53.5% 09:09:07 INFO - TinderboxPrint: I/O read bytes / time
33,020,928 / 309 09:09:07 INFO - TinderboxPrint: I/O write bytes / time
708,878,848 / 2,044 09:09:07 INFO - TinderboxPrint: CPU idle
2,259.4 (46.5%) 09:09:07 INFO - TinderboxPrint: CPU system
973.6 (20.0%) 09:09:07 INFO - TinderboxPrint: CPU user
1,624.6 (33.4%) 09:09:07 INFO - TinderboxPrint: Swap in / out
436,895,744 / 0 09:09:07 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392702464; Write bytes: 387302912; Read time: 29372; Write time: 721 09:09:07 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 32082944; Write bytes: 296895488; Read time: 295; Write time: 1264 09:09:08 INFO - Running post-run listener: _upload_blobber_files 09:09:08 INFO - Blob upload gear active. 09:09:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:08 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 09:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:09:08 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:09 INFO - (blobuploader) - INFO - Done attempting. 09:09:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:09:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:10 INFO - (blobuploader) - INFO - Done attempting. 09:09:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:09:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:12 INFO - (blobuploader) - INFO - Done attempting. 09:09:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:09:12 INFO - Return code: 0 09:09:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:09:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:09:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d42ea6289141ba98e09452456bbed66ec787488beef02c77253cbe9fdecd1009f1416fdd312560f36d52059237b6d39ebe7edb3f5f4d681237cb03c47341d36c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e3fb01b47452cbc80d4d8fe2c74be1b309957385f3ddbe3d74dbd7ebcae63645652b8b1e047d60121e3075faad7ea5c3968686d0d4613448032a27f20371ebc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/663818f96326d52028f419703fe58015e6f4e7102703417b9dd5d55176bc8029fe7159ade6def61c23a2c1a190d5a330f1d6ede298031e6be84fdafbba050ca2"} 09:09:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:09:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:09:12 INFO - Contents: 09:09:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d42ea6289141ba98e09452456bbed66ec787488beef02c77253cbe9fdecd1009f1416fdd312560f36d52059237b6d39ebe7edb3f5f4d681237cb03c47341d36c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e3fb01b47452cbc80d4d8fe2c74be1b309957385f3ddbe3d74dbd7ebcae63645652b8b1e047d60121e3075faad7ea5c3968686d0d4613448032a27f20371ebc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/663818f96326d52028f419703fe58015e6f4e7102703417b9dd5d55176bc8029fe7159ade6def61c23a2c1a190d5a330f1d6ede298031e6be84fdafbba050ca2"} 09:09:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:09:12 INFO - Copying logs to upload dir... 09:09:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:09:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.845308 ========= master_lag: 1.51 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-14 09:09:14.285777) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 09:09:14.291359) ========= 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.cdFR66HUsr/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.DqxJMrglSr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d42ea6289141ba98e09452456bbed66ec787488beef02c77253cbe9fdecd1009f1416fdd312560f36d52059237b6d39ebe7edb3f5f4d681237cb03c47341d36c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e3fb01b47452cbc80d4d8fe2c74be1b309957385f3ddbe3d74dbd7ebcae63645652b8b1e047d60121e3075faad7ea5c3968686d0d4613448032a27f20371ebc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/663818f96326d52028f419703fe58015e6f4e7102703417b9dd5d55176bc8029fe7159ade6def61c23a2c1a190d5a330f1d6ede298031e6be84fdafbba050ca2"} build_url:https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011081 build_url: 'https://queue.taskcluster.net/v1/task/Da3lKkDXQMCNoZxHHNXNIA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d42ea6289141ba98e09452456bbed66ec787488beef02c77253cbe9fdecd1009f1416fdd312560f36d52059237b6d39ebe7edb3f5f4d681237cb03c47341d36c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e3fb01b47452cbc80d4d8fe2c74be1b309957385f3ddbe3d74dbd7ebcae63645652b8b1e047d60121e3075faad7ea5c3968686d0d4613448032a27f20371ebc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/663818f96326d52028f419703fe58015e6f4e7102703417b9dd5d55176bc8029fe7159ade6def61c23a2c1a190d5a330f1d6ede298031e6be84fdafbba050ca2"}' ========= master_lag: 0.38 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 09:09:14.686084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:09:14.686422) ========= 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.cdFR66HUsr/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.DqxJMrglSr/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005333 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:09:14.712722) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 09:09:14.713258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 09:09:14.713572) ========= ========= Total master_lag: 4.39 =========