builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0152 starttime: 1484306449.68 results: success (0) buildid: 20170113004016 builduid: 1ddab738b6c94dabbf70c85d9d7abfce revision: 1e322cd9c7410917d4c5f1fa5b2cd78eb4a79ec6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:49.682054) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:49.682574) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:49.682857) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:49.714968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:49.715453) ========= 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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 --2017-01-13 03:20:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2017-01-13 03:20:49 (14.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.142254 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:49.885193) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:49.885512) ========= 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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.027300 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:49.934169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 03:20:49.934572) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1e322cd9c7410917d4c5f1fa5b2cd78eb4a79ec6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1e322cd9c7410917d4c5f1fa5b2cd78eb4a79ec6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vC5SQ4k3eq/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.8bahV4eK6d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 2017-01-13 03:20:50,028 truncating revision to first 12 chars 2017-01-13 03:20:50,028 Setting DEBUG logging. 2017-01-13 03:20:50,028 attempt 1/10 2017-01-13 03:20:50,028 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1e322cd9c741?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 03:20:50,802 unpacking tar archive at: mozilla-aurora-1e322cd9c741/testing/mozharness/ program finished with exit code 0 elapsedTime=1.032919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 03:20:50.990776) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:50.994148) ========= script_repo_revision: 1e322cd9c7410917d4c5f1fa5b2cd78eb4a79ec6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:50.994568) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:50.994837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 03:20:51.014772) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-13 03:20:51.015153) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vC5SQ4k3eq/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.8bahV4eK6d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:20:51 INFO - MultiFileLogger online at 20170113 03:20:51 in /builds/slave/test 03:20:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:20:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:20:51 INFO - {'append_to_log': False, 03:20:51 INFO - 'base_work_dir': '/builds/slave/test', 03:20:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:20:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:20:51 INFO - 'branch': 'Mozilla-Aurora', 03:20:51 INFO - 'buildbot_json_path': 'buildprops.json', 03:20:51 INFO - 'config_files': ('talos/mac_config.py',), 03:20:51 INFO - 'default_actions': ('clobber', 03:20:51 INFO - 'read-buildbot-config', 03:20:51 INFO - 'download-and-extract', 03:20:51 INFO - 'populate-webroot', 03:20:51 INFO - 'create-virtualenv', 03:20:51 INFO - 'install', 03:20:51 INFO - 'run-tests'), 03:20:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:20:51 INFO - 'download_minidump_stackwalk': True, 03:20:51 INFO - 'download_symbols': 'ondemand', 03:20:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:20:51 INFO - 'tooltool.py': '/tools/tooltool.py', 03:20:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:20:51 INFO - '/tools/misc-python/virtualenv.py')}, 03:20:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:20:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:20:51 INFO - 'installer_path': 'installer.exe', 03:20:51 INFO - 'log_level': 'info', 03:20:51 INFO - 'log_name': 'talos', 03:20:51 INFO - 'log_to_console': True, 03:20:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:20:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:20:51 INFO - 'opt_config_files': (), 03:20:51 INFO - 'pip_index': False, 03:20:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:51 INFO - 'cmd': ('bash', 03:20:51 INFO - '-c', 03:20:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:51 INFO - 'enabled': True, 03:20:51 INFO - 'halt_on_failure': False, 03:20:51 INFO - 'name': 'check_screen_resolution'},), 03:20:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:51 INFO - 'cmd': ('bash', 03:20:51 INFO - '-c', 03:20:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:51 INFO - 'enabled': True, 03:20:51 INFO - 'halt_on_failure': False, 03:20:51 INFO - 'name': 'check_screen_resolution'},), 03:20:51 INFO - 'run_cmd_checks_enabled': True, 03:20:51 INFO - 'sps_profile': False, 03:20:51 INFO - 'sps_profile_interval': 0, 03:20:51 INFO - 'suite': 'dromaeojs', 03:20:51 INFO - 'system_bits': '32', 03:20:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:20:51 INFO - 'title': 't-yosemite-r7-0152', 03:20:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:20:51 INFO - 'use_talos_json': True, 03:20:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:20:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:20:51 INFO - 'work_dir': 'build'} 03:20:51 INFO - [mozharness: 2017-01-13 11:20:51.223057Z] Running clobber step. 03:20:51 INFO - Running pre-action listener: _resource_record_pre_action 03:20:51 INFO - Running main action method: clobber 03:20:51 INFO - rmtree: /builds/slave/test/build 03:20:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:20:53 INFO - Running post-action listener: _resource_record_post_action 03:20:53 INFO - [mozharness: 2017-01-13 11:20:53.469155Z] Finished clobber step (success) 03:20:53 INFO - [mozharness: 2017-01-13 11:20:53.469291Z] Running read-buildbot-config step. 03:20:53 INFO - Running pre-action listener: _resource_record_pre_action 03:20:53 INFO - Running main action method: read_buildbot_config 03:20:53 INFO - Using buildbot properties: 03:20:53 INFO - { 03:20:53 INFO - "project": "", 03:20:53 INFO - "product": "firefox", 03:20:53 INFO - "script_repo_revision": "production", 03:20:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:20:53 INFO - "repository": "", 03:20:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:20:53 INFO - "buildid": "20170113004016", 03:20:53 INFO - "stage_platform": "macosx64", 03:20:53 INFO - "basedir": "/builds/slave/test", 03:20:53 INFO - "nightly_build": "True", 03:20:53 INFO - "buildnumber": 74, 03:20:53 INFO - "slavename": "t-yosemite-r7-0152", 03:20:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:20:53 INFO - "platform": "yosemite_r7", 03:20:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:20:53 INFO - "branch": "mozilla-aurora", 03:20:53 INFO - "repo_path": "releases/mozilla-aurora", 03:20:53 INFO - "revision": "1e322cd9c7410917d4c5f1fa5b2cd78eb4a79ec6", 03:20:53 INFO - "pgo_build": "False", 03:20:53 INFO - "builduid": "1ddab738b6c94dabbf70c85d9d7abfce", 03:20:53 INFO - "slavebuilddir": "test" 03:20:53 INFO - } 03:20:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:20:53 INFO - Running post-action listener: _resource_record_post_action 03:20:53 INFO - [mozharness: 2017-01-13 11:20:53.472709Z] Finished read-buildbot-config step (success) 03:20:53 INFO - [mozharness: 2017-01-13 11:20:53.472790Z] Running download-and-extract step. 03:20:53 INFO - Running pre-action listener: _resource_record_pre_action 03:20:53 INFO - Running main action method: download_and_extract 03:20:53 INFO - mkdir: /builds/slave/test/build/tests 03:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:20:53 INFO - trying https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:20:53 INFO - Downloading https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:20:54 INFO - Downloaded 1199 bytes. 03:20:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:20:54 INFO - Using the following test package requirements: 03:20:54 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:20:54 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:20:54 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:20:54 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:20:54 INFO - u'jsshell-mac.zip'], 03:20:54 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:20:54 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:20:54 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:20:54 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:20:54 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:20:54 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:20:54 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:20:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:20:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:20:54 INFO - Fetch https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:20:55 INFO - Http code: 200 03:20:55 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 03:20:55 INFO - x-amz-cf-id: FG0J9kjQxEeyVHenzGrdcp0EkB6b6DBZVDwThILvV28ceTaEdVvWJQ== 03:20:55 INFO - x-amz-version-id: pr94pN5S1A1W4FoYhg2Y00jjrSF0Wqhs 03:20:55 INFO - Expected file size: 16275888 03:20:55 INFO - Obtained file size: 16275888 03:21:02 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:21:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:21:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:21:02 INFO - Fetch https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:21:02 INFO - Http code: 200 03:21:02 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 03:21:02 INFO - x-amz-cf-id: TDLtTI_E4BjaFXav1F2R3X0NWiXW3NjXBTlc0lkEH5FrE41rwrOATw== 03:21:02 INFO - x-amz-version-id: pr94pN5S1A1W4FoYhg2Y00jjrSF0Wqhs 03:21:03 INFO - Expected file size: 16275888 03:21:03 INFO - Obtained file size: 16275888 03:21:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:21:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:21:09 INFO - Fetch https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:21:10 INFO - Http code: 200 03:21:10 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 03:21:10 INFO - x-amz-cf-id: AZlWBr6RJHjsnFn5SdRXpWVNOCMf2cTDpHbjAEz_l1Zh-LK3adz-Ew== 03:21:10 INFO - x-amz-version-id: uzr247d2gNkUV3ntayVRnLiczvaM0oHu 03:21:10 INFO - Expected file size: 14409038 03:21:10 INFO - Obtained file size: 14409038 03:21:11 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')]} 03:21:11 INFO - trying https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:11 INFO - Downloading https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:21:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:21:13 INFO - Downloaded 110046105 bytes. 03:21:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:13 INFO - mkdir: /builds/slave/test/properties 03:21:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:13 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:13 INFO - Contents: 03:21:13 INFO - build_url:https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:14 INFO - Running post-action listener: _resource_record_post_action 03:21:14 INFO - Running post-action listener: set_extra_try_arguments 03:21:14 INFO - [mozharness: 2017-01-13 11:21:14.670110Z] Finished download-and-extract step (success) 03:21:14 INFO - [mozharness: 2017-01-13 11:21:14.670264Z] Running populate-webroot step. 03:21:14 INFO - Running pre-action listener: _resource_record_pre_action 03:21:14 INFO - Running main action method: populate_webroot 03:21:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:14 INFO - '--tppagecycles', 03:21:14 INFO - '7'], 03:21:14 INFO - 'tests': ['tsvgm']}}, 03:21:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:14 INFO - 'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:14 INFO - 'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['damp', 'tps']}, 03:21:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:14 INFO - 'tests': ['damp', 'tps']}, 03:21:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['dromaeo_dom']}, 03:21:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['basic_compositor_video']}, 03:21:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:21:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['a11yr', 03:21:14 INFO - 'ts_paint', 03:21:14 INFO - 'tpaint', 03:21:14 INFO - 'sessionrestore', 03:21:14 INFO - 'sessionrestore_no_auto_restore', 03:21:14 INFO - 'tabpaint']}, 03:21:14 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:14 INFO - 'ts_paint', 03:21:14 INFO - 'tpaint', 03:21:14 INFO - 'sessionrestore', 03:21:14 INFO - 'sessionrestore_no_auto_restore', 03:21:14 INFO - 'tabpaint']}, 03:21:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:14 INFO - 'ts_paint', 03:21:14 INFO - 'tpaint', 03:21:14 INFO - 'sessionrestore', 03:21:14 INFO - 'sessionrestore_no_auto_restore', 03:21:14 INFO - 'tabpaint']}, 03:21:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:14 INFO - 'ts_paint', 03:21:14 INFO - 'tpaint', 03:21:14 INFO - 'sessionrestore', 03:21:14 INFO - 'sessionrestore_no_auto_restore', 03:21:14 INFO - 'tabpaint']}, 03:21:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['a11yr', 03:21:14 INFO - 'ts_paint', 03:21:14 INFO - 'tpaint', 03:21:14 INFO - 'sessionrestore', 03:21:14 INFO - 'sessionrestore_no_auto_restore', 03:21:14 INFO - 'tabpaint']}, 03:21:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['a11yr', 03:21:14 INFO - 'ts_paint', 03:21:14 INFO - 'tpaint', 03:21:14 INFO - 'sessionrestore', 03:21:14 INFO - 'sessionrestore_no_auto_restore', 03:21:14 INFO - 'tabpaint']}, 03:21:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['tsvgx', 03:21:14 INFO - 'tsvgr_opacity', 03:21:14 INFO - 'tart', 03:21:14 INFO - 'tscrollx', 03:21:14 INFO - 'cart']}, 03:21:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:14 INFO - 'tsvgr_opacity', 03:21:14 INFO - 'tart', 03:21:14 INFO - 'tscrollx', 03:21:14 INFO - 'cart']}, 03:21:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:14 INFO - 'talos_options': ['--disable-e10s'], 03:21:14 INFO - 'tests': ['tp5o']}, 03:21:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:14 INFO - 'tests': ['tp5o']}, 03:21:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:14 INFO - 'talos_options': ['--disable-e10s', 03:21:14 INFO - '--xperf_path', 03:21:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:14 INFO - 'tests': ['tp5n']}, 03:21:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:14 INFO - 'talos_options': ['--xperf_path', 03:21:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:14 INFO - 'tests': ['tp5n']}}, 03:21:14 INFO - 'talos.zip': {'path': '', 03:21:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:14 INFO - Running post-action listener: _resource_record_post_action 03:21:14 INFO - [mozharness: 2017-01-13 11:21:14.688944Z] Finished populate-webroot step (success) 03:21:14 INFO - [mozharness: 2017-01-13 11:21:14.689035Z] Running create-virtualenv step. 03:21:14 INFO - Running pre-action listener: _resource_record_pre_action 03:21:14 INFO - Running main action method: create_virtualenv 03:21:14 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:14 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 03:21:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:14 INFO - Using real prefix '/tools/python27' 03:21:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:15 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:18 INFO - Installing pip.................done. 03:21:18 INFO - Return code: 0 03:21:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:21:18 INFO - Reading from file tmpfile_stdout 03:21:18 INFO - Output received: 03:21:18 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) 03:21:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:18 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')]} 03:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:18 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')]} 03:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:18 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 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:18 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 03:21:18 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 03:21:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:18 INFO - 'HOME': '/Users/cltbld', 03:21:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:18 INFO - 'LOGNAME': 'cltbld', 03:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:18 INFO - 'MOZ_NO_REMOTE': '1', 03:21:18 INFO - 'NO_EM_RESTART': '1', 03:21:18 INFO - 'PAGER': '/bin/cat', 03:21:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:18 INFO - 'PWD': '/builds/slave/test', 03:21:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:18 INFO - 'SHELL': '/bin/bash', 03:21:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:18 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:18 INFO - 'USER': 'cltbld', 03:21:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:18 INFO - 'XPC_FLAGS': '0x0', 03:21:18 INFO - 'XPC_SERVICE_NAME': '0', 03:21:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:18 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 03:21:18 INFO - Cleaning up... 03:21:18 INFO - Return code: 0 03:21:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:18 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')]} 03:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:18 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')]} 03:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:18 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 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:18 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 03:21:18 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 03:21:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:18 INFO - 'HOME': '/Users/cltbld', 03:21:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:18 INFO - 'LOGNAME': 'cltbld', 03:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:18 INFO - 'MOZ_NO_REMOTE': '1', 03:21:18 INFO - 'NO_EM_RESTART': '1', 03:21:18 INFO - 'PAGER': '/bin/cat', 03:21:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:18 INFO - 'PWD': '/builds/slave/test', 03:21:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:18 INFO - 'SHELL': '/bin/bash', 03:21:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:18 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:18 INFO - 'USER': 'cltbld', 03:21:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:18 INFO - 'XPC_FLAGS': '0x0', 03:21:18 INFO - 'XPC_SERVICE_NAME': '0', 03:21:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:19 INFO - Downloading/unpacking psutil>=3.1.1 03:21:19 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 03:21:19 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 03:21:19 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 03:21:19 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 03:21:19 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 03:21:19 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 03:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:22 INFO - Installing collected packages: psutil 03:21:22 INFO - Running setup.py install for psutil 03:21:22 INFO - building 'psutil._psutil_osx' extension 03:21:22 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 03:21:23 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 03:21:23 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 03:21:23 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 03:21:23 INFO - building 'psutil._psutil_posix' extension 03:21:23 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 03:21:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:23 INFO - ^ 03:21:23 INFO - 1 warning generated. 03:21:23 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 03:21:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:23 INFO - Successfully installed psutil 03:21:23 INFO - Cleaning up... 03:21:24 INFO - Return code: 0 03:21:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:21:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:24 INFO - 'HOME': '/Users/cltbld', 03:21:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:24 INFO - 'LOGNAME': 'cltbld', 03:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:24 INFO - 'MOZ_NO_REMOTE': '1', 03:21:24 INFO - 'NO_EM_RESTART': '1', 03:21:24 INFO - 'PAGER': '/bin/cat', 03:21:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:24 INFO - 'PWD': '/builds/slave/test', 03:21:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:24 INFO - 'SHELL': '/bin/bash', 03:21:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:24 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:24 INFO - 'USER': 'cltbld', 03:21:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:24 INFO - 'XPC_FLAGS': '0x0', 03:21:24 INFO - 'XPC_SERVICE_NAME': '0', 03:21:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:21:27 INFO - Installing collected packages: mozsystemmonitor 03:21:27 INFO - Running setup.py install for mozsystemmonitor 03:21:27 INFO - Successfully installed mozsystemmonitor 03:21:27 INFO - Cleaning up... 03:21:27 INFO - Return code: 0 03:21:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:27 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 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:27 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 03:21:27 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 03:21:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:27 INFO - 'HOME': '/Users/cltbld', 03:21:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:27 INFO - 'LOGNAME': 'cltbld', 03:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:27 INFO - 'MOZ_NO_REMOTE': '1', 03:21:27 INFO - 'NO_EM_RESTART': '1', 03:21:27 INFO - 'PAGER': '/bin/cat', 03:21:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:27 INFO - 'PWD': '/builds/slave/test', 03:21:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:27 INFO - 'SHELL': '/bin/bash', 03:21:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:27 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:27 INFO - 'USER': 'cltbld', 03:21:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:27 INFO - 'XPC_FLAGS': '0x0', 03:21:27 INFO - 'XPC_SERVICE_NAME': '0', 03:21:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:27 INFO - Downloading/unpacking jsonschema==2.5.1 03:21:27 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 03:21:27 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 03:21:27 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 03:21:27 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 03:21:27 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 03:21:27 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 03:21:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:21:30 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 03:21:30 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 03:21:30 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 03:21:30 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 03:21:30 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 03:21:30 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 03:21:30 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:21:30 INFO - warning: no files found matching '*.txt' 03:21:30 INFO - no previously-included directories found matching 'build' 03:21:30 INFO - no previously-included directories found matching 'dist' 03:21:30 INFO - no previously-included directories found matching '.git*' 03:21:30 INFO - Installing collected packages: jsonschema, functools32 03:21:30 INFO - Running setup.py install for functools32 03:21:30 INFO - warning: no files found matching '*.txt' 03:21:30 INFO - no previously-included directories found matching 'build' 03:21:30 INFO - no previously-included directories found matching 'dist' 03:21:30 INFO - no previously-included directories found matching '.git*' 03:21:30 INFO - Successfully installed jsonschema functools32 03:21:30 INFO - Cleaning up... 03:21:30 INFO - Return code: 0 03:21:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:21:30 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')]} 03:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:30 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')]} 03:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:30 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 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:30 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 03:21:30 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 03:21:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:30 INFO - 'HOME': '/Users/cltbld', 03:21:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:30 INFO - 'LOGNAME': 'cltbld', 03:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:30 INFO - 'MOZ_NO_REMOTE': '1', 03:21:30 INFO - 'NO_EM_RESTART': '1', 03:21:30 INFO - 'PAGER': '/bin/cat', 03:21:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:30 INFO - 'PWD': '/builds/slave/test', 03:21:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:30 INFO - 'SHELL': '/bin/bash', 03:21:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:30 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:30 INFO - 'USER': 'cltbld', 03:21:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:30 INFO - 'XPC_FLAGS': '0x0', 03:21:30 INFO - 'XPC_SERVICE_NAME': '0', 03:21:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:21:31 INFO - Cleaning up... 03:21:31 INFO - Return code: 0 03:21:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:31 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')]} 03:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:31 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')]} 03:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:31 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 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:31 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 03:21:31 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 03:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:31 INFO - 'HOME': '/Users/cltbld', 03:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:31 INFO - 'LOGNAME': 'cltbld', 03:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:31 INFO - 'MOZ_NO_REMOTE': '1', 03:21:31 INFO - 'NO_EM_RESTART': '1', 03:21:31 INFO - 'PAGER': '/bin/cat', 03:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:31 INFO - 'PWD': '/builds/slave/test', 03:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:31 INFO - 'SHELL': '/bin/bash', 03:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:31 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:31 INFO - 'USER': 'cltbld', 03:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:31 INFO - 'XPC_FLAGS': '0x0', 03:21:31 INFO - 'XPC_SERVICE_NAME': '0', 03:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:31 INFO - Downloading/unpacking blobuploader==1.2.4 03:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:34 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21:35 INFO - Downloading docopt-0.6.1.tar.gz 03:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:35 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:35 INFO - Running setup.py install for blobuploader 03:21:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:35 INFO - Running setup.py install for requests 03:21:36 INFO - Running setup.py install for docopt 03:21:36 INFO - Successfully installed blobuploader requests docopt 03:21:36 INFO - Cleaning up... 03:21:36 INFO - Return code: 0 03:21:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21: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')]} 03:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21: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')]} 03:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:36 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 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:36 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 03:21:36 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 03:21:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:36 INFO - 'HOME': '/Users/cltbld', 03:21:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:36 INFO - 'LOGNAME': 'cltbld', 03:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:36 INFO - 'MOZ_NO_REMOTE': '1', 03:21:36 INFO - 'NO_EM_RESTART': '1', 03:21:36 INFO - 'PAGER': '/bin/cat', 03:21:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:36 INFO - 'PWD': '/builds/slave/test', 03:21:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:36 INFO - 'SHELL': '/bin/bash', 03:21:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:36 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:36 INFO - 'USER': 'cltbld', 03:21:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:36 INFO - 'XPC_FLAGS': '0x0', 03:21:36 INFO - 'XPC_SERVICE_NAME': '0', 03:21:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:36 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-BKMOzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:36 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Z29Iyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:36 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-yCxKAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:36 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-ZuqSiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:36 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-IWC7eB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-JB9MV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-tb5n5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-2pkOYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-POlecU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-EnATZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-CQpxEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-E5Q1fK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-MXkSjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-ZRPAba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:37 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-fiBsnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:38 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-zBnUbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:38 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-N0XQpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:21:38 INFO - Running setup.py install for manifestparser 03:21:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Running setup.py install for mozcrash 03:21:38 INFO - Running setup.py install for mozdebug 03:21:38 INFO - Running setup.py install for mozdevice 03:21:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Running setup.py install for mozfile 03:21:39 INFO - Running setup.py install for mozhttpd 03:21:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Running setup.py install for mozinfo 03:21:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Running setup.py install for mozInstall 03:21:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Running setup.py install for mozleak 03:21:39 INFO - Running setup.py install for mozlog 03:21:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Running setup.py install for moznetwork 03:21:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Running setup.py install for mozprocess 03:21:40 INFO - Running setup.py install for mozprofile 03:21:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:21:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:21:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:21:40 INFO - Running setup.py install for mozrunner 03:21:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:21:40 INFO - Running setup.py install for mozscreenshot 03:21:40 INFO - Running setup.py install for moztest 03:21:40 INFO - Running setup.py install for mozversion 03:21:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:21:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:21:40 INFO - Cleaning up... 03:21:40 INFO - Return code: 0 03:21:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:41 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 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:41 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 03:21:41 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 03:21:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:41 INFO - 'HOME': '/Users/cltbld', 03:21:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:41 INFO - 'LOGNAME': 'cltbld', 03:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:41 INFO - 'MOZ_NO_REMOTE': '1', 03:21:41 INFO - 'NO_EM_RESTART': '1', 03:21:41 INFO - 'PAGER': '/bin/cat', 03:21:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:41 INFO - 'PWD': '/builds/slave/test', 03:21:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:41 INFO - 'SHELL': '/bin/bash', 03:21:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:41 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:41 INFO - 'USER': 'cltbld', 03:21:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:41 INFO - 'XPC_FLAGS': '0x0', 03:21:41 INFO - 'XPC_SERVICE_NAME': '0', 03:21:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:41 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-n24fFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:41 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)) 03:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:41 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-fp3RuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-oAoap3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:42 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-HTy0m6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:42 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-4Q7cZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:42 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Orr_tB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:42 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-tjgTxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:42 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-xA_bGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:42 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-EV3eTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:42 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-aYOvdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:42 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)) 03:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:42 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-gavKwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-32ePhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:43 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)) 03:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-ikoYr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:43 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)) 03:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-r4eq56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 03:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-ARwznP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:43 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)) 03:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-eaeeDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 03:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-RWKFlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:43 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)) 03:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:21:43 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)) 03:21:43 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)) 03:21:43 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)) 03:21:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:46 INFO - Downloading blessings-1.6.tar.gz 03:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:21:46 INFO - Installing collected packages: blessings 03:21:46 INFO - Running setup.py install for blessings 03:21:46 INFO - Successfully installed blessings 03:21:46 INFO - Cleaning up... 03:21:47 INFO - Return code: 0 03:21:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:21:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:47 INFO - Reading from file tmpfile_stdout 03:21:47 INFO - Current package versions: 03:21:47 INFO - blessings == 1.6 03:21:47 INFO - blobuploader == 1.2.4 03:21:47 INFO - docopt == 0.6.1 03:21:47 INFO - functools32 == 3.2.3-2 03:21:47 INFO - jsonschema == 2.5.1 03:21:47 INFO - manifestparser == 1.1 03:21:47 INFO - mozInstall == 1.12 03:21:47 INFO - mozcrash == 1.0 03:21:47 INFO - mozdebug == 0.1 03:21:47 INFO - mozdevice == 0.48 03:21:47 INFO - mozfile == 1.2 03:21:47 INFO - mozhttpd == 0.7 03:21:47 INFO - mozinfo == 0.9 03:21:47 INFO - mozleak == 0.1 03:21:47 INFO - mozlog == 3.4 03:21:47 INFO - moznetwork == 0.27 03:21:47 INFO - mozprocess == 0.23 03:21:47 INFO - mozprofile == 0.28 03:21:47 INFO - mozrunner == 6.13 03:21:47 INFO - mozscreenshot == 0.1 03:21:47 INFO - mozsystemmonitor == 0.3 03:21:47 INFO - moztest == 0.8 03:21:47 INFO - mozversion == 1.4 03:21:47 INFO - psutil == 3.1.1 03:21:47 INFO - requests == 1.2.3 03:21:47 INFO - wsgiref == 0.1.2 03:21:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:47 INFO - 'HOME': '/Users/cltbld', 03:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:47 INFO - 'LOGNAME': 'cltbld', 03:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:47 INFO - 'MOZ_NO_REMOTE': '1', 03:21:47 INFO - 'NO_EM_RESTART': '1', 03:21:47 INFO - 'PAGER': '/bin/cat', 03:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:47 INFO - 'PWD': '/builds/slave/test', 03:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:47 INFO - 'SHELL': '/bin/bash', 03:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:47 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:47 INFO - 'USER': 'cltbld', 03:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:47 INFO - 'XPC_FLAGS': '0x0', 03:21:47 INFO - 'XPC_SERVICE_NAME': '0', 03:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 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)) 03:21:47 INFO - Cleaning up... 03:21:47 INFO - Return code: 0 03:21:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:47 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 0x104ab4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc6acba2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, '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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 03:21:47 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 03:21:47 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 03:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:47 INFO - 'HOME': '/Users/cltbld', 03:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:47 INFO - 'LOGNAME': 'cltbld', 03:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:47 INFO - 'MOZ_NO_REMOTE': '1', 03:21:47 INFO - 'NO_EM_RESTART': '1', 03:21:47 INFO - 'PAGER': '/bin/cat', 03:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:47 INFO - 'PWD': '/builds/slave/test', 03:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:47 INFO - 'SHELL': '/bin/bash', 03:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:21:47 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:47 INFO - 'USER': 'cltbld', 03:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:47 INFO - 'XPC_FLAGS': '0x0', 03:21:47 INFO - 'XPC_SERVICE_NAME': '0', 03:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:21:47 INFO - Cleaning up... 03:21:47 INFO - Return code: 0 03:21:47 INFO - Running post-action listener: _resource_record_post_action 03:21:47 INFO - Running post-action listener: _start_resource_monitoring 03:21:47 INFO - Starting resource monitoring. 03:21:47 INFO - [mozharness: 2017-01-13 11:21:47.879762Z] Finished create-virtualenv step (success) 03:21:47 INFO - [mozharness: 2017-01-13 11:21:47.880769Z] Running install step. 03:21:47 INFO - Running pre-action listener: _resource_record_pre_action 03:21:47 INFO - Running main action method: install 03:21:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:48 INFO - Reading from file tmpfile_stdout 03:21:48 INFO - Detecting whether we're running mozinstall >=1.0... 03:21:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:21:48 INFO - Reading from file tmpfile_stdout 03:21:48 INFO - Output received: 03:21:48 INFO - Usage: mozinstall [options] installer 03:21:48 INFO - Options: 03:21:48 INFO - -h, --help show this help message and exit 03:21:48 INFO - -d DEST, --destination=DEST 03:21:48 INFO - Directory to install application into. [default: 03:21:48 INFO - "/builds/slave/test"] 03:21:48 INFO - --app=APP Application being installed. [default: firefox] 03:21:48 INFO - mkdir: /builds/slave/test/build/application 03:21:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:22:19 INFO - Reading from file tmpfile_stdout 03:22:19 INFO - Output received: 03:22:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:22:19 INFO - Running post-action listener: _resource_record_post_action 03:22:19 INFO - [mozharness: 2017-01-13 11:22:19.075929Z] Finished install step (success) 03:22:19 INFO - [mozharness: 2017-01-13 11:22:19.076084Z] Running run-tests step. 03:22:19 INFO - Running pre-action listener: _resource_record_pre_action 03:22:19 INFO - Running main action method: run_tests 03:22:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:19 INFO - 2017-01-13 03:22:19.090 screenresolution[1623:7292] starting screenresolution argv=screenresolution get 03:22:19 INFO - 2017-01-13 03:22:19.093 screenresolution[1623:7292] Display 0: 1600x1200x32@60 03:22:19 INFO - 2017-01-13 03:22:19.099 screenresolution[1624:7294] starting screenresolution argv=screenresolution list 03:22:19 INFO - Available Modes on Display 0 03:22:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:19 INFO - Intel Iris: 03:22:19 INFO - Chipset Model: Intel Iris 03:22:19 INFO - Type: GPU 03:22:19 INFO - Bus: Built-In 03:22:19 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:19 INFO - Vendor: Intel (0x8086) 03:22:19 INFO - Device ID: 0x0a2e 03:22:19 INFO - Revision ID: 0x0009 03:22:19 INFO - Displays: 03:22:19 INFO - Display: 03:22:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:19 INFO - Display Serial Number: 1600x1200 60 03:22:19 INFO - Main Display: Yes 03:22:19 INFO - Mirror: Off 03:22:19 INFO - Online: Yes 03:22:19 INFO - Rotation: Supported 03:22:19 INFO - Return code: 0 03:22:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:19 INFO - Python 2.7.3 03:22:19 INFO - Return code: 0 03:22:19 INFO - grabbing minidump binary from tooltool 03:22:19 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')]} 03:22:19 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 0x1048c8d30>, '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 0x10472edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048c0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:19 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 03:22:19 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 03:22:19 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 03:22:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:19 INFO - Return code: 0 03:22:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0152 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vC5SQ4k3eq/Render', 03:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:19 INFO - 'HOME': '/Users/cltbld', 03:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:19 INFO - 'LOGNAME': 'cltbld', 03:22:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:19 INFO - 'MOZ_NO_REMOTE': '1', 03:22:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:19 INFO - 'NO_EM_RESTART': '1', 03:22:19 INFO - 'PAGER': '/bin/cat', 03:22:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:19 INFO - 'PWD': '/builds/slave/test', 03:22:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:19 INFO - 'SHELL': '/bin/bash', 03:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8bahV4eK6d/Listeners', 03:22:19 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 03:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:19 INFO - 'USER': 'cltbld', 03:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:19 INFO - 'XPC_FLAGS': '0x0', 03:22:19 INFO - 'XPC_SERVICE_NAME': '0', 03:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:22:19 INFO - mozversion application_buildid: 20170113004016 03:22:19 INFO - mozversion application_changeset: 1e322cd9c7410917d4c5f1fa5b2cd78eb4a79ec6 03:22:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:22:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:19 INFO - mozversion application_name: Firefox 03:22:19 INFO - mozversion application_remotingname: firefox-dev 03:22:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:19 INFO - mozversion application_vendor: Mozilla 03:22:19 INFO - mozversion application_version: 52.0a2 03:22:19 INFO - mozversion platform_buildid: 20170113004016 03:22:19 INFO - mozversion platform_changeset: 1e322cd9c7410917d4c5f1fa5b2cd78eb4a79ec6 03:22:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:19 INFO - mozversion platform_version: 52.0a2 03:22:19 INFO - using testdate: 1484306539 03:22:19 INFO - actual date: 1484306539 03:22:19 INFO - starting webserver on 'localhost:49237' 03:22:19 INFO - SUITE-START | Running 2 tests 03:22:19 INFO - TEST-START | dromaeo_css 03:22:19 INFO - operating with platform_type : mac_ 03:22:19 INFO - Initialising browser for dromaeo_css test... 03:22:19 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmp6OIxVi/profile http://localhost:49237/getInfo.html) 03:22:22 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 03:22:22 INFO - PROCESS | 1634 | colorDepth:24 03:22:22 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 03:22:22 INFO - PROCESS | 1634 | __metrics 03:22:22 INFO - PROCESS | 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:22:22 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:22:23 INFO - TEST-INFO | 1634: exit 0 03:22:23 INFO - Browser initialized. 03:22:23 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:23 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmp6OIxVi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:35 INFO - PROCESS | 1637 | RSS: Main: 222134272 03:22:35 INFO - PROCESS | 1637 | 03:25:38 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 03:25:40 INFO - PROCESS | 1637 | RSS: Main: 376295424 03:25:40 INFO - PROCESS | 1637 | 03:28:43 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 03:28:44 INFO - PROCESS | 1637 | RSS: Main: 348336128 03:28:44 INFO - PROCESS | 1637 | 03:31:46 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 03:31:47 INFO - PROCESS | 1637 | RSS: Main: 317493248 03:31:47 INFO - PROCESS | 1637 | 03:34:49 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 03:34:50 INFO - PROCESS | 1637 | RSS: Main: 319832064 03:34:50 INFO - PROCESS | 1637 | 03:37:53 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 03:37:54 INFO - PROCESS | 1637 | RSS: Main: 311697408 03:37:54 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 03:40:57 INFO - PROCESS | 1637 | RSS: Main: 358567936 03:40:57 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | __start_tp_report 03:40:57 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 03:40:57 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 03:40:57 INFO - PROCESS | 1637 | |i|pagename|runs| 03:40:57 INFO - PROCESS | 1637 | |0;dojo.html;2749.9175024749256;3294.6985940553473;3318.1041118897897;3333.2833440810223;3378.239896023395;5544.306961629796;5619.915701264485;5646.774129034839;5654.858628534278;5661.8584535386635;5552.7223638817995;5605.5515558755305;5636.605437619368;5664.461876121762;5726.541876649869;5091.490850914916;5127.051495473331;5142.10013247682;5184.974075129619;5237.083510385686;5094.541491265785;5106.183010718286;5106.923396149061;5203.739813009353;5214.713190774506;5109.412417571971;5137.5633071188995;5166.586673066165;5178.974105129488;5209.869753256161;5182.533571978521;5193.480651934777;5203.9219411708855;5225.6603320784025;5228.241904923802;5111.543210185117;5160.993606246783;5184.688918664892;5185.55922746567;5199.090159222121;5503.284573005523;5525.392206857243;5541.0580201365465;5561.694106824123;5604.943950560483;5911.29064512257;5911.9113213301835;5929.792457264019;5934.762609495659;5944.554158438091;6102.72537735803;6106.419890110433;6110.419510146529;6112.816615501542;6126.724297406628;5590.496855283003;5610.971945140248;5641.689707066069;5666.829995100071;5714.571407144481;6284.811455656338;6290.24517057956;6299.212598425197;6300.905486417708;6304.275008374096;5997.460228579449;6006.429389208019;6045.516358691294;6060.606060606046;6064.514838812885;5099.923501147486;6054.182685337511;6115.571909966348;6128.988716861757;6140.600860944113;5042.218456139304;6115.111496210469;6149.477294429934;6152.446279834837;6166.105914642351;6124.571280010446;6181.629102253879;6181.690915454209;6204.348543402968;6208.9689551551955;4810.975945120322;4862.416510018749;4871.537203965618;4895.779689913962;4909.2881532177635;4349.325854492558;4374.300111982067;4381.583749543788;4393;4444.799984000728;5713.5714821388565;5720.370759216483;5724.227229324071;5727.828165155052;5733.197352370671;6093.086037094383;6238;6249.562530622813;6267.843303917347;6270.404311590391;5582.86042848932;5660.320761508563;5661.886762264731;5698.772049117989;5704.486596206361;5305.602079844027;5328.733563321818;5349.090654588729;5363.865903352447;5379.757910894019;6204.193454850841;6261.342559031327;6324.367563243731;6333.334999824952;6356.396142366468;5255.817441075727;6088.5737998341465;6113.719560719409;6130.887362651087;6248.343923887926;6296.968515157395;6316.147320111726;6324;6329.398707122816;6358.77744278948;6093.268807743099;6093.542984276109;6102.755889764538;6103.938960610337;6108.633481991094;6054.7275372607455;6128.724207410766;6168.691565421621;6209.813705588839;6213.596116252429;5912.526997840077;5925.466707996301;6013.789517366871;6030.48740857032;6101.420365065312;6030.155778190969;6061.787837425669;6062.120992456157;6064.757409703562;6070.969645151746;5823.650580965155;5888.440598143341;5890.028145356139;5896.764129434775;5898.64608123514;5849.327327357239;5927.229460170151;5930.377310382348;5962.731677074629;5986.341502434641;5857;5875.823725288077;5898.321693005188;5900.291964964232;5936.970315148396 03:40:57 INFO - PROCESS | 1637 | |1;ext.html;512.6129772022123;516.987075323117;527.3592585009213;534.8315280686586;555.0093083845337;29538;30236.790528378868;37974;38938;39123;40254;40627;40650.78047658568;40709;40782.59217407825;9101.453912765239;9260.861087083666;9273.97986221515;9277.257819374468;9299.209567186797;9254.53727313635;9343.532823358804;9354.251659867194;9359.251259899193;9359.76600584984;9342.159205671489;9398.34211605188;9398.859017114748;9408.952955235249;9423.764405889873;9333.393329433598;9363.0168832273;9374.812503749956;9383.671571494964;9390.295727820441;9229.769255768593;9288.721338359792;9298.953505232499;9303.813923721555;9320.347575669706;38414.23171536554;38698.613013869784;38720;38739.41890871639;38826.417603735965;3199.936001279985;3204;3367.646397128315;3431.7769344992494;3939.5666476687543;26284.474310513684;27693;30412;30548.472576371092;30703.2324191897;3065.2183693158104;3130.2956834712236;3161.96838031619;3169.429502689515;3214.164317277501;477308;479921;481930;482856;490123;32538.511922321184;33116.84091056801;33361.165970850925;58722.41277587169;59157.70421147867;34610.65389346125;34643.960681179604;34779.478307825404;34798.652013480045;34806.47790283148;14163.64590885236;14332.283385830668;14332.355044022836;14335.56993290203;14365.281735913162;12051.819222711667;12170.939145304217;12184.02527797774;12194.268343899394;12211.938940305241;6421.486281097501;7690.500117492457;7766.883496747553;7833.764987050397;7855.5286682799215;3205.6954589313978;3219.5814544109116;3261.4292498812615;3263.118957881406;3292.111129994888;802.1457148137215;871.4422769427572;876.4434584039053;939.4175611121149;972.1056627902346;28174.577381339295;28773.71226287752;28980.130596082152;29555.704442955725;29561;848.985462372459;949.0923244279048;997.8952210017989;1039.9480025998669;1044.8589440425596;14502.85497145015;14912.328945197492;14999.700006000037;15282.312295946487;15388.461403850812;629608;636768;636966;636995;637845;54942;55307.72346138243;55322.61693457696;55338;55420.16869746957;1310.4103153580736;1335.8063080853221;1358.9252591107584;1361.2309045389322;1362.1690768631127;28331.900257953766;28575.571366429103;29013.564796527648;29478.557821633123;29780.255493612833;43679.78160109179;43994;44023.77988110039;44128.33807492955;44151.55848441603;538608;540461;542976;543891;544268;25462.49075018526;25583.872080639478;25932.35169120787;26011;26141.6078758815;44410.777946110065;44784.328235075845;45040.54959450364;45178.54821451743;45531.54468455273;36703.265934681694;36986.44520332143;37694.434583481816;37831;37871;30195.943141989927;36010.73962411408;36141.457878131325;37552.81223593865;38563.807180963915;29891.55162672518;31072.68927310698;31095.222619434695;31800;36895.89312320635;26926;31198;33371;33478.33043339071;33526;24568.877155614107;24628.266204706735;24722.25833225006;24953.251402457954;25398.49203015966 03:40:57 INFO - PROCESS | 1637 | |2;jquery.html;366.30767848765856;372.65529385318575;374.3430279858849;386.7331541236547;404.80771633474086;15385.69228615427;15593.000970704616;17773.111344432775;20128.39614811556;20575.074121664522;15767.60580985476;17188.398406055792;20339.694904576405;20431.18275268991;20702.89648551755;5776.913346299809;6089.81730548082;6105.908411373833;6121.66330851803;6128.816135515942;5750.108733146369;6042.123892035652;6082.878342433149;6104.603200791957;6104.633721976674;4147.983743982713;4391.319345501436;6058.091286307045;6271.717772700239;6558.114654521626;6561.398281940485;6593.901091483632;6642.070110184578;6646.634435106067;6657.167854018272;5649.293838270216;5860.70696465175;5866.090755932837;5870.794522191745;5901.380355062743;24968.876400562018;24976.750232497623;25159.874200629063;25306.1142859999;25359.87320063388;2260.2993072147688;2333.0784340185533;2490.066225165563;2592.844429334246;3043.5130379139455;14667.926660366737;15619;15643.687126257524;15831.60420989473;16001.91999040009;1800.8829426087264;1820.3537744100918;1821.3226556961247;1871;1910.6656335141433;111440.44279778631;115768;127253.00108494029;128532.714672853;135892;9348.672796452092;9745.317827752131;10024.496325551225;10055.446950417798;10174.338667986558;12490.12569120171;12579.685507862194;12595.496180152692;12602.991760659126;12606.432710528048;7638.350740187036;7862.960685196537;7884.448088633855;7949.761507154794;7955.0851652060755;7049.603194212455;8150.592470376458;8154.572949733892;8238.8352232955;8293.95853020731;3871.070942973666;3888.766673999569;3897.376419772879;3927.980360098238;3979.263836190314;1900.6388786130592;1904.352520143158;1905.3521802587186;1906.8283854453166;1945.737325461073;864.6584599083327;907;908.4322298563397;924.2421214604094;931.1107891963159;20016.49958751043;20675.689864652042;20845.478863028242;21094;21373.67939480909;796.653455746752;879.6657270237268;913.1051569461964;935.6491315756591;978.6868202175235;10829;11172.173982553692;11291.830622540669;11458.4270786462;11507.309561426342;561190;562111;565057;565059;565416;124211.75788242182;124272.75727242611;125890;127618.08572871414;133345;887.2103827593447;897.3763234551924;916.8303863785221;919.990800092004;920.4891285336569;14942.477013304482;18418.815811842247;18722.344717934808;18942.147603357607;19007.698802463598;54857.1771423421;57398.42601573931;57712.4228757707;57773;57821.421785781604;76532.23467765251;78210.21789782029;79723;79994.6000269995;80181.59909200417;7895.68417263303;7913.248241417056;8203.876941845996;8231.012278526616;8270.917290827256;6778.762743303961;9528.523573821143;11356.26184298018;12579.496820127093;12893.097483176087;10846.403447810446;10932.672019839418;11078.944605276922;11315.567153062131;11355.772884542097;10178.389296642225;10549.841752373566;10687.412192329497;10957.397343145885;11283.887161128612;9439.339246252697;11139.665810025712;11176.944115279372;11390.974812266935;11543.76912461763;6813.3867951882585;7225.6387180641805;7336.669849856876;8046.114927358103;8218.054923683852;10346.896531034894;10643.361398315816;10685;11064.059554937598;11213.327200368005 03:40:57 INFO - PROCESS | 1637 | |3;mootools.html;1302.2642207152965;1442.5095467541034;1479.7706355514895;1620.8298128696485;1818.2817786974153;7434.739784107557;7827.6477558510005;7829;7840.843183136334;7843.80390490238;7656.88514672279;7986.763706162269;8206.343492520598;8207.466514676542;8311.91688083119;2277.237125562934;2313.699219101523;2313.8495997760174;2315.3979965209082;2318.107528601484;2281.0761641535205;2314.259436980167;2335.614623587106;2338.38032921276;2341.274204996457;2255;2271.568402003614;2305.4121199094207;2308.3305841306;2311.9422014449688;2239.518503521734;2268.092762894839;2291.2209848651537;2292.575873463398;2292.8968196431283;2174.8695078295195;2268.6728263965433;2286.142696488817;2312.063614236237;2313.2366319114653;6814.1482314710665;7987.560684162427;8086.595670216525;8110.107888132301;8319;4866.635002374836;5002.324686167393;5008.073506401327;5014.473480284554;5018.824341148079;2973.2269609901364;3435.8282085895607;3532.682058614737;3533.063738109403;3535.5934067582302;1048.8636477257985;1087.8748943871476;1090.9127269818396;1105.4804242006244;1107.1143085531623;9235.907640923571;9259.30555208362;10937.726556836144;11001.834972475419;11016.559337626486;4395.582419670126;4561.29299958507;4727.83452579165;4747.8575642730775;4752.073345697625;8676.652933882575;8695;8711.651533938699;8715.738527844174;8746.737597871946;5127.154019586801;5130.769115389817;5135.589152867761;5140.717260550706;5150.613703972217;4029.2545879012478;4142.958570414257;4770.809167633325;4774.4509381420885;4808.8797780055775;3009.623797025372;3621.5473065866768;3731.5149030625844;3745.026293163797;3756.2487502499607;1051.7265510967204;1140.942952852354;1147.5696613769837;1147.6786499780076;1153.7173392518887;722.1442590530238;858.9484630922165;868.7524055644237;888.1917455115943;944.622151139536;5561;5729.5702822288495;5811.825645230565;5986.401359864066;6182.258129024546;866.4887716247445;915.1351972386032;921.5346250143634;923.8152369526122;949.943003419797;3245.707886290198;3292.3415316936707;3727.049602351383;3836.827342769582;3891.64975152232;9744.756381090388;10172.237082218864;10300.712410948632;10308.381497110197;10313.329633573794;10251.282410231212;10253.231007674454;10266.486675666187;10267.024632659886;10269.435181065113;7730.806729831799;7912;7913.733802591558;7924.524528528307;7930.167332430127;5427.782888684408;5576.498115169654;5587.664740115606;5781.797637082682;5867.178594996619;9323.906760932576;9415.905840941503;9436.433813971184;9541.618335266512;9612.951935240279;9055.864162037444;9469.431834089977;9498.430094194371;9544.141027307576;9611.615535378507;7152.392046676091;7378.188399275968;7384.741534046282;7771.261730135628;7944.602769861599;7519.360854327443;7589.810254743676;7596.202398748273;7704.26809453101;7940.92059079425;7056.964715176391;7227.891581626174;7272.381847543018;7315.451341149329;7398.704051838082;6863.759768408082;7019.262977387505;7034.437245020284;7128.073350464407;7184.856302874018;6631.204255489372;6657.06801047844;6744.932550674431;6757.93242067573;6760.08738820253;4774.1883879739835;4777.4505931818285;4810.56704896547;4847.61219102478;4871.391076115486;4696.2486002239475;4714.57568818808;4738.715677059387;4885.193942999365;4977 03:40:57 INFO - PROCESS | 1637 | |4;prototype.html;300.18649459963495;309.5774268124011;310.5931230088584;317.21172885379826;319.7665704036052;4784.44978827435;5126.359205099363;5132.512411320928;5153.252778347147;5153.3558305211845;3764.303603833293;4132.462779838625;5043.839916819134;5143.305653736745;5186.948130518694;3722.32998060349;3857.903552411184;3890.2219556088753;3893.201893611817;3913.119548101683;3564.6078931317666;3601.243738814852;3890.9805450972704;3896.4934558507352;3926.371780515118;3879.02169893355;3889.241597888413;3898.8050597470155;3918.7271382598024;3923.7449565778275;3736.3648179809225;3899;3908.1988192420627;3916.1971795781797;3972.463717398172;3240.643529211785;3300.7359411247044;3316.2538428853313;3370.460726283807;3454.7408944329277;4885.7312847793355;4912.733032533397;4925.458199598042;5005.22419025048;5006.274090256892;4438.223310920576;4464.665150113722;4468.396766436522;4478.955210447886;4487.371767952489;4630.305454181866;5233.685978841244;5286.207068939651;5354.678719276856;5393.406725260218;3125.249940014403;3716.8699095531797;3747.306748251582;3761.887143385703;3766.3597188478025;5098.337216161875;5124.359455068116;5599.8880022399735;5618.325800903917;5647.43525647436;2876.2090425133256;3468.063622821824;3555.826577229508;3784.905377365533;3807.6573108420375;3772.188979369394;3798.639129282769;3852.7110466715108;3875.2636998970106;3888.863889763901;4155.833766649361;4387.3328135308375;4481.3195051855555;4513.81944722214;4529.614982726439;5447.754851031713;5454.400015998237;5480.561555075585;5488.56091512678;5492.588055895823;1128.2666266926399;1154.5554961339944;1175.3476820364779;1196.7127889306503;1235.7025123620342;827.755812035451;836.3016880904513;851.9606080581681;855.5754668476916;866.7518772966907;4108.4453598764385;4373.1472362888935;4410.316400957857;4457.64338852891;4826.420829500483;4410.213863385334;4741.243727688031;5220.9638241086;5304.655197412156;5313.57491400687;4113.449661210069;4807.7836497357;4823.3729615150505;4833.540813622701;4842.951570484321;2392;2624.973750262473;2798.734120258572;2808.3711447360483;2825.5055365310986;4672.976635116803;4731.361266229083;5520.585956053232;5595.524380427628;5635.774569017275;5368;5583.972080139573;5646.435356464321;5669.092945128759;5717.799877004285;4160.771157586301;4704.83577554322;4900.422691435272;5305.9204111939125;5395.460453954652;4444.555544445529;4482.394876691736;4850.975745121393;5622.156676498558;5627.577931655061;4578.427696537933;4674.719516829001;5344.866378340573;5550.084236100998;5575.972120139373;4684.906301874012;5155.922661160011;5233.058049550965;5249.955026084938;5821.126830975388;3117;3439.5872495300723;3763.1909139535132;3792.241551689728;3792.5259342582176;5607.5513958884;5614.568285087276;5624.803131890364;5653.773849045992;5727.828165155052;4144.772037537964;4286.635635970977;4543.977280113579;4870.9269360960325;5452.26394436746;4342.69601127918;4595.93106103402;5271.78912843482;5447.984245829069;5475.4250803665045;4474.597286244254;4599.6550258731395;5118.749594007906;5305.787768489217;5306.8407947761625;4794.664373493962;5159.845204643867;5388.568914486753;5429.565634749131;5516.9448305518035;4588.0365123323145;4725.50382209863;4904.5340692634145;5253.684778913277;5391.514763671288 03:40:57 INFO - PROCESS | 1637 | |5;yui.html;688.5868478912653;690.0649619765214;696.3767428151805;746.727444482764;751.781983224865;12504.624861254155;13938.44246230151;14351.138931664082;14645.999999999987;14655.560333189995;11808.704782380444;13150.736985260313;13178.934105329485;13266.005049621268;13274.402651880664;653.7809833705702;673.5083389125941;795.8686816675241;818.3943881527656;823.9464434811719;817.6443247187492;818.071488860142;823.5017814222399;824.4146655874337;824.5877061469265;865.3466632692308;865.6753717355991;866.7356456280831;866.9783255418633;869.1048220333067;695.085961960021;719.3022767915114;819.8975128108987;840.6973489543759;856.1395797223781;634.0204384226381;634.1724050114599;636.0681601453846;636.9299377068519;638.4828289085856;13549.187048777007;13612.31938403076;13618.250996195304;13651.522196723066;13685.178889266675;12787.232766033974;12995.545155919497;13019.349032548429;13154.60536183916;13163.868361316456;11361.943190284079;11676.1826672133;11748.060155187564;12100.576479823163;12183.208091474025;4282.164977829325;4359.324304732771;4427.822887084512;4491.640668746492;4501.954980450187;83242.58378708128;84199;87316.12683873207;87553;88606;10292.330998485075;10388.37669739818;10424.739381515523;10453.84319235212;10474.895251047392;16563.751543726852;19878.801211987986;19915.302964396466;20100.19599216015;20122.798772012386;7245.492815502864;7352.301531354513;7462.664180111908;7674.232576742281;7695.730649427243;4160.43834082395;4207.431996680409;4278.593533614302;4320.027993701392;4391.385206071152;3208.903732888017;3252.788568743024;3298.274379636476;3301.092199645117;3314.834258287076;621.7450845153465;624.9125122482856;631.1447987976318;635.0537698828804;637.7162162837585;541.6831153775007;552.9751161197755;566.8554518597731;567.7899177304424;568.1279236372148;8119.878201826978;8583.313334933191;8650;8762.992255890527;8860.955695221483;585.6398315036307;601.3024891126272;604.2899592978321;608.4675908579992;621.1521273461692;4161.459010328638;4250.447441832542;4299.247631664446;4374.868753937387;4818.807247710122;105043;108304;110740;111658;119309;97998;99370;102081;105207.94792051981;106337;88841;91014.17971640397;91809.54095229482;92414.99999999866;94057;7743.419243556868;9144.954275228582;9590.616375344907;9638.180754635934;9644.75888102775;43067.353989690804;55052;56047.879042419736;57371.56571085756;57823.13265301108;66697.33302666912;67073.66463167653;80329;81674.59162704149;85245.1475485262;9691.321607487409;11904.28574285511;12056.939715301367;12237.326947018;12446.751064978831;11506.94246528762;12804;13796.655083622589;14482.203478808346;14495.710085798435;12935.02987275977;13131.277779722206;13163.078584498993;13169.209847409185;13209.207447553177;12781.424835882222;12890.613281601567;12920.741585168433;12940.352982351033;13386.933065334611;12148.452421703065;12192.268463892195;12307.076969227164;12404.937975310066;12469.75060498803;10743.623973161215;10882.401467919024;10908.563657453931;11361.886381136082;11539.8269025963;10877.401742904216;10960.397178154959;11171.106311494897;11205.551777928791;11208.607698730506 03:40:57 INFO - PROCESS | 1637 | __end_tp_report 03:40:57 INFO - PROCESS | 1637 | __start_cc_report 03:40:57 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1825 03:40:57 INFO - PROCESS | 1637 | __end_cc_report 03:40:57 INFO - PROCESS | 1637 | __startTimestamp1484307657503__endTimestamp 03:40:57 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | ------- Summary: start ------- 03:40:57 INFO - PROCESS | 1637 | Number of tests: 6 03:40:57 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5657.75 Median:5888.44 stddev:641.72 (10.9%) stddev-sans-first:601.78 03:40:57 INFO - PROCESS | 1637 | Values: 2749.9 3294.7 3318.1 3333.3 3378.2 5544.3 5619.9 5646.8 5654.9 5661.9 5552.7 5605.6 5636.6 5664.5 5726.5 5091.5 5127.1 5142.1 5185.0 5237.1 5094.5 5106.2 5106.9 5203.7 5214.7 5109.4 5137.6 5166.6 5179.0 5209.9 5182.5 5193.5 5203.9 5225.7 5228.2 5111.5 5161.0 5184.7 5185.6 5199.1 5503.3 5525.4 5541.1 5561.7 5604.9 5911.3 5911.9 5929.8 5934.8 5944.6 6102.7 6106.4 6110.4 6112.8 6126.7 5590.5 5611.0 5641.7 5666.8 5714.6 6284.8 6290.2 6299.2 6300.9 6304.3 5997.5 6006.4 6045.5 6060.6 6064.5 5099.9 6054.2 6115.6 6129.0 6140.6 5042.2 6115.1 6149.5 6152.4 6166.1 6124.6 6181.6 6181.7 6204.3 6209.0 4811.0 4862.4 4871.5 4895.8 4909.3 4349.3 4374.3 4381.6 4393.0 4444.8 5713.6 5720.4 5724.2 5727.8 5733.2 6093.1 6238.0 6249.6 6267.8 6270.4 5582.9 5660.3 5661.9 5698.8 5704.5 5305.6 5328.7 5349.1 5363.9 5379.8 6204.2 6261.3 6324.4 6333.3 6356.4 5255.8 6088.6 6113.7 6130.9 6248.3 6297.0 6316.1 6324.0 6329.4 6358.8 6093.3 6093.5 6102.8 6103.9 6108.6 6054.7 6128.7 6168.7 6209.8 6213.6 5912.5 5925.5 6013.8 6030.5 6101.4 6030.2 6061.8 6062.1 6064.8 6071.0 5823.7 5888.4 5890.0 5896.8 5898.6 5849.3 5927.2 5930.4 5962.7 5986.3 5857.0 5875.8 5898.3 5900.3 5937.0 03:40:57 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:66136.68 Median:38194.12 stddev:149250.40 (390.8%) stddev-sans-first:149587.76 03:40:57 INFO - PROCESS | 1637 | Values: 512.6 517.0 527.4 534.8 555.0 29538.0 30236.8 37974.0 38938.0 39123.0 40254.0 40627.0 40650.8 40709.0 40782.6 9101.5 9260.9 9274.0 9277.3 9299.2 9254.5 9343.5 9354.3 9359.3 9359.8 9342.2 9398.3 9398.9 9409.0 9423.8 9333.4 9363.0 9374.8 9383.7 9390.3 9229.8 9288.7 9299.0 9303.8 9320.3 38414.2 38698.6 38720.0 38739.4 38826.4 3199.9 3204.0 3367.6 3431.8 3939.6 26284.5 27693.0 30412.0 30548.5 30703.2 3065.2 3130.3 3162.0 3169.4 3214.2 477308.0 479921.0 481930.0 482856.0 490123.0 32538.5 33116.8 33361.2 58722.4 59157.7 34610.7 34644.0 34779.5 34798.7 34806.5 14163.6 14332.3 14332.4 14335.6 14365.3 12051.8 12170.9 12184.0 12194.3 12211.9 6421.5 7690.5 7766.9 7833.8 7855.5 3205.7 3219.6 3261.4 3263.1 3292.1 802.1 871.4 876.4 939.4 972.1 28174.6 28773.7 28980.1 29555.7 29561.0 849.0 949.1 997.9 1039.9 1044.9 14502.9 14912.3 14999.7 15282.3 15388.5 629608.0 636768.0 636966.0 636995.0 637845.0 54942.0 55307.7 55322.6 55338.0 55420.2 1310.4 1335.8 1358.9 1361.2 1362.2 28331.9 28575.6 29013.6 29478.6 29780.3 43679.8 43994.0 44023.8 44128.3 44151.6 538608.0 540461.0 542976.0 543891.0 544268.0 25462.5 25583.9 25932.4 26011.0 26141.6 44410.8 44784.3 45040.5 45178.5 45531.5 36703.3 36986.4 37694.4 37831.0 37871.0 30195.9 36010.7 36141.5 37552.8 38563.8 29891.6 31072.7 31095.2 31800.0 36895.9 26926.0 31198.0 33371.0 33478.3 33526.0 24568.9 24628.3 24722.3 24953.3 25398.5 03:40:57 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:34155.79 Median:369.48 stddev:94639.50 (25614.1%) stddev-sans-first:94870.98 03:40:57 INFO - PROCESS | 1637 | Values: 366.3 372.7 374.3 386.7 404.8 15385.7 15593.0 17773.1 20128.4 20575.1 15767.6 17188.4 20339.7 20431.2 20702.9 5776.9 6089.8 6105.9 6121.7 6128.8 5750.1 6042.1 6082.9 6104.6 6104.6 4148.0 4391.3 6058.1 6271.7 6558.1 6561.4 6593.9 6642.1 6646.6 6657.2 5649.3 5860.7 5866.1 5870.8 5901.4 24968.9 24976.8 25159.9 25306.1 25359.9 2260.3 2333.1 2490.1 2592.8 3043.5 14667.9 15619.0 15643.7 15831.6 16001.9 1800.9 1820.4 1821.3 1871.0 1910.7 111440.4 115768.0 127253.0 128532.7 135892.0 9348.7 9745.3 10024.5 10055.4 10174.3 12490.1 12579.7 12595.5 12603.0 12606.4 7638.4 7863.0 7884.4 7949.8 7955.1 7049.6 8150.6 8154.6 8238.8 8294.0 3871.1 3888.8 3897.4 3928.0 3979.3 1900.6 1904.4 1905.4 1906.8 1945.7 864.7 907.0 908.4 924.2 931.1 20016.5 20675.7 20845.5 21094.0 21373.7 796.7 879.7 913.1 935.6 978.7 10829.0 11172.2 11291.8 11458.4 11507.3 561190.0 562111.0 565057.0 565059.0 565416.0 124211.8 124272.8 125890.0 127618.1 133345.0 887.2 897.4 916.8 920.0 920.5 14942.5 18418.8 18722.3 18942.1 19007.7 54857.2 57398.4 57712.4 57773.0 57821.4 76532.2 78210.2 79723.0 79994.6 80181.6 7895.7 7913.2 8203.9 8231.0 8270.9 6778.8 9528.5 11356.3 12579.5 12893.1 10846.4 10932.7 11078.9 11315.6 11355.8 10178.4 10549.8 10687.4 10957.4 11283.9 9439.3 11139.7 11176.9 11391.0 11543.8 6813.4 7225.6 7336.7 8046.1 8218.1 10346.9 10643.4 10685.0 11064.1 11213.3 03:40:57 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5390.49 Median:5016.65 stddev:2953.49 (58.9%) stddev-sans-first:2945.79 03:40:57 INFO - PROCESS | 1637 | Values: 1302.3 1442.5 1479.8 1620.8 1818.3 7434.7 7827.6 7829.0 7840.8 7843.8 7656.9 7986.8 8206.3 8207.5 8311.9 2277.2 2313.7 2313.8 2315.4 2318.1 2281.1 2314.3 2335.6 2338.4 2341.3 2255.0 2271.6 2305.4 2308.3 2311.9 2239.5 2268.1 2291.2 2292.6 2292.9 2174.9 2268.7 2286.1 2312.1 2313.2 6814.1 7987.6 8086.6 8110.1 8319.0 4866.6 5002.3 5008.1 5014.5 5018.8 2973.2 3435.8 3532.7 3533.1 3535.6 1048.9 1087.9 1090.9 1105.5 1107.1 9235.9 9259.3 10937.7 11001.8 11016.6 4395.6 4561.3 4727.8 4747.9 4752.1 8676.7 8695.0 8711.7 8715.7 8746.7 5127.2 5130.8 5135.6 5140.7 5150.6 4029.3 4143.0 4770.8 4774.5 4808.9 3009.6 3621.5 3731.5 3745.0 3756.2 1051.7 1140.9 1147.6 1147.7 1153.7 722.1 858.9 868.8 888.2 944.6 5561.0 5729.6 5811.8 5986.4 6182.3 866.5 915.1 921.5 923.8 949.9 3245.7 3292.3 3727.0 3836.8 3891.6 9744.8 10172.2 10300.7 10308.4 10313.3 10251.3 10253.2 10266.5 10267.0 10269.4 7730.8 7912.0 7913.7 7924.5 7930.2 5427.8 5576.5 5587.7 5781.8 5867.2 9323.9 9415.9 9436.4 9541.6 9613.0 9055.9 9469.4 9498.4 9544.1 9611.6 7152.4 7378.2 7384.7 7771.3 7944.6 7519.4 7589.8 7596.2 7704.3 7940.9 7057.0 7227.9 7272.4 7315.5 7398.7 6863.8 7019.3 7034.4 7128.1 7184.9 6631.2 6657.1 6744.9 6757.9 6760.1 4774.2 4777.5 4810.6 4847.6 4871.4 4696.2 4714.6 4738.7 4885.2 4977.0 03:40:57 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4269.14 Median:4651.64 stddev:1305.47 (28.1%) stddev-sans-first:1274.69 03:40:57 INFO - PROCESS | 1637 | Values: 300.2 309.6 310.6 317.2 319.8 4784.4 5126.4 5132.5 5153.3 5153.4 3764.3 4132.5 5043.8 5143.3 5186.9 3722.3 3857.9 3890.2 3893.2 3913.1 3564.6 3601.2 3891.0 3896.5 3926.4 3879.0 3889.2 3898.8 3918.7 3923.7 3736.4 3899.0 3908.2 3916.2 3972.5 3240.6 3300.7 3316.3 3370.5 3454.7 4885.7 4912.7 4925.5 5005.2 5006.3 4438.2 4464.7 4468.4 4479.0 4487.4 4630.3 5233.7 5286.2 5354.7 5393.4 3125.2 3716.9 3747.3 3761.9 3766.4 5098.3 5124.4 5599.9 5618.3 5647.4 2876.2 3468.1 3555.8 3784.9 3807.7 3772.2 3798.6 3852.7 3875.3 3888.9 4155.8 4387.3 4481.3 4513.8 4529.6 5447.8 5454.4 5480.6 5488.6 5492.6 1128.3 1154.6 1175.3 1196.7 1235.7 827.8 836.3 852.0 855.6 866.8 4108.4 4373.1 4410.3 4457.6 4826.4 4410.2 4741.2 5221.0 5304.7 5313.6 4113.4 4807.8 4823.4 4833.5 4843.0 2392.0 2625.0 2798.7 2808.4 2825.5 4673.0 4731.4 5520.6 5595.5 5635.8 5368.0 5584.0 5646.4 5669.1 5717.8 4160.8 4704.8 4900.4 5305.9 5395.5 4444.6 4482.4 4851.0 5622.2 5627.6 4578.4 4674.7 5344.9 5550.1 5576.0 4684.9 5155.9 5233.1 5250.0 5821.1 3117.0 3439.6 3763.2 3792.2 3792.5 5607.6 5614.6 5624.8 5653.8 5727.8 4144.8 4286.6 4544.0 4870.9 5452.3 4342.7 4595.9 5271.8 5448.0 5475.4 4474.6 4599.7 5118.7 5305.8 5306.8 4794.7 5159.8 5388.6 5429.6 5516.9 4588.0 4725.5 4904.5 5253.7 5391.5 03:40:57 INFO - PROCESS | 1637 | 03:40:57 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20829.13 Median:4339.68 stddev:31042.73 (715.3%) stddev-sans-first:31092.98 03:40:57 INFO - PROCESS | 1637 | Values: 688.6 690.1 696.4 746.7 751.8 12504.6 13938.4 14351.1 14646.0 14655.6 11808.7 13150.7 13178.9 13266.0 13274.4 653.8 673.5 795.9 818.4 823.9 817.6 818.1 823.5 824.4 824.6 865.3 865.7 866.7 867.0 869.1 695.1 719.3 819.9 840.7 856.1 634.0 634.2 636.1 636.9 638.5 13549.2 13612.3 13618.3 13651.5 13685.2 12787.2 12995.5 13019.3 13154.6 13163.9 11361.9 11676.2 11748.1 12100.6 12183.2 4282.2 4359.3 4427.8 4491.6 4502.0 83242.6 84199.0 87316.1 87553.0 88606.0 10292.3 10388.4 10424.7 10453.8 10474.9 16563.8 19878.8 19915.3 20100.2 20122.8 7245.5 7352.3 7462.7 7674.2 7695.7 4160.4 4207.4 4278.6 4320.0 4391.4 3208.9 3252.8 3298.3 3301.1 3314.8 621.7 624.9 631.1 635.1 637.7 541.7 553.0 566.9 567.8 568.1 8119.9 8583.3 8650.0 8763.0 8861.0 585.6 601.3 604.3 608.5 621.2 4161.5 4250.4 4299.2 4374.9 4818.8 105043.0 108304.0 110740.0 111658.0 119309.0 97998.0 99370.0 102081.0 105207.9 106337.0 88841.0 91014.2 91809.5 92415.0 94057.0 7743.4 9145.0 9590.6 9638.2 9644.8 43067.4 55052.0 56047.9 57371.6 57823.1 66697.3 67073.7 80329.0 81674.6 85245.1 9691.3 11904.3 12056.9 12237.3 12446.8 11506.9 12804.0 13796.7 14482.2 14495.7 12935.0 13131.3 13163.1 13169.2 13209.2 12781.4 12890.6 12920.7 12940.4 13386.9 12148.5 12192.3 12307.1 12404.9 12469.8 10743.6 10882.4 10908.6 11361.9 11539.8 10877.4 10960.4 11171.1 11205.6 11208.6 03:40:57 INFO - PROCESS | 1637 | -------- Summary: end -------- 03:40:57 INFO - PROCESS | 1637 | 03:40:58 INFO - TEST-INFO | 1637: exit 0 03:40:58 INFO - TEST-OK | dromaeo_css | took 1118438ms 03:40:58 INFO - TEST-START | kraken 03:40:58 INFO - operating with platform_type : mac_ 03:40:58 INFO - Initialising browser for kraken test... 03:40:58 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpuyvAPJ/profile http://localhost:49237/getInfo.html) 03:41:00 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 03:41:00 INFO - PROCESS | 1669 | colorDepth:24 03:41:00 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 03:41:00 INFO - PROCESS | 1669 | __metrics 03:41:00 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:41:00 INFO - PROCESS | 1669 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:41:01 INFO - TEST-INFO | 1669: exit 0 03:41:01 INFO - Browser initialized. 03:41:01 INFO - Running cycle 1/1 for kraken test... 03:41:01 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpuyvAPJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:14 INFO - PROCESS | 1671 | RSS: Main: 222449664 03:41:14 INFO - PROCESS | 1671 | 03:41:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:17 INFO - PROCESS | 1671 | RSS: Main: 272093184 03:41:17 INFO - PROCESS | 1671 | 03:41:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 03:41:20 INFO - PROCESS | 1671 | RSS: Main: 284381184 03:41:20 INFO - PROCESS | 1671 | 03:41:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 03:41:24 INFO - PROCESS | 1671 | RSS: Main: 380698624 03:41:24 INFO - PROCESS | 1671 | 03:41:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 03:41:27 INFO - PROCESS | 1671 | RSS: Main: 282865664 03:41:27 INFO - PROCESS | 1671 | 03:41:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:30 INFO - PROCESS | 1671 | RSS: Main: 284753920 03:41:30 INFO - PROCESS | 1671 | 03:41:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:33 INFO - PROCESS | 1671 | RSS: Main: 326270976 03:41:33 INFO - PROCESS | 1671 | 03:41:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:37 INFO - PROCESS | 1671 | RSS: Main: 326594560 03:41:37 INFO - PROCESS | 1671 | 03:41:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 03:41:40 INFO - PROCESS | 1671 | RSS: Main: 323264512 03:41:40 INFO - PROCESS | 1671 | 03:41:41 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:41:42 INFO - PROCESS | 1671 | RSS: Main: 299266048 03:41:42 INFO - PROCESS | 1671 | 03:41:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:41:45 INFO - PROCESS | 1671 | RSS: Main: 300449792 03:41:45 INFO - PROCESS | 1671 | 03:41:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:41:47 INFO - PROCESS | 1671 | RSS: Main: 302845952 03:41:47 INFO - PROCESS | 1671 | 03:41:50 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:41:51 INFO - PROCESS | 1671 | RSS: Main: 319483904 03:41:51 INFO - PROCESS | 1671 | 03:41:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:41:55 INFO - PROCESS | 1671 | RSS: Main: 313335808 03:41:55 INFO - PROCESS | 1671 | 03:41:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 03:41:57 INFO - PROCESS | 1671 | RSS: Main: 357163008 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | __start_tp_report 03:41:57 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 03:41:57 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 03:41:57 INFO - PROCESS | 1671 | |i|pagename|runs| 03:41:57 INFO - PROCESS | 1671 | |0;ai-astar;115;115;114;114;114;114;114;113;112;115 03:41:57 INFO - PROCESS | 1671 | |1;audio-beat-detection;141;110;110;143;125;140;142;112;140;113 03:41:57 INFO - PROCESS | 1671 | |2;audio-dft;182;186;184;185;186;182;183;181;184;184 03:41:57 INFO - PROCESS | 1671 | |3;audio-fft;81;59;60;105;85;81;64;59;83;59 03:41:57 INFO - PROCESS | 1671 | |4;audio-oscillator;82;99;127;100;98;69;88;66;68;90 03:41:57 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;86;85;87;85;87;83;88;84;87;87 03:41:57 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;94;94;94;93;93;94;93;93;101 03:41:57 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;81;80;79;81;84;83;83;84;81 03:41:57 INFO - PROCESS | 1671 | |8;json-parse-financial;54;53;56;56;59;58;57;57;56;57 03:41:57 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;67;70;67;75;70;68;69;69;70;70 03:41:57 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;83;84;83;84;82;83;84;83;83;85 03:41:57 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;139;140;144;156;158;143;143;143;185;136 03:41:57 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;174;229;186;166;165;164;165;164;166;164 03:41:57 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;67;67;77;75;84;68;68;67;67;66 03:41:57 INFO - PROCESS | 1671 | __end_tp_report 03:41:57 INFO - PROCESS | 1671 | __start_cc_report 03:41:57 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1990 03:41:57 INFO - PROCESS | 1671 | __end_cc_report 03:41:57 INFO - PROCESS | 1671 | __startTimestamp1484307717145__endTimestamp 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | ------- Summary: start ------- 03:41:57 INFO - PROCESS | 1671 | Number of tests: 14 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.94 (0.8%) stddev-sans-first:0.93 03:41:57 INFO - PROCESS | 1671 | Values: 115.0 115.0 114.0 114.0 114.0 114.0 114.0 113.0 112.0 115.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:127.60 Median:140.00 stddev:14.96 (10.7%) stddev-sans-first:15.06 03:41:57 INFO - PROCESS | 1671 | Values: 141.0 110.0 110.0 143.0 125.0 140.0 142.0 112.0 140.0 113.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.70 (0.9%) stddev-sans-first:1.69 03:41:57 INFO - PROCESS | 1671 | Values: 182.0 186.0 184.0 185.0 186.0 182.0 183.0 181.0 184.0 184.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:73.60 Median:72.50 stddev:15.74 (21.7%) stddev-sans-first:16.47 03:41:57 INFO - PROCESS | 1671 | Values: 81.0 59.0 60.0 105.0 85.0 81.0 64.0 59.0 83.0 59.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:85.00 stddev:18.76 (22.1%) stddev-sans-first:19.74 03:41:57 INFO - PROCESS | 1671 | Values: 82.0 99.0 127.0 100.0 98.0 69.0 88.0 66.0 68.0 90.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:1.60 (1.8%) stddev-sans-first:1.69 03:41:57 INFO - PROCESS | 1671 | Values: 86.0 85.0 87.0 85.0 87.0 83.0 88.0 84.0 87.0 87.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:93.50 stddev:2.44 (2.6%) stddev-sans-first:2.55 03:41:57 INFO - PROCESS | 1671 | Values: 93.0 94.0 94.0 94.0 93.0 93.0 94.0 93.0 93.0 101.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:83.00 stddev:1.83 (2.2%) stddev-sans-first:1.79 03:41:57 INFO - PROCESS | 1671 | Values: 84.0 81.0 80.0 79.0 81.0 84.0 83.0 83.0 84.0 81.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.77 (3.1%) stddev-sans-first:1.67 03:41:57 INFO - PROCESS | 1671 | Values: 54.0 53.0 56.0 56.0 59.0 58.0 57.0 57.0 56.0 57.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.00 stddev:2.27 (3.2%) stddev-sans-first:2.22 03:41:57 INFO - PROCESS | 1671 | Values: 67.0 70.0 67.0 75.0 70.0 68.0 69.0 69.0 70.0 70.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.50 stddev:0.84 (1.0%) stddev-sans-first:0.88 03:41:57 INFO - PROCESS | 1671 | Values: 83.0 84.0 83.0 84.0 82.0 83.0 84.0 83.0 83.0 85.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:143.50 stddev:14.56 (10.1%) stddev-sans-first:15.01 03:41:57 INFO - PROCESS | 1671 | Values: 139.0 140.0 144.0 156.0 158.0 143.0 143.0 143.0 185.0 136.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:166.00 stddev:20.43 (12.3%) stddev-sans-first:21.67 03:41:57 INFO - PROCESS | 1671 | Values: 174.0 229.0 186.0 166.0 165.0 164.0 165.0 164.0 166.0 164.0 03:41:57 INFO - PROCESS | 1671 | 03:41:57 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:68.00 stddev:6.02 (8.9%) stddev-sans-first:6.24 03:41:57 INFO - PROCESS | 1671 | Values: 67.0 67.0 77.0 75.0 84.0 68.0 68.0 67.0 67.0 66.0 03:41:57 INFO - PROCESS | 1671 | -------- Summary: end -------- 03:41:57 INFO - PROCESS | 1671 | 03:41:58 INFO - TEST-INFO | 1671: exit 0 03:41:58 INFO - TEST-OK | kraken | took 60087ms 03:41:58 INFO - SUITE-END | took 1178s 03:41:58 INFO - Completed test suite (00:19:38) 03:41:58 INFO - javascript benchmark 03:41:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2749.9175024749256, 3294.6985940553473, 3318.1041118897897, 3333.2833440810223, 3378.239896023395, 5544.306961629796, 5619.915701264485, 5646.774129034839, 5654.858628534278, 5661.8584535386635, 5552.7223638817995, 5605.5515558755305, 5636.605437619368, 5664.461876121762, 5726.541876649869, 5091.490850914916, 5127.051495473331, 5142.10013247682, 5184.974075129619, 5237.083510385686, 5094.541491265785, 5106.183010718286, 5106.923396149061, 5203.739813009353, 5214.713190774506, 5109.412417571971, 5137.5633071188995, 5166.586673066165, 5178.974105129488, 5209.869753256161, 5182.533571978521, 5193.480651934777, 5203.9219411708855, 5225.6603320784025, 5228.241904923802, 5111.543210185117, 5160.993606246783, 5184.688918664892, 5185.55922746567, 5199.090159222121, 5503.284573005523, 5525.392206857243, 5541.0580201365465, 5561.694106824123, 5604.943950560483, 5911.29064512257, 5911.9113213301835, 5929.792457264019, 5934.762609495659, 5944.554158438091, 6102.72537735803, 6106.419890110433, 6110.419510146529, 6112.816615501542, 6126.724297406628, 5590.496855283003, 5610.971945140248, 5641.689707066069, 5666.829995100071, 5714.571407144481, 6284.811455656338, 6290.24517057956, 6299.212598425197, 6300.905486417708, 6304.275008374096, 5997.460228579449, 6006.429389208019, 6045.516358691294, 6060.606060606046, 6064.514838812885, 5099.923501147486, 6054.182685337511, 6115.571909966348, 6128.988716861757, 6140.600860944113, 5042.218456139304, 6115.111496210469, 6149.477294429934, 6152.446279834837, 6166.105914642351, 6124.571280010446, 6181.629102253879, 6181.690915454209, 6204.348543402968, 6208.9689551551955, 4810.975945120322, 4862.416510018749, 4871.537203965618, 4895.779689913962, 4909.2881532177635, 4349.325854492558, 4374.300111982067, 4381.583749543788, 4393.0, 4444.799984000728, 5713.5714821388565, 5720.370759216483, 5724.227229324071, 5727.828165155052, 5733.197352370671, 6093.086037094383, 6238.0, 6249.562530622813, 6267.843303917347, 6270.404311590391, 5582.86042848932, 5660.320761508563, 5661.886762264731, 5698.772049117989, 5704.486596206361, 5305.602079844027, 5328.733563321818, 5349.090654588729, 5363.865903352447, 5379.757910894019, 6204.193454850841, 6261.342559031327, 6324.367563243731, 6333.334999824952, 6356.396142366468, 5255.817441075727, 6088.5737998341465, 6113.719560719409, 6130.887362651087, 6248.343923887926, 6296.968515157395, 6316.147320111726, 6324.0, 6329.398707122816, 6358.77744278948, 6093.268807743099, 6093.542984276109, 6102.755889764538, 6103.938960610337, 6108.633481991094, 6054.7275372607455, 6128.724207410766, 6168.691565421621, 6209.813705588839, 6213.596116252429, 5912.526997840077, 5925.466707996301, 6013.789517366871, 6030.48740857032, 6101.420365065312, 6030.155778190969, 6061.787837425669, 6062.120992456157, 6064.757409703562, 6070.969645151746, 5823.650580965155, 5888.440598143341, 5890.028145356139, 5896.764129434775, 5898.64608123514, 5849.327327357239, 5927.229460170151, 5930.377310382348, 5962.731677074629, 5986.341502434641, 5857.0, 5875.823725288077, 5898.321693005188, 5900.291964964232, 5936.970315148396], "value": 5615.762687074085, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.6129772022123, 516.987075323117, 527.3592585009213, 534.8315280686586, 555.0093083845337, 29538.0, 30236.790528378868, 37974.0, 38938.0, 39123.0, 40254.0, 40627.0, 40650.78047658568, 40709.0, 40782.59217407825, 9101.453912765239, 9260.861087083666, 9273.97986221515, 9277.257819374468, 9299.209567186797, 9254.53727313635, 9343.532823358804, 9354.251659867194, 9359.251259899193, 9359.76600584984, 9342.159205671489, 9398.34211605188, 9398.859017114748, 9408.952955235249, 9423.764405889873, 9333.393329433598, 9363.0168832273, 9374.812503749956, 9383.671571494964, 9390.295727820441, 9229.769255768593, 9288.721338359792, 9298.953505232499, 9303.813923721555, 9320.347575669706, 38414.23171536554, 38698.613013869784, 38720.0, 38739.41890871639, 38826.417603735965, 3199.936001279985, 3204.0, 3367.646397128315, 3431.7769344992494, 3939.5666476687543, 26284.474310513684, 27693.0, 30412.0, 30548.472576371092, 30703.2324191897, 3065.2183693158104, 3130.2956834712236, 3161.96838031619, 3169.429502689515, 3214.164317277501, 477308.0, 479921.0, 481930.0, 482856.0, 490123.0, 32538.511922321184, 33116.84091056801, 33361.165970850925, 58722.41277587169, 59157.70421147867, 34610.65389346125, 34643.960681179604, 34779.478307825404, 34798.652013480045, 34806.47790283148, 14163.64590885236, 14332.283385830668, 14332.355044022836, 14335.56993290203, 14365.281735913162, 12051.819222711667, 12170.939145304217, 12184.02527797774, 12194.268343899394, 12211.938940305241, 6421.486281097501, 7690.500117492457, 7766.883496747553, 7833.764987050397, 7855.5286682799215, 3205.6954589313978, 3219.5814544109116, 3261.4292498812615, 3263.118957881406, 3292.111129994888, 802.1457148137215, 871.4422769427572, 876.4434584039053, 939.4175611121149, 972.1056627902346, 28174.577381339295, 28773.71226287752, 28980.130596082152, 29555.704442955725, 29561.0, 848.985462372459, 949.0923244279048, 997.8952210017989, 1039.9480025998669, 1044.8589440425596, 14502.85497145015, 14912.328945197492, 14999.700006000037, 15282.312295946487, 15388.461403850812, 629608.0, 636768.0, 636966.0, 636995.0, 637845.0, 54942.0, 55307.72346138243, 55322.61693457696, 55338.0, 55420.16869746957, 1310.4103153580736, 1335.8063080853221, 1358.9252591107584, 1361.2309045389322, 1362.1690768631127, 28331.900257953766, 28575.571366429103, 29013.564796527648, 29478.557821633123, 29780.255493612833, 43679.78160109179, 43994.0, 44023.77988110039, 44128.33807492955, 44151.55848441603, 538608.0, 540461.0, 542976.0, 543891.0, 544268.0, 25462.49075018526, 25583.872080639478, 25932.35169120787, 26011.0, 26141.6078758815, 44410.777946110065, 44784.328235075845, 45040.54959450364, 45178.54821451743, 45531.54468455273, 36703.265934681694, 36986.44520332143, 37694.434583481816, 37831.0, 37871.0, 30195.943141989927, 36010.73962411408, 36141.457878131325, 37552.81223593865, 38563.807180963915, 29891.55162672518, 31072.68927310698, 31095.222619434695, 31800.0, 36895.89312320635, 26926.0, 31198.0, 33371.0, 33478.33043339071, 33526.0, 24568.877155614107, 24628.266204706735, 24722.25833225006, 24953.251402457954, 25398.49203015966], "value": 17773.723195676223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.30767848765856, 372.65529385318575, 374.3430279858849, 386.7331541236547, 404.80771633474086, 15385.69228615427, 15593.000970704616, 17773.111344432775, 20128.39614811556, 20575.074121664522, 15767.60580985476, 17188.398406055792, 20339.694904576405, 20431.18275268991, 20702.89648551755, 5776.913346299809, 6089.81730548082, 6105.908411373833, 6121.66330851803, 6128.816135515942, 5750.108733146369, 6042.123892035652, 6082.878342433149, 6104.603200791957, 6104.633721976674, 4147.983743982713, 4391.319345501436, 6058.091286307045, 6271.717772700239, 6558.114654521626, 6561.398281940485, 6593.901091483632, 6642.070110184578, 6646.634435106067, 6657.167854018272, 5649.293838270216, 5860.70696465175, 5866.090755932837, 5870.794522191745, 5901.380355062743, 24968.876400562018, 24976.750232497623, 25159.874200629063, 25306.1142859999, 25359.87320063388, 2260.2993072147688, 2333.0784340185533, 2490.066225165563, 2592.844429334246, 3043.5130379139455, 14667.926660366737, 15619.0, 15643.687126257524, 15831.60420989473, 16001.91999040009, 1800.8829426087264, 1820.3537744100918, 1821.3226556961247, 1871.0, 1910.6656335141433, 111440.44279778631, 115768.0, 127253.00108494029, 128532.714672853, 135892.0, 9348.672796452092, 9745.317827752131, 10024.496325551225, 10055.446950417798, 10174.338667986558, 12490.12569120171, 12579.685507862194, 12595.496180152692, 12602.991760659126, 12606.432710528048, 7638.350740187036, 7862.960685196537, 7884.448088633855, 7949.761507154794, 7955.0851652060755, 7049.603194212455, 8150.592470376458, 8154.572949733892, 8238.8352232955, 8293.95853020731, 3871.070942973666, 3888.766673999569, 3897.376419772879, 3927.980360098238, 3979.263836190314, 1900.6388786130592, 1904.352520143158, 1905.3521802587186, 1906.8283854453166, 1945.737325461073, 864.6584599083327, 907.0, 908.4322298563397, 924.2421214604094, 931.1107891963159, 20016.49958751043, 20675.689864652042, 20845.478863028242, 21094.0, 21373.67939480909, 796.653455746752, 879.6657270237268, 913.1051569461964, 935.6491315756591, 978.6868202175235, 10829.0, 11172.173982553692, 11291.830622540669, 11458.4270786462, 11507.309561426342, 561190.0, 562111.0, 565057.0, 565059.0, 565416.0, 124211.75788242182, 124272.75727242611, 125890.0, 127618.08572871414, 133345.0, 887.2103827593447, 897.3763234551924, 916.8303863785221, 919.990800092004, 920.4891285336569, 14942.477013304482, 18418.815811842247, 18722.344717934808, 18942.147603357607, 19007.698802463598, 54857.1771423421, 57398.42601573931, 57712.4228757707, 57773.0, 57821.421785781604, 76532.23467765251, 78210.21789782029, 79723.0, 79994.6000269995, 80181.59909200417, 7895.68417263303, 7913.248241417056, 8203.876941845996, 8231.012278526616, 8270.917290827256, 6778.762743303961, 9528.523573821143, 11356.26184298018, 12579.496820127093, 12893.097483176087, 10846.403447810446, 10932.672019839418, 11078.944605276922, 11315.567153062131, 11355.772884542097, 10178.389296642225, 10549.841752373566, 10687.412192329497, 10957.397343145885, 11283.887161128612, 9439.339246252697, 11139.665810025712, 11176.944115279372, 11390.974812266935, 11543.76912461763, 6813.3867951882585, 7225.6387180641805, 7336.669849856876, 8046.114927358103, 8218.054923683852, 10346.896531034894, 10643.361398315816, 10685.0, 11064.059554937598, 11213.327200368005], "value": 9431.43281636513, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.2642207152965, 1442.5095467541034, 1479.7706355514895, 1620.8298128696485, 1818.2817786974153, 7434.739784107557, 7827.6477558510005, 7829.0, 7840.843183136334, 7843.80390490238, 7656.88514672279, 7986.763706162269, 8206.343492520598, 8207.466514676542, 8311.91688083119, 2277.237125562934, 2313.699219101523, 2313.8495997760174, 2315.3979965209082, 2318.107528601484, 2281.0761641535205, 2314.259436980167, 2335.614623587106, 2338.38032921276, 2341.274204996457, 2255.0, 2271.568402003614, 2305.4121199094207, 2308.3305841306, 2311.9422014449688, 2239.518503521734, 2268.092762894839, 2291.2209848651537, 2292.575873463398, 2292.8968196431283, 2174.8695078295195, 2268.6728263965433, 2286.142696488817, 2312.063614236237, 2313.2366319114653, 6814.1482314710665, 7987.560684162427, 8086.595670216525, 8110.107888132301, 8319.0, 4866.635002374836, 5002.324686167393, 5008.073506401327, 5014.473480284554, 5018.824341148079, 2973.2269609901364, 3435.8282085895607, 3532.682058614737, 3533.063738109403, 3535.5934067582302, 1048.8636477257985, 1087.8748943871476, 1090.9127269818396, 1105.4804242006244, 1107.1143085531623, 9235.907640923571, 9259.30555208362, 10937.726556836144, 11001.834972475419, 11016.559337626486, 4395.582419670126, 4561.29299958507, 4727.83452579165, 4747.8575642730775, 4752.073345697625, 8676.652933882575, 8695.0, 8711.651533938699, 8715.738527844174, 8746.737597871946, 5127.154019586801, 5130.769115389817, 5135.589152867761, 5140.717260550706, 5150.613703972217, 4029.2545879012478, 4142.958570414257, 4770.809167633325, 4774.4509381420885, 4808.8797780055775, 3009.623797025372, 3621.5473065866768, 3731.5149030625844, 3745.026293163797, 3756.2487502499607, 1051.7265510967204, 1140.942952852354, 1147.5696613769837, 1147.6786499780076, 1153.7173392518887, 722.1442590530238, 858.9484630922165, 868.7524055644237, 888.1917455115943, 944.622151139536, 5561.0, 5729.5702822288495, 5811.825645230565, 5986.401359864066, 6182.258129024546, 866.4887716247445, 915.1351972386032, 921.5346250143634, 923.8152369526122, 949.943003419797, 3245.707886290198, 3292.3415316936707, 3727.049602351383, 3836.827342769582, 3891.64975152232, 9744.756381090388, 10172.237082218864, 10300.712410948632, 10308.381497110197, 10313.329633573794, 10251.282410231212, 10253.231007674454, 10266.486675666187, 10267.024632659886, 10269.435181065113, 7730.806729831799, 7912.0, 7913.733802591558, 7924.524528528307, 7930.167332430127, 5427.782888684408, 5576.498115169654, 5587.664740115606, 5781.797637082682, 5867.178594996619, 9323.906760932576, 9415.905840941503, 9436.433813971184, 9541.618335266512, 9612.951935240279, 9055.864162037444, 9469.431834089977, 9498.430094194371, 9544.141027307576, 9611.615535378507, 7152.392046676091, 7378.188399275968, 7384.741534046282, 7771.261730135628, 7944.602769861599, 7519.360854327443, 7589.810254743676, 7596.202398748273, 7704.26809453101, 7940.92059079425, 7056.964715176391, 7227.891581626174, 7272.381847543018, 7315.451341149329, 7398.704051838082, 6863.759768408082, 7019.262977387505, 7034.437245020284, 7128.073350464407, 7184.856302874018, 6631.204255489372, 6657.06801047844, 6744.932550674431, 6757.93242067573, 6760.08738820253, 4774.1883879739835, 4777.4505931818285, 4810.56704896547, 4847.61219102478, 4871.391076115486, 4696.2486002239475, 4714.57568818808, 4738.715677059387, 4885.193942999365, 4977.0], "value": 4363.092206941368, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.18649459963495, 309.5774268124011, 310.5931230088584, 317.21172885379826, 319.7665704036052, 4784.44978827435, 5126.359205099363, 5132.512411320928, 5153.252778347147, 5153.3558305211845, 3764.303603833293, 4132.462779838625, 5043.839916819134, 5143.305653736745, 5186.948130518694, 3722.32998060349, 3857.903552411184, 3890.2219556088753, 3893.201893611817, 3913.119548101683, 3564.6078931317666, 3601.243738814852, 3890.9805450972704, 3896.4934558507352, 3926.371780515118, 3879.02169893355, 3889.241597888413, 3898.8050597470155, 3918.7271382598024, 3923.7449565778275, 3736.3648179809225, 3899.0, 3908.1988192420627, 3916.1971795781797, 3972.463717398172, 3240.643529211785, 3300.7359411247044, 3316.2538428853313, 3370.460726283807, 3454.7408944329277, 4885.7312847793355, 4912.733032533397, 4925.458199598042, 5005.22419025048, 5006.274090256892, 4438.223310920576, 4464.665150113722, 4468.396766436522, 4478.955210447886, 4487.371767952489, 4630.305454181866, 5233.685978841244, 5286.207068939651, 5354.678719276856, 5393.406725260218, 3125.249940014403, 3716.8699095531797, 3747.306748251582, 3761.887143385703, 3766.3597188478025, 5098.337216161875, 5124.359455068116, 5599.8880022399735, 5618.325800903917, 5647.43525647436, 2876.2090425133256, 3468.063622821824, 3555.826577229508, 3784.905377365533, 3807.6573108420375, 3772.188979369394, 3798.639129282769, 3852.7110466715108, 3875.2636998970106, 3888.863889763901, 4155.833766649361, 4387.3328135308375, 4481.3195051855555, 4513.81944722214, 4529.614982726439, 5447.754851031713, 5454.400015998237, 5480.561555075585, 5488.56091512678, 5492.588055895823, 1128.2666266926399, 1154.5554961339944, 1175.3476820364779, 1196.7127889306503, 1235.7025123620342, 827.755812035451, 836.3016880904513, 851.9606080581681, 855.5754668476916, 866.7518772966907, 4108.4453598764385, 4373.1472362888935, 4410.316400957857, 4457.64338852891, 4826.420829500483, 4410.213863385334, 4741.243727688031, 5220.9638241086, 5304.655197412156, 5313.57491400687, 4113.449661210069, 4807.7836497357, 4823.3729615150505, 4833.540813622701, 4842.951570484321, 2392.0, 2624.973750262473, 2798.734120258572, 2808.3711447360483, 2825.5055365310986, 4672.976635116803, 4731.361266229083, 5520.585956053232, 5595.524380427628, 5635.774569017275, 5368.0, 5583.972080139573, 5646.435356464321, 5669.092945128759, 5717.799877004285, 4160.771157586301, 4704.83577554322, 4900.422691435272, 5305.9204111939125, 5395.460453954652, 4444.555544445529, 4482.394876691736, 4850.975745121393, 5622.156676498558, 5627.577931655061, 4578.427696537933, 4674.719516829001, 5344.866378340573, 5550.084236100998, 5575.972120139373, 4684.906301874012, 5155.922661160011, 5233.058049550965, 5249.955026084938, 5821.126830975388, 3117.0, 3439.5872495300723, 3763.1909139535132, 3792.241551689728, 3792.5259342582176, 5607.5513958884, 5614.568285087276, 5624.803131890364, 5653.773849045992, 5727.828165155052, 4144.772037537964, 4286.635635970977, 4543.977280113579, 4870.9269360960325, 5452.26394436746, 4342.69601127918, 4595.93106103402, 5271.78912843482, 5447.984245829069, 5475.4250803665045, 4474.597286244254, 4599.6550258731395, 5118.749594007906, 5305.787768489217, 5306.8407947761625, 4794.664373493962, 5159.845204643867, 5388.568914486753, 5429.565634749131, 5516.9448305518035, 4588.0365123323145, 4725.50382209863, 4904.5340692634145, 5253.684778913277, 5391.514763671288], "value": 3859.914567477752, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.5868478912653, 690.0649619765214, 696.3767428151805, 746.727444482764, 751.781983224865, 12504.624861254155, 13938.44246230151, 14351.138931664082, 14645.999999999987, 14655.560333189995, 11808.704782380444, 13150.736985260313, 13178.934105329485, 13266.005049621268, 13274.402651880664, 653.7809833705702, 673.5083389125941, 795.8686816675241, 818.3943881527656, 823.9464434811719, 817.6443247187492, 818.071488860142, 823.5017814222399, 824.4146655874337, 824.5877061469265, 865.3466632692308, 865.6753717355991, 866.7356456280831, 866.9783255418633, 869.1048220333067, 695.085961960021, 719.3022767915114, 819.8975128108987, 840.6973489543759, 856.1395797223781, 634.0204384226381, 634.1724050114599, 636.0681601453846, 636.9299377068519, 638.4828289085856, 13549.187048777007, 13612.31938403076, 13618.250996195304, 13651.522196723066, 13685.178889266675, 12787.232766033974, 12995.545155919497, 13019.349032548429, 13154.60536183916, 13163.868361316456, 11361.943190284079, 11676.1826672133, 11748.060155187564, 12100.576479823163, 12183.208091474025, 4282.164977829325, 4359.324304732771, 4427.822887084512, 4491.640668746492, 4501.954980450187, 83242.58378708128, 84199.0, 87316.12683873207, 87553.0, 88606.0, 10292.330998485075, 10388.37669739818, 10424.739381515523, 10453.84319235212, 10474.895251047392, 16563.751543726852, 19878.801211987986, 19915.302964396466, 20100.19599216015, 20122.798772012386, 7245.492815502864, 7352.301531354513, 7462.664180111908, 7674.232576742281, 7695.730649427243, 4160.43834082395, 4207.431996680409, 4278.593533614302, 4320.027993701392, 4391.385206071152, 3208.903732888017, 3252.788568743024, 3298.274379636476, 3301.092199645117, 3314.834258287076, 621.7450845153465, 624.9125122482856, 631.1447987976318, 635.0537698828804, 637.7162162837585, 541.6831153775007, 552.9751161197755, 566.8554518597731, 567.7899177304424, 568.1279236372148, 8119.878201826978, 8583.313334933191, 8650.0, 8762.992255890527, 8860.955695221483, 585.6398315036307, 601.3024891126272, 604.2899592978321, 608.4675908579992, 621.1521273461692, 4161.459010328638, 4250.447441832542, 4299.247631664446, 4374.868753937387, 4818.807247710122, 105043.0, 108304.0, 110740.0, 111658.0, 119309.0, 97998.0, 99370.0, 102081.0, 105207.94792051981, 106337.0, 88841.0, 91014.17971640397, 91809.54095229482, 92414.99999999866, 94057.0, 7743.419243556868, 9144.954275228582, 9590.616375344907, 9638.180754635934, 9644.75888102775, 43067.353989690804, 55052.0, 56047.879042419736, 57371.56571085756, 57823.13265301108, 66697.33302666912, 67073.66463167653, 80329.0, 81674.59162704149, 85245.1475485262, 9691.321607487409, 11904.28574285511, 12056.939715301367, 12237.326947018, 12446.751064978831, 11506.94246528762, 12804.0, 13796.655083622589, 14482.203478808346, 14495.710085798435, 12935.02987275977, 13131.277779722206, 13163.078584498993, 13169.209847409185, 13209.207447553177, 12781.424835882222, 12890.613281601567, 12920.741585168433, 12940.352982351033, 13386.933065334611, 12148.452421703065, 12192.268463892195, 12307.076969227164, 12404.937975310066, 12469.75060498803, 10743.623973161215, 10882.401467919024, 10908.563657453931, 11361.886381136082, 11539.8269025963, 10877.401742904216, 10960.397178154959, 11171.106311494897, 11205.551777928791, 11208.607698730506], "value": 7203.705089492486, "unit": "score"}], "name": "dromaeo_css", "value": 6965.567135684749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 112.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 110.0, 143.0, 125.0, 140.0, 142.0, 112.0, 140.0, 113.0], "value": 127.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 184.0, 185.0, 186.0, 182.0, 183.0, 181.0, 184.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 105.0, 85.0, 81.0, 64.0, 59.0, 83.0, 59.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 99.0, 127.0, 100.0, 98.0, 69.0, 88.0, 66.0, 68.0, 90.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 85.0, 87.0, 83.0, 88.0, 84.0, 87.0, 87.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 101.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 80.0, 79.0, 81.0, 84.0, 83.0, 83.0, 84.0, 81.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 56.0, 56.0, 59.0, 58.0, 57.0, 57.0, 56.0, 57.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 67.0, 75.0, 70.0, 68.0, 69.0, 69.0, 70.0, 70.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 84.0, 82.0, 83.0, 84.0, 83.0, 83.0, 85.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 144.0, 156.0, 158.0, 143.0, 143.0, 143.0, 185.0, 136.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 229.0, 186.0, 166.0, 165.0, 164.0, 165.0, 164.0, 166.0, 164.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 77.0, 75.0, 84.0, 68.0, 68.0, 67.0, 67.0, 66.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1452.5, "alertThreshold": 2.0}]} 03:41:58 INFO - Return code: 0 03:41:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:41:58 INFO - # TBPL SUCCESS # 03:41:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:41:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:41:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:41:58 INFO - 2017-01-13 03:41:58.452 screenresolution[1675:9691] starting screenresolution argv=screenresolution get 03:41:58 INFO - 2017-01-13 03:41:58.455 screenresolution[1675:9691] Display 0: 1600x1200x32@60 03:41:58 INFO - 2017-01-13 03:41:58.462 screenresolution[1676:9694] starting screenresolution argv=screenresolution list 03:41:58 INFO - Available Modes on Display 0 03:41:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:41:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:41:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:41:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:41:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:41:58 INFO - Intel Iris: 03:41:58 INFO - Chipset Model: Intel Iris 03:41:58 INFO - Type: GPU 03:41:58 INFO - Bus: Built-In 03:41:58 INFO - VRAM (Dynamic, Max): 1536 MB 03:41:58 INFO - Vendor: Intel (0x8086) 03:41:58 INFO - Device ID: 0x0a2e 03:41:58 INFO - Revision ID: 0x0009 03:41:58 INFO - Displays: 03:41:58 INFO - Display: 03:41:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:41:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:41:58 INFO - Display Serial Number: 1600x1200 60 03:41:58 INFO - Main Display: Yes 03:41:58 INFO - Mirror: Off 03:41:58 INFO - Online: Yes 03:41:58 INFO - Rotation: Supported 03:41:58 INFO - Return code: 0 03:41:58 INFO - Running post-action listener: _resource_record_post_action 03:41:58 INFO - [mozharness: 2017-01-13 11:41:58.709757Z] Finished run-tests step (success) 03:41:58 INFO - Running post-run listener: _resource_record_post_run 03:41:58 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 38781952; Write bytes: 659718656; Read time: 375; Write time: 1949 03:41:58 INFO - TinderboxPrint: CPU usage
53.3% 03:41:58 INFO - TinderboxPrint: I/O read bytes / time
38,781,952 / 375 03:41:58 INFO - TinderboxPrint: I/O write bytes / time
659,718,656 / 1,949 03:41:58 INFO - TinderboxPrint: CPU idle
2,263.2 (46.7%) 03:41:58 INFO - TinderboxPrint: CPU system
964.8 (19.9%) 03:41:58 INFO - TinderboxPrint: CPU user
1,613.7 (33.3%) 03:41:58 INFO - TinderboxPrint: Swap in / out
387,948,544 / 0 03:41:58 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348465664; Write bytes: 346880000; Read time: 26901; Write time: 647 03:41:58 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36664320; Write bytes: 305535488; Read time: 351; Write time: 1273 03:41:59 INFO - Running post-run listener: _upload_blobber_files 03:41:59 INFO - Blob upload gear active. 03:41:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:41:59 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 03:41:59 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:41:59 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:41:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:41:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:41:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:42:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:00 INFO - (blobuploader) - INFO - Done attempting. 03:42:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:42:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:42:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:01 INFO - (blobuploader) - INFO - Done attempting. 03:42:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:42:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:04 INFO - (blobuploader) - INFO - Done attempting. 03:42:04 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:04 INFO - Return code: 0 03:42:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/647d8b54585ec7b7f7aff83679100bd0bfdf721b4fb2feac92f9c3770ef5061a2f335b7a6070c0adc85fc406213e24952ee3fee84177bcb9e3c14ecec5a68f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0669a5493d266dbb240e521febcb9901b31245e77048ad4e0c0a028a5ec33dc40525cfdfc46089dcc2ce26cc26c9da0c20d8cd7eb8906d6de90ed84f66dd8f3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/217dee47579f007932d0fa1f4fbbc479121a971711fe9e2771657251bd20fa82b9b7936520f2ff18fee47a57c6a5835f5642bd9f969f96572ced803c1bcb1840"} 03:42:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:04 INFO - Contents: 03:42:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/647d8b54585ec7b7f7aff83679100bd0bfdf721b4fb2feac92f9c3770ef5061a2f335b7a6070c0adc85fc406213e24952ee3fee84177bcb9e3c14ecec5a68f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0669a5493d266dbb240e521febcb9901b31245e77048ad4e0c0a028a5ec33dc40525cfdfc46089dcc2ce26cc26c9da0c20d8cd7eb8906d6de90ed84f66dd8f3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/217dee47579f007932d0fa1f4fbbc479121a971711fe9e2771657251bd20fa82b9b7936520f2ff18fee47a57c6a5835f5642bd9f969f96572ced803c1bcb1840"} 03:42:04 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:04 INFO - Copying logs to upload dir... 03:42:04 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.303720 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-13 03:42:04.381955) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 03:42:04.386579) ========= 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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/647d8b54585ec7b7f7aff83679100bd0bfdf721b4fb2feac92f9c3770ef5061a2f335b7a6070c0adc85fc406213e24952ee3fee84177bcb9e3c14ecec5a68f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0669a5493d266dbb240e521febcb9901b31245e77048ad4e0c0a028a5ec33dc40525cfdfc46089dcc2ce26cc26c9da0c20d8cd7eb8906d6de90ed84f66dd8f3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/217dee47579f007932d0fa1f4fbbc479121a971711fe9e2771657251bd20fa82b9b7936520f2ff18fee47a57c6a5835f5642bd9f969f96572ced803c1bcb1840"} build_url:https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011121 build_url: 'https://queue.taskcluster.net/v1/task/MV7GH3y_Re-_KInEFwuQLQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/647d8b54585ec7b7f7aff83679100bd0bfdf721b4fb2feac92f9c3770ef5061a2f335b7a6070c0adc85fc406213e24952ee3fee84177bcb9e3c14ecec5a68f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0669a5493d266dbb240e521febcb9901b31245e77048ad4e0c0a028a5ec33dc40525cfdfc46089dcc2ce26cc26c9da0c20d8cd7eb8906d6de90ed84f66dd8f3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/217dee47579f007932d0fa1f4fbbc479121a971711fe9e2771657251bd20fa82b9b7936520f2ff18fee47a57c6a5835f5642bd9f969f96572ced803c1bcb1840"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 03:42:04.428192) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 03:42:04.428576) ========= 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.vC5SQ4k3eq/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.8bahV4eK6d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.005226 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 03:42:04.476977) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 03:42:04.480367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 03:42:04.480732) ========= ========= Total master_lag: 0.21 =========