builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0320 starttime: 1475174822.7 results: success (0) buildid: 20160929074223 builduid: 9af9495b6ef5418c8f3f9d523e1b2566 revision: 2027e4ba3584e9bb84ca452ed4d50d180b3cae62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:02.696318) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:02.696752) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:02.697039) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:02.740728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:02.741089) ========= 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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-29 11:47:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-09-29 11:47:03 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142763 ========= master_lag: 0.59 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:03.473883) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:03.474250) ========= 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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.040486 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:03.537520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-29 11:47:03.540928) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2027e4ba3584e9bb84ca452ed4d50d180b3cae62 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2027e4ba3584e9bb84ca452ed4d50d180b3cae62 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ESRKeXDVI7/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.ChsdOl4QAX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-29 11:47:03,624 truncating revision to first 12 chars 2016-09-29 11:47:03,624 Setting DEBUG logging. 2016-09-29 11:47:03,624 attempt 1/10 2016-09-29 11:47:03,624 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2027e4ba3584?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 11:47:04,344 unpacking tar archive at: fx-team-2027e4ba3584/testing/mozharness/ program finished with exit code 0 elapsedTime=1.000847 ========= master_lag: 1.69 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-29 11:47:06.233805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:06.234095) ========= script_repo_revision: 2027e4ba3584e9bb84ca452ed4d50d180b3cae62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:06.234472) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:06.234738) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 11:47:06.279058) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-29 11:47:06.279384) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ESRKeXDVI7/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.ChsdOl4QAX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:06 INFO - MultiFileLogger online at 20160929 11:47:06 in /builds/slave/test 11:47:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:47:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:47:06 INFO - {'append_to_log': False, 11:47:06 INFO - 'base_work_dir': '/builds/slave/test', 11:47:06 INFO - 'blob_upload_branch': 'Fx-Team', 11:47:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:47:06 INFO - 'branch': 'Fx-Team', 11:47:06 INFO - 'buildbot_json_path': 'buildprops.json', 11:47:06 INFO - 'config_files': ('talos/mac_config.py',), 11:47:06 INFO - 'default_actions': ('clobber', 11:47:06 INFO - 'read-buildbot-config', 11:47:06 INFO - 'download-and-extract', 11:47:06 INFO - 'populate-webroot', 11:47:06 INFO - 'create-virtualenv', 11:47:06 INFO - 'install', 11:47:06 INFO - 'run-tests'), 11:47:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:47:06 INFO - 'download_minidump_stackwalk': True, 11:47:06 INFO - 'download_symbols': 'ondemand', 11:47:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:47:06 INFO - 'tooltool.py': '/tools/tooltool.py', 11:47:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:47:06 INFO - '/tools/misc-python/virtualenv.py')}, 11:47:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:47:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:47:06 INFO - 'installer_path': 'installer.exe', 11:47:06 INFO - 'log_level': 'info', 11:47:06 INFO - 'log_name': 'talos', 11:47:06 INFO - 'log_to_console': True, 11:47:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:47:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:47:06 INFO - 'opt_config_files': (), 11:47:06 INFO - 'pip_index': False, 11:47:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:47:06 INFO - 'cmd': ('bash', 11:47:06 INFO - '-c', 11:47:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:47:06 INFO - 'enabled': True, 11:47:06 INFO - 'halt_on_failure': False, 11:47:06 INFO - 'name': 'check_screen_resolution'},), 11:47:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:47:06 INFO - 'cmd': ('bash', 11:47:06 INFO - '-c', 11:47:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:47:06 INFO - 'enabled': True, 11:47:06 INFO - 'halt_on_failure': False, 11:47:06 INFO - 'name': 'check_screen_resolution'},), 11:47:06 INFO - 'run_cmd_checks_enabled': True, 11:47:06 INFO - 'sps_profile': False, 11:47:06 INFO - 'sps_profile_interval': 0, 11:47:06 INFO - 'suite': 'dromaeojs-e10s', 11:47:06 INFO - 'system_bits': '32', 11:47:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:47:06 INFO - 'title': 't-yosemite-r7-0320', 11:47:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:47:06 INFO - 'use_talos_json': True, 11:47:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:47:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:47:06 INFO - 'work_dir': 'build'} 11:47:06 INFO - [mozharness: 2016-09-29 18:47:06.481723Z] Running clobber step. 11:47:06 INFO - Running pre-action listener: _resource_record_pre_action 11:47:06 INFO - Running main action method: clobber 11:47:06 INFO - rmtree: /builds/slave/test/build 11:47:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:47:08 INFO - Running post-action listener: _resource_record_post_action 11:47:08 INFO - [mozharness: 2016-09-29 18:47:08.946145Z] Finished clobber step (success) 11:47:08 INFO - [mozharness: 2016-09-29 18:47:08.946313Z] Running read-buildbot-config step. 11:47:08 INFO - Running pre-action listener: _resource_record_pre_action 11:47:08 INFO - Running main action method: read_buildbot_config 11:47:08 INFO - Using buildbot properties: 11:47:08 INFO - { 11:47:08 INFO - "project": "", 11:47:08 INFO - "product": "firefox", 11:47:08 INFO - "script_repo_revision": "production", 11:47:08 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 11:47:08 INFO - "repository": "", 11:47:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 11:47:08 INFO - "buildid": "20160929074223", 11:47:08 INFO - "pgo_build": "False", 11:47:08 INFO - "basedir": "/builds/slave/test", 11:47:08 INFO - "buildnumber": 23, 11:47:08 INFO - "slavename": "t-yosemite-r7-0320", 11:47:08 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:47:08 INFO - "platform": "yosemite_r7", 11:47:08 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 11:47:08 INFO - "branch": "fx-team", 11:47:08 INFO - "repo_path": "integration/fx-team", 11:47:08 INFO - "revision": "2027e4ba3584e9bb84ca452ed4d50d180b3cae62", 11:47:08 INFO - "stage_platform": "macosx64", 11:47:08 INFO - "builduid": "9af9495b6ef5418c8f3f9d523e1b2566", 11:47:08 INFO - "slavebuilddir": "test" 11:47:08 INFO - } 11:47:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 11:47:08 INFO - Running post-action listener: _resource_record_post_action 11:47:08 INFO - [mozharness: 2016-09-29 18:47:08.949504Z] Finished read-buildbot-config step (success) 11:47:08 INFO - [mozharness: 2016-09-29 18:47:08.949612Z] Running download-and-extract step. 11:47:08 INFO - Running pre-action listener: _resource_record_pre_action 11:47:08 INFO - Running main action method: download_and_extract 11:47:08 INFO - mkdir: /builds/slave/test/build/tests 11:47:08 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')]} 11:47:08 INFO - trying https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 11:47:08 INFO - Downloading https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/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 11:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/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 11:47:09 INFO - Downloaded 1199 bytes. 11:47:09 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 11:47:09 INFO - Using the following test package requirements: 11:47:09 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:47:09 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:47:09 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 11:47:09 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:47:09 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 11:47:09 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:47:09 INFO - u'jsshell-mac.zip'], 11:47:09 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:47:09 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 11:47:09 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:47:09 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:47:09 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 11:47:09 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:47:09 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 11:47:09 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:47:09 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 11:47:09 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:47:09 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 11:47:09 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:47:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:47:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:47:09 INFO - Fetch https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:47:10 INFO - Http code: 200 11:47:10 INFO - Content-Encoding: None 11:47:10 INFO - Content-Type: application/zip 11:47:10 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 11:47:10 INFO - x-amz-cf-id: kTcc5EUPi9l__pl8PYHCevabIsVFyjR-AsQusZgTE96oM-WouQ_eYQ== 11:47:10 INFO - x-amz-version-id: LPISRz5IpQYMK4t0.hZrojTL2VxuCvuR 11:47:10 INFO - x-cache: Hit from cloudfront 11:47:10 INFO - Expected file size: 16473028 11:47:10 INFO - Obtained file size: 16473028 11:47:17 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 11:47:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:47:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:47:17 INFO - Fetch https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:47:17 INFO - Http code: 200 11:47:17 INFO - Content-Encoding: None 11:47:17 INFO - Content-Type: application/zip 11:47:17 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 11:47:17 INFO - x-amz-cf-id: C5tk0rPExYC32SbBq8Xjfr7XIBvovf0XBCxghZnOUHqpcdZ5tYKTQg== 11:47:17 INFO - x-amz-version-id: LPISRz5IpQYMK4t0.hZrojTL2VxuCvuR 11:47:17 INFO - x-cache: Hit from cloudfront 11:47:18 INFO - Expected file size: 16473028 11:47:18 INFO - Obtained file size: 16473028 11:47:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 11:47:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:47:24 INFO - Fetch https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 11:47:25 INFO - Http code: 200 11:47:25 INFO - Content-Encoding: None 11:47:25 INFO - Content-Type: application/zip 11:47:25 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 11:47:25 INFO - x-amz-cf-id: nBlW4gbtXao4JJdcTgXSbEo3XVdPPnaE8m0OuzsnlTZyr7a7f4SGAQ== 11:47:25 INFO - x-amz-version-id: fjfpXzyC5R1xPFEuHdqen.WGjCI0agWX 11:47:25 INFO - x-cache: Hit from cloudfront 11:47:26 INFO - Expected file size: 14414067 11:47:26 INFO - Obtained file size: 14414067 11:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:26 INFO - trying https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:47:26 INFO - Downloading https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 11:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/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 11:47:29 INFO - Downloaded 111785307 bytes. 11:47:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:47:29 INFO - mkdir: /builds/slave/test/properties 11:47:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:47:29 INFO - Writing to file /builds/slave/test/properties/build_url 11:47:29 INFO - Contents: 11:47:29 INFO - build_url:https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:47:29 INFO - Running post-action listener: _resource_record_post_action 11:47:29 INFO - Running post-action listener: set_extra_try_arguments 11:47:29 INFO - [mozharness: 2016-09-29 18:47:29.947404Z] Finished download-and-extract step (success) 11:47:29 INFO - [mozharness: 2016-09-29 18:47:29.947528Z] Running populate-webroot step. 11:47:29 INFO - Running pre-action listener: _resource_record_pre_action 11:47:29 INFO - Running main action method: populate_webroot 11:47:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:47:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:47:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:47:29 INFO - '--tppagecycles', 11:47:29 INFO - '7'], 11:47:29 INFO - 'tests': ['tsvgm']}}, 11:47:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:47:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:47:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:47:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:47:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:29 INFO - 'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:47:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:47:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:29 INFO - 'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['damp', 'tps']}, 11:47:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:29 INFO - 'tests': ['damp', 'tps']}, 11:47:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['dromaeo_dom']}, 11:47:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:47:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['basic_compositor_video']}, 11:47:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:47:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['a11yr', 11:47:29 INFO - 'ts_paint', 11:47:29 INFO - 'tpaint', 11:47:29 INFO - 'sessionrestore', 11:47:29 INFO - 'sessionrestore_no_auto_restore', 11:47:29 INFO - 'tabpaint']}, 11:47:29 INFO - 'other-e10s': {'tests': ['a11yr', 11:47:29 INFO - 'ts_paint', 11:47:29 INFO - 'tpaint', 11:47:29 INFO - 'sessionrestore', 11:47:29 INFO - 'sessionrestore_no_auto_restore', 11:47:29 INFO - 'tabpaint']}, 11:47:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:47:29 INFO - 'ts_paint', 11:47:29 INFO - 'tpaint', 11:47:29 INFO - 'sessionrestore', 11:47:29 INFO - 'sessionrestore_no_auto_restore', 11:47:29 INFO - 'tabpaint']}, 11:47:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:47:29 INFO - 'ts_paint', 11:47:29 INFO - 'tpaint', 11:47:29 INFO - 'sessionrestore', 11:47:29 INFO - 'sessionrestore_no_auto_restore', 11:47:29 INFO - 'tabpaint']}, 11:47:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['a11yr', 11:47:29 INFO - 'ts_paint', 11:47:29 INFO - 'tpaint', 11:47:29 INFO - 'sessionrestore', 11:47:29 INFO - 'sessionrestore_no_auto_restore', 11:47:29 INFO - 'tabpaint']}, 11:47:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['a11yr', 11:47:29 INFO - 'ts_paint', 11:47:29 INFO - 'tpaint', 11:47:29 INFO - 'sessionrestore', 11:47:29 INFO - 'sessionrestore_no_auto_restore', 11:47:29 INFO - 'tabpaint']}, 11:47:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['tsvgx', 11:47:29 INFO - 'tsvgr_opacity', 11:47:29 INFO - 'tart', 11:47:29 INFO - 'tscrollx', 11:47:29 INFO - 'cart']}, 11:47:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:47:29 INFO - 'tsvgr_opacity', 11:47:29 INFO - 'tart', 11:47:29 INFO - 'tscrollx', 11:47:29 INFO - 'cart']}, 11:47:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:29 INFO - 'talos_options': ['--disable-e10s'], 11:47:29 INFO - 'tests': ['tp5o']}, 11:47:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:29 INFO - 'tests': ['tp5o']}, 11:47:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:29 INFO - 'talos_options': ['--disable-e10s', 11:47:29 INFO - '--xperf_path', 11:47:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:47:29 INFO - 'tests': ['tp5n']}, 11:47:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:47:29 INFO - 'talos_options': ['--xperf_path', 11:47:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:47:29 INFO - 'tests': ['tp5n']}}, 11:47:29 INFO - 'talos.zip': {'path': '', 11:47:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:47:29 INFO - Running post-action listener: _resource_record_post_action 11:47:29 INFO - [mozharness: 2016-09-29 18:47:29.966727Z] Finished populate-webroot step (success) 11:47:29 INFO - [mozharness: 2016-09-29 18:47:29.966835Z] Running create-virtualenv step. 11:47:29 INFO - Running pre-action listener: _resource_record_pre_action 11:47:29 INFO - Running main action method: create_virtualenv 11:47:29 INFO - Creating virtualenv /builds/slave/test/build/venv 11:47:29 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 11:47:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:47:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:47:30 INFO - Using real prefix '/tools/python27' 11:47:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:47:30 INFO - Installing distribute.............................................................................................................................................................................................done. 11:47:33 INFO - Installing pip.................done. 11:47:33 INFO - Return code: 0 11:47:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:47:34 INFO - Reading from file tmpfile_stdout 11:47:34 INFO - Output received: 11:47:34 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) 11:47:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:47:34 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')]} 11:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:34 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')]} 11:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:34 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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:34 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 11:47:34 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 11:47:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:47:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:34 INFO - 'HOME': '/Users/cltbld', 11:47:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:34 INFO - 'LOGNAME': 'cltbld', 11:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:34 INFO - 'MOZ_NO_REMOTE': '1', 11:47:34 INFO - 'NO_EM_RESTART': '1', 11:47:34 INFO - 'PAGER': '/bin/cat', 11:47:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:34 INFO - 'PWD': '/builds/slave/test', 11:47:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:34 INFO - 'SHELL': '/bin/bash', 11:47:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:47:34 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:47:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:34 INFO - 'USER': 'cltbld', 11:47:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:34 INFO - 'XPC_FLAGS': '0x0', 11:47:34 INFO - 'XPC_SERVICE_NAME': '0', 11:47:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:34 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 11:47:34 INFO - Cleaning up... 11:47:34 INFO - Return code: 0 11:47:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:47:34 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')]} 11:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:34 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')]} 11:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:34 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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:34 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 11:47:34 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 11:47:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:47:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:34 INFO - 'HOME': '/Users/cltbld', 11:47:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:34 INFO - 'LOGNAME': 'cltbld', 11:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:34 INFO - 'MOZ_NO_REMOTE': '1', 11:47:34 INFO - 'NO_EM_RESTART': '1', 11:47:34 INFO - 'PAGER': '/bin/cat', 11:47:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:34 INFO - 'PWD': '/builds/slave/test', 11:47:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:34 INFO - 'SHELL': '/bin/bash', 11:47:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:47:34 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:47:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:34 INFO - 'USER': 'cltbld', 11:47:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:34 INFO - 'XPC_FLAGS': '0x0', 11:47:34 INFO - 'XPC_SERVICE_NAME': '0', 11:47:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:34 INFO - Downloading/unpacking psutil>=3.1.1 11:47:34 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 11:47:34 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 11:47:34 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 11:47:34 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 11:47:34 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 11:47:34 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 11:47:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:47:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:47:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:47:38 INFO - Installing collected packages: psutil 11:47:38 INFO - Running setup.py install for psutil 11:47:38 INFO - building 'psutil._psutil_osx' extension 11:47:38 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 11:47:39 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 11:47:39 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 11:47:39 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 11:47:39 INFO - building 'psutil._psutil_posix' extension 11:47:39 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 11:47:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:47:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:47:39 INFO - ^ 11:47:39 INFO - 1 warning generated. 11:47:39 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 11:47:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:47:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:47:39 INFO - Successfully installed psutil 11:47:39 INFO - Cleaning up... 11:47:39 INFO - Return code: 0 11:47:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:39 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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:39 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 11:47:39 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 11:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:39 INFO - 'HOME': '/Users/cltbld', 11:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:39 INFO - 'LOGNAME': 'cltbld', 11:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:39 INFO - 'MOZ_NO_REMOTE': '1', 11:47:39 INFO - 'NO_EM_RESTART': '1', 11:47:39 INFO - 'PAGER': '/bin/cat', 11:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:39 INFO - 'PWD': '/builds/slave/test', 11:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:39 INFO - 'SHELL': '/bin/bash', 11:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:47:39 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:39 INFO - 'USER': 'cltbld', 11:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:39 INFO - 'XPC_FLAGS': '0x0', 11:47:39 INFO - 'XPC_SERVICE_NAME': '0', 11:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:47: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 11:47: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 11:47: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 11:47: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 11:47: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 11:47: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 11:47:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:47:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:47:42 INFO - Installing collected packages: mozsystemmonitor 11:47:42 INFO - Running setup.py install for mozsystemmonitor 11:47:42 INFO - Successfully installed mozsystemmonitor 11:47:42 INFO - Cleaning up... 11:47:42 INFO - Return code: 0 11:47:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:42 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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:42 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 11:47:42 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 11:47:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:47:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:42 INFO - 'HOME': '/Users/cltbld', 11:47:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:42 INFO - 'LOGNAME': 'cltbld', 11:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:42 INFO - 'MOZ_NO_REMOTE': '1', 11:47:42 INFO - 'NO_EM_RESTART': '1', 11:47:42 INFO - 'PAGER': '/bin/cat', 11:47:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:42 INFO - 'PWD': '/builds/slave/test', 11:47:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:42 INFO - 'SHELL': '/bin/bash', 11:47:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:47:42 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:47:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:42 INFO - 'USER': 'cltbld', 11:47:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:42 INFO - 'XPC_FLAGS': '0x0', 11:47:42 INFO - 'XPC_SERVICE_NAME': '0', 11:47:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:42 INFO - Downloading/unpacking jsonschema==2.5.1 11:47:42 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 11:47:42 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 11:47:42 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 11:47:42 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 11:47:42 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 11:47:42 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 11:47:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:47:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:47:45 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 11:47:45 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 11:47:45 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 11:47:45 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 11:47:45 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 11:47:45 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 11:47:45 INFO - Downloading functools32-3.2.3-2.tar.gz 11:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:47:45 INFO - warning: no files found matching '*.txt' 11:47:45 INFO - no previously-included directories found matching 'build' 11:47:45 INFO - no previously-included directories found matching 'dist' 11:47:45 INFO - no previously-included directories found matching '.git*' 11:47:45 INFO - Installing collected packages: jsonschema, functools32 11:47:46 INFO - Running setup.py install for functools32 11:47:46 INFO - warning: no files found matching '*.txt' 11:47:46 INFO - no previously-included directories found matching 'build' 11:47:46 INFO - no previously-included directories found matching 'dist' 11:47:46 INFO - no previously-included directories found matching '.git*' 11:47:46 INFO - Successfully installed jsonschema functools32 11:47:46 INFO - Cleaning up... 11:47:46 INFO - Return code: 0 11:47:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:47: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')]} 11:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47: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')]} 11:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:46 INFO - 'HOME': '/Users/cltbld', 11:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:46 INFO - 'LOGNAME': 'cltbld', 11:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:46 INFO - 'MOZ_NO_REMOTE': '1', 11:47:46 INFO - 'NO_EM_RESTART': '1', 11:47:46 INFO - 'PAGER': '/bin/cat', 11:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:46 INFO - 'PWD': '/builds/slave/test', 11:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:46 INFO - 'SHELL': '/bin/bash', 11:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:47:46 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:46 INFO - 'USER': 'cltbld', 11:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:46 INFO - 'XPC_FLAGS': '0x0', 11:47:46 INFO - 'XPC_SERVICE_NAME': '0', 11:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:47:46 INFO - Cleaning up... 11:47:46 INFO - Return code: 0 11:47:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:47: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')]} 11:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47: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')]} 11:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:46 INFO - 'HOME': '/Users/cltbld', 11:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:46 INFO - 'LOGNAME': 'cltbld', 11:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:46 INFO - 'MOZ_NO_REMOTE': '1', 11:47:46 INFO - 'NO_EM_RESTART': '1', 11:47:46 INFO - 'PAGER': '/bin/cat', 11:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:46 INFO - 'PWD': '/builds/slave/test', 11:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:46 INFO - 'SHELL': '/bin/bash', 11:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:47:46 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:46 INFO - 'USER': 'cltbld', 11:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:46 INFO - 'XPC_FLAGS': '0x0', 11:47:46 INFO - 'XPC_SERVICE_NAME': '0', 11:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:46 INFO - Downloading/unpacking blobuploader==1.2.4 11:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:47:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:47:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:47:49 INFO - Downloading blobuploader-1.2.4.tar.gz 11:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:47:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:47:49 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 11:47:49 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 11:47:49 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 11:47:49 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 11:47:49 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 11:47:49 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 11:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:47:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:47:49 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 11:47:49 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 11:47:49 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 11:47:49 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 11:47:49 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 11:47:49 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 11:47:50 INFO - Downloading docopt-0.6.1.tar.gz 11:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:47:50 INFO - Installing collected packages: blobuploader, requests, docopt 11:47:50 INFO - Running setup.py install for blobuploader 11:47:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:47:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:47:50 INFO - Running setup.py install for requests 11:47:51 INFO - Running setup.py install for docopt 11:47:51 INFO - Successfully installed blobuploader requests docopt 11:47:51 INFO - Cleaning up... 11:47:51 INFO - Return code: 0 11:47:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:51 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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:51 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 11:47:51 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 11:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:51 INFO - 'HOME': '/Users/cltbld', 11:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:51 INFO - 'LOGNAME': 'cltbld', 11:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:51 INFO - 'MOZ_NO_REMOTE': '1', 11:47:51 INFO - 'NO_EM_RESTART': '1', 11:47:51 INFO - 'PAGER': '/bin/cat', 11:47:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:51 INFO - 'PWD': '/builds/slave/test', 11:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:51 INFO - 'SHELL': '/bin/bash', 11:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:47:51 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:51 INFO - 'USER': 'cltbld', 11:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:51 INFO - 'XPC_FLAGS': '0x0', 11:47:51 INFO - 'XPC_SERVICE_NAME': '0', 11:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:47:51 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-UZ30lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:47:51 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-TGKyyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:47:51 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-8ZGZlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:47:51 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-yb_QBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-tQjf3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wlXUmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-n9_AQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-jA9dZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-9gbAfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Zp1I61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-zKyJWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-p4GPF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-gWOWRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:47:52 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-lQGlj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:47:53 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-4hFKOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:47:53 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-yTJNNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:47:53 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wDqaTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:47:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:47:53 INFO - Running setup.py install for manifestparser 11:47:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:47:53 INFO - Running setup.py install for mozcrash 11:47:53 INFO - Running setup.py install for mozdebug 11:47:53 INFO - Running setup.py install for mozdevice 11:47:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:47:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:47:53 INFO - Running setup.py install for mozfile 11:47:54 INFO - Running setup.py install for mozhttpd 11:47:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Running setup.py install for mozinfo 11:47:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Running setup.py install for mozInstall 11:47:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Running setup.py install for mozleak 11:47:54 INFO - Running setup.py install for mozlog 11:47:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Running setup.py install for moznetwork 11:47:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Running setup.py install for mozprocess 11:47:55 INFO - Running setup.py install for mozprofile 11:47:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for mozrunner 11:47:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for mozscreenshot 11:47:55 INFO - Running setup.py install for moztest 11:47:55 INFO - Running setup.py install for mozversion 11:47:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:47:55 INFO - Cleaning up... 11:47:55 INFO - Return code: 0 11:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:47:55 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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:47:55 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 11:47:55 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 11:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:55 INFO - 'HOME': '/Users/cltbld', 11:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:55 INFO - 'LOGNAME': 'cltbld', 11:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:55 INFO - 'MOZ_NO_REMOTE': '1', 11:47:55 INFO - 'NO_EM_RESTART': '1', 11:47:55 INFO - 'PAGER': '/bin/cat', 11:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:55 INFO - 'PWD': '/builds/slave/test', 11:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:55 INFO - 'SHELL': '/bin/bash', 11:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:47:55 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:55 INFO - 'USER': 'cltbld', 11:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:55 INFO - 'XPC_FLAGS': '0x0', 11:47:55 INFO - 'XPC_SERVICE_NAME': '0', 11:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:47:56 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-SQbQmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:47:56 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-s8KYsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:47:56 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-vS_Fff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:47:56 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-iYcvUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:47:56 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-iNHu_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:47:56 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-EGYel0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:47:56 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ja00AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:47:56 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-rruD8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-bkuPyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Lu98nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-lCeEp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-hhFKWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-LctEOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-EQzGRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-rqFMny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-M2o928-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:47:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-cUzmkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:47:58 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)) 11:47:58 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)) 11:47:58 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)) 11:47:58 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)) 11:47:58 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)) 11:47:58 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)) 11:47:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:47:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:47:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:47:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:47:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:48:00 INFO - Downloading blessings-1.6.tar.gz 11:48:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:48:00 INFO - Installing collected packages: blessings 11:48:00 INFO - Running setup.py install for blessings 11:48:00 INFO - Successfully installed blessings 11:48:00 INFO - Cleaning up... 11:48:00 INFO - Return code: 0 11:48:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:48:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:48:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:48:01 INFO - Reading from file tmpfile_stdout 11:48:01 INFO - Current package versions: 11:48:01 INFO - blessings == 1.6 11:48:01 INFO - blobuploader == 1.2.4 11:48:01 INFO - docopt == 0.6.1 11:48:01 INFO - functools32 == 3.2.3-2 11:48:01 INFO - jsonschema == 2.5.1 11:48:01 INFO - manifestparser == 1.1 11:48:01 INFO - mozInstall == 1.12 11:48:01 INFO - mozcrash == 0.17 11:48:01 INFO - mozdebug == 0.1 11:48:01 INFO - mozdevice == 0.48 11:48:01 INFO - mozfile == 1.2 11:48:01 INFO - mozhttpd == 0.7 11:48:01 INFO - mozinfo == 0.9 11:48:01 INFO - mozleak == 0.1 11:48:01 INFO - mozlog == 3.4 11:48:01 INFO - moznetwork == 0.27 11:48:01 INFO - mozprocess == 0.23 11:48:01 INFO - mozprofile == 0.28 11:48:01 INFO - mozrunner == 6.12 11:48:01 INFO - mozscreenshot == 0.1 11:48:01 INFO - mozsystemmonitor == 0.3 11:48:01 INFO - moztest == 0.7 11:48:01 INFO - mozversion == 1.4 11:48:01 INFO - psutil == 3.1.1 11:48:01 INFO - requests == 1.2.3 11:48:01 INFO - wsgiref == 0.1.2 11:48:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:48:01 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')]} 11:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:01 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')]} 11:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:01 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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:48:01 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 11:48:01 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 11:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:01 INFO - 'HOME': '/Users/cltbld', 11:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:01 INFO - 'LOGNAME': 'cltbld', 11:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:01 INFO - 'MOZ_NO_REMOTE': '1', 11:48:01 INFO - 'NO_EM_RESTART': '1', 11:48:01 INFO - 'PAGER': '/bin/cat', 11:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:01 INFO - 'PWD': '/builds/slave/test', 11:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:01 INFO - 'SHELL': '/bin/bash', 11:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:48:01 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:01 INFO - 'USER': 'cltbld', 11:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:01 INFO - 'XPC_FLAGS': '0x0', 11:48:01 INFO - 'XPC_SERVICE_NAME': '0', 11:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 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)) 11:48:01 INFO - Cleaning up... 11:48:01 INFO - Return code: 0 11:48:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:48:01 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')]} 11:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:01 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')]} 11:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:01 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 0x10f678490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fead9c77300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, '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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 11:48:01 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 11:48:01 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 11:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:01 INFO - 'HOME': '/Users/cltbld', 11:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:01 INFO - 'LOGNAME': 'cltbld', 11:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:01 INFO - 'MOZ_NO_REMOTE': '1', 11:48:01 INFO - 'NO_EM_RESTART': '1', 11:48:01 INFO - 'PAGER': '/bin/cat', 11:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:01 INFO - 'PWD': '/builds/slave/test', 11:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:01 INFO - 'SHELL': '/bin/bash', 11:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:48:01 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:01 INFO - 'USER': 'cltbld', 11:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:01 INFO - 'XPC_FLAGS': '0x0', 11:48:01 INFO - 'XPC_SERVICE_NAME': '0', 11:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:48:01 INFO - Cleaning up... 11:48:01 INFO - Return code: 0 11:48:01 INFO - Running post-action listener: _resource_record_post_action 11:48:01 INFO - Running post-action listener: _start_resource_monitoring 11:48:01 INFO - Starting resource monitoring. 11:48:01 INFO - [mozharness: 2016-09-29 18:48:01.740352Z] Finished create-virtualenv step (success) 11:48:01 INFO - [mozharness: 2016-09-29 18:48:01.741303Z] Running install step. 11:48:01 INFO - Running pre-action listener: _resource_record_pre_action 11:48:01 INFO - Running main action method: install 11:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:48:01 INFO - Reading from file tmpfile_stdout 11:48:01 INFO - Detecting whether we're running mozinstall >=1.0... 11:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:48:02 INFO - Reading from file tmpfile_stdout 11:48:02 INFO - Output received: 11:48:02 INFO - Usage: mozinstall [options] installer 11:48:02 INFO - Options: 11:48:02 INFO - -h, --help show this help message and exit 11:48:02 INFO - -d DEST, --destination=DEST 11:48:02 INFO - Directory to install application into. [default: 11:48:02 INFO - "/builds/slave/test"] 11:48:02 INFO - --app=APP Application being installed. [default: firefox] 11:48:02 INFO - mkdir: /builds/slave/test/build/application 11:48:02 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'] 11:48:02 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 11:48:36 INFO - Reading from file tmpfile_stdout 11:48:36 INFO - Output received: 11:48:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:48:36 INFO - Running post-action listener: _resource_record_post_action 11:48:36 INFO - [mozharness: 2016-09-29 18:48:36.037074Z] Finished install step (success) 11:48:36 INFO - [mozharness: 2016-09-29 18:48:36.037188Z] Running run-tests step. 11:48:36 INFO - Running pre-action listener: _resource_record_pre_action 11:48:36 INFO - Running main action method: run_tests 11:48:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:48:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:48:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:48:36 INFO - 2016-09-29 11:48:36.051 screenresolution[1645:7838] starting screenresolution argv=screenresolution get 11:48:36 INFO - 2016-09-29 11:48:36.054 screenresolution[1645:7838] Display 0: 1600x1200x32@60 11:48:36 INFO - 2016-09-29 11:48:36.060 screenresolution[1646:7840] starting screenresolution argv=screenresolution list 11:48:36 INFO - Available Modes on Display 0 11:48:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:48:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:48:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:48:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:48:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:48:36 INFO - Intel Iris: 11:48:36 INFO - Chipset Model: Intel Iris 11:48:36 INFO - Type: GPU 11:48:36 INFO - Bus: Built-In 11:48:36 INFO - VRAM (Dynamic, Max): 1536 MB 11:48:36 INFO - Vendor: Intel (0x8086) 11:48:36 INFO - Device ID: 0x0a2e 11:48:36 INFO - Revision ID: 0x0009 11:48:36 INFO - Displays: 11:48:36 INFO - Display: 11:48:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:48:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:48:36 INFO - Display Serial Number: 1600x1200 60 11:48:36 INFO - Main Display: Yes 11:48:36 INFO - Mirror: Off 11:48:36 INFO - Online: Yes 11:48:36 INFO - Rotation: Supported 11:48:36 INFO - Return code: 0 11:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:48:36 INFO - Python 2.7.3 11:48:36 INFO - Return code: 0 11:48:36 INFO - grabbing minidump binary from tooltool 11: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')]} 11:48:36 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 0x10f48cd30>, '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 0x10f2b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f484b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:48:36 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 11:48:36 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 11:48:36 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 11:48:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:48:36 INFO - Return code: 0 11:48:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:48:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:48:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:48:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:48:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0320 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ESRKeXDVI7/Render', 11:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:48:36 INFO - 'HOME': '/Users/cltbld', 11:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:48:36 INFO - 'LOGNAME': 'cltbld', 11:48:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:48:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:36 INFO - 'MOZ_NO_REMOTE': '1', 11:48:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:48:36 INFO - 'NO_EM_RESTART': '1', 11:48:36 INFO - 'PAGER': '/bin/cat', 11:48:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:36 INFO - 'PWD': '/builds/slave/test', 11:48:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:48:36 INFO - 'SHELL': '/bin/bash', 11:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ChsdOl4QAX/Listeners', 11:48:36 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 11:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:48:36 INFO - 'USER': 'cltbld', 11:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:36 INFO - 'XPC_FLAGS': '0x0', 11:48:36 INFO - 'XPC_SERVICE_NAME': '0', 11:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:48:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:48:36 INFO - mozversion application_buildid: 20160929074223 11:48:36 INFO - mozversion application_changeset: 2027e4ba3584e9bb84ca452ed4d50d180b3cae62 11:48:36 INFO - mozversion application_display_name: Nightly 11:48:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:48:36 INFO - mozversion application_name: Firefox 11:48:36 INFO - mozversion application_remotingname: firefox 11:48:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:48:36 INFO - mozversion application_vendor: Mozilla 11:48:36 INFO - mozversion application_version: 52.0a1 11:48:36 INFO - mozversion platform_buildid: 20160929074223 11:48:36 INFO - mozversion platform_changeset: 2027e4ba3584e9bb84ca452ed4d50d180b3cae62 11:48:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:48:36 INFO - mozversion platform_version: 52.0a1 11:48:36 INFO - using testdate: 1475174916 11:48:36 INFO - actual date: 1475174916 11:48:36 INFO - starting webserver on 'localhost:49275' 11:48:36 INFO - SUITE-START | Running 2 tests 11:48:36 INFO - TEST-START | dromaeo_css 11:48:36 INFO - operating with platform_type : mac_ 11:48:36 INFO - Initialising browser for dromaeo_css test... 11:48:36 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpepaziu/profile http://localhost:49275/getInfo.html) 11:48:39 INFO - PROCESS | 1656 | 2016-09-29 11:48:39.414 plugin-container[1659:8032] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 11:48:39 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:48:39 INFO - PROCESS | 1656 | 2016-09-29 11:48:39.417 plugin-container[1659:8032] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4913, name = 'com.apple.CFPasteboardClient' 11:48:39 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:48:39 INFO - PROCESS | 1656 | 2016-09-29 11:48:39.417 plugin-container[1659:8032] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:48:39 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 11:48:39 INFO - PROCESS | 1656 | colorDepth:24 11:48:39 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 11:48:39 INFO - PROCESS | 1656 | __metrics 11:48:39 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 11:48:40 INFO - TEST-INFO | 1656: exit 0 11:48:40 INFO - Browser initialized. 11:48:40 INFO - Running cycle 1/1 for dromaeo_css test... 11:48:40 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpepaziu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:48:42 INFO - PROCESS | 1660 | 2016-09-29 11:48:42.492 plugin-container[1661:8151] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8827, name = 'com.apple.tsm.portname' 11:48:42 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:48:42 INFO - PROCESS | 1660 | 2016-09-29 11:48:42.495 plugin-container[1661:8151] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d03, name = 'com.apple.CFPasteboardClient' 11:48:42 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:48:42 INFO - PROCESS | 1660 | 2016-09-29 11:48:42.495 plugin-container[1661:8151] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:48:52 INFO - PROCESS | 1660 | RSS: Main: 240054272 11:48:52 INFO - PROCESS | 1660 | 11:49:41 INFO - PROCESS | 1660 | 1475174981776 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] 11:51:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 11:51:55 INFO - PROCESS | 1660 | RSS: Main: 253243392 11:51:55 INFO - PROCESS | 1660 | 11:54:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 11:54:57 INFO - PROCESS | 1660 | RSS: Main: 265592832 11:54:57 INFO - PROCESS | 1660 | 11:57:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 11:58:00 INFO - PROCESS | 1660 | RSS: Main: 270270464 11:58:00 INFO - PROCESS | 1660 | 12:01:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:01:02 INFO - PROCESS | 1660 | RSS: Main: 269996032 12:01:02 INFO - PROCESS | 1660 | 12:04:05 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:04:06 INFO - PROCESS | 1660 | RSS: Main: 275431424 12:04:06 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:07:09 INFO - PROCESS | 1660 | RSS: Main: 278089728 12:07:09 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | __start_tp_report 12:07:09 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 12:07:09 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 12:07:09 INFO - PROCESS | 1660 | |i|pagename|runs| 12:07:09 INFO - PROCESS | 1660 | |0;dojo.html;3391.2030672791907;3391.8304084795764;3431.2451260722646;3447.896563103108;3458.1354661334667;5677.0632845580485;5738.7417566209515;5743.5118014968675;5749.281339832521;5751.511121554673;5769.509591684701;5793.188953546507;5800.854978625526;5830.5044071254;5905.911411328822;5238.580913526918;5303.814366497173;5333.893322133555;5342.866428339303;5372.7313634318325;5210.400803907562;5256.132738098209;5343.038253114438;5373.919391209135;5388.272583201257;5179.274901513772;5247.370315562138;5304.787808487656;5339.439358867341;5352.357717073937;5304.761285742113;5314.840554783363;5320.813771518017;5341.946580534183;5353.705546194958;5251.947480525222;5273.551748101416;5287.576993840484;5310;5349.090654588729;4259.1268789897995;5665.5467562594895;5730.36965933747;5755.942440575583;5803.303603567599;5935.970320148372;6223.25320961483;6239.750409983595;6259.061140828867;6264.310925798204;5261.447548007481;6174.8147555573405;6292.7099944511265;6293.559450838443;6297.716602752887;5746.109353050284;5802.738876750556;5834.445727655866;5905.704714764244;5946.197263369476;6375.808725738235;6418.518611104092;6422.518311126684;6438.8390290243115;6450.548461607643;6142.508599312045;6163.568550201443;6179.69101544921;6192.318844927055;6205.81382558524;5928.584999050111;6035.456808887221;6112.381899552462;6135.753292002367;6250.374962503804;5616.859578510488;6018.458338749533;6153.107799369156;6255.905216587079;6266.060090986389;6239.77320766918;6268.404501572343;6277.623342599459;6279.811605651837;6287.874242515124;3717.70258379329;4179.958200418017;4884.633652476007;4947.060058588857;5025.84922452327;3763.473113764075;3893.980530097388;4449.421575195269;4450.109978004347;4501.77491125449;5860.062390017577;5907.940920590739;5951.5238780897425;5955.493783028404;6007.189029481052;6147.708981113927;6229.781957631461;6234.657093859882;6298.685065746694;6316.210473690789;5925;5976.073708575178;5987.43119403656;6007.879842403215;6033.004554248588;5479.013777520085;5484.149956756709;5485.588580856372;5512.39363669996;5531.032069387841;6214.161088253028;6279.686015699288;6438.903416448757;6451.903221451682;6456.741730330734;6153.769246150878;6183.659898705614;6201.720922558406;6286.339934307015;6289.339619340087;6384.361563843764;6386.616802991835;6440.516961228021;6459.128017717736;6472.35276472349;5538.117040206349;6111.926012480528;6244.125822384779;6245.3754624539;6249.875002500015;6165.938340616536;6202.069689546604;6218.595791273553;6219.502439804714;6264.154339164154;4992.750362481934;6023.626769009194;6203.8138855834395;6240.157578726996;6252.3122456530655;6118.724657390517;6196.47329976957;6199.256089269316;6201.875962480635;6241.157443745218;5875.265591801025;5924.763009479572;5925;5952.434518720715;6029.366916473882;6171.382861713792;6203.0075187969705;6206.06908963659;6239.656818874824;6250.4374606285655;5925.733341999534;6079.848003799941;6081.5742897996715;6088.695565221809;6094 12:07:09 INFO - PROCESS | 1660 | |1;ext.html;502.6958689992553;512.8692183493209;536.6565398145187;536.9502622373261;537.1539824775977;38640.42039369408;40536.79731601341;40637.59362406375;40643;40647;41506.377404338884;41683.37474937878;41874.58125418745;42013.57986420135;42042;9577.185939195177;9688.951555242214;9695.515224238796;9784.26618003649;9784.951075244613;8848.938127424686;9125.58553424217;9647.372920760163;9680.322377433582;9714.65998690046;9736.610535578568;9760.511974401286;9766.706998790047;9792.706218813448;9799.118079372854;9699.951500242489;9744.02559744027;9768.609255629766;9769.316147869651;9771.90228097717;9646.228301735915;9686.660966866126;9728.610855565768;9750.756231094208;9762.414255144715;39967.8001609993;39985.60014399877;40130;40220.597794021975;40344.39483407751;2966.7033296670375;3310.453775127101;3719.200371920023;3871.9225615487812;4020.3768415895584;27796.166115016582;31015;31042.534361984588;31167.844160779277;32029.519557206662;3242.4649932761304;3246.448103822332;3251.3984912790975;3349.2631621043574;3400.0479865637412;357014.9999999974;425819;472974;474432;483806;34263.657363426544;46855.29717054247;50146;55394.72302638461;57771.11144442761;34513.65486345155;35007.64992350044;35045.64954350475;35165.76919807846;35476;14521.419143234363;14673.77989330161;14721.484748033981;14722.631934201516;14743.041702289316;12260.386980650932;12339.62981110568;12342;12384.256944583354;12396.070294727931;7793.80515487106;7799.649015794303;7801.180876008051;7868.881966770503;7875.527468351917;2809.592609071714;3066.264096616796;3116.7377212229085;3308.3052558962886;3313.701766840996;938.671464987249;959.9424034557949;962.1746939100417;1008.4503945349655;1024.6721049264163;29988.85005575002;30038;30040.84979575088;30083.849580751958;30835.845820771203;1031.8710161229847;1055.0926203465015;1055.519738518972;1096.413418820927;1106.253279036647;14848.554543363716;15560;15624.140672262907;15643.687126257411;15775.526734197992;682147;690565;691385;692806.535967317;697901;56228.43771562232;56553.717231414405;56819.715901420226;56864.43135568674;56877.71561142168;1302.8730148421548;1315.7763180259378;1328.2827273272517;1331.6204881608694;1335.9398827052805;28483.28791780222;29298.974535891142;29427.558586621646;29830;29930.551041733957;48739.02521949612;48972.5102748968;49181;49308;49327.50672493229;540560;541236;541433;543005;543067;25790;26293.868530657226;26635.33411664724;26644.467110658064;26695.599566006913;43877.78061109674;45745.54254457413;45804.541954581364;45948.310775337726;46356.53643463522;37457.62542374541;38000;38538.843834685;38581.807090964365;39187.41218881776;37485;37865.24269514539;38318;38988;39013.41479877747;36541.81729091337;36832.631673683994;37097.443538347485;37461.4380784283;37489.81255093707;29739.405211896075;32532.34935301328;33122.66877331196;34034.31931361408;34485.48271775875;24426.389340266636;24722.13472528453;24834.379140521633;24889.751102488743;24925.750742492342 12:07:09 INFO - PROCESS | 1660 | |2;jquery.html;374.335554390956;376.617733001004;384.03415410243235;388.45422181834533;404.5954045954046;18735.812641873596;20879.582408351842;20910.26814061508;20969;21040.89479552604;20648;20885.477863053395;20999.47501312468;21090.261840835574;21371.786282137175;4718.77311898907;5128.256402821588;5340.385855626604;5507.669539827603;6010.098485227207;5946.048632218846;6038.305594856582;6073.392660733935;6078.848028799272;6081.087836824468;5684.715764211794;5870;5915.88168236635;5924.88150236995;5938.435848594377;4445.688801783876;4946.307516947631;5674.716264186774;5978.461938425563;6012.308584512772;4713.882152946169;4780.378550788375;5096.821611243626;5118.872028199287;5147.871303217449;24779.25662230136;25053.248402547953;25532.744672553406;25780.87109564459;25788.871055644602;2237.697910782056;2309.595820731382;2398.100712232913;2530.582453895105;2973.8810447582073;14689.926550367287;14905.627359315995;15094.54716358511;15404.46084387041;15540;1823.6626224148442;1858.4053103007043;1860.981390186108;1862.0410488598382;1937;106122;112198.43900780525;112485.43757281243;112523;115002.84997150005;8208.507489550646;9500;9791.657291994889;9931.602735890483;10504.691022328096;11846.348450835114;12097.637070887888;12188.756224875453;12205.084618653636;12205.75588488243;6177.752889884354;6527.902081468782;7837;7868.960655196687;7939.880901786478;8195.590220488952;8204;8231;8360.540170290693;8361.163883611713;3458.7202734988214;3575.106223444139;3815.465834783133;3821.9617803821607;3887.378019516864;1910.2740958435706;1911.7036859286832;1919.9136038878285;1923.9903800480909;1962.9018549072775;715.7602203261965;814.5804910471112;883.9469631822111;886.8448021596195;911.0434044253508;17951.910240448713;18470.630587388445;19061.379782718348;19499.220031198878;20263.696044559343;704.6371118873784;774.2915232562256;884.7920738626418;924.4268553496875;929.7861491856776;10413.9479302603;10942.288751231305;10955.780884382268;10964.17768667353;11014.953579409943;473706;542237;550195;558818;559448;108138.45930770296;110090.44954775335;110197;112100.43949780361;112244;897.228383590112;940.229012209995;957.7892863570004;958.990410095904;978.7650963768644;17955;18056.90971545134;18310;18340;18579.79231349958;45986;46481.53518464772;47260;47853.760731197515;48730;68591.97112043423;70909.6454517724;71116.64441677759;76827.61586192033;77839.61080194563;5492.148716948879;7731.574763388067;7759.650815713194;7916.6041697916025;8201.26188643025;10361.378317300987;12041.277523348277;12220.450079746255;12397.62807115788;12466.314352710688;9177.678781242625;10168.034036766496;10230.693079207636;10519.21105917044;10579.682609521727;10384;10589.417582033062;10684.626038088629;10756.139508839431;10835.079018283534;10771.515281812493;10858.782824343627;10992.340459572451;11057.557697692002;11103.777924441627;6401.327860574573;7991.2807847294025;8049.637766300413;8067;8151;9617.519124043909;10653.840192397276;10801.783964320826;10855.782884342427;10914.672559823219 12:07:09 INFO - PROCESS | 1660 | |3;mootools.html;1417.2984372735496;1516.3631274864558;1710.6236627941855;1768.8761786674938;1804.2151664026158;8008.439409241356;8255.711050113232;8481.491110533365;8989.101089891008;8991.010988791244;8452.577371131449;8766.430182038162;8827.558622068887;8854.24738897193;8873.733787986355;2355.3758254062686;2375.10933399975;2376.0852071952336;2376.7742064503846;2389.665446837444;2346.038124369009;2354.058376649337;2367.2898130560848;2373.1456675596773;2392.6650268962358;2360.5986982213067;2366.668666386707;2375.6080246759266;2387.415083304593;2389.7968672662846;2268.7958083772373;2287.1194590082855;2290.805281551062;2294.231432470133;2299.4596269876565;2331.7901388875084;2355.0697474497647;2358.5990381635165;2363.61000434928;2367.4554852383876;8759.124087591254;8759.430637008574;8809.163129502687;8871.112888711143;8880.111988801133;4728.290756386536;5071.7210553419545;5211.739413029333;5318.601104917147;5390.595705322116;3269.73842092632;3337.8831740888954;3591.982040089809;3627.909302267438;3662.121090938182;889.6752685269895;1059.7721489879714;1069.1340014588131;1081.1242893256488;1089.215764649459;11601.303921764636;11624;11733.178677492579;11744.236624619287;11777.470013849397;4046.635802777764;4523.2988886722615;4545.999880026389;4773.451053128865;4776.498467660846;9859.605615775288;9953.452560109263;9957.00429957013;9962.00379961998;9966.20270378368;5270.472952704776;5288.8413347599635;5295.179247216687;5298.761555729924;5304.814331498379;4384.495782984934;4548.476925153584;4721.480637129913;4859.975700121477;4891.266310053449;3485.233248685285;3783.9783258520047;3921.313770090223;3961.4057891315956;3997.6402123809;1271.3643178410794;1289.7807372746656;1290.2129700882454;1291.2188126183712;1307.5355601726128;836.9539675317794;853.4239388412797;866.6403442571388;896.8430524658158;907.0566610724773;4592.747398893093;5365.704886231217;5645.23789288449;5689.004424225744;5781.132830075522;861.8664107063415;867.648602316063;921.3918813583003;938.1978408460805;962.8507581324906;3147.763917706181;3323.7507186961075;3687.483752274684;3695.150115473456;3783.9432408513894;10573.89426105729;10653.627123050655;10748.193885458622;10762.461876906124;10812.080973117216;10642.574297028186;10691.786164276671;10729.731756706145;10748.140148788078;10757.462126893624;7616.276453736886;7911.169327220559;8044.678212871537;8056.758297251092;8113.18868113184;5636.549076073822;5693.572982026448;5748.281464816898;5817.534597232296;5861.0915308127305;8773.122687731176;9289.674861379819;9787.412755234709;10062.69811905644;10106.89893101089;9981.301308908307;10036.698899033041;10108.39349639024;10250.743731406475;10375.014373634493;6573.408393244631;7284.67218975158;7420.5176663516695;7564.659590318339;7566.281203285679;8242.958785206036;8349.791255218668;8426.536540490331;8426.620802063799;8505.489670619783;7298.197198308288;7472.850542989218;7486.775396738107;7540.132884718328;7570.621468926642;7229.349358557544;7286.526375785557;7323.048003759477;7369.705211791683;7448.73929412468;5738;5935.376785437639;6828.624425656438;6994.6852391643515;7005.299470052954;3887.8833634990997;3911.3546264865977;4031.7580945143386;4693.55411235932;4816.133096042606;4645.839684546567;4713.31656909753;4721.74030428316;4745.976270118627;4799.760011999456 12:07:09 INFO - PROCESS | 1660 | |4;prototype.html;304.66486864449104;311.7817527730588;312.9280265538926;318.1283283802382;318.84536000039964;4192.9161416771685;5465.480779325962;5546.973809845182;5567.387587365387;5568.860778480535;5424.321959755031;5472.63184203949;5487.341519017714;5549.916751248725;5576.637518561291;3514.9297014059703;4010.8596199133035;4122.073327937168;4171.791410429481;4190.601892820177;3489.598696149937;3683.907902302437;4107.568705285947;4113.992071942378;4222.936655950148;3842.2699687059508;4217.734679596124;4218.388333691613;4237.389791879097;4246.7027308088445;3463.5324231228965;3496.003638962909;3693.9445908311395;4189.454132700443;4222.429971953778;3070.094322174964;3233.7898036627544;3614.150674591469;3649.6532829381167;3676.761010534307;4392.34114882767;5361.410244873061;5411.296531450926;5414.593905457067;5422.674639521641;3761.097336639187;3904.8438062477467;4647.442306923157;4725.975375759516;4833.419989601271;5213;5750.856228594277;5847.7953271635715;5924.7333869975955;5931.851703707399;3689.5572531296416;3963.742356746802;3963.7819919904396;3980.7213495055357;3984.2230765000836;5434.972825135888;5826.912596311059;5900.409959004108;6167.660778657172;6185.35053819347;3728.6763199064485;3890.319194141014;3973.324534829086;3989.5212574490665;4032.193561287754;3695.5380577427823;3813.961860381416;3821.9044523887123;4079.204555111784;4079.796010199478;4354.216241076637;4428.4685837699035;4714.858554243378;4736.7631618418945;4763.285507173951;4581.846532129894;5030.717167122433;5820.798560791633;5835.270591176103;5898.380670029671;1350.4058214385639;1377.3319939829175;1377.6004958561905;1378.3246209357342;1378.4141739760562;648.9545731798823;806.9267873728045;824.1017291152552;841.9158084191532;850.4174640371366;4771.570558649738;4861.635377346643;5468.781248750085;5483.670979741229;5523.912304894349;4896.755162241873;5693.345265294462;6020.247469066367;6025.674351642631;6043.425874541911;4647.49035520815;5041.6722913010535;5255.500727430888;5499.4225606310765;5506.889862202734;2618.9524190324023;2863.7113299015527;3006.633159809729;3143.701348371901;3154.1010811918495;5368.892622147535;6047.969760151171;6103.298120716086;6206.627602343874;6263.123162757218;4808.61531077513;5368.570514358842;5634.4365563443325;5742.368339482654;6129.682118344577;5168.431472538018;5411.78352865881;5416.593755468356;5792.8551786204835;5866.178734977192;5219.347581552306;5232.686038837682;5435.070549954868;5689.7439615218245;5997.940020599913;5581;5873.823785286449;5876;5940.821775346573;5984.970075149597;4866.513348665105;4902.534259245366;5752.367239603556;6106.374259068643;6130.478909292582;3737.9160043587053;3784.2499040480016;3860.613938606117;3934.6425483208245;3963.3262345400776;5550.417206193291;5970.492508136857;5977.671228082323;5980.3421623622235;6000.729967151401;5305.442928492607;5450.22276387031;5510.8071217507195;5820.505257053198;5826.41735826414;5465.945340546703;5698;5713.628614140068;5753.367129615655;5757.251557297524;4512.684112112121;5350.785968561214;5653.547716182778;5705.286839145107;5718.313802343745;5164.509371609691;5570.718734690962;5741.770329186786;5743.8564035899435;5786.42135786418;4985.6260780442335;5445.428230035789;5625.634333768292;5677.120046392815;5692.914606280992 12:07:09 INFO - PROCESS | 1660 | |5;yui.html;705.0869124483796;763.9350655194309;779.123486078162;785.4109417936547;813.5606772342937;15476.84523154768;15659.843401565995;16445.424410145646;16457.588560285967;16468.423605173808;12086.395680215997;14704.852951470482;14931.029483083592;14962.551123466286;14977.951543391968;667.8764428580728;696.0081883316279;824.1346586084617;833.3291700181352;865.6321063547998;845.995770021149;864.3517361978517;865.485036403339;868.3227082875368;870.4342177584557;886.0474989386403;919.9862002069974;920.6639576554576;921.9585118669676;922.2115091596689;899.0604817965242;908.3142227618139;912;912.6942474271127;915.8946721127089;668.518666560076;677.3091446724363;680.904673345732;686.7672527812589;689.3279052923409;15051.548453546411;15462.45881394157;15475.226238688021;15477.380904763795;15487.922560387238;14693.33879975393;14703.558893233114;14714.264286785618;14734;14743.631409214748;11790.587329443513;11976;11994.760104797855;12087;12146.817797733042;3764.0213544478634;3765.717571182145;3829.3872980323013;3848.461215429842;3969.98015009926;79550;86878;104219.478902605;119656.40171799173;121169;11251.606193783178;11471.197016208785;11528.481218345194;11678.88321116778;11895;23774.405639859142;23987;24006.87996560006;24069.879650601633;24082.63876041861;8047.396445266629;8165.2243036911395;8243.876341854877;8317.41778075541;8322.8335433291;4107.691923105779;4211;4266.189424009491;4379.496357918816;4385.166818304512;3618.4391419330045;3691.4093745000673;3736.925261494809;3738.8504459821847;3748.156664750409;601.0653433910311;645.4255712415953;650.4893658478167;657.7927952694886;678.0845858091635;556.9920436825267;569.1775384569257;572.6764378126345;637.2671427858;647.6955830759536;9194.862077068849;9571.521952825966;9646.469444180637;9720;9817.673200539622;504.64674727690766;658.1181217169017;667.1212974768006;667.9966600167065;692.1313751242159;4536.478304994902;4719.882002949954;4770.957318495644;4822.7829747661435;4840.83057093;153313;166406;167915.24593278352;170173;172160;84661;138374;146752;149615.25192373968;152588;71862;86384.13615863762;104955;107460.92539074666;109246.90753092685;8613.612387442456;8904.376693631384;8976.596053177493;9102.149656621043;9119.544022798966;66490;67547.59619230352;67905.98141027789;68605.62788744297;69384;99788;103058.48470757598;105491.4725426368;106710;106803;9981.401115933068;11946.402679866145;12079.698007549881;12403.379831008233;12652.683682908;13072.542461013818;13183.143095699132;13313.933430332787;13358.465661373437;13661.453541858215;12041.75916481648;12132.757344852876;12301.44643491027;12556.434960426985;12693.873061269269;10110.595576176871;11946.342951137423;12550;12576.434060467118;12659.240445573296;12262.877371226174;12314.507419703112;12451.377431128227;12478.126531142732;12551.686207844878;10184.592616295266;10477.633282835064;10552.155827533625;10773.353598784099;11025.83461248066;8855.911440885766;9790.51047447639;10364.073331082265;10468.895311046792;10652.84020739705 12:07:09 INFO - PROCESS | 1660 | __end_tp_report 12:07:09 INFO - PROCESS | 1660 | __start_cc_report 12:07:09 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,355 12:07:09 INFO - PROCESS | 1660 | __end_cc_report 12:07:09 INFO - PROCESS | 1660 | __startTimestamp1475176029806__endTimestamp 12:07:09 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | ------- Summary: start ------- 12:07:09 INFO - PROCESS | 1660 | Number of tests: 6 12:07:09 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5762.46 Median:5987.43 stddev:685.11 (11.4%) stddev-sans-first:661.47 12:07:09 INFO - PROCESS | 1660 | Values: 3391.2 3391.8 3431.2 3447.9 3458.1 5677.1 5738.7 5743.5 5749.3 5751.5 5769.5 5793.2 5800.9 5830.5 5905.9 5238.6 5303.8 5333.9 5342.9 5372.7 5210.4 5256.1 5343.0 5373.9 5388.3 5179.3 5247.4 5304.8 5339.4 5352.4 5304.8 5314.8 5320.8 5341.9 5353.7 5251.9 5273.6 5287.6 5310.0 5349.1 4259.1 5665.5 5730.4 5755.9 5803.3 5936.0 6223.3 6239.8 6259.1 6264.3 5261.4 6174.8 6292.7 6293.6 6297.7 5746.1 5802.7 5834.4 5905.7 5946.2 6375.8 6418.5 6422.5 6438.8 6450.5 6142.5 6163.6 6179.7 6192.3 6205.8 5928.6 6035.5 6112.4 6135.8 6250.4 5616.9 6018.5 6153.1 6255.9 6266.1 6239.8 6268.4 6277.6 6279.8 6287.9 3717.7 4180.0 4884.6 4947.1 5025.8 3763.5 3894.0 4449.4 4450.1 4501.8 5860.1 5907.9 5951.5 5955.5 6007.2 6147.7 6229.8 6234.7 6298.7 6316.2 5925.0 5976.1 5987.4 6007.9 6033.0 5479.0 5484.1 5485.6 5512.4 5531.0 6214.2 6279.7 6438.9 6451.9 6456.7 6153.8 6183.7 6201.7 6286.3 6289.3 6384.4 6386.6 6440.5 6459.1 6472.4 5538.1 6111.9 6244.1 6245.4 6249.9 6165.9 6202.1 6218.6 6219.5 6264.2 4992.8 6023.6 6203.8 6240.2 6252.3 6118.7 6196.5 6199.3 6201.9 6241.2 5875.3 5924.8 5925.0 5952.4 6029.4 6171.4 6203.0 6206.1 6239.7 6250.4 5925.7 6079.8 6081.6 6088.7 6094.0 12:07:09 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:67598.94 Median:21429.96 stddev:152433.25 (711.3%) stddev-sans-first:152777.62 12:07:09 INFO - PROCESS | 1660 | Values: 502.7 512.9 536.7 537.0 537.2 38640.4 40536.8 40637.6 40643.0 40647.0 41506.4 41683.4 41874.6 42013.6 42042.0 9577.2 9689.0 9695.5 9784.3 9785.0 8848.9 9125.6 9647.4 9680.3 9714.7 9736.6 9760.5 9766.7 9792.7 9799.1 9700.0 9744.0 9768.6 9769.3 9771.9 9646.2 9686.7 9728.6 9750.8 9762.4 39967.8 39985.6 40130.0 40220.6 40344.4 2966.7 3310.5 3719.2 3871.9 4020.4 27796.2 31015.0 31042.5 31167.8 32029.5 3242.5 3246.4 3251.4 3349.3 3400.0 357015.0 425819.0 472974.0 474432.0 483806.0 34263.7 46855.3 50146.0 55394.7 57771.1 34513.7 35007.6 35045.6 35165.8 35476.0 14521.4 14673.8 14721.5 14722.6 14743.0 12260.4 12339.6 12342.0 12384.3 12396.1 7793.8 7799.6 7801.2 7868.9 7875.5 2809.6 3066.3 3116.7 3308.3 3313.7 938.7 959.9 962.2 1008.5 1024.7 29988.9 30038.0 30040.8 30083.8 30835.8 1031.9 1055.1 1055.5 1096.4 1106.3 14848.6 15560.0 15624.1 15643.7 15775.5 682147.0 690565.0 691385.0 692806.5 697901.0 56228.4 56553.7 56819.7 56864.4 56877.7 1302.9 1315.8 1328.3 1331.6 1335.9 28483.3 29299.0 29427.6 29830.0 29930.6 48739.0 48972.5 49181.0 49308.0 49327.5 540560.0 541236.0 541433.0 543005.0 543067.0 25790.0 26293.9 26635.3 26644.5 26695.6 43877.8 45745.5 45804.5 45948.3 46356.5 37457.6 38000.0 38538.8 38581.8 39187.4 37485.0 37865.2 38318.0 38988.0 39013.4 36541.8 36832.6 37097.4 37461.4 37489.8 29739.4 32532.3 33122.7 34034.3 34485.5 24426.4 24722.1 24834.4 24889.8 24925.8 12:07:09 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:32134.50 Median:1974.72 stddev:89722.37 (4543.5%) stddev-sans-first:89942.37 12:07:09 INFO - PROCESS | 1660 | Values: 374.3 376.6 384.0 388.5 404.6 18735.8 20879.6 20910.3 20969.0 21040.9 20648.0 20885.5 20999.5 21090.3 21371.8 4718.8 5128.3 5340.4 5507.7 6010.1 5946.0 6038.3 6073.4 6078.8 6081.1 5684.7 5870.0 5915.9 5924.9 5938.4 4445.7 4946.3 5674.7 5978.5 6012.3 4713.9 4780.4 5096.8 5118.9 5147.9 24779.3 25053.2 25532.7 25780.9 25788.9 2237.7 2309.6 2398.1 2530.6 2973.9 14689.9 14905.6 15094.5 15404.5 15540.0 1823.7 1858.4 1861.0 1862.0 1937.0 106122.0 112198.4 112485.4 112523.0 115002.8 8208.5 9500.0 9791.7 9931.6 10504.7 11846.3 12097.6 12188.8 12205.1 12205.8 6177.8 6527.9 7837.0 7869.0 7939.9 8195.6 8204.0 8231.0 8360.5 8361.2 3458.7 3575.1 3815.5 3822.0 3887.4 1910.3 1911.7 1919.9 1924.0 1962.9 715.8 814.6 883.9 886.8 911.0 17951.9 18470.6 19061.4 19499.2 20263.7 704.6 774.3 884.8 924.4 929.8 10413.9 10942.3 10955.8 10964.2 11015.0 473706.0 542237.0 550195.0 558818.0 559448.0 108138.5 110090.4 110197.0 112100.4 112244.0 897.2 940.2 957.8 959.0 978.8 17955.0 18056.9 18310.0 18340.0 18579.8 45986.0 46481.5 47260.0 47853.8 48730.0 68592.0 70909.6 71116.6 76827.6 77839.6 5492.1 7731.6 7759.7 7916.6 8201.3 10361.4 12041.3 12220.5 12397.6 12466.3 9177.7 10168.0 10230.7 10519.2 10579.7 10384.0 10589.4 10684.6 10756.1 10835.1 10771.5 10858.8 10992.3 11057.6 11103.8 6401.3 7991.3 8049.6 8067.0 8151.0 9617.5 10653.8 10801.8 10855.8 10914.7 12:07:09 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5612.11 Median:4759.71 stddev:3205.78 (67.4%) stddev-sans-first:3199.27 12:07:09 INFO - PROCESS | 1660 | Values: 1417.3 1516.4 1710.6 1768.9 1804.2 8008.4 8255.7 8481.5 8989.1 8991.0 8452.6 8766.4 8827.6 8854.2 8873.7 2355.4 2375.1 2376.1 2376.8 2389.7 2346.0 2354.1 2367.3 2373.1 2392.7 2360.6 2366.7 2375.6 2387.4 2389.8 2268.8 2287.1 2290.8 2294.2 2299.5 2331.8 2355.1 2358.6 2363.6 2367.5 8759.1 8759.4 8809.2 8871.1 8880.1 4728.3 5071.7 5211.7 5318.6 5390.6 3269.7 3337.9 3592.0 3627.9 3662.1 889.7 1059.8 1069.1 1081.1 1089.2 11601.3 11624.0 11733.2 11744.2 11777.5 4046.6 4523.3 4546.0 4773.5 4776.5 9859.6 9953.5 9957.0 9962.0 9966.2 5270.5 5288.8 5295.2 5298.8 5304.8 4384.5 4548.5 4721.5 4860.0 4891.3 3485.2 3784.0 3921.3 3961.4 3997.6 1271.4 1289.8 1290.2 1291.2 1307.5 837.0 853.4 866.6 896.8 907.1 4592.7 5365.7 5645.2 5689.0 5781.1 861.9 867.6 921.4 938.2 962.9 3147.8 3323.8 3687.5 3695.2 3783.9 10573.9 10653.6 10748.2 10762.5 10812.1 10642.6 10691.8 10729.7 10748.1 10757.5 7616.3 7911.2 8044.7 8056.8 8113.2 5636.5 5693.6 5748.3 5817.5 5861.1 8773.1 9289.7 9787.4 10062.7 10106.9 9981.3 10036.7 10108.4 10250.7 10375.0 6573.4 7284.7 7420.5 7564.7 7566.3 8243.0 8349.8 8426.5 8426.6 8505.5 7298.2 7472.9 7486.8 7540.1 7570.6 7229.3 7286.5 7323.0 7369.7 7448.7 5738.0 5935.4 6828.6 6994.7 7005.3 3887.9 3911.4 4031.8 4693.6 4816.1 4645.8 4713.3 4721.7 4746.0 4799.8 12:07:09 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4615.26 Median:5328.11 stddev:1444.16 (27.1%) stddev-sans-first:1411.50 12:07:09 INFO - PROCESS | 1660 | Values: 304.7 311.8 312.9 318.1 318.8 4192.9 5465.5 5547.0 5567.4 5568.9 5424.3 5472.6 5487.3 5549.9 5576.6 3514.9 4010.9 4122.1 4171.8 4190.6 3489.6 3683.9 4107.6 4114.0 4222.9 3842.3 4217.7 4218.4 4237.4 4246.7 3463.5 3496.0 3693.9 4189.5 4222.4 3070.1 3233.8 3614.2 3649.7 3676.8 4392.3 5361.4 5411.3 5414.6 5422.7 3761.1 3904.8 4647.4 4726.0 4833.4 5213.0 5750.9 5847.8 5924.7 5931.9 3689.6 3963.7 3963.8 3980.7 3984.2 5435.0 5826.9 5900.4 6167.7 6185.4 3728.7 3890.3 3973.3 3989.5 4032.2 3695.5 3814.0 3821.9 4079.2 4079.8 4354.2 4428.5 4714.9 4736.8 4763.3 4581.8 5030.7 5820.8 5835.3 5898.4 1350.4 1377.3 1377.6 1378.3 1378.4 649.0 806.9 824.1 841.9 850.4 4771.6 4861.6 5468.8 5483.7 5523.9 4896.8 5693.3 6020.2 6025.7 6043.4 4647.5 5041.7 5255.5 5499.4 5506.9 2619.0 2863.7 3006.6 3143.7 3154.1 5368.9 6048.0 6103.3 6206.6 6263.1 4808.6 5368.6 5634.4 5742.4 6129.7 5168.4 5411.8 5416.6 5792.9 5866.2 5219.3 5232.7 5435.1 5689.7 5997.9 5581.0 5873.8 5876.0 5940.8 5985.0 4866.5 4902.5 5752.4 6106.4 6130.5 3737.9 3784.2 3860.6 3934.6 3963.3 5550.4 5970.5 5977.7 5980.3 6000.7 5305.4 5450.2 5510.8 5820.5 5826.4 5465.9 5698.0 5713.6 5753.4 5757.3 4512.7 5350.8 5653.5 5705.3 5718.3 5164.5 5570.7 5741.8 5743.9 5786.4 4985.6 5445.4 5625.6 5677.1 5692.9 12:07:09 INFO - PROCESS | 1660 | 12:07:09 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:25324.99 Median:3756.09 stddev:41607.81 (1107.7%) stddev-sans-first:41683.47 12:07:09 INFO - PROCESS | 1660 | Values: 705.1 763.9 779.1 785.4 813.6 15476.8 15659.8 16445.4 16457.6 16468.4 12086.4 14704.9 14931.0 14962.6 14978.0 667.9 696.0 824.1 833.3 865.6 846.0 864.4 865.5 868.3 870.4 886.0 920.0 920.7 922.0 922.2 899.1 908.3 912.0 912.7 915.9 668.5 677.3 680.9 686.8 689.3 15051.5 15462.5 15475.2 15477.4 15487.9 14693.3 14703.6 14714.3 14734.0 14743.6 11790.6 11976.0 11994.8 12087.0 12146.8 3764.0 3765.7 3829.4 3848.5 3970.0 79550.0 86878.0 104219.5 119656.4 121169.0 11251.6 11471.2 11528.5 11678.9 11895.0 23774.4 23987.0 24006.9 24069.9 24082.6 8047.4 8165.2 8243.9 8317.4 8322.8 4107.7 4211.0 4266.2 4379.5 4385.2 3618.4 3691.4 3736.9 3738.9 3748.2 601.1 645.4 650.5 657.8 678.1 557.0 569.2 572.7 637.3 647.7 9194.9 9571.5 9646.5 9720.0 9817.7 504.6 658.1 667.1 668.0 692.1 4536.5 4719.9 4771.0 4822.8 4840.8 153313.0 166406.0 167915.2 170173.0 172160.0 84661.0 138374.0 146752.0 149615.3 152588.0 71862.0 86384.1 104955.0 107460.9 109246.9 8613.6 8904.4 8976.6 9102.1 9119.5 66490.0 67547.6 67906.0 68605.6 69384.0 99788.0 103058.5 105491.5 106710.0 106803.0 9981.4 11946.4 12079.7 12403.4 12652.7 13072.5 13183.1 13313.9 13358.5 13661.5 12041.8 12132.8 12301.4 12556.4 12693.9 10110.6 11946.3 12550.0 12576.4 12659.2 12262.9 12314.5 12451.4 12478.1 12551.7 10184.6 10477.6 10552.2 10773.4 11025.8 8855.9 9790.5 10364.1 10468.9 10652.8 12:07:09 INFO - PROCESS | 1660 | -------- Summary: end -------- 12:07:09 INFO - PROCESS | 1660 | 12:07:10 INFO - TEST-INFO | 1660: exit 0 12:07:10 INFO - TEST-OK | dromaeo_css | took 1113812ms 12:07:10 INFO - TEST-START | kraken 12:07:10 INFO - operating with platform_type : mac_ 12:07:10 INFO - Initialising browser for kraken test... 12:07:10 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpSVvwy7/profile http://localhost:49275/getInfo.html) 12:07:13 INFO - PROCESS | 1669 | 2016-09-29 12:07:13.290 plugin-container[1670:10405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8927, name = 'com.apple.tsm.portname' 12:07:13 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:07:13 INFO - PROCESS | 1669 | 2016-09-29 12:07:13.293 plugin-container[1670:10405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 12:07:13 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:07:13 INFO - PROCESS | 1669 | 2016-09-29 12:07:13.293 plugin-container[1670:10405] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:07:13 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 12:07:13 INFO - PROCESS | 1669 | colorDepth:24 12:07:13 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 12:07:13 INFO - PROCESS | 1669 | __metrics 12:07:13 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 12:07:14 INFO - TEST-INFO | 1669: exit 0 12:07:14 INFO - Browser initialized. 12:07:14 INFO - Running cycle 1/1 for kraken test... 12:07:14 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpSVvwy7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:07:16 INFO - PROCESS | 1671 | 2016-09-29 12:07:16.695 plugin-container[1672:10520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x871f, name = 'com.apple.tsm.portname' 12:07:16 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:07:16 INFO - PROCESS | 1671 | 2016-09-29 12:07:16.699 plugin-container[1672:10520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 12:07:16 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:07:16 INFO - PROCESS | 1671 | 2016-09-29 12:07:16.699 plugin-container[1672:10520] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:07:26 INFO - PROCESS | 1671 | RSS: Main: 240291840 12:07:26 INFO - PROCESS | 1671 | 12:07:28 INFO - PROCESS | 1671 | 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) 12:07:29 INFO - PROCESS | 1671 | RSS: Main: 253661184 12:07:29 INFO - PROCESS | 1671 | 12:07:32 INFO - PROCESS | 1671 | 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) 12:07:32 INFO - PROCESS | 1671 | RSS: Main: 261226496 12:07:32 INFO - PROCESS | 1671 | 12:07:36 INFO - PROCESS | 1671 | 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) 12:07:37 INFO - PROCESS | 1671 | RSS: Main: 261799936 12:07:37 INFO - PROCESS | 1671 | 12:07:39 INFO - PROCESS | 1671 | 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) 12:07:39 INFO - PROCESS | 1671 | RSS: Main: 263094272 12:07:39 INFO - PROCESS | 1671 | 12:07:41 INFO - PROCESS | 1671 | 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) 12:07:42 INFO - PROCESS | 1671 | RSS: Main: 264740864 12:07:42 INFO - PROCESS | 1671 | 12:07:44 INFO - PROCESS | 1671 | 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) 12:07:45 INFO - PROCESS | 1671 | RSS: Main: 264970240 12:07:45 INFO - PROCESS | 1671 | 12:07:48 INFO - PROCESS | 1671 | 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) 12:07:49 INFO - PROCESS | 1671 | RSS: Main: 264957952 12:07:49 INFO - PROCESS | 1671 | 12:07:51 INFO - PROCESS | 1671 | 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) 12:07:52 INFO - PROCESS | 1671 | RSS: Main: 265379840 12:07:52 INFO - PROCESS | 1671 | 12:07:54 INFO - PROCESS | 1671 | 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) 12:07:54 INFO - PROCESS | 1671 | RSS: Main: 265199616 12:07:54 INFO - PROCESS | 1671 | 12:07:56 INFO - PROCESS | 1671 | 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) 12:07:57 INFO - PROCESS | 1671 | RSS: Main: 265166848 12:07:57 INFO - PROCESS | 1671 | 12:07:59 INFO - PROCESS | 1671 | 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) 12:08:00 INFO - PROCESS | 1671 | RSS: Main: 265162752 12:08:00 INFO - PROCESS | 1671 | 12:08:03 INFO - PROCESS | 1671 | 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) 12:08:04 INFO - PROCESS | 1671 | RSS: Main: 265142272 12:08:04 INFO - PROCESS | 1671 | 12:08:07 INFO - PROCESS | 1671 | 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) 12:08:07 INFO - PROCESS | 1671 | RSS: Main: 265474048 12:08:07 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | 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) 12:08:09 INFO - PROCESS | 1671 | RSS: Main: 267169792 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | __start_tp_report 12:08:09 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 12:08:09 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 12:08:09 INFO - PROCESS | 1671 | |i|pagename|runs| 12:08:09 INFO - PROCESS | 1671 | |0;ai-astar;110;116;117;118;116;118;116;117;117;117 12:08:09 INFO - PROCESS | 1671 | |1;audio-beat-detection;145;114;111;167;106;103;97;92;147;102 12:08:09 INFO - PROCESS | 1671 | |2;audio-dft;193;198;199;235;226;267;197;200;194;195 12:08:09 INFO - PROCESS | 1671 | |3;audio-fft;81;83;85;81;60;59;60;61;83;88 12:08:09 INFO - PROCESS | 1671 | |4;audio-oscillator;89;70;89;74;69;72;88;70;91;71 12:08:09 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;90;86;89;86;89;86;89;87;90;86 12:08:09 INFO - PROCESS | 1671 | |6;imaging-darkroom;155;153;94;96;94;95;96;97;93;138 12:08:09 INFO - PROCESS | 1671 | |7;imaging-desaturate;90;90;90;90;90;91;92;91;92;89 12:08:09 INFO - PROCESS | 1671 | |8;json-parse-financial;57;58;55;60;62;62;52;52;79;90 12:08:09 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;67;72;83;82;104;98;100;76;69;71 12:08:09 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;84;96;90;86;91;86;88;89;91;91 12:08:09 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;140;169;158;156;151;145;129;161;206;154 12:08:09 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;175;174;171;189;236;240;174;173;172;175 12:08:09 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;70;69;68;70;70;68;69;67;67;70 12:08:09 INFO - PROCESS | 1671 | __end_tp_report 12:08:09 INFO - PROCESS | 1671 | __start_cc_report 12:08:09 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,922 12:08:09 INFO - PROCESS | 1671 | __end_cc_report 12:08:09 INFO - PROCESS | 1671 | __startTimestamp1475176089586__endTimestamp 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | ------- Summary: start ------- 12:08:09 INFO - PROCESS | 1671 | Number of tests: 14 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:116.20 Median:117.00 stddev:2.30 (2.0%) stddev-sans-first:0.78 12:08:09 INFO - PROCESS | 1671 | Values: 110.0 116.0 117.0 118.0 116.0 118.0 116.0 117.0 117.0 117.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:118.40 Median:146.00 stddev:25.33 (17.4%) stddev-sans-first:24.98 12:08:09 INFO - PROCESS | 1671 | Values: 145.0 114.0 111.0 167.0 106.0 103.0 97.0 92.0 147.0 102.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:210.40 Median:199.50 stddev:24.57 (12.3%) stddev-sans-first:25.24 12:08:09 INFO - PROCESS | 1671 | Values: 193.0 198.0 199.0 235.0 226.0 267.0 197.0 200.0 194.0 195.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:74.10 Median:82.00 stddev:12.31 (15.0%) stddev-sans-first:12.80 12:08:09 INFO - PROCESS | 1671 | Values: 81.0 83.0 85.0 81.0 60.0 59.0 60.0 61.0 83.0 88.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:81.00 stddev:9.55 (11.8%) stddev-sans-first:9.31 12:08:09 INFO - PROCESS | 1671 | Values: 89.0 70.0 89.0 74.0 69.0 72.0 88.0 70.0 91.0 71.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.75 (2.0%) stddev-sans-first:1.67 12:08:09 INFO - PROCESS | 1671 | Values: 90.0 86.0 89.0 86.0 89.0 86.0 89.0 87.0 90.0 86.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:94.50 stddev:26.32 (27.8%) stddev-sans-first:22.62 12:08:09 INFO - PROCESS | 1671 | Values: 155.0 153.0 94.0 96.0 94.0 95.0 96.0 97.0 93.0 138.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:90.50 Median:90.50 stddev:0.97 (1.1%) stddev-sans-first:1.01 12:08:09 INFO - PROCESS | 1671 | Values: 90.0 90.0 90.0 90.0 90.0 91.0 92.0 91.0 92.0 89.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:61.00 stddev:12.30 (20.2%) stddev-sans-first:12.87 12:08:09 INFO - PROCESS | 1671 | Values: 57.0 58.0 55.0 60.0 62.0 62.0 52.0 52.0 79.0 90.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:82.20 Median:74.00 stddev:13.81 (18.7%) stddev-sans-first:13.50 12:08:09 INFO - PROCESS | 1671 | Values: 67.0 72.0 83.0 82.0 104.0 98.0 100.0 76.0 69.0 71.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:90.50 stddev:3.43 (3.8%) stddev-sans-first:3.07 12:08:09 INFO - PROCESS | 1671 | Values: 84.0 96.0 90.0 86.0 91.0 86.0 88.0 89.0 91.0 91.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:156.90 Median:157.00 stddev:20.62 (13.1%) stddev-sans-first:20.94 12:08:09 INFO - PROCESS | 1671 | Values: 140.0 169.0 158.0 156.0 151.0 145.0 129.0 161.0 206.0 154.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.90 Median:175.00 stddev:26.89 (15.4%) stddev-sans-first:28.12 12:08:09 INFO - PROCESS | 1671 | Values: 175.0 174.0 171.0 189.0 236.0 240.0 174.0 173.0 172.0 175.0 12:08:09 INFO - PROCESS | 1671 | 12:08:09 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:1.23 (1.8%) stddev-sans-first:1.22 12:08:09 INFO - PROCESS | 1671 | Values: 70.0 69.0 68.0 70.0 70.0 68.0 69.0 67.0 67.0 70.0 12:08:09 INFO - PROCESS | 1671 | -------- Summary: end -------- 12:08:09 INFO - PROCESS | 1671 | 12:08:10 INFO - TEST-INFO | 1671: exit 0 12:08:10 INFO - TEST-OK | kraken | took 59941ms 12:08:10 INFO - SUITE-END | took 1173s 12:08:10 INFO - Completed test suite (00:19:34) 12:08:10 INFO - javascript benchmark 12:08:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7206.316238534273, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3391.2030672791907, 3391.8304084795764, 3431.2451260722646, 3447.896563103108, 3458.1354661334667, 5677.0632845580485, 5738.7417566209515, 5743.5118014968675, 5749.281339832521, 5751.511121554673, 5769.509591684701, 5793.188953546507, 5800.854978625526, 5830.5044071254, 5905.911411328822, 5238.580913526918, 5303.814366497173, 5333.893322133555, 5342.866428339303, 5372.7313634318325, 5210.400803907562, 5256.132738098209, 5343.038253114438, 5373.919391209135, 5388.272583201257, 5179.274901513772, 5247.370315562138, 5304.787808487656, 5339.439358867341, 5352.357717073937, 5304.761285742113, 5314.840554783363, 5320.813771518017, 5341.946580534183, 5353.705546194958, 5251.947480525222, 5273.551748101416, 5287.576993840484, 5310.0, 5349.090654588729, 4259.1268789897995, 5665.5467562594895, 5730.36965933747, 5755.942440575583, 5803.303603567599, 5935.970320148372, 6223.25320961483, 6239.750409983595, 6259.061140828867, 6264.310925798204, 5261.447548007481, 6174.8147555573405, 6292.7099944511265, 6293.559450838443, 6297.716602752887, 5746.109353050284, 5802.738876750556, 5834.445727655866, 5905.704714764244, 5946.197263369476, 6375.808725738235, 6418.518611104092, 6422.518311126684, 6438.8390290243115, 6450.548461607643, 6142.508599312045, 6163.568550201443, 6179.69101544921, 6192.318844927055, 6205.81382558524, 5928.584999050111, 6035.456808887221, 6112.381899552462, 6135.753292002367, 6250.374962503804, 5616.859578510488, 6018.458338749533, 6153.107799369156, 6255.905216587079, 6266.060090986389, 6239.77320766918, 6268.404501572343, 6277.623342599459, 6279.811605651837, 6287.874242515124, 3717.70258379329, 4179.958200418017, 4884.633652476007, 4947.060058588857, 5025.84922452327, 3763.473113764075, 3893.980530097388, 4449.421575195269, 4450.109978004347, 4501.77491125449, 5860.062390017577, 5907.940920590739, 5951.5238780897425, 5955.493783028404, 6007.189029481052, 6147.708981113927, 6229.781957631461, 6234.657093859882, 6298.685065746694, 6316.210473690789, 5925.0, 5976.073708575178, 5987.43119403656, 6007.879842403215, 6033.004554248588, 5479.013777520085, 5484.149956756709, 5485.588580856372, 5512.39363669996, 5531.032069387841, 6214.161088253028, 6279.686015699288, 6438.903416448757, 6451.903221451682, 6456.741730330734, 6153.769246150878, 6183.659898705614, 6201.720922558406, 6286.339934307015, 6289.339619340087, 6384.361563843764, 6386.616802991835, 6440.516961228021, 6459.128017717736, 6472.35276472349, 5538.117040206349, 6111.926012480528, 6244.125822384779, 6245.3754624539, 6249.875002500015, 6165.938340616536, 6202.069689546604, 6218.595791273553, 6219.502439804714, 6264.154339164154, 4992.750362481934, 6023.626769009194, 6203.8138855834395, 6240.157578726996, 6252.3122456530655, 6118.724657390517, 6196.47329976957, 6199.256089269316, 6201.875962480635, 6241.157443745218, 5875.265591801025, 5924.763009479572, 5925.0, 5952.434518720715, 6029.366916473882, 6171.382861713792, 6203.0075187969705, 6206.06908963659, 6239.656818874824, 6250.4374606285655, 5925.733341999534, 6079.848003799941, 6081.5742897996715, 6088.695565221809, 6094.0], "value": 5718.243512172839, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.6958689992553, 512.8692183493209, 536.6565398145187, 536.9502622373261, 537.1539824775977, 38640.42039369408, 40536.79731601341, 40637.59362406375, 40643.0, 40647.0, 41506.377404338884, 41683.37474937878, 41874.58125418745, 42013.57986420135, 42042.0, 9577.185939195177, 9688.951555242214, 9695.515224238796, 9784.26618003649, 9784.951075244613, 8848.938127424686, 9125.58553424217, 9647.372920760163, 9680.322377433582, 9714.65998690046, 9736.610535578568, 9760.511974401286, 9766.706998790047, 9792.706218813448, 9799.118079372854, 9699.951500242489, 9744.02559744027, 9768.609255629766, 9769.316147869651, 9771.90228097717, 9646.228301735915, 9686.660966866126, 9728.610855565768, 9750.756231094208, 9762.414255144715, 39967.8001609993, 39985.60014399877, 40130.0, 40220.597794021975, 40344.39483407751, 2966.7033296670375, 3310.453775127101, 3719.200371920023, 3871.9225615487812, 4020.3768415895584, 27796.166115016582, 31015.0, 31042.534361984588, 31167.844160779277, 32029.519557206662, 3242.4649932761304, 3246.448103822332, 3251.3984912790975, 3349.2631621043574, 3400.0479865637412, 357014.9999999974, 425819.0, 472974.0, 474432.0, 483806.0, 34263.657363426544, 46855.29717054247, 50146.0, 55394.72302638461, 57771.11144442761, 34513.65486345155, 35007.64992350044, 35045.64954350475, 35165.76919807846, 35476.0, 14521.419143234363, 14673.77989330161, 14721.484748033981, 14722.631934201516, 14743.041702289316, 12260.386980650932, 12339.62981110568, 12342.0, 12384.256944583354, 12396.070294727931, 7793.80515487106, 7799.649015794303, 7801.180876008051, 7868.881966770503, 7875.527468351917, 2809.592609071714, 3066.264096616796, 3116.7377212229085, 3308.3052558962886, 3313.701766840996, 938.671464987249, 959.9424034557949, 962.1746939100417, 1008.4503945349655, 1024.6721049264163, 29988.85005575002, 30038.0, 30040.84979575088, 30083.849580751958, 30835.845820771203, 1031.8710161229847, 1055.0926203465015, 1055.519738518972, 1096.413418820927, 1106.253279036647, 14848.554543363716, 15560.0, 15624.140672262907, 15643.687126257411, 15775.526734197992, 682147.0, 690565.0, 691385.0, 692806.535967317, 697901.0, 56228.43771562232, 56553.717231414405, 56819.715901420226, 56864.43135568674, 56877.71561142168, 1302.8730148421548, 1315.7763180259378, 1328.2827273272517, 1331.6204881608694, 1335.9398827052805, 28483.28791780222, 29298.974535891142, 29427.558586621646, 29830.0, 29930.551041733957, 48739.02521949612, 48972.5102748968, 49181.0, 49308.0, 49327.50672493229, 540560.0, 541236.0, 541433.0, 543005.0, 543067.0, 25790.0, 26293.868530657226, 26635.33411664724, 26644.467110658064, 26695.599566006913, 43877.78061109674, 45745.54254457413, 45804.541954581364, 45948.310775337726, 46356.53643463522, 37457.62542374541, 38000.0, 38538.843834685, 38581.807090964365, 39187.41218881776, 37485.0, 37865.24269514539, 38318.0, 38988.0, 39013.41479877747, 36541.81729091337, 36832.631673683994, 37097.443538347485, 37461.4380784283, 37489.81255093707, 29739.405211896075, 32532.34935301328, 33122.66877331196, 34034.31931361408, 34485.48271775875, 24426.389340266636, 24722.13472528453, 24834.379140521633, 24889.751102488743, 24925.750742492342], "value": 18462.123089214074, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.335554390956, 376.617733001004, 384.03415410243235, 388.45422181834533, 404.5954045954046, 18735.812641873596, 20879.582408351842, 20910.26814061508, 20969.0, 21040.89479552604, 20648.0, 20885.477863053395, 20999.47501312468, 21090.261840835574, 21371.786282137175, 4718.77311898907, 5128.256402821588, 5340.385855626604, 5507.669539827603, 6010.098485227207, 5946.048632218846, 6038.305594856582, 6073.392660733935, 6078.848028799272, 6081.087836824468, 5684.715764211794, 5870.0, 5915.88168236635, 5924.88150236995, 5938.435848594377, 4445.688801783876, 4946.307516947631, 5674.716264186774, 5978.461938425563, 6012.308584512772, 4713.882152946169, 4780.378550788375, 5096.821611243626, 5118.872028199287, 5147.871303217449, 24779.25662230136, 25053.248402547953, 25532.744672553406, 25780.87109564459, 25788.871055644602, 2237.697910782056, 2309.595820731382, 2398.100712232913, 2530.582453895105, 2973.8810447582073, 14689.926550367287, 14905.627359315995, 15094.54716358511, 15404.46084387041, 15540.0, 1823.6626224148442, 1858.4053103007043, 1860.981390186108, 1862.0410488598382, 1937.0, 106122.0, 112198.43900780525, 112485.43757281243, 112523.0, 115002.84997150005, 8208.507489550646, 9500.0, 9791.657291994889, 9931.602735890483, 10504.691022328096, 11846.348450835114, 12097.637070887888, 12188.756224875453, 12205.084618653636, 12205.75588488243, 6177.752889884354, 6527.902081468782, 7837.0, 7868.960655196687, 7939.880901786478, 8195.590220488952, 8204.0, 8231.0, 8360.540170290693, 8361.163883611713, 3458.7202734988214, 3575.106223444139, 3815.465834783133, 3821.9617803821607, 3887.378019516864, 1910.2740958435706, 1911.7036859286832, 1919.9136038878285, 1923.9903800480909, 1962.9018549072775, 715.7602203261965, 814.5804910471112, 883.9469631822111, 886.8448021596195, 911.0434044253508, 17951.910240448713, 18470.630587388445, 19061.379782718348, 19499.220031198878, 20263.696044559343, 704.6371118873784, 774.2915232562256, 884.7920738626418, 924.4268553496875, 929.7861491856776, 10413.9479302603, 10942.288751231305, 10955.780884382268, 10964.17768667353, 11014.953579409943, 473706.0, 542237.0, 550195.0, 558818.0, 559448.0, 108138.45930770296, 110090.44954775335, 110197.0, 112100.43949780361, 112244.0, 897.228383590112, 940.229012209995, 957.7892863570004, 958.990410095904, 978.7650963768644, 17955.0, 18056.90971545134, 18310.0, 18340.0, 18579.79231349958, 45986.0, 46481.53518464772, 47260.0, 47853.760731197515, 48730.0, 68591.97112043423, 70909.6454517724, 71116.64441677759, 76827.61586192033, 77839.61080194563, 5492.148716948879, 7731.574763388067, 7759.650815713194, 7916.6041697916025, 8201.26188643025, 10361.378317300987, 12041.277523348277, 12220.450079746255, 12397.62807115788, 12466.314352710688, 9177.678781242625, 10168.034036766496, 10230.693079207636, 10519.21105917044, 10579.682609521727, 10384.0, 10589.417582033062, 10684.626038088629, 10756.139508839431, 10835.079018283534, 10771.515281812493, 10858.782824343627, 10992.340459572451, 11057.557697692002, 11103.777924441627, 6401.327860574573, 7991.2807847294025, 8049.637766300413, 8067.0, 8151.0, 9617.519124043909, 10653.840192397276, 10801.783964320826, 10855.782884342427, 10914.672559823219], "value": 9129.154281688363, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.2984372735496, 1516.3631274864558, 1710.6236627941855, 1768.8761786674938, 1804.2151664026158, 8008.439409241356, 8255.711050113232, 8481.491110533365, 8989.101089891008, 8991.010988791244, 8452.577371131449, 8766.430182038162, 8827.558622068887, 8854.24738897193, 8873.733787986355, 2355.3758254062686, 2375.10933399975, 2376.0852071952336, 2376.7742064503846, 2389.665446837444, 2346.038124369009, 2354.058376649337, 2367.2898130560848, 2373.1456675596773, 2392.6650268962358, 2360.5986982213067, 2366.668666386707, 2375.6080246759266, 2387.415083304593, 2389.7968672662846, 2268.7958083772373, 2287.1194590082855, 2290.805281551062, 2294.231432470133, 2299.4596269876565, 2331.7901388875084, 2355.0697474497647, 2358.5990381635165, 2363.61000434928, 2367.4554852383876, 8759.124087591254, 8759.430637008574, 8809.163129502687, 8871.112888711143, 8880.111988801133, 4728.290756386536, 5071.7210553419545, 5211.739413029333, 5318.601104917147, 5390.595705322116, 3269.73842092632, 3337.8831740888954, 3591.982040089809, 3627.909302267438, 3662.121090938182, 889.6752685269895, 1059.7721489879714, 1069.1340014588131, 1081.1242893256488, 1089.215764649459, 11601.303921764636, 11624.0, 11733.178677492579, 11744.236624619287, 11777.470013849397, 4046.635802777764, 4523.2988886722615, 4545.999880026389, 4773.451053128865, 4776.498467660846, 9859.605615775288, 9953.452560109263, 9957.00429957013, 9962.00379961998, 9966.20270378368, 5270.472952704776, 5288.8413347599635, 5295.179247216687, 5298.761555729924, 5304.814331498379, 4384.495782984934, 4548.476925153584, 4721.480637129913, 4859.975700121477, 4891.266310053449, 3485.233248685285, 3783.9783258520047, 3921.313770090223, 3961.4057891315956, 3997.6402123809, 1271.3643178410794, 1289.7807372746656, 1290.2129700882454, 1291.2188126183712, 1307.5355601726128, 836.9539675317794, 853.4239388412797, 866.6403442571388, 896.8430524658158, 907.0566610724773, 4592.747398893093, 5365.704886231217, 5645.23789288449, 5689.004424225744, 5781.132830075522, 861.8664107063415, 867.648602316063, 921.3918813583003, 938.1978408460805, 962.8507581324906, 3147.763917706181, 3323.7507186961075, 3687.483752274684, 3695.150115473456, 3783.9432408513894, 10573.89426105729, 10653.627123050655, 10748.193885458622, 10762.461876906124, 10812.080973117216, 10642.574297028186, 10691.786164276671, 10729.731756706145, 10748.140148788078, 10757.462126893624, 7616.276453736886, 7911.169327220559, 8044.678212871537, 8056.758297251092, 8113.18868113184, 5636.549076073822, 5693.572982026448, 5748.281464816898, 5817.534597232296, 5861.0915308127305, 8773.122687731176, 9289.674861379819, 9787.412755234709, 10062.69811905644, 10106.89893101089, 9981.301308908307, 10036.698899033041, 10108.39349639024, 10250.743731406475, 10375.014373634493, 6573.408393244631, 7284.67218975158, 7420.5176663516695, 7564.659590318339, 7566.281203285679, 8242.958785206036, 8349.791255218668, 8426.536540490331, 8426.620802063799, 8505.489670619783, 7298.197198308288, 7472.850542989218, 7486.775396738107, 7540.132884718328, 7570.621468926642, 7229.349358557544, 7286.526375785557, 7323.048003759477, 7369.705211791683, 7448.73929412468, 5738.0, 5935.376785437639, 6828.624425656438, 6994.6852391643515, 7005.299470052954, 3887.8833634990997, 3911.3546264865977, 4031.7580945143386, 4693.55411235932, 4816.133096042606, 4645.839684546567, 4713.31656909753, 4721.74030428316, 4745.976270118627, 4799.760011999456], "value": 4497.252720795302, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.66486864449104, 311.7817527730588, 312.9280265538926, 318.1283283802382, 318.84536000039964, 4192.9161416771685, 5465.480779325962, 5546.973809845182, 5567.387587365387, 5568.860778480535, 5424.321959755031, 5472.63184203949, 5487.341519017714, 5549.916751248725, 5576.637518561291, 3514.9297014059703, 4010.8596199133035, 4122.073327937168, 4171.791410429481, 4190.601892820177, 3489.598696149937, 3683.907902302437, 4107.568705285947, 4113.992071942378, 4222.936655950148, 3842.2699687059508, 4217.734679596124, 4218.388333691613, 4237.389791879097, 4246.7027308088445, 3463.5324231228965, 3496.003638962909, 3693.9445908311395, 4189.454132700443, 4222.429971953778, 3070.094322174964, 3233.7898036627544, 3614.150674591469, 3649.6532829381167, 3676.761010534307, 4392.34114882767, 5361.410244873061, 5411.296531450926, 5414.593905457067, 5422.674639521641, 3761.097336639187, 3904.8438062477467, 4647.442306923157, 4725.975375759516, 4833.419989601271, 5213.0, 5750.856228594277, 5847.7953271635715, 5924.7333869975955, 5931.851703707399, 3689.5572531296416, 3963.742356746802, 3963.7819919904396, 3980.7213495055357, 3984.2230765000836, 5434.972825135888, 5826.912596311059, 5900.409959004108, 6167.660778657172, 6185.35053819347, 3728.6763199064485, 3890.319194141014, 3973.324534829086, 3989.5212574490665, 4032.193561287754, 3695.5380577427823, 3813.961860381416, 3821.9044523887123, 4079.204555111784, 4079.796010199478, 4354.216241076637, 4428.4685837699035, 4714.858554243378, 4736.7631618418945, 4763.285507173951, 4581.846532129894, 5030.717167122433, 5820.798560791633, 5835.270591176103, 5898.380670029671, 1350.4058214385639, 1377.3319939829175, 1377.6004958561905, 1378.3246209357342, 1378.4141739760562, 648.9545731798823, 806.9267873728045, 824.1017291152552, 841.9158084191532, 850.4174640371366, 4771.570558649738, 4861.635377346643, 5468.781248750085, 5483.670979741229, 5523.912304894349, 4896.755162241873, 5693.345265294462, 6020.247469066367, 6025.674351642631, 6043.425874541911, 4647.49035520815, 5041.6722913010535, 5255.500727430888, 5499.4225606310765, 5506.889862202734, 2618.9524190324023, 2863.7113299015527, 3006.633159809729, 3143.701348371901, 3154.1010811918495, 5368.892622147535, 6047.969760151171, 6103.298120716086, 6206.627602343874, 6263.123162757218, 4808.61531077513, 5368.570514358842, 5634.4365563443325, 5742.368339482654, 6129.682118344577, 5168.431472538018, 5411.78352865881, 5416.593755468356, 5792.8551786204835, 5866.178734977192, 5219.347581552306, 5232.686038837682, 5435.070549954868, 5689.7439615218245, 5997.940020599913, 5581.0, 5873.823785286449, 5876.0, 5940.821775346573, 5984.970075149597, 4866.513348665105, 4902.534259245366, 5752.367239603556, 6106.374259068643, 6130.478909292582, 3737.9160043587053, 3784.2499040480016, 3860.613938606117, 3934.6425483208245, 3963.3262345400776, 5550.417206193291, 5970.492508136857, 5977.671228082323, 5980.3421623622235, 6000.729967151401, 5305.442928492607, 5450.22276387031, 5510.8071217507195, 5820.505257053198, 5826.41735826414, 5465.945340546703, 5698.0, 5713.628614140068, 5753.367129615655, 5757.251557297524, 4512.684112112121, 5350.785968561214, 5653.547716182778, 5705.286839145107, 5718.313802343745, 5164.509371609691, 5570.718734690962, 5741.770329186786, 5743.8564035899435, 5786.42135786418, 4985.6260780442335, 5445.428230035789, 5625.634333768292, 5677.120046392815, 5692.914606280992], "value": 4149.66555877311, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.0869124483796, 763.9350655194309, 779.123486078162, 785.4109417936547, 813.5606772342937, 15476.84523154768, 15659.843401565995, 16445.424410145646, 16457.588560285967, 16468.423605173808, 12086.395680215997, 14704.852951470482, 14931.029483083592, 14962.551123466286, 14977.951543391968, 667.8764428580728, 696.0081883316279, 824.1346586084617, 833.3291700181352, 865.6321063547998, 845.995770021149, 864.3517361978517, 865.485036403339, 868.3227082875368, 870.4342177584557, 886.0474989386403, 919.9862002069974, 920.6639576554576, 921.9585118669676, 922.2115091596689, 899.0604817965242, 908.3142227618139, 912.0, 912.6942474271127, 915.8946721127089, 668.518666560076, 677.3091446724363, 680.904673345732, 686.7672527812589, 689.3279052923409, 15051.548453546411, 15462.45881394157, 15475.226238688021, 15477.380904763795, 15487.922560387238, 14693.33879975393, 14703.558893233114, 14714.264286785618, 14734.0, 14743.631409214748, 11790.587329443513, 11976.0, 11994.760104797855, 12087.0, 12146.817797733042, 3764.0213544478634, 3765.717571182145, 3829.3872980323013, 3848.461215429842, 3969.98015009926, 79550.0, 86878.0, 104219.478902605, 119656.40171799173, 121169.0, 11251.606193783178, 11471.197016208785, 11528.481218345194, 11678.88321116778, 11895.0, 23774.405639859142, 23987.0, 24006.87996560006, 24069.879650601633, 24082.63876041861, 8047.396445266629, 8165.2243036911395, 8243.876341854877, 8317.41778075541, 8322.8335433291, 4107.691923105779, 4211.0, 4266.189424009491, 4379.496357918816, 4385.166818304512, 3618.4391419330045, 3691.4093745000673, 3736.925261494809, 3738.8504459821847, 3748.156664750409, 601.0653433910311, 645.4255712415953, 650.4893658478167, 657.7927952694886, 678.0845858091635, 556.9920436825267, 569.1775384569257, 572.6764378126345, 637.2671427858, 647.6955830759536, 9194.862077068849, 9571.521952825966, 9646.469444180637, 9720.0, 9817.673200539622, 504.64674727690766, 658.1181217169017, 667.1212974768006, 667.9966600167065, 692.1313751242159, 4536.478304994902, 4719.882002949954, 4770.957318495644, 4822.7829747661435, 4840.83057093, 153313.0, 166406.0, 167915.24593278352, 170173.0, 172160.0, 84661.0, 138374.0, 146752.0, 149615.25192373968, 152588.0, 71862.0, 86384.13615863762, 104955.0, 107460.92539074666, 109246.90753092685, 8613.612387442456, 8904.376693631384, 8976.596053177493, 9102.149656621043, 9119.544022798966, 66490.0, 67547.59619230352, 67905.98141027789, 68605.62788744297, 69384.0, 99788.0, 103058.48470757598, 105491.4725426368, 106710.0, 106803.0, 9981.401115933068, 11946.402679866145, 12079.698007549881, 12403.379831008233, 12652.683682908, 13072.542461013818, 13183.143095699132, 13313.933430332787, 13358.465661373437, 13661.453541858215, 12041.75916481648, 12132.757344852876, 12301.44643491027, 12556.434960426985, 12693.873061269269, 10110.595576176871, 11946.342951137423, 12550.0, 12576.434060467118, 12659.240445573296, 12262.877371226174, 12314.507419703112, 12451.377431128227, 12478.126531142732, 12551.686207844878, 10184.592616295266, 10477.633282835064, 10552.155827533625, 10773.353598784099, 11025.83461248066, 8855.911440885766, 9790.51047447639, 10364.073331082265, 10468.895311046792, 10652.84020739705], "value": 7785.781833287281, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 117.0, 118.0, 116.0, 118.0, 116.0, 117.0, 117.0, 117.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 111.0, 167.0, 106.0, 103.0, 97.0, 92.0, 147.0, 102.0], "value": 118.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 198.0, 199.0, 235.0, 226.0, 267.0, 197.0, 200.0, 194.0, 195.0], "value": 210.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 85.0, 81.0, 60.0, 59.0, 60.0, 61.0, 83.0, 88.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 89.0, 74.0, 69.0, 72.0, 88.0, 70.0, 91.0, 71.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 86.0, 89.0, 86.0, 89.0, 87.0, 90.0, 86.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 153.0, 94.0, 96.0, 94.0, 95.0, 96.0, 97.0, 93.0, 138.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 90.0, 90.0, 91.0, 92.0, 91.0, 92.0, 89.0], "value": 90.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 55.0, 60.0, 62.0, 62.0, 52.0, 52.0, 79.0, 90.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 83.0, 82.0, 104.0, 98.0, 100.0, 76.0, 69.0, 71.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 96.0, 90.0, 86.0, 91.0, 86.0, 88.0, 89.0, 91.0, 91.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 169.0, 158.0, 156.0, 151.0, 145.0, 129.0, 161.0, 206.0, 154.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 171.0, 189.0, 236.0, 240.0, 174.0, 173.0, 172.0, 175.0], "value": 187.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 68.0, 70.0, 70.0, 68.0, 69.0, 67.0, 67.0, 70.0], "value": 68.8, "unit": "score"}]}]} 12:08:10 INFO - Return code: 0 12:08:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:08:10 ERROR - # TBPL SUCCESS # 12:08:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:08:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:08:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:08:10 INFO - 2016-09-29 12:08:10.916 screenresolution[1676:10909] starting screenresolution argv=screenresolution get 12:08:10 INFO - 2016-09-29 12:08:10.919 screenresolution[1676:10909] Display 0: 1600x1200x32@60 12:08:10 INFO - 2016-09-29 12:08:10.925 screenresolution[1677:10912] starting screenresolution argv=screenresolution list 12:08:10 INFO - Available Modes on Display 0 12:08:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:08:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:08:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:08:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:08:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:08:11 INFO - Intel Iris: 12:08:11 INFO - Chipset Model: Intel Iris 12:08:11 INFO - Type: GPU 12:08:11 INFO - Bus: Built-In 12:08:11 INFO - VRAM (Dynamic, Max): 1536 MB 12:08:11 INFO - Vendor: Intel (0x8086) 12:08:11 INFO - Device ID: 0x0a2e 12:08:11 INFO - Revision ID: 0x0009 12:08:11 INFO - Displays: 12:08:11 INFO - Display: 12:08:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:08:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:08:11 INFO - Display Serial Number: 1600x1200 60 12:08:11 INFO - Main Display: Yes 12:08:11 INFO - Mirror: Off 12:08:11 INFO - Online: Yes 12:08:11 INFO - Rotation: Supported 12:08:11 INFO - Return code: 0 12:08:11 INFO - Running post-action listener: _resource_record_post_action 12:08:11 INFO - [mozharness: 2016-09-29 19:08:11.162747Z] Finished run-tests step (success) 12:08:11 INFO - Running post-run listener: _resource_record_post_run 12:08:11 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 32666112; Write bytes: 711846912; Read time: 200; Write time: 2031 12:08:11 INFO - TinderboxPrint: CPU usage
54.4% 12:08:11 INFO - TinderboxPrint: I/O read bytes / time
32,666,112 / 200 12:08:11 INFO - TinderboxPrint: I/O write bytes / time
711,846,912 / 2,031 12:08:11 INFO - TinderboxPrint: CPU idle
2,202.8 (45.6%) 12:08:11 INFO - TinderboxPrint: CPU system
976.4 (20.2%) 12:08:11 INFO - TinderboxPrint: CPU user
1,654.5 (34.2%) 12:08:11 INFO - TinderboxPrint: Swap in / out
436,514,816 / 0 12:08:11 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404060672; Write bytes: 398272000; Read time: 29832; Write time: 802 12:08:11 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30573056; Write bytes: 300680704; Read time: 180; Write time: 1191 12:08:11 INFO - Running post-run listener: _upload_blobber_files 12:08:11 INFO - Blob upload gear active. 12:08:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:08:11 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 12:08:11 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:08:11 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:08:12 INFO - (blobuploader) - INFO - Open directory for files ... 12:08:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:08:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:08:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:13 INFO - (blobuploader) - INFO - Done attempting. 12:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:08:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:14 INFO - (blobuploader) - INFO - Done attempting. 12:08:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:08:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:08:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:16 INFO - (blobuploader) - INFO - Done attempting. 12:08:16 INFO - (blobuploader) - INFO - Iteration through files over. 12:08:16 INFO - Return code: 0 12:08:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:08:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:08:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ba245f066857fb3fec79f6e0f5c3025c44cdd6058e30953a0c6108c1aa4c6fc342795ff9b6bfacc87b340bc1261d5944fcb3e0537a2045b6feb995ce7b8af31", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/900e30ff21299dd1b11832fee2b77028b20882fbefe9961a18cb065d5b8a78d2bc0798431183d937e754134c46e58ae530cfb65287b832341dff5166e11a27e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7b50d2984a506e7eb01faded2058722d45654f7b66498d62004c0f61b1ba874b4c9722f59e5f998f16daa7078a1b3ed7b08bd9c2d7cdc784d637c66afab60ab"} 12:08:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:08:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:08:16 INFO - Contents: 12:08:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ba245f066857fb3fec79f6e0f5c3025c44cdd6058e30953a0c6108c1aa4c6fc342795ff9b6bfacc87b340bc1261d5944fcb3e0537a2045b6feb995ce7b8af31", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/900e30ff21299dd1b11832fee2b77028b20882fbefe9961a18cb065d5b8a78d2bc0798431183d937e754134c46e58ae530cfb65287b832341dff5166e11a27e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7b50d2984a506e7eb01faded2058722d45654f7b66498d62004c0f61b1ba874b4c9722f59e5f998f16daa7078a1b3ed7b08bd9c2d7cdc784d637c66afab60ab"} 12:08:16 INFO - Running post-run listener: copy_logs_to_upload_dir 12:08:16 INFO - Copying logs to upload dir... 12:08:16 INFO - mkdir: /builds/slave/test/build/upload/logs 12:08:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.898314 ========= master_lag: 5.57 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-29 12:08:21.745240) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 12:08:21.749119) ========= 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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ba245f066857fb3fec79f6e0f5c3025c44cdd6058e30953a0c6108c1aa4c6fc342795ff9b6bfacc87b340bc1261d5944fcb3e0537a2045b6feb995ce7b8af31", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/900e30ff21299dd1b11832fee2b77028b20882fbefe9961a18cb065d5b8a78d2bc0798431183d937e754134c46e58ae530cfb65287b832341dff5166e11a27e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7b50d2984a506e7eb01faded2058722d45654f7b66498d62004c0f61b1ba874b4c9722f59e5f998f16daa7078a1b3ed7b08bd9c2d7cdc784d637c66afab60ab"} build_url:https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011028 build_url: 'https://queue.taskcluster.net/v1/task/IqImxmDrSUG53Eri6guU-Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ba245f066857fb3fec79f6e0f5c3025c44cdd6058e30953a0c6108c1aa4c6fc342795ff9b6bfacc87b340bc1261d5944fcb3e0537a2045b6feb995ce7b8af31", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/900e30ff21299dd1b11832fee2b77028b20882fbefe9961a18cb065d5b8a78d2bc0798431183d937e754134c46e58ae530cfb65287b832341dff5166e11a27e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7b50d2984a506e7eb01faded2058722d45654f7b66498d62004c0f61b1ba874b4c9722f59e5f998f16daa7078a1b3ed7b08bd9c2d7cdc784d637c66afab60ab"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 12:08:21.791171) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-09-29 12:08:21.791515) ========= 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.ESRKeXDVI7/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.ChsdOl4QAX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.005143 ========= master_lag: 4.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-09-29 12:08:25.836093) ========= ========= Started reboot skipped (results: 3, elapsed: 9 secs) (at 2016-09-29 12:08:25.836396) ========= ========= Finished reboot skipped (results: 3, elapsed: 9 secs) (at 2016-09-29 12:08:34.895463) ========= ========= Total master_lag: 11.94 =========