builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0151 starttime: 1475534315.76 results: success (0) buildid: 20161003131557 builduid: 088bdbae1413404abd8ebc5312e5bcd1 revision: 8db4b1b5dfae11b9681a85373de5dcc0d9cdc7b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.766048) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.766625) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.766951) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.791748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.792118) ========= 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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-03 15:38:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.63M=0.001s 2016-10-03 15:38:35 (8.63 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101547 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.914371) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.914746) ========= 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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.018869 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.963951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:35.964377) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8db4b1b5dfae11b9681a85373de5dcc0d9cdc7b9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8db4b1b5dfae11b9681a85373de5dcc0d9cdc7b9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-03 15:38:36,030 truncating revision to first 12 chars 2016-10-03 15:38:36,030 Setting DEBUG logging. 2016-10-03 15:38:36,030 attempt 1/10 2016-10-03 15:38:36,031 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8db4b1b5dfae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 15:38:36,706 unpacking tar archive at: fx-team-8db4b1b5dfae/testing/mozharness/ program finished with exit code 0 elapsedTime=0.930898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:36.917657) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:36.918044) ========= script_repo_revision: 8db4b1b5dfae11b9681a85373de5dcc0d9cdc7b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:36.918534) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:36.918848) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 15:38:36.937344) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-03 15:38:36.937670) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:38:37 INFO - MultiFileLogger online at 20161003 15:38:37 in /builds/slave/test 15:38:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:38:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:38:37 INFO - {'append_to_log': False, 15:38:37 INFO - 'base_work_dir': '/builds/slave/test', 15:38:37 INFO - 'blob_upload_branch': 'Fx-Team', 15:38:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:38:37 INFO - 'branch': 'Fx-Team', 15:38:37 INFO - 'buildbot_json_path': 'buildprops.json', 15:38:37 INFO - 'config_files': ('talos/mac_config.py',), 15:38:37 INFO - 'default_actions': ('clobber', 15:38:37 INFO - 'read-buildbot-config', 15:38:37 INFO - 'download-and-extract', 15:38:37 INFO - 'populate-webroot', 15:38:37 INFO - 'create-virtualenv', 15:38:37 INFO - 'install', 15:38:37 INFO - 'run-tests'), 15:38:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:38:37 INFO - 'download_minidump_stackwalk': True, 15:38:37 INFO - 'download_symbols': 'ondemand', 15:38:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:38:37 INFO - 'tooltool.py': '/tools/tooltool.py', 15:38:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:38:37 INFO - '/tools/misc-python/virtualenv.py')}, 15:38:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:38:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:38:37 INFO - 'installer_path': 'installer.exe', 15:38:37 INFO - 'log_level': 'info', 15:38:37 INFO - 'log_name': 'talos', 15:38:37 INFO - 'log_to_console': True, 15:38:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:38:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:38:37 INFO - 'opt_config_files': (), 15:38:37 INFO - 'pip_index': False, 15:38:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:38:37 INFO - 'cmd': ('bash', 15:38:37 INFO - '-c', 15:38:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:38:37 INFO - 'enabled': True, 15:38:37 INFO - 'halt_on_failure': False, 15:38:37 INFO - 'name': 'check_screen_resolution'},), 15:38:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:38:37 INFO - 'cmd': ('bash', 15:38:37 INFO - '-c', 15:38:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:38:37 INFO - 'enabled': True, 15:38:37 INFO - 'halt_on_failure': False, 15:38:37 INFO - 'name': 'check_screen_resolution'},), 15:38:37 INFO - 'run_cmd_checks_enabled': True, 15:38:37 INFO - 'sps_profile': False, 15:38:37 INFO - 'sps_profile_interval': 0, 15:38:37 INFO - 'suite': 'dromaeojs', 15:38:37 INFO - 'system_bits': '32', 15:38:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:38:37 INFO - 'title': 't-yosemite-r7-0151', 15:38:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:38:37 INFO - 'use_talos_json': True, 15:38:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:38:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:38:37 INFO - 'work_dir': 'build'} 15:38:37 INFO - [mozharness: 2016-10-03 22:38:37.134800Z] Running clobber step. 15:38:37 INFO - Running pre-action listener: _resource_record_pre_action 15:38:37 INFO - Running main action method: clobber 15:38:37 INFO - rmtree: /builds/slave/test/build 15:38:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:38:38 INFO - Running post-action listener: _resource_record_post_action 15:38:38 INFO - [mozharness: 2016-10-03 22:38:38.042294Z] Finished clobber step (success) 15:38:38 INFO - [mozharness: 2016-10-03 22:38:38.042396Z] Running read-buildbot-config step. 15:38:38 INFO - Running pre-action listener: _resource_record_pre_action 15:38:38 INFO - Running main action method: read_buildbot_config 15:38:38 INFO - Using buildbot properties: 15:38:38 INFO - { 15:38:38 INFO - "project": "", 15:38:38 INFO - "product": "firefox", 15:38:38 INFO - "script_repo_revision": "production", 15:38:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:38:38 INFO - "repository": "", 15:38:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:38:38 INFO - "buildid": "20161003131557", 15:38:38 INFO - "pgo_build": "False", 15:38:38 INFO - "basedir": "/builds/slave/test", 15:38:38 INFO - "buildnumber": 849, 15:38:38 INFO - "slavename": "t-yosemite-r7-0151", 15:38:38 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:38:38 INFO - "platform": "yosemite_r7", 15:38:38 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:38:38 INFO - "branch": "fx-team", 15:38:38 INFO - "repo_path": "integration/fx-team", 15:38:38 INFO - "revision": "8db4b1b5dfae11b9681a85373de5dcc0d9cdc7b9", 15:38:38 INFO - "stage_platform": "macosx64", 15:38:38 INFO - "builduid": "088bdbae1413404abd8ebc5312e5bcd1", 15:38:38 INFO - "slavebuilddir": "test" 15:38:38 INFO - } 15:38:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:38:38 INFO - Running post-action listener: _resource_record_post_action 15:38:38 INFO - [mozharness: 2016-10-03 22:38:38.045618Z] Finished read-buildbot-config step (success) 15:38:38 INFO - [mozharness: 2016-10-03 22:38:38.045723Z] Running download-and-extract step. 15:38:38 INFO - Running pre-action listener: _resource_record_pre_action 15:38:38 INFO - Running main action method: download_and_extract 15:38:38 INFO - mkdir: /builds/slave/test/build/tests 15:38:38 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')]} 15:38:38 INFO - trying https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:38:38 INFO - Downloading https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:38:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 15:38:39 INFO - Downloaded 1199 bytes. 15:38:39 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:38:39 INFO - Using the following test package requirements: 15:38:39 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:38:39 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:38:39 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:38:39 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:38:39 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:38:39 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:38:39 INFO - u'jsshell-mac.zip'], 15:38:39 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:38:39 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:38:39 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:38:39 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:38:39 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:38:39 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:38:39 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:38:39 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:38:39 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:38:39 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:38:39 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:38:39 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:38:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:38:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:38:39 INFO - Fetch https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:38:40 INFO - Http code: 200 15:38:40 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 15:38:40 INFO - x-amz-cf-id: jmubjDz0wZA2PSFi8sTjoxXWo5sYKFLQmjtsegjCJZWSI6Y-yxiQMQ== 15:38:40 INFO - x-amz-version-id: kk7Ta2QDydik.RgoYy4BSUv9l7deszso 15:38:41 INFO - Expected file size: 16475794 15:38:41 INFO - Obtained file size: 16475794 15:38:47 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:38:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:38:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:38:47 INFO - Fetch https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:38:48 INFO - Http code: 200 15:38:48 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 15:38:48 INFO - x-amz-cf-id: _H3ljsKJHk-3TlaAlI4Ap17tJ_UZGjXu70RKEBHghh9mcZqY2BPz3w== 15:38:48 INFO - x-amz-version-id: kk7Ta2QDydik.RgoYy4BSUv9l7deszso 15:38:48 INFO - Expected file size: 16475794 15:38:48 INFO - Obtained file size: 16475794 15:38:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:38:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:38:55 INFO - Fetch https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:38:56 INFO - Http code: 200 15:38:56 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 15:38:56 INFO - x-amz-cf-id: xoIGdPTp67XJDuS1IR34KmlVYe9ogiUIpTbNNd-yNYVosV13kphMFQ== 15:38:56 INFO - x-amz-version-id: 0TLWp3wLORCFbGKIBsSvtCMOYe4k6QCP 15:38:56 INFO - Expected file size: 14409006 15:38:56 INFO - Obtained file size: 14409006 15:38:57 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')]} 15:38:57 INFO - trying https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:38:57 INFO - Downloading https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:38:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 15:39:00 INFO - Downloaded 111808368 bytes. 15:39:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:39:00 INFO - mkdir: /builds/slave/test/properties 15:39:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:39:00 INFO - Writing to file /builds/slave/test/properties/build_url 15:39:00 INFO - Contents: 15:39:00 INFO - build_url:https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:39:01 INFO - Running post-action listener: _resource_record_post_action 15:39:01 INFO - Running post-action listener: set_extra_try_arguments 15:39:01 INFO - [mozharness: 2016-10-03 22:39:01.101536Z] Finished download-and-extract step (success) 15:39:01 INFO - [mozharness: 2016-10-03 22:39:01.101656Z] Running populate-webroot step. 15:39:01 INFO - Running pre-action listener: _resource_record_pre_action 15:39:01 INFO - Running main action method: populate_webroot 15:39:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:39:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:39:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:39:01 INFO - '--tppagecycles', 15:39:01 INFO - '7'], 15:39:01 INFO - 'tests': ['tsvgm']}}, 15:39:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:39:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:39:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:39:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:39:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:01 INFO - 'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:01 INFO - 'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['damp', 'tps']}, 15:39:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:01 INFO - 'tests': ['damp', 'tps']}, 15:39:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['dromaeo_dom']}, 15:39:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:39:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['basic_compositor_video']}, 15:39:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:39:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['a11yr', 15:39:01 INFO - 'ts_paint', 15:39:01 INFO - 'tpaint', 15:39:01 INFO - 'sessionrestore', 15:39:01 INFO - 'sessionrestore_no_auto_restore', 15:39:01 INFO - 'tabpaint']}, 15:39:01 INFO - 'other-e10s': {'tests': ['a11yr', 15:39:01 INFO - 'ts_paint', 15:39:01 INFO - 'tpaint', 15:39:01 INFO - 'sessionrestore', 15:39:01 INFO - 'sessionrestore_no_auto_restore', 15:39:01 INFO - 'tabpaint']}, 15:39:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:39:01 INFO - 'ts_paint', 15:39:01 INFO - 'tpaint', 15:39:01 INFO - 'sessionrestore', 15:39:01 INFO - 'sessionrestore_no_auto_restore', 15:39:01 INFO - 'tabpaint']}, 15:39:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:39:01 INFO - 'ts_paint', 15:39:01 INFO - 'tpaint', 15:39:01 INFO - 'sessionrestore', 15:39:01 INFO - 'sessionrestore_no_auto_restore', 15:39:01 INFO - 'tabpaint']}, 15:39:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['a11yr', 15:39:01 INFO - 'ts_paint', 15:39:01 INFO - 'tpaint', 15:39:01 INFO - 'sessionrestore', 15:39:01 INFO - 'sessionrestore_no_auto_restore', 15:39:01 INFO - 'tabpaint']}, 15:39:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['a11yr', 15:39:01 INFO - 'ts_paint', 15:39:01 INFO - 'tpaint', 15:39:01 INFO - 'sessionrestore', 15:39:01 INFO - 'sessionrestore_no_auto_restore', 15:39:01 INFO - 'tabpaint']}, 15:39:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['tsvgx', 15:39:01 INFO - 'tsvgr_opacity', 15:39:01 INFO - 'tart', 15:39:01 INFO - 'tscrollx', 15:39:01 INFO - 'cart']}, 15:39:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:39:01 INFO - 'tsvgr_opacity', 15:39:01 INFO - 'tart', 15:39:01 INFO - 'tscrollx', 15:39:01 INFO - 'cart']}, 15:39:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:01 INFO - 'talos_options': ['--disable-e10s'], 15:39:01 INFO - 'tests': ['tp5o']}, 15:39:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:01 INFO - 'tests': ['tp5o']}, 15:39:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:01 INFO - 'talos_options': ['--disable-e10s', 15:39:01 INFO - '--xperf_path', 15:39:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:01 INFO - 'tests': ['tp5n']}, 15:39:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:01 INFO - 'talos_options': ['--xperf_path', 15:39:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:01 INFO - 'tests': ['tp5n']}}, 15:39:01 INFO - 'talos.zip': {'path': '', 15:39:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:39:01 INFO - Running post-action listener: _resource_record_post_action 15:39:01 INFO - [mozharness: 2016-10-03 22:39:01.120543Z] Finished populate-webroot step (success) 15:39:01 INFO - [mozharness: 2016-10-03 22:39:01.120683Z] Running create-virtualenv step. 15:39:01 INFO - Running pre-action listener: _resource_record_pre_action 15:39:01 INFO - Running main action method: create_virtualenv 15:39:01 INFO - Creating virtualenv /builds/slave/test/build/venv 15:39:01 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 15:39:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:39:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:39:01 INFO - Using real prefix '/tools/python27' 15:39:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:39:01 INFO - Installing distribute.............................................................................................................................................................................................done. 15:39:04 INFO - Installing pip.................done. 15:39:04 INFO - Return code: 0 15:39:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:39:05 INFO - Reading from file tmpfile_stdout 15:39:05 INFO - Output received: 15:39:05 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) 15:39:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:39:05 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')]} 15:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:05 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')]} 15:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:05 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:05 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 15:39:05 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 15:39:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:05 INFO - 'HOME': '/Users/cltbld', 15:39:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:05 INFO - 'LOGNAME': 'cltbld', 15:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:05 INFO - 'MOZ_NO_REMOTE': '1', 15:39:05 INFO - 'NO_EM_RESTART': '1', 15:39:05 INFO - 'PAGER': '/bin/cat', 15:39:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:05 INFO - 'PWD': '/builds/slave/test', 15:39:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:05 INFO - 'SHELL': '/bin/bash', 15:39:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:05 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:05 INFO - 'USER': 'cltbld', 15:39:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:05 INFO - 'XPC_FLAGS': '0x0', 15:39:05 INFO - 'XPC_SERVICE_NAME': '0', 15:39:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:05 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 15:39:05 INFO - Cleaning up... 15:39:05 INFO - Return code: 0 15:39:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39:05 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')]} 15:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:05 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')]} 15:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:05 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:05 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 15:39:05 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 15:39:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:05 INFO - 'HOME': '/Users/cltbld', 15:39:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:05 INFO - 'LOGNAME': 'cltbld', 15:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:05 INFO - 'MOZ_NO_REMOTE': '1', 15:39:05 INFO - 'NO_EM_RESTART': '1', 15:39:05 INFO - 'PAGER': '/bin/cat', 15:39:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:05 INFO - 'PWD': '/builds/slave/test', 15:39:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:05 INFO - 'SHELL': '/bin/bash', 15:39:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:05 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:05 INFO - 'USER': 'cltbld', 15:39:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:05 INFO - 'XPC_FLAGS': '0x0', 15:39:05 INFO - 'XPC_SERVICE_NAME': '0', 15:39:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:05 INFO - Downloading/unpacking psutil>=3.1.1 15:39:05 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 15:39:05 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 15:39:05 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 15:39:05 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 15:39:05 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 15:39:05 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 15:39:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:09 INFO - Installing collected packages: psutil 15:39:09 INFO - Running setup.py install for psutil 15:39:09 INFO - building 'psutil._psutil_osx' extension 15:39:09 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 15:39:09 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 15:39:09 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 15:39:09 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 15:39:09 INFO - building 'psutil._psutil_posix' extension 15:39:09 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 15:39:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:39:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:39:09 INFO - ^ 15:39:09 INFO - 1 warning generated. 15:39:09 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 15:39:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:09 INFO - Successfully installed psutil 15:39:09 INFO - Cleaning up... 15:39:09 INFO - Return code: 0 15:39:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:09 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')]} 15:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:09 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')]} 15:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:09 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:09 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 15:39:09 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 15:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:09 INFO - 'HOME': '/Users/cltbld', 15:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:09 INFO - 'LOGNAME': 'cltbld', 15:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:09 INFO - 'MOZ_NO_REMOTE': '1', 15:39:09 INFO - 'NO_EM_RESTART': '1', 15:39:09 INFO - 'PAGER': '/bin/cat', 15:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:09 INFO - 'PWD': '/builds/slave/test', 15:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:09 INFO - 'SHELL': '/bin/bash', 15:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:09 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:09 INFO - 'USER': 'cltbld', 15:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:09 INFO - 'XPC_FLAGS': '0x0', 15:39:09 INFO - 'XPC_SERVICE_NAME': '0', 15:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:10 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 15:39:10 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 15:39:10 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 15:39:10 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 15:39:10 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 15:39:10 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 15:39:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:12 INFO - Installing collected packages: mozsystemmonitor 15:39:12 INFO - Running setup.py install for mozsystemmonitor 15:39:12 INFO - Successfully installed mozsystemmonitor 15:39:12 INFO - Cleaning up... 15:39:12 INFO - Return code: 0 15:39:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:39:12 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')]} 15:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:12 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')]} 15:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:12 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:12 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 15:39:12 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 15:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:12 INFO - 'HOME': '/Users/cltbld', 15:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:12 INFO - 'LOGNAME': 'cltbld', 15:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:12 INFO - 'MOZ_NO_REMOTE': '1', 15:39:12 INFO - 'NO_EM_RESTART': '1', 15:39:12 INFO - 'PAGER': '/bin/cat', 15:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:12 INFO - 'PWD': '/builds/slave/test', 15:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:12 INFO - 'SHELL': '/bin/bash', 15:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:12 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:12 INFO - 'USER': 'cltbld', 15:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:12 INFO - 'XPC_FLAGS': '0x0', 15:39:12 INFO - 'XPC_SERVICE_NAME': '0', 15:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:13 INFO - Downloading/unpacking jsonschema==2.5.1 15:39:13 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 15:39:13 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 15:39:13 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 15:39:13 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 15:39:13 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 15:39:13 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 15:39:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:39:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:39:15 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 15:39:15 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 15:39:15 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 15:39:15 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 15:39:15 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 15:39:15 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 15:39:16 INFO - Downloading functools32-3.2.3-2.tar.gz 15:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:39:16 INFO - warning: no files found matching '*.txt' 15:39:16 INFO - no previously-included directories found matching 'build' 15:39:16 INFO - no previously-included directories found matching 'dist' 15:39:16 INFO - no previously-included directories found matching '.git*' 15:39:16 INFO - Installing collected packages: jsonschema, functools32 15:39:16 INFO - Running setup.py install for functools32 15:39:16 INFO - warning: no files found matching '*.txt' 15:39:16 INFO - no previously-included directories found matching 'build' 15:39:16 INFO - no previously-included directories found matching 'dist' 15:39:16 INFO - no previously-included directories found matching '.git*' 15:39:16 INFO - Successfully installed jsonschema functools32 15:39:16 INFO - Cleaning up... 15:39:16 INFO - Return code: 0 15:39:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:16 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:16 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 15:39:16 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 15:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:16 INFO - 'HOME': '/Users/cltbld', 15:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:16 INFO - 'LOGNAME': 'cltbld', 15:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:16 INFO - 'MOZ_NO_REMOTE': '1', 15:39:16 INFO - 'NO_EM_RESTART': '1', 15:39:16 INFO - 'PAGER': '/bin/cat', 15:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:16 INFO - 'PWD': '/builds/slave/test', 15:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:16 INFO - 'SHELL': '/bin/bash', 15:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:16 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:16 INFO - 'USER': 'cltbld', 15:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:16 INFO - 'XPC_FLAGS': '0x0', 15:39:16 INFO - 'XPC_SERVICE_NAME': '0', 15:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:16 INFO - Cleaning up... 15:39:16 INFO - Return code: 0 15:39:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:16 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:16 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 15:39:16 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 15:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:16 INFO - 'HOME': '/Users/cltbld', 15:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:16 INFO - 'LOGNAME': 'cltbld', 15:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:16 INFO - 'MOZ_NO_REMOTE': '1', 15:39:16 INFO - 'NO_EM_RESTART': '1', 15:39:16 INFO - 'PAGER': '/bin/cat', 15:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:16 INFO - 'PWD': '/builds/slave/test', 15:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:16 INFO - 'SHELL': '/bin/bash', 15:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:16 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:16 INFO - 'USER': 'cltbld', 15:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:16 INFO - 'XPC_FLAGS': '0x0', 15:39:16 INFO - 'XPC_SERVICE_NAME': '0', 15:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:17 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:17 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 15:39:17 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 15:39:17 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 15:39:17 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 15:39:17 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 15:39:17 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 15:39:19 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39: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 15:39: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 15:39: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 15:39: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 15:39: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 15:39: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 15:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:20 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 15:39:20 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 15:39:20 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 15:39:20 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 15:39:20 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 15:39:20 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 15:39:20 INFO - Downloading docopt-0.6.1.tar.gz 15:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:20 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:20 INFO - Running setup.py install for blobuploader 15:39:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:20 INFO - Running setup.py install for requests 15:39:21 INFO - Running setup.py install for docopt 15:39:21 INFO - Successfully installed blobuploader requests docopt 15:39:21 INFO - Cleaning up... 15:39:21 INFO - Return code: 0 15:39:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:21 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:21 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 15:39:21 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 15:39:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:21 INFO - 'HOME': '/Users/cltbld', 15:39:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:21 INFO - 'LOGNAME': 'cltbld', 15:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:21 INFO - 'MOZ_NO_REMOTE': '1', 15:39:21 INFO - 'NO_EM_RESTART': '1', 15:39:21 INFO - 'PAGER': '/bin/cat', 15:39:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:21 INFO - 'PWD': '/builds/slave/test', 15:39:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:21 INFO - 'SHELL': '/bin/bash', 15:39:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:21 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:21 INFO - 'USER': 'cltbld', 15:39:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:21 INFO - 'XPC_FLAGS': '0x0', 15:39:21 INFO - 'XPC_SERVICE_NAME': '0', 15:39:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:21 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-k8TJAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-4DNWZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-JblK1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ZOATQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-nAxHgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vx6fMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-A295mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-1Y7eO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-2YeaIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-4fBljW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-NMCgYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vuflVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-1eCofp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-DaatFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-nXbUjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-_1LQZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-I0fcHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:23 INFO - Running setup.py install for manifestparser 15:39:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:39:23 INFO - Running setup.py install for mozcrash 15:39:23 INFO - Running setup.py install for mozdebug 15:39:24 INFO - Running setup.py install for mozdevice 15:39:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:39:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:39:24 INFO - Running setup.py install for mozfile 15:39:24 INFO - Running setup.py install for mozhttpd 15:39:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:39:24 INFO - Running setup.py install for mozinfo 15:39:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:39:24 INFO - Running setup.py install for mozInstall 15:39:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:39:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:39:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:39:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:39:24 INFO - Running setup.py install for mozleak 15:39:24 INFO - Running setup.py install for mozlog 15:39:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:25 INFO - Running setup.py install for moznetwork 15:39:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:39:25 INFO - Running setup.py install for mozprocess 15:39:25 INFO - Running setup.py install for mozprofile 15:39:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:39:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:39:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:39:25 INFO - Running setup.py install for mozrunner 15:39:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:39:25 INFO - Running setup.py install for mozscreenshot 15:39:25 INFO - Running setup.py install for moztest 15:39:26 INFO - Running setup.py install for mozversion 15:39:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:39:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:39:26 INFO - Cleaning up... 15:39:26 INFO - Return code: 0 15:39:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:26 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:26 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 15:39:26 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 15:39:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:26 INFO - 'HOME': '/Users/cltbld', 15:39:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:26 INFO - 'LOGNAME': 'cltbld', 15:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:26 INFO - 'MOZ_NO_REMOTE': '1', 15:39:26 INFO - 'NO_EM_RESTART': '1', 15:39:26 INFO - 'PAGER': '/bin/cat', 15:39:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:26 INFO - 'PWD': '/builds/slave/test', 15:39:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:26 INFO - 'SHELL': '/bin/bash', 15:39:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:26 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:26 INFO - 'USER': 'cltbld', 15:39:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:26 INFO - 'XPC_FLAGS': '0x0', 15:39:26 INFO - 'XPC_SERVICE_NAME': '0', 15:39:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-U8kGt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:26 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)) 15:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-RoMfj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-SutXBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:26 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)) 15:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-hnP0xA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:26 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)) 15:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Oh55El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-DImXjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-TvsWwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-FATzrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-_IUbdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-jt2YYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-kM6kHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-p6tJcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-FYgKhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:27 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)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-SfrCfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-oILc9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:28 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)) 15:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ginqtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-xu3wGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:28 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)) 15:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:39:28 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)) 15:39:28 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)) 15:39:28 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)) 15:39:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:39:28 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 15:39:28 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 15:39:28 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 15:39:28 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 15:39:28 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 15:39:28 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 15:39:30 INFO - Downloading blessings-1.6.tar.gz 15:39:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:39:30 INFO - Installing collected packages: blessings 15:39:30 INFO - Running setup.py install for blessings 15:39:30 INFO - Successfully installed blessings 15:39:30 INFO - Cleaning up... 15:39:31 INFO - Return code: 0 15:39:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:31 INFO - Reading from file tmpfile_stdout 15:39:31 INFO - Current package versions: 15:39:31 INFO - blessings == 1.6 15:39:31 INFO - blobuploader == 1.2.4 15:39:31 INFO - docopt == 0.6.1 15:39:31 INFO - functools32 == 3.2.3-2 15:39:31 INFO - jsonschema == 2.5.1 15:39:31 INFO - manifestparser == 1.1 15:39:31 INFO - mozInstall == 1.12 15:39:31 INFO - mozcrash == 0.17 15:39:31 INFO - mozdebug == 0.1 15:39:31 INFO - mozdevice == 0.48 15:39:31 INFO - mozfile == 1.2 15:39:31 INFO - mozhttpd == 0.7 15:39:31 INFO - mozinfo == 0.9 15:39:31 INFO - mozleak == 0.1 15:39:31 INFO - mozlog == 3.4 15:39:31 INFO - moznetwork == 0.27 15:39:31 INFO - mozprocess == 0.23 15:39:31 INFO - mozprofile == 0.28 15:39:31 INFO - mozrunner == 6.12 15:39:31 INFO - mozscreenshot == 0.1 15:39:31 INFO - mozsystemmonitor == 0.3 15:39:31 INFO - moztest == 0.7 15:39:31 INFO - mozversion == 1.4 15:39:31 INFO - psutil == 3.1.1 15:39:31 INFO - requests == 1.2.3 15:39:31 INFO - wsgiref == 0.1.2 15:39:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39: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')]} 15:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:31 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 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39:31 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 15:39:31 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 15:39:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:31 INFO - 'HOME': '/Users/cltbld', 15:39:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:31 INFO - 'LOGNAME': 'cltbld', 15:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:31 INFO - 'MOZ_NO_REMOTE': '1', 15:39:31 INFO - 'NO_EM_RESTART': '1', 15:39:31 INFO - 'PAGER': '/bin/cat', 15:39:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:31 INFO - 'PWD': '/builds/slave/test', 15:39:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:31 INFO - 'SHELL': '/bin/bash', 15:39:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:31 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:31 INFO - 'USER': 'cltbld', 15:39:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:31 INFO - 'XPC_FLAGS': '0x0', 15:39:31 INFO - 'XPC_SERVICE_NAME': '0', 15:39:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 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)) 15:39:31 INFO - Cleaning up... 15:39:31 INFO - Return code: 0 15:39:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39: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')]} 15:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e956490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da486790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, '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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:39: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', 'jsonschema'] in /builds/slave/test/build 15:39: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 jsonschema 15:39:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:39:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:31 INFO - 'HOME': '/Users/cltbld', 15:39:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:31 INFO - 'LOGNAME': 'cltbld', 15:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:31 INFO - 'MOZ_NO_REMOTE': '1', 15:39:31 INFO - 'NO_EM_RESTART': '1', 15:39:31 INFO - 'PAGER': '/bin/cat', 15:39:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:31 INFO - 'PWD': '/builds/slave/test', 15:39:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:31 INFO - 'SHELL': '/bin/bash', 15:39:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:39:31 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:39:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:31 INFO - 'USER': 'cltbld', 15:39:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:31 INFO - 'XPC_FLAGS': '0x0', 15:39:31 INFO - 'XPC_SERVICE_NAME': '0', 15:39:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:39:31 INFO - Cleaning up... 15:39:31 INFO - Return code: 0 15:39:31 INFO - Running post-action listener: _resource_record_post_action 15:39:31 INFO - Running post-action listener: _start_resource_monitoring 15:39:31 INFO - Starting resource monitoring. 15:39:31 INFO - [mozharness: 2016-10-03 22:39:31.944475Z] Finished create-virtualenv step (success) 15:39:31 INFO - [mozharness: 2016-10-03 22:39:31.945502Z] Running install step. 15:39:31 INFO - Running pre-action listener: _resource_record_pre_action 15:39:31 INFO - Running main action method: install 15:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:32 INFO - Reading from file tmpfile_stdout 15:39:32 INFO - Detecting whether we're running mozinstall >=1.0... 15:39:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:39:32 INFO - Reading from file tmpfile_stdout 15:39:32 INFO - Output received: 15:39:32 INFO - Usage: mozinstall [options] installer 15:39:32 INFO - Options: 15:39:32 INFO - -h, --help show this help message and exit 15:39:32 INFO - -d DEST, --destination=DEST 15:39:32 INFO - Directory to install application into. [default: 15:39:32 INFO - "/builds/slave/test"] 15:39:32 INFO - --app=APP Application being installed. [default: firefox] 15:39:32 INFO - mkdir: /builds/slave/test/build/application 15:39:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:40:06 INFO - Reading from file tmpfile_stdout 15:40:06 INFO - Output received: 15:40:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:40:06 INFO - Running post-action listener: _resource_record_post_action 15:40:06 INFO - [mozharness: 2016-10-03 22:40:06.672906Z] Finished install step (success) 15:40:06 INFO - [mozharness: 2016-10-03 22:40:06.673036Z] Running run-tests step. 15:40:06 INFO - Running pre-action listener: _resource_record_pre_action 15:40:06 INFO - Running main action method: run_tests 15:40:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:40:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:40:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:40:06 INFO - 2016-10-03 15:40:06.687 screenresolution[1929:13902] starting screenresolution argv=screenresolution get 15:40:06 INFO - 2016-10-03 15:40:06.689 screenresolution[1929:13902] Display 0: 1600x1200x32@60 15:40:06 INFO - 2016-10-03 15:40:06.695 screenresolution[1930:13904] starting screenresolution argv=screenresolution list 15:40:06 INFO - Available Modes on Display 0 15:40:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:40:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:40:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:40:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:40:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:40:06 INFO - Intel Iris: 15:40:06 INFO - Chipset Model: Intel Iris 15:40:06 INFO - Type: GPU 15:40:06 INFO - Bus: Built-In 15:40:06 INFO - VRAM (Dynamic, Max): 1536 MB 15:40:06 INFO - Vendor: Intel (0x8086) 15:40:06 INFO - Device ID: 0x0a2e 15:40:06 INFO - Revision ID: 0x0009 15:40:06 INFO - Displays: 15:40:06 INFO - Display: 15:40:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:40:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:40:06 INFO - Display Serial Number: 1600x1200 60 15:40:06 INFO - Main Display: Yes 15:40:06 INFO - Mirror: Off 15:40:06 INFO - Online: Yes 15:40:06 INFO - Rotation: Supported 15:40:06 INFO - Return code: 0 15:40:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:06 INFO - Python 2.7.3 15:40:06 INFO - Return code: 0 15:40:06 INFO - grabbing minidump binary from tooltool 15:40:06 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')]} 15:40:06 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 0x10e76ad30>, '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 0x10e590db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e762b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:06 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 15:40:06 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 15:40:06 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 15:40:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:07 INFO - Return code: 0 15:40:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:40:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:40:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 15:40:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --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 15:40:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q17ZeMe2gG/Render', 15:40:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:07 INFO - 'HOME': '/Users/cltbld', 15:40:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:07 INFO - 'LOGNAME': 'cltbld', 15:40:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:07 INFO - 'MOZ_NO_REMOTE': '1', 15:40:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:07 INFO - 'NO_EM_RESTART': '1', 15:40:07 INFO - 'PAGER': '/bin/cat', 15:40:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:07 INFO - 'PWD': '/builds/slave/test', 15:40:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:07 INFO - 'SHELL': '/bin/bash', 15:40:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuYPf3IAK5/Listeners', 15:40:07 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:40:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:07 INFO - 'USER': 'cltbld', 15:40:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:07 INFO - 'XPC_FLAGS': '0x0', 15:40:07 INFO - 'XPC_SERVICE_NAME': '0', 15:40:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 15:40:07 INFO - mozversion application_buildid: 20161003131557 15:40:07 INFO - mozversion application_changeset: 8db4b1b5dfae11b9681a85373de5dcc0d9cdc7b9 15:40:07 INFO - mozversion application_display_name: Nightly 15:40:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:07 INFO - mozversion application_name: Firefox 15:40:07 INFO - mozversion application_remotingname: firefox 15:40:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:40:07 INFO - mozversion application_vendor: Mozilla 15:40:07 INFO - mozversion application_version: 52.0a1 15:40:07 INFO - mozversion platform_buildid: 20161003131557 15:40:07 INFO - mozversion platform_changeset: 8db4b1b5dfae11b9681a85373de5dcc0d9cdc7b9 15:40:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:40:07 INFO - mozversion platform_version: 52.0a1 15:40:07 INFO - using testdate: 1475534407 15:40:07 INFO - actual date: 1475534407 15:40:07 INFO - starting webserver on 'localhost:49439' 15:40:07 INFO - SUITE-START | Running 2 tests 15:40:07 INFO - TEST-START | dromaeo_css 15:40:07 INFO - operating with platform_type : mac_ 15:40:07 INFO - Initialising browser for dromaeo_css test... 15:40:07 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpkOAZxv/profile http://localhost:49439/getInfo.html) 15:40:09 INFO - PROCESS | 1940 | __metrics Screen width/height:1600/1200 15:40:09 INFO - PROCESS | 1940 | colorDepth:24 15:40:09 INFO - PROCESS | 1940 | Browser inner width/height: 1024/689 15:40:09 INFO - PROCESS | 1940 | __metrics 15:40:09 INFO - PROCESS | 1940 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:40:09 INFO - PROCESS | 1940 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:40:10 INFO - TEST-INFO | 1940: exit 0 15:40:10 INFO - Browser initialized. 15:40:10 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:10 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpkOAZxv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:22 INFO - PROCESS | 1942 | RSS: Main: 236388352 15:40:22 INFO - PROCESS | 1942 | 15:41:14 INFO - PROCESS | 1942 | 1475534474210 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:43:26 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49439/tests/dromaeo/cssquery-ext.html) 15:43:27 INFO - PROCESS | 1942 | RSS: Main: 362946560 15:43:27 INFO - PROCESS | 1942 | 15:46:30 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-ext.html (next: http://localhost:49439/tests/dromaeo/cssquery-jquery.html) 15:46:31 INFO - PROCESS | 1942 | RSS: Main: 370089984 15:46:31 INFO - PROCESS | 1942 | 15:49:33 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49439/tests/dromaeo/cssquery-mootools.html) 15:49:34 INFO - PROCESS | 1942 | RSS: Main: 342487040 15:49:34 INFO - PROCESS | 1942 | 15:52:36 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49439/tests/dromaeo/cssquery-prototype.html) 15:52:37 INFO - PROCESS | 1942 | RSS: Main: 336183296 15:52:37 INFO - PROCESS | 1942 | 15:55:40 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49439/tests/dromaeo/cssquery-yui.html) 15:55:41 INFO - PROCESS | 1942 | RSS: Main: 323907584 15:55:41 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-yui.html (next: http://localhost:49439/tests/dromaeo/cssquery-dojo.html) 15:58:44 INFO - PROCESS | 1942 | RSS: Main: 450052096 15:58:44 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | __start_tp_report 15:58:44 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 15:58:44 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 15:58:44 INFO - PROCESS | 1942 | |i|pagename|runs| 15:58:44 INFO - PROCESS | 1942 | |0;dojo.html;2901.5792710057044;3288.7533434992383;3320.5849268841393;3348.5479460272854;3357.5467311912885;5584.106542953128;5633.52115070219;5636.7181640918;5687.914681279784;5707.372189059205;5634.605577609569;5660.9716951415285;5721.256236689224;5724.656520608766;5765.279340082479;5086.160783470723;5119.129747942859;5123.359580052494;5136.460671629481;5175.534201921826;4732.1718699227495;5224.4775522447835;5246.003259380725;5247;5294.708791016493;4340.826366945318;5192.506711862367;5203.557697595709;5232.110541208004;5233.345831771028;5054.1660625996665;5136.460671629462;5145.614078944057;5153.768080436389;5171.301874246966;5103.260027296058;5128.640995130342;5130.204818253177;5174.534291913746;5216.66091704038;5589.524890384322;5673.035583950697;5676.148577713376;5678.659280443146;5729.4843464088435;5970.13433052205;6019.036954087369;6037.667928263943;6160.845978850564;6187.319394866561;5128.333316668809;6167.229096362955;6255.218097737783;6268.373162683741;6290.528210384194;5767.711614419304;5785.971070144622;5787.074068149118;5788.479036886701;5794.072948328248;6105.490073363267;6264;6313.652749098844;6367.649779262092;6370.426661600478;5877.0596704527925;6075.48358389533;6095.3904609538695;6123.465646421124;6127.437503436684;6235;6237.532185086136;6239.812805615838;6250.749970001149;6257.78097766576;6105.206323177958;6109.236345456818;6158.876822463526;6187.752489900444;6193.659348735863;6036.9698151508965;6120.14317995481;6123.387661233841;6146.416090471398;6147.969260153671;4861.781219845116;4872.293517439952;4872.4640289568115;4897.4857639948;4911.63162762787;4365.978170109129;4390.319500477431;4414.050979039522;4431.800568974404;4487;5699.173619825102;5736.168255602997;5753.913691294634;5762;5775;6084.330723620398;6097.054956481752;6118;6125.662799505599;6176.382361763787;5707.086866101404;5729.1119876419225;5738.311402631711;5753.597248192586;5787.421257874179;5319.9202011969855;5327.493888080626;5332.280142180756;5347.117725575236;5350.411454739976;6220.844478887883;6245.281792593728;6251.499880009497;6276.9372306276355;6375.075614035846;6192.442680158807;6216.627002379872;6291.653959032297;6302.873942521032;6314.778982735582;6326.936730632819;6329.145565348619;6337.556371053982;6339.809805705836;6358.713857876499;5975.910361344497;6054.3642917493025;6137.202163718688;6170.413810687977;6181;6112.898653789679;6113.755449781959;6148.200733904564;6205.410486003822;6234.1583886174785;5952.623194088149;5973.1637570741;5976.073708575178;5981.043033114681;5990.31111422192;5728.112142617901;5850.356460789225;5973.581849270509;5984.611000284967;5985.221921150222;5777.884442311046;5822.854428639318;5873.148393482836;5873.94126058751;5940.910886336795;6019.398060193946;6025.307089684742;6038.3357830637715;6045.0327947528185;6059.303180134341;5718.456746609066;5776.768929242783;5789.45000224978;5826.970865145647;5849.239598852122 15:58:44 INFO - PROCESS | 1942 | |1;ext.html;451.1270691212503;475.23724422302206;475.96240196371895;484.88847565060036;526.0636067799319;30834.074977750675;31248.99999999997;31316.373672526563;40866.7956660217;41787;42167;42486.78756606213;42489.725308240726;42534;42589.36115958263;9649.614015439392;9833.90166098337;9834.26243031772;9837.704868853945;9873.506324683773;8810.955945220265;9044.502552359618;9053.411528250675;9110.180083792457;9713.61145554177;8926.196642302191;10211.948940255288;11982.46078926446;12276.32480213588;12433;12110.636680899543;12187.57343492978;12191.207571507824;12193.634190974284;12205.511779528808;11890.405479726065;11967.102467314899;11968.281903085843;11975.46110425033;11998.400079995965;31637.683623163703;32309.83845080783;39536.40695389571;40268.19463610712;40317.59682403197;3202.3915456063273;3219.7907136036083;3286.6713328666942;3339.34882697874;3636.9636303637153;30771.384572308652;30838.53742193869;31198;31214.843925780453;32081;2720.986395068032;3197.8081315121167;3212.4378233809225;3218.6620404857385;3302.075418882717;443220;444751;445647;445823.99999999674;447968;59733.40266597278;60272.39727602668;60340;60340.396596033475;60380.69809650924;58976.82046359049;63127;63170.68414657989;63174;63284.683576581825;15968.201589920458;16180.67638647244;16197;16202.918985405233;16222.675546489005;12481.687957801129;12567.497300107892;12569.245845249314;12580.30808305552;12594.685132871751;7480;7792.376609871197;7797.532148071134;7803.843923121619;17690.85009474406;6885.311468853075;6943.6875340609795;7890.802729931797;8036;8133.820596013545;863.3309185381254;937.2033771294482;951.2009911674088;984.7981163861391;1000.7248006798188;22507.385985872857;29807.254818629273;30159.246018849703;30171.05308731802;30531.084067478012;1006.8036732837173;1024.8616436781087;1032.0608246495653;1058.25921854702;1153.9653810385703;14625.488107916171;15426;15470.872185410437;15516.612084697746;15776.92111539458;642013;647125;648498.9676496881;649360;649895;49954.50045499571;50709.74645126751;58027.4197258022;58267.70866145642;59115.70442147762;1316;1349.2713934475469;1360.5306169371552;1361.4622224221378;1365.0240078343834;30093.37501059359;30644.387112257184;30827.0751877444;31024.121984751906;31417.52873706942;51119.2332115026;51626.48373516367;51663.966720666125;51713.48286517237;51750;570066;573347;574318;574557;574843;21469.67795483038;25436.364090897874;25501.107461238767;25911.35221619399;25915.22254332373;46886.29670555013;47372;47783.761081194374;47805;48292;29727;34844.9546513605;36632.81683591565;37484.062898426666;39007.804960975016;31038.68961310358;38635;38664.6133538661;39442.605573943896;40210.79894600508;37991.81004094962;38341.80829095948;38705.806470967465;38722.2255554893;38770.80614596909;30143.246418839706;31335.84332078325;32487.837560812044;34361.82819085888;34385.82807086049;25130;25182.370440738396;25254.873725631252;25750.87124564365;26027.95888164452 15:58:44 INFO - PROCESS | 1942 | |2;jquery.html;344.0452743636407;358.31382901743166;361.40007587404915;367.5442451360313;394.06409776780146;14051.735343819055;15002.699946001074;16692.415765448226;21260.255891043817;21383.57232855342;19655.410337689857;20557.691634625455;20693.482662933395;20711.378658640264;20898.955052247402;5818;5834.533237341003;5947.821565353046;6104.481119104881;6125.418085281914;5283.471652834705;5897.911531327033;5929.080992446177;5965.313988891268;6022.849428764294;4112.670986321102;4334.393184954109;5953.493953013999;6219.662772503911;6572.901406478906;6456.935430645727;6519.119918810995;6569.540132190748;6624.53628246023;6627.403533681992;4604.608608268301;5642.069058605325;5699.173619825144;5713.085906254979;5715.942840571583;21476.926153692253;24820;25714.6142807858;25774.87112564425;25802.612960805604;2468.839525430841;2471.7033955925403;2726.7320695876315;2858.771298296131;3122.5784519089857;14816.407343706238;15503.767443488357;15834.445794397256;15858.603534911605;16330.510084697478;1834.8440382567499;1845.5478407790176;1885.9434216973514;1888.3862744608057;1897.250586018515;62611;112967;119609;120585.39707301494;121008;10384.480775961323;10458.163346932228;10666.306690065121;10829.67510974672;10832.891671083345;13482.595522134352;13493.190408575518;13495.25776082325;13498.325083745773;13500.594982150551;6558.639274839929;8403.495790252604;8412.116727743622;8592.699255526026;8599.484030958038;7276.781696549112;8395.70615028471;8510.70212542558;8516.403851730443;8680.913190868137;6381.1385462962835;6530.869382612416;6540.771073012422;6551.6069035858;6553.803385898431;1361.4486133116104;1619.8380161983944;1668.1825905306312;1796.119901248379;1798.5054110119822;832.9083800781909;884.9026607073346;942.7784470649392;967.7870868408938;975.0882924465648;20910.895445522674;22033.669494957587;22136.889315553643;22156.556868862855;22180.112795488323;905.4963027229716;935.6023689931887;991.3902949685892;1016.8576399304103;1055.767731099157;10451.895481045245;10652.307600005975;11479.024282935878;11487.885121148849;11515.309081454972;583541;589106;594177.0291148603;595769;609150.588223531;90336;114077.42961285307;119024.69172722491;119504;124836.75163248433;926.7636752628036;958.726762872578;991.9008099190168;992.846108853129;996.4370130875886;15870.2064896757;19950.50123746918;20078.598428031648;20255.59488810186;20258.79741202569;52610.73694631655;52915;53861;53941.73029134829;54005.67954563547;71962.28037719555;74094.62952685202;74958.62520687362;77236.61381693055;77403.61298193473;7601.3918886490055;7773.844523109393;7805.409511624716;8037.919620803717;8097.190280971856;13018.739625207632;13133.540326088541;13147;13219.471221151047;13554.932225339204;11265.324080555192;11315.830262545904;11610.071194304604;11703.941480292544;11704.414779260833;11136.888631113585;11247.606333778278;11306.378149201873;11432;11571.074314054687;11597.826032609686;11747.706307342385;11921.701957450787;11944.343061131374;12045.578404755792;7624.58064806441;7836.84326313482;7881;7959.761207163794;7969.282764550986;11239.662810115382;11385.829212561985;11426.02878755315;11657.300561966309;11812.70468238301 15:58:44 INFO - PROCESS | 1942 | |3;mootools.html;1525.9999999999995;1536.2011753887978;1756.2272600055974;1888.6978083506642;1915.942521724348;8431.283340916021;8958.148975847293;8965.731028069169;8968.372213945026;8983.865242021358;6575.802725918218;8055.838883222346;8080.030396352446;8086.070101938279;8098.271155595995;2062.453449835795;2244.5847518209184;2325.7092863392077;2325.7209134903837;2331.5686597979343;1977.4265463015709;2365.9881700591473;2366.692329997098;2369.3247424484025;2372.240882917467;2281.6577513372963;2375.92872213833;2378.8096952243777;2381.4641705616273;2383.5471260460545;2324.0703718512646;2328.382978510696;2366.183666635008;2369.2300002499082;2369.6919400478;2328.52265285616;2348.2720356689483;2356.658284548731;2357.39886328985;2364.0780095762666;7456.7390141344795;8550.973883134002;8705.564721763887;8713.738587842376;8789.692360767407;5149.150390185615;5237.16205407133;5329.733513324319;5335.226392173152;5344.278522399504;3464.861405543775;3599.9460008099636;3669.412893936984;3672.320620685155;3689.797061161635;1020.7958408318366;1082.2045796339685;1104.1222228328497;1104.5471356743697;1106.6845948904524;11676.883231167665;11684.12369072323;11685.474153663106;11696.415179241005;11713.765724685543;3925.391564307537;4347.347897815353;4514.255147900625;4706.576408123216;4732.621390288768;9893.307468477282;9912.405255684682;9915.80168396628;9916.4545949972;9916.95871933451;5292.417834038252;5295.781970146888;5305.230741542455;5326.067938110815;5330.813421530305;4738.620910327166;4807.543283388072;4859.392575928009;4878.341423907798;4883.8290659826735;3638.9090272742865;3797.7911214882893;3798.5441746989986;3809.580946095927;3811.6378944000276;1201.783678937782;1243.160866415166;1280.8334916460801;1284.6852521132382;1285.7042880137622;760.444875241077;863.1799790199336;865.7878819689216;878.3851304087165;904.1455824246025;4755.738434386073;5300.549453296436;5782.335031471434;5834.27071616048;5862.912056319158;887.3389324952951;902.7697937026147;911.3256190419042;947.7772723409993;967.4002118686459;3745.606711295329;3750.268697603941;3752.962470375316;3840.558335791364;3862.517185351831;10230.335028223142;10620.946895265473;10698.62554810578;10700.518476668569;10700.571977120984;10661.84007239892;10670.62652807148;10679.83980240297;10681.092107170822;10686.625968091235;6385.36146385367;7906.090799558125;8040.27637512615;8191;8231.547264900368;5462.754176062007;5585.860353491195;5593.804216852391;5614.606977511535;5836.066229403275;9778.9022109778;9831.410115393099;10049.346792458467;10053.19574434058;10176.796464070825;10046.69859904204;10100.242481813746;10224;10318.17454603645;10319.638812641522;6950.791476255518;7088.787336379917;7091.2554181810165;7091.503594748318;7094.467914906506;8090.231428014329;8166.183381661786;8235.788042663486;8245.216704413071;8253;7293.890591641236;7329.853402931805;7350.816229594303;7482.476226664299;7492;7239.601821899846;7277.417806575355;7295.3798927089665;7307.525010874276;7319.304666056716;6739.887918493394;6754.223264324665;6851.45188384918;6856.554323968926;6878.105846240157;4811.975940120277;4821.9035619288115;4861.222204447413;4862.3922009748785;4902.803887844446;4787.138315103176;4806.279058141167;4863.076015557032;4933.851984440473;5049.2930989660745 15:58:44 INFO - PROCESS | 1942 | |4;prototype.html;289.2277618757915;293.15863471835826;301.61845265738845;306.0466646396473;307.22425874666465;3865.884023479293;4774.069056533977;4851.986425633358;5366.517013468787;5442.2380866678695;4533.637309015271;4710.293455981596;5191.974040129794;5235.240890070937;5262.9473705262935;3294.4234758917214;3839.2897313997;3990.4612877261634;3990.8825528851967;4003.11931375097;3331.8671651638438;3361.848716807737;3658.432942893856;4044.413560033793;4049.129437171008;3134.7304341741633;3997.820642910334;4010.598940105995;4016.8393264269394;4026.45642838216;3132.7963682360687;3837.5586807517216;3998.6001399860074;4000.3199456092243;4032.8386864525382;3493.493443450711;3502.5972013218297;3512.227309991798;3519.260955199411;3530.8638273844467;4619.3994780678295;4959.479254678279;5125.436202017775;5169.689818610896;5193.688378697252;3999.8800035998966;4078.4494093297617;4444.977775111136;4495.235809912323;4601.401817763703;5330.520253177233;5564.666120032811;5570.693611851347;5587.580931430119;5589.664620122806;3735.4396840473873;3774.735768496206;3807.257584770971;3859.9192226176433;3863.652271295597;5805.129230615399;5806.38712579341;5808.46075789916;5831.358550559435;5900.881982360372;3901.492805935267;3913.7476007677838;3936.8622098226424;3942.3100957332354;3946.743936252603;3643.107438677541;3954.268460334847;3984.266843922903;3999.8200080996426;4003.319435695939;3992.5807790182193;4513.074819661962;4546.749928753884;4607.3319368691355;4615.884602884955;5747.729646789495;5748.45389687979;5754.309482862083;5764.365919748824;5778.797742079007;1181.261711430356;1207.6618546806908;1246.1775228349245;1257.2770656872335;1357.3484727330936;728.4318231779221;835.4502398051527;860.7761981884755;894.932880034;915.8351496730519;5160.406553246425;5259.263703081571;5279.653688309533;5307.6019298552765;5311.380029091087;4345.956540434619;5432.565394768409;5456.345238571397;5734.39788822176;5764.538836893039;4580.381648477412;5022.89954200914;5354.277172581729;5378.892422151535;5406.324209473816;2978.9722545721693;3017.879284828582;3026.530887712408;3044.862981165853;3059.7552195824283;5118.97440512795;5156.974215128976;5252.579793616502;5593.105103183471;6000.819975400745;4937.5556199942175;5437.13005919051;5729.567608097976;5928.436798504135;5987.700614969234;5357.571394288526;5996;6030.216071910711;6040.909386359208;6090.451859332681;5050.72221027847;5050.898982020265;5838.357780644041;5858.882822343615;5871.94128058714;5083.364579427572;5780.682062486436;5802.82591522255;5819.5053420459735;5822.70886455667;5309.062192299705;5796.971015144897;5990.6705131218205;6033.487153591818;6091.725872335667;3354.8825791097192;3386;3541.3802584547084;3725.9254814903006;4089.483044581717;5174.14626586624;5811;5826.805504871576;5829.766809327749;5895.702945352015;4977.253411988231;5264.420913699567;5303.946960530499;5711.3431955324;5715.771369145354;5644.548436125182;5648.039833228443;5654.3497497788285;5656.1798539212405;5657.60396772222;5257.605679574123;5427.674339539641;5442.6462279951675;5443.237946687546;5566.916496252478;5450.645708028966;5470.507654311131;5611.915821262603;5650.378458369494;5674.2623458950075;5219.269302297757;5468.3711373191;5495.230667706604;5534.501894829485;5604.30993123295 15:58:44 INFO - PROCESS | 1942 | |5;yui.html;605.7049649904519;717.0678118446019;735.9889601655973;750.8122969257686;827.1769589258687;15323.770143447844;15676.451324203656;15845.762313565307;15899.602509937258;15948.36206551738;13111.6722081948;13207.537736179236;13278.800817987738;13281.933590332059;13297.867021329808;736.5985537881854;852.4373913217278;855.7646647172015;855.9101294364095;856.9801935696532;678.8302924268933;696.8815301398774;824.6701319472229;859.922606965373;860.5009094725044;859.4886042804521;877.9912200878006;903.3405613901856;905.9943462756336;906.8413027720155;897.8473659477904;899.2086963472103;901.0583939782877;901.5582364641311;901.8376692195402;522.3470661672909;538.4480907070246;647.3073811022208;665.9001149827516;671.4829581222432;15161.545153645298;15212.011219270822;15240.695186096216;15249.161296128708;15249.695006099815;13635.590932271949;14037.298135093306;14211.147331160164;14426.422943082263;14448.205348705817;12587.24476531411;12685;12737.745245095139;12868.549600764021;12872.210447575115;4507.188706032913;4553;4553.635709143261;4621.537846215385;4760.238361862085;104997.47501262445;106155;107843;108854.99999999921;110391.44804276008;11028.2280240384;11103.777924441465;11160.441977901073;11296.71758206035;11531.365774882463;22292;22316.995735191955;22325.441863953532;22343;22383.32850014502;6770.729170833111;7836.608169591497;7912.446128771046;7966;8215.973003374578;4316.266234740102;4389.517153113155;4459.464864216315;4516.051629157913;4595.9080818383445;3466.046837119812;3612.5303710517464;3615.9819200903826;3617.3127105849803;3617.4212126059706;660.6531570925303;671.7145213284334;679.3071067511111;680.3706571421416;680.7515256931283;602.9758809647652;617.1791517282094;621.5773274173605;625.7403177681302;644.223710428932;8457.619407126695;8623.223909848144;8882.955585222162;9108.499032553273;9254.444733316022;561.5423429904615;614.1923370767427;677.3497442455298;684.5071548485083;693.4591019005184;4128.814203360856;4560.88597785058;4611.354410382549;4647.674662773641;4791.712497250176;85206;104022.95977040133;119671.40164299123;121256;133108;118929.40535297268;120394;120468;123940;124067;60660;60770.392296076476;60923.69538152281;61963.38036619666;83291.5835420819;8696.391252612257;8953.418027828171;8979.640814367613;9086.500242486462;9226.40028398152;53114.734426327625;58332.70833645974;58856.4114358868;59594.70202648959;60254.397456024875;56830.715846420506;57391.139132912205;59177;88825.66761498453;91087.51512242803;11663.708407289885;11746.882531174579;11982.70043248891;12116.151869369412;12457.314847683465;13200.273984930536;13211.405486752927;13357.933210333886;13497.797533037208;13633.522826701017;12102.346514924582;12465.688357791129;12479.750404992032;12601.534577991126;12650.177738446971;11496.597619083292;12245.632631021084;12266.509339626315;12378.133530652767;12485.12604117703;11995.340256285988;12065.879341206826;12285.136501516698;12519.812202817147;12526.561570344995;9423.764405889908;10243.84634230502;10436.895631043591;10788.244822862323;11049.447527623426;10418.270721049454;10729.892701073202;10747.838782418426;10758.8386174209;10784.299020563638 15:58:44 INFO - PROCESS | 1942 | __end_tp_report 15:58:44 INFO - PROCESS | 1942 | __start_cc_report 15:58:44 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,1484 15:58:44 INFO - PROCESS | 1942 | __end_cc_report 15:58:44 INFO - PROCESS | 1942 | __startTimestamp1475535524840__endTimestamp 15:58:44 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | ------- Summary: start ------- 15:58:44 INFO - PROCESS | 1942 | Number of tests: 6 15:58:44 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5683.46 Median:5826.97 stddev:651.10 (11.2%) stddev-sans-first:615.44 15:58:44 INFO - PROCESS | 1942 | Values: 2901.6 3288.8 3320.6 3348.5 3357.5 5584.1 5633.5 5636.7 5687.9 5707.4 5634.6 5661.0 5721.3 5724.7 5765.3 5086.2 5119.1 5123.4 5136.5 5175.5 4732.2 5224.5 5246.0 5247.0 5294.7 4340.8 5192.5 5203.6 5232.1 5233.3 5054.2 5136.5 5145.6 5153.8 5171.3 5103.3 5128.6 5130.2 5174.5 5216.7 5589.5 5673.0 5676.1 5678.7 5729.5 5970.1 6019.0 6037.7 6160.8 6187.3 5128.3 6167.2 6255.2 6268.4 6290.5 5767.7 5786.0 5787.1 5788.5 5794.1 6105.5 6264.0 6313.7 6367.6 6370.4 5877.1 6075.5 6095.4 6123.5 6127.4 6235.0 6237.5 6239.8 6250.7 6257.8 6105.2 6109.2 6158.9 6187.8 6193.7 6037.0 6120.1 6123.4 6146.4 6148.0 4861.8 4872.3 4872.5 4897.5 4911.6 4366.0 4390.3 4414.1 4431.8 4487.0 5699.2 5736.2 5753.9 5762.0 5775.0 6084.3 6097.1 6118.0 6125.7 6176.4 5707.1 5729.1 5738.3 5753.6 5787.4 5319.9 5327.5 5332.3 5347.1 5350.4 6220.8 6245.3 6251.5 6276.9 6375.1 6192.4 6216.6 6291.7 6302.9 6314.8 6326.9 6329.1 6337.6 6339.8 6358.7 5975.9 6054.4 6137.2 6170.4 6181.0 6112.9 6113.8 6148.2 6205.4 6234.2 5952.6 5973.2 5976.1 5981.0 5990.3 5728.1 5850.4 5973.6 5984.6 5985.2 5777.9 5822.9 5873.1 5873.9 5940.9 6019.4 6025.3 6038.3 6045.0 6059.3 5718.5 5776.8 5789.5 5827.0 5849.2 15:58:44 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:68578.57 Median:37737.94 stddev:150207.81 (398.0%) stddev-sans-first:150542.09 15:58:44 INFO - PROCESS | 1942 | Values: 451.1 475.2 476.0 484.9 526.1 30834.1 31249.0 31316.4 40866.8 41787.0 42167.0 42486.8 42489.7 42534.0 42589.4 9649.6 9833.9 9834.3 9837.7 9873.5 8811.0 9044.5 9053.4 9110.2 9713.6 8926.2 10211.9 11982.5 12276.3 12433.0 12110.6 12187.6 12191.2 12193.6 12205.5 11890.4 11967.1 11968.3 11975.5 11998.4 31637.7 32309.8 39536.4 40268.2 40317.6 3202.4 3219.8 3286.7 3339.3 3637.0 30771.4 30838.5 31198.0 31214.8 32081.0 2721.0 3197.8 3212.4 3218.7 3302.1 443220.0 444751.0 445647.0 445824.0 447968.0 59733.4 60272.4 60340.0 60340.4 60380.7 58976.8 63127.0 63170.7 63174.0 63284.7 15968.2 16180.7 16197.0 16202.9 16222.7 12481.7 12567.5 12569.2 12580.3 12594.7 7480.0 7792.4 7797.5 7803.8 17690.9 6885.3 6943.7 7890.8 8036.0 8133.8 863.3 937.2 951.2 984.8 1000.7 22507.4 29807.3 30159.2 30171.1 30531.1 1006.8 1024.9 1032.1 1058.3 1154.0 14625.5 15426.0 15470.9 15516.6 15776.9 642013.0 647125.0 648499.0 649360.0 649895.0 49954.5 50709.7 58027.4 58267.7 59115.7 1316.0 1349.3 1360.5 1361.5 1365.0 30093.4 30644.4 30827.1 31024.1 31417.5 51119.2 51626.5 51664.0 51713.5 51750.0 570066.0 573347.0 574318.0 574557.0 574843.0 21469.7 25436.4 25501.1 25911.4 25915.2 46886.3 47372.0 47783.8 47805.0 48292.0 29727.0 34845.0 36632.8 37484.1 39007.8 31038.7 38635.0 38664.6 39442.6 40210.8 37991.8 38341.8 38705.8 38722.2 38770.8 30143.2 31335.8 32487.8 34361.8 34385.8 25130.0 25182.4 25254.9 25750.9 26028.0 15:58:44 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:34323.17 Median:25788.74 stddev:98674.35 (382.6%) stddev-sans-first:98918.17 15:58:44 INFO - PROCESS | 1942 | Values: 344.0 358.3 361.4 367.5 394.1 14051.7 15002.7 16692.4 21260.3 21383.6 19655.4 20557.7 20693.5 20711.4 20899.0 5818.0 5834.5 5947.8 6104.5 6125.4 5283.5 5897.9 5929.1 5965.3 6022.8 4112.7 4334.4 5953.5 6219.7 6572.9 6456.9 6519.1 6569.5 6624.5 6627.4 4604.6 5642.1 5699.2 5713.1 5715.9 21476.9 24820.0 25714.6 25774.9 25802.6 2468.8 2471.7 2726.7 2858.8 3122.6 14816.4 15503.8 15834.4 15858.6 16330.5 1834.8 1845.5 1885.9 1888.4 1897.3 62611.0 112967.0 119609.0 120585.4 121008.0 10384.5 10458.2 10666.3 10829.7 10832.9 13482.6 13493.2 13495.3 13498.3 13500.6 6558.6 8403.5 8412.1 8592.7 8599.5 7276.8 8395.7 8510.7 8516.4 8680.9 6381.1 6530.9 6540.8 6551.6 6553.8 1361.4 1619.8 1668.2 1796.1 1798.5 832.9 884.9 942.8 967.8 975.1 20910.9 22033.7 22136.9 22156.6 22180.1 905.5 935.6 991.4 1016.9 1055.8 10451.9 10652.3 11479.0 11487.9 11515.3 583541.0 589106.0 594177.0 595769.0 609150.6 90336.0 114077.4 119024.7 119504.0 124836.8 926.8 958.7 991.9 992.8 996.4 15870.2 19950.5 20078.6 20255.6 20258.8 52610.7 52915.0 53861.0 53941.7 54005.7 71962.3 74094.6 74958.6 77236.6 77403.6 7601.4 7773.8 7805.4 8037.9 8097.2 13018.7 13133.5 13147.0 13219.5 13554.9 11265.3 11315.8 11610.1 11703.9 11704.4 11136.9 11247.6 11306.4 11432.0 11571.1 11597.8 11747.7 11921.7 11944.3 12045.6 7624.6 7836.8 7881.0 7959.8 7969.3 11239.7 11385.8 11426.0 11657.3 11812.7 15:58:44 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5611.37 Median:4860.31 stddev:3166.38 (65.1%) stddev-sans-first:3160.38 15:58:44 INFO - PROCESS | 1942 | Values: 1526.0 1536.2 1756.2 1888.7 1915.9 8431.3 8958.1 8965.7 8968.4 8983.9 6575.8 8055.8 8080.0 8086.1 8098.3 2062.5 2244.6 2325.7 2325.7 2331.6 1977.4 2366.0 2366.7 2369.3 2372.2 2281.7 2375.9 2378.8 2381.5 2383.5 2324.1 2328.4 2366.2 2369.2 2369.7 2328.5 2348.3 2356.7 2357.4 2364.1 7456.7 8551.0 8705.6 8713.7 8789.7 5149.2 5237.2 5329.7 5335.2 5344.3 3464.9 3599.9 3669.4 3672.3 3689.8 1020.8 1082.2 1104.1 1104.5 1106.7 11676.9 11684.1 11685.5 11696.4 11713.8 3925.4 4347.3 4514.3 4706.6 4732.6 9893.3 9912.4 9915.8 9916.5 9917.0 5292.4 5295.8 5305.2 5326.1 5330.8 4738.6 4807.5 4859.4 4878.3 4883.8 3638.9 3797.8 3798.5 3809.6 3811.6 1201.8 1243.2 1280.8 1284.7 1285.7 760.4 863.2 865.8 878.4 904.1 4755.7 5300.5 5782.3 5834.3 5862.9 887.3 902.8 911.3 947.8 967.4 3745.6 3750.3 3753.0 3840.6 3862.5 10230.3 10620.9 10698.6 10700.5 10700.6 10661.8 10670.6 10679.8 10681.1 10686.6 6385.4 7906.1 8040.3 8191.0 8231.5 5462.8 5585.9 5593.8 5614.6 5836.1 9778.9 9831.4 10049.3 10053.2 10176.8 10046.7 10100.2 10224.0 10318.2 10319.6 6950.8 7088.8 7091.3 7091.5 7094.5 8090.2 8166.2 8235.8 8245.2 8253.0 7293.9 7329.9 7350.8 7482.5 7492.0 7239.6 7277.4 7295.4 7307.5 7319.3 6739.9 6754.2 6851.5 6856.6 6878.1 4812.0 4821.9 4861.2 4862.4 4902.8 4787.1 4806.3 4863.1 4933.9 5049.3 15:58:44 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:4529.53 Median:5183.06 stddev:1423.42 (27.5%) stddev-sans-first:1391.37 15:58:44 INFO - PROCESS | 1942 | Values: 289.2 293.2 301.6 306.0 307.2 3865.9 4774.1 4852.0 5366.5 5442.2 4533.6 4710.3 5192.0 5235.2 5262.9 3294.4 3839.3 3990.5 3990.9 4003.1 3331.9 3361.8 3658.4 4044.4 4049.1 3134.7 3997.8 4010.6 4016.8 4026.5 3132.8 3837.6 3998.6 4000.3 4032.8 3493.5 3502.6 3512.2 3519.3 3530.9 4619.4 4959.5 5125.4 5169.7 5193.7 3999.9 4078.4 4445.0 4495.2 4601.4 5330.5 5564.7 5570.7 5587.6 5589.7 3735.4 3774.7 3807.3 3859.9 3863.7 5805.1 5806.4 5808.5 5831.4 5900.9 3901.5 3913.7 3936.9 3942.3 3946.7 3643.1 3954.3 3984.3 3999.8 4003.3 3992.6 4513.1 4546.7 4607.3 4615.9 5747.7 5748.5 5754.3 5764.4 5778.8 1181.3 1207.7 1246.2 1257.3 1357.3 728.4 835.5 860.8 894.9 915.8 5160.4 5259.3 5279.7 5307.6 5311.4 4346.0 5432.6 5456.3 5734.4 5764.5 4580.4 5022.9 5354.3 5378.9 5406.3 2979.0 3017.9 3026.5 3044.9 3059.8 5119.0 5157.0 5252.6 5593.1 6000.8 4937.6 5437.1 5729.6 5928.4 5987.7 5357.6 5996.0 6030.2 6040.9 6090.5 5050.7 5050.9 5838.4 5858.9 5871.9 5083.4 5780.7 5802.8 5819.5 5822.7 5309.1 5797.0 5990.7 6033.5 6091.7 3354.9 3386.0 3541.4 3725.9 4089.5 5174.1 5811.0 5826.8 5829.8 5895.7 4977.3 5264.4 5303.9 5711.3 5715.8 5644.5 5648.0 5654.3 5656.2 5657.6 5257.6 5427.7 5442.6 5443.2 5566.9 5450.6 5470.5 5611.9 5650.4 5674.3 5219.3 5468.4 5495.2 5534.5 5604.3 15:58:44 INFO - PROCESS | 1942 | 15:58:44 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:21487.85 Median:4424.49 stddev:32855.54 (742.6%) stddev-sans-first:32910.29 15:58:44 INFO - PROCESS | 1942 | Values: 605.7 717.1 736.0 750.8 827.2 15323.8 15676.5 15845.8 15899.6 15948.4 13111.7 13207.5 13278.8 13281.9 13297.9 736.6 852.4 855.8 855.9 857.0 678.8 696.9 824.7 859.9 860.5 859.5 878.0 903.3 906.0 906.8 897.8 899.2 901.1 901.6 901.8 522.3 538.4 647.3 665.9 671.5 15161.5 15212.0 15240.7 15249.2 15249.7 13635.6 14037.3 14211.1 14426.4 14448.2 12587.2 12685.0 12737.7 12868.5 12872.2 4507.2 4553.0 4553.6 4621.5 4760.2 104997.5 106155.0 107843.0 108855.0 110391.4 11028.2 11103.8 11160.4 11296.7 11531.4 22292.0 22317.0 22325.4 22343.0 22383.3 6770.7 7836.6 7912.4 7966.0 8216.0 4316.3 4389.5 4459.5 4516.1 4595.9 3466.0 3612.5 3616.0 3617.3 3617.4 660.7 671.7 679.3 680.4 680.8 603.0 617.2 621.6 625.7 644.2 8457.6 8623.2 8883.0 9108.5 9254.4 561.5 614.2 677.3 684.5 693.5 4128.8 4560.9 4611.4 4647.7 4791.7 85206.0 104023.0 119671.4 121256.0 133108.0 118929.4 120394.0 120468.0 123940.0 124067.0 60660.0 60770.4 60923.7 61963.4 83291.6 8696.4 8953.4 8979.6 9086.5 9226.4 53114.7 58332.7 58856.4 59594.7 60254.4 56830.7 57391.1 59177.0 88825.7 91087.5 11663.7 11746.9 11982.7 12116.2 12457.3 13200.3 13211.4 13357.9 13497.8 13633.5 12102.3 12465.7 12479.8 12601.5 12650.2 11496.6 12245.6 12266.5 12378.1 12485.1 11995.3 12065.9 12285.1 12519.8 12526.6 9423.8 10243.8 10436.9 10788.2 11049.4 10418.3 10729.9 10747.8 10758.8 10784.3 15:58:44 INFO - PROCESS | 1942 | -------- Summary: end -------- 15:58:44 INFO - PROCESS | 1942 | 15:58:45 INFO - TEST-INFO | 1942: exit 0 15:58:45 INFO - TEST-OK | dromaeo_css | took 1118228ms 15:58:45 INFO - TEST-START | kraken 15:58:45 INFO - operating with platform_type : mac_ 15:58:45 INFO - Initialising browser for kraken test... 15:58:45 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmprJaq2k/profile http://localhost:49439/getInfo.html) 15:58:48 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 15:58:48 INFO - PROCESS | 1945 | colorDepth:24 15:58:48 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 15:58:48 INFO - PROCESS | 1945 | __metrics 15:58:48 INFO - PROCESS | 1945 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:58:48 INFO - PROCESS | 1945 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:58:49 INFO - TEST-INFO | 1945: exit 0 15:58:49 INFO - Browser initialized. 15:58:49 INFO - Running cycle 1/1 for kraken test... 15:58:49 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmprJaq2k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:00 INFO - PROCESS | 1946 | RSS: Main: 236384256 15:59:00 INFO - PROCESS | 1946 | 15:59:03 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49439/tests/kraken/driver.html?testName=audio-beat-detection) 15:59:04 INFO - PROCESS | 1946 | RSS: Main: 294813696 15:59:04 INFO - PROCESS | 1946 | 15:59:07 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49439/tests/kraken/driver.html?testName=audio-dft) 15:59:07 INFO - PROCESS | 1946 | RSS: Main: 293588992 15:59:07 INFO - PROCESS | 1946 | 15:59:11 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49439/tests/kraken/driver.html?testName=audio-fft) 15:59:12 INFO - PROCESS | 1946 | RSS: Main: 384692224 15:59:12 INFO - PROCESS | 1946 | 15:59:14 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49439/tests/kraken/driver.html?testName=audio-oscillator) 15:59:14 INFO - PROCESS | 1946 | RSS: Main: 285270016 15:59:14 INFO - PROCESS | 1946 | 15:59:16 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49439/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:59:17 INFO - PROCESS | 1946 | RSS: Main: 290680832 15:59:17 INFO - PROCESS | 1946 | 15:59:20 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49439/tests/kraken/driver.html?testName=imaging-darkroom) 15:59:20 INFO - PROCESS | 1946 | RSS: Main: 328122368 15:59:20 INFO - PROCESS | 1946 | 15:59:23 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49439/tests/kraken/driver.html?testName=imaging-desaturate) 15:59:24 INFO - PROCESS | 1946 | RSS: Main: 329773056 15:59:24 INFO - PROCESS | 1946 | 15:59:27 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49439/tests/kraken/driver.html?testName=json-parse-financial) 15:59:27 INFO - PROCESS | 1946 | RSS: Main: 327041024 15:59:27 INFO - PROCESS | 1946 | 15:59:29 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49439/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:59:29 INFO - PROCESS | 1946 | RSS: Main: 303992832 15:59:29 INFO - PROCESS | 1946 | 15:59:31 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:59:32 INFO - PROCESS | 1946 | RSS: Main: 304635904 15:59:32 INFO - PROCESS | 1946 | 15:59:34 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:59:35 INFO - PROCESS | 1946 | RSS: Main: 305127424 15:59:35 INFO - PROCESS | 1946 | 15:59:38 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:38 INFO - PROCESS | 1946 | RSS: Main: 317349888 15:59:38 INFO - PROCESS | 1946 | 15:59:41 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:59:42 INFO - PROCESS | 1946 | RSS: Main: 319143936 15:59:42 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49439/tests/kraken/driver.html?testName=ai-astar) 15:59:44 INFO - PROCESS | 1946 | RSS: Main: 362512384 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | __start_tp_report 15:59:44 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 15:59:44 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 15:59:44 INFO - PROCESS | 1946 | |i|pagename|runs| 15:59:44 INFO - PROCESS | 1946 | |0;ai-astar;113;113;112;110;112;112;112;113;112;112 15:59:44 INFO - PROCESS | 1946 | |1;audio-beat-detection;150;113;113;150;112;114;113;114;146;113 15:59:44 INFO - PROCESS | 1946 | |2;audio-dft;191;259;185;191;215;184;186;184;178;184 15:59:44 INFO - PROCESS | 1946 | |3;audio-fft;79;60;59;89;62;59;61;59;82;57 15:59:44 INFO - PROCESS | 1946 | |4;audio-oscillator;88;72;91;69;68;69;92;69;70;89 15:59:44 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;87;84;91;130;88;84;89;85;87;84 15:59:44 INFO - PROCESS | 1946 | |6;imaging-darkroom;93;94;94;94;93;94;94;94;94;94 15:59:44 INFO - PROCESS | 1946 | |7;imaging-desaturate;86;86;84;86;86;84;87;85;84;85 15:59:44 INFO - PROCESS | 1946 | |8;json-parse-financial;57;56;57;57;58;58;56;55;55;54 15:59:44 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;74;66;82;73;73;69;69;69;69;71 15:59:44 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;87;86;86;84;83;84;88;89;87;87 15:59:44 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;146;146;148;145;156;151;157;145;172;144 15:59:44 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;170;169;173;172;172;174;168;171;172;171 15:59:44 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;68;70;70;68;67;68;68;69;68;66 15:59:44 INFO - PROCESS | 1946 | __end_tp_report 15:59:44 INFO - PROCESS | 1946 | __start_cc_report 15:59:44 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,1942 15:59:44 INFO - PROCESS | 1946 | __end_cc_report 15:59:44 INFO - PROCESS | 1946 | __startTimestamp1475535584387__endTimestamp 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | ------- Summary: start ------- 15:59:44 INFO - PROCESS | 1946 | Number of tests: 14 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:0.88 (0.8%) stddev-sans-first:0.87 15:59:44 INFO - PROCESS | 1946 | Values: 113.0 113.0 112.0 110.0 112.0 112.0 112.0 113.0 112.0 112.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:123.80 Median:114.00 stddev:17.20 (15.1%) stddev-sans-first:15.41 15:59:44 INFO - PROCESS | 1946 | Values: 150.0 113.0 113.0 150.0 112.0 114.0 113.0 114.0 146.0 113.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:195.70 Median:188.50 stddev:24.40 (12.9%) stddev-sans-first:25.82 15:59:44 INFO - PROCESS | 1946 | Values: 191.0 259.0 185.0 191.0 215.0 184.0 186.0 184.0 178.0 184.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.50 stddev:11.80 (19.2%) stddev-sans-first:11.65 15:59:44 INFO - PROCESS | 1946 | Values: 79.0 60.0 59.0 89.0 62.0 59.0 61.0 59.0 82.0 57.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:80.00 stddev:10.69 (13.4%) stddev-sans-first:10.67 15:59:44 INFO - PROCESS | 1946 | Values: 88.0 72.0 91.0 69.0 68.0 69.0 92.0 69.0 70.0 89.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:90.90 Median:87.00 stddev:13.94 (16.0%) stddev-sans-first:14.71 15:59:44 INFO - PROCESS | 1946 | Values: 87.0 84.0 91.0 130.0 88.0 84.0 89.0 85.0 87.0 84.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 15:59:44 INFO - PROCESS | 1946 | Values: 93.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:86.00 stddev:1.06 (1.2%) stddev-sans-first:1.09 15:59:44 INFO - PROCESS | 1946 | Values: 86.0 86.0 84.0 86.0 86.0 84.0 87.0 85.0 84.0 85.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.34 (2.3%) stddev-sans-first:1.39 15:59:44 INFO - PROCESS | 1946 | Values: 57.0 56.0 57.0 57.0 58.0 58.0 56.0 55.0 55.0 54.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:4.43 (6.2%) stddev-sans-first:4.60 15:59:44 INFO - PROCESS | 1946 | Values: 74.0 66.0 82.0 73.0 73.0 69.0 69.0 69.0 69.0 71.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:87.00 stddev:1.91 (2.2%) stddev-sans-first:2.00 15:59:44 INFO - PROCESS | 1946 | Values: 87.0 86.0 86.0 84.0 83.0 84.0 88.0 89.0 87.0 87.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:149.50 stddev:8.71 (5.8%) stddev-sans-first:9.04 15:59:44 INFO - PROCESS | 1946 | Values: 146.0 146.0 148.0 145.0 156.0 151.0 157.0 145.0 172.0 144.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:172.00 stddev:1.81 (1.1%) stddev-sans-first:1.87 15:59:44 INFO - PROCESS | 1946 | Values: 170.0 169.0 173.0 172.0 172.0 174.0 168.0 171.0 172.0 171.0 15:59:44 INFO - PROCESS | 1946 | 15:59:44 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 15:59:44 INFO - PROCESS | 1946 | Values: 68.0 70.0 70.0 68.0 67.0 68.0 68.0 69.0 68.0 66.0 15:59:44 INFO - PROCESS | 1946 | -------- Summary: end -------- 15:59:44 INFO - PROCESS | 1946 | 15:59:45 INFO - TEST-INFO | 1946: exit 0 15:59:45 INFO - TEST-OK | kraken | took 59942ms 15:59:45 INFO - SUITE-END | took 1178s 15:59:45 INFO - Completed test suite (00:19:38) 15:59:45 INFO - javascript benchmark 15:59:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2901.5792710057044, 3288.7533434992383, 3320.5849268841393, 3348.5479460272854, 3357.5467311912885, 5584.106542953128, 5633.52115070219, 5636.7181640918, 5687.914681279784, 5707.372189059205, 5634.605577609569, 5660.9716951415285, 5721.256236689224, 5724.656520608766, 5765.279340082479, 5086.160783470723, 5119.129747942859, 5123.359580052494, 5136.460671629481, 5175.534201921826, 4732.1718699227495, 5224.4775522447835, 5246.003259380725, 5247.0, 5294.708791016493, 4340.826366945318, 5192.506711862367, 5203.557697595709, 5232.110541208004, 5233.345831771028, 5054.1660625996665, 5136.460671629462, 5145.614078944057, 5153.768080436389, 5171.301874246966, 5103.260027296058, 5128.640995130342, 5130.204818253177, 5174.534291913746, 5216.66091704038, 5589.524890384322, 5673.035583950697, 5676.148577713376, 5678.659280443146, 5729.4843464088435, 5970.13433052205, 6019.036954087369, 6037.667928263943, 6160.845978850564, 6187.319394866561, 5128.333316668809, 6167.229096362955, 6255.218097737783, 6268.373162683741, 6290.528210384194, 5767.711614419304, 5785.971070144622, 5787.074068149118, 5788.479036886701, 5794.072948328248, 6105.490073363267, 6264.0, 6313.652749098844, 6367.649779262092, 6370.426661600478, 5877.0596704527925, 6075.48358389533, 6095.3904609538695, 6123.465646421124, 6127.437503436684, 6235.0, 6237.532185086136, 6239.812805615838, 6250.749970001149, 6257.78097766576, 6105.206323177958, 6109.236345456818, 6158.876822463526, 6187.752489900444, 6193.659348735863, 6036.9698151508965, 6120.14317995481, 6123.387661233841, 6146.416090471398, 6147.969260153671, 4861.781219845116, 4872.293517439952, 4872.4640289568115, 4897.4857639948, 4911.63162762787, 4365.978170109129, 4390.319500477431, 4414.050979039522, 4431.800568974404, 4487.0, 5699.173619825102, 5736.168255602997, 5753.913691294634, 5762.0, 5775.0, 6084.330723620398, 6097.054956481752, 6118.0, 6125.662799505599, 6176.382361763787, 5707.086866101404, 5729.1119876419225, 5738.311402631711, 5753.597248192586, 5787.421257874179, 5319.9202011969855, 5327.493888080626, 5332.280142180756, 5347.117725575236, 5350.411454739976, 6220.844478887883, 6245.281792593728, 6251.499880009497, 6276.9372306276355, 6375.075614035846, 6192.442680158807, 6216.627002379872, 6291.653959032297, 6302.873942521032, 6314.778982735582, 6326.936730632819, 6329.145565348619, 6337.556371053982, 6339.809805705836, 6358.713857876499, 5975.910361344497, 6054.3642917493025, 6137.202163718688, 6170.413810687977, 6181.0, 6112.898653789679, 6113.755449781959, 6148.200733904564, 6205.410486003822, 6234.1583886174785, 5952.623194088149, 5973.1637570741, 5976.073708575178, 5981.043033114681, 5990.31111422192, 5728.112142617901, 5850.356460789225, 5973.581849270509, 5984.611000284967, 5985.221921150222, 5777.884442311046, 5822.854428639318, 5873.148393482836, 5873.94126058751, 5940.910886336795, 6019.398060193946, 6025.307089684742, 6038.3357830637715, 6045.0327947528185, 6059.303180134341, 5718.456746609066, 5776.768929242783, 5789.45000224978, 5826.970865145647, 5849.239598852122], "value": 5640.282632553088, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.1270691212503, 475.23724422302206, 475.96240196371895, 484.88847565060036, 526.0636067799319, 30834.074977750675, 31248.99999999997, 31316.373672526563, 40866.7956660217, 41787.0, 42167.0, 42486.78756606213, 42489.725308240726, 42534.0, 42589.36115958263, 9649.614015439392, 9833.90166098337, 9834.26243031772, 9837.704868853945, 9873.506324683773, 8810.955945220265, 9044.502552359618, 9053.411528250675, 9110.180083792457, 9713.61145554177, 8926.196642302191, 10211.948940255288, 11982.46078926446, 12276.32480213588, 12433.0, 12110.636680899543, 12187.57343492978, 12191.207571507824, 12193.634190974284, 12205.511779528808, 11890.405479726065, 11967.102467314899, 11968.281903085843, 11975.46110425033, 11998.400079995965, 31637.683623163703, 32309.83845080783, 39536.40695389571, 40268.19463610712, 40317.59682403197, 3202.3915456063273, 3219.7907136036083, 3286.6713328666942, 3339.34882697874, 3636.9636303637153, 30771.384572308652, 30838.53742193869, 31198.0, 31214.843925780453, 32081.0, 2720.986395068032, 3197.8081315121167, 3212.4378233809225, 3218.6620404857385, 3302.075418882717, 443220.0, 444751.0, 445647.0, 445823.99999999674, 447968.0, 59733.40266597278, 60272.39727602668, 60340.0, 60340.396596033475, 60380.69809650924, 58976.82046359049, 63127.0, 63170.68414657989, 63174.0, 63284.683576581825, 15968.201589920458, 16180.67638647244, 16197.0, 16202.918985405233, 16222.675546489005, 12481.687957801129, 12567.497300107892, 12569.245845249314, 12580.30808305552, 12594.685132871751, 7480.0, 7792.376609871197, 7797.532148071134, 7803.843923121619, 17690.85009474406, 6885.311468853075, 6943.6875340609795, 7890.802729931797, 8036.0, 8133.820596013545, 863.3309185381254, 937.2033771294482, 951.2009911674088, 984.7981163861391, 1000.7248006798188, 22507.385985872857, 29807.254818629273, 30159.246018849703, 30171.05308731802, 30531.084067478012, 1006.8036732837173, 1024.8616436781087, 1032.0608246495653, 1058.25921854702, 1153.9653810385703, 14625.488107916171, 15426.0, 15470.872185410437, 15516.612084697746, 15776.92111539458, 642013.0, 647125.0, 648498.9676496881, 649360.0, 649895.0, 49954.50045499571, 50709.74645126751, 58027.4197258022, 58267.70866145642, 59115.70442147762, 1316.0, 1349.2713934475469, 1360.5306169371552, 1361.4622224221378, 1365.0240078343834, 30093.37501059359, 30644.387112257184, 30827.0751877444, 31024.121984751906, 31417.52873706942, 51119.2332115026, 51626.48373516367, 51663.966720666125, 51713.48286517237, 51750.0, 570066.0, 573347.0, 574318.0, 574557.0, 574843.0, 21469.67795483038, 25436.364090897874, 25501.107461238767, 25911.35221619399, 25915.22254332373, 46886.29670555013, 47372.0, 47783.761081194374, 47805.0, 48292.0, 29727.0, 34844.9546513605, 36632.81683591565, 37484.062898426666, 39007.804960975016, 31038.68961310358, 38635.0, 38664.6133538661, 39442.605573943896, 40210.79894600508, 37991.81004094962, 38341.80829095948, 38705.806470967465, 38722.2255554893, 38770.80614596909, 30143.246418839706, 31335.84332078325, 32487.837560812044, 34361.82819085888, 34385.82807086049, 25130.0, 25182.370440738396, 25254.873725631252, 25750.87124564365, 26027.95888164452], "value": 19561.724350894172, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.0452743636407, 358.31382901743166, 361.40007587404915, 367.5442451360313, 394.06409776780146, 14051.735343819055, 15002.699946001074, 16692.415765448226, 21260.255891043817, 21383.57232855342, 19655.410337689857, 20557.691634625455, 20693.482662933395, 20711.378658640264, 20898.955052247402, 5818.0, 5834.533237341003, 5947.821565353046, 6104.481119104881, 6125.418085281914, 5283.471652834705, 5897.911531327033, 5929.080992446177, 5965.313988891268, 6022.849428764294, 4112.670986321102, 4334.393184954109, 5953.493953013999, 6219.662772503911, 6572.901406478906, 6456.935430645727, 6519.119918810995, 6569.540132190748, 6624.53628246023, 6627.403533681992, 4604.608608268301, 5642.069058605325, 5699.173619825144, 5713.085906254979, 5715.942840571583, 21476.926153692253, 24820.0, 25714.6142807858, 25774.87112564425, 25802.612960805604, 2468.839525430841, 2471.7033955925403, 2726.7320695876315, 2858.771298296131, 3122.5784519089857, 14816.407343706238, 15503.767443488357, 15834.445794397256, 15858.603534911605, 16330.510084697478, 1834.8440382567499, 1845.5478407790176, 1885.9434216973514, 1888.3862744608057, 1897.250586018515, 62611.0, 112967.0, 119609.0, 120585.39707301494, 121008.0, 10384.480775961323, 10458.163346932228, 10666.306690065121, 10829.67510974672, 10832.891671083345, 13482.595522134352, 13493.190408575518, 13495.25776082325, 13498.325083745773, 13500.594982150551, 6558.639274839929, 8403.495790252604, 8412.116727743622, 8592.699255526026, 8599.484030958038, 7276.781696549112, 8395.70615028471, 8510.70212542558, 8516.403851730443, 8680.913190868137, 6381.1385462962835, 6530.869382612416, 6540.771073012422, 6551.6069035858, 6553.803385898431, 1361.4486133116104, 1619.8380161983944, 1668.1825905306312, 1796.119901248379, 1798.5054110119822, 832.9083800781909, 884.9026607073346, 942.7784470649392, 967.7870868408938, 975.0882924465648, 20910.895445522674, 22033.669494957587, 22136.889315553643, 22156.556868862855, 22180.112795488323, 905.4963027229716, 935.6023689931887, 991.3902949685892, 1016.8576399304103, 1055.767731099157, 10451.895481045245, 10652.307600005975, 11479.024282935878, 11487.885121148849, 11515.309081454972, 583541.0, 589106.0, 594177.0291148603, 595769.0, 609150.588223531, 90336.0, 114077.42961285307, 119024.69172722491, 119504.0, 124836.75163248433, 926.7636752628036, 958.726762872578, 991.9008099190168, 992.846108853129, 996.4370130875886, 15870.2064896757, 19950.50123746918, 20078.598428031648, 20255.59488810186, 20258.79741202569, 52610.73694631655, 52915.0, 53861.0, 53941.73029134829, 54005.67954563547, 71962.28037719555, 74094.62952685202, 74958.62520687362, 77236.61381693055, 77403.61298193473, 7601.3918886490055, 7773.844523109393, 7805.409511624716, 8037.919620803717, 8097.190280971856, 13018.739625207632, 13133.540326088541, 13147.0, 13219.471221151047, 13554.932225339204, 11265.324080555192, 11315.830262545904, 11610.071194304604, 11703.941480292544, 11704.414779260833, 11136.888631113585, 11247.606333778278, 11306.378149201873, 11432.0, 11571.074314054687, 11597.826032609686, 11747.706307342385, 11921.701957450787, 11944.343061131374, 12045.578404755792, 7624.58064806441, 7836.84326313482, 7881.0, 7959.761207163794, 7969.282764550986, 11239.662810115382, 11385.829212561985, 11426.02878755315, 11657.300561966309, 11812.70468238301], "value": 9690.35966053331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1525.9999999999995, 1536.2011753887978, 1756.2272600055974, 1888.6978083506642, 1915.942521724348, 8431.283340916021, 8958.148975847293, 8965.731028069169, 8968.372213945026, 8983.865242021358, 6575.802725918218, 8055.838883222346, 8080.030396352446, 8086.070101938279, 8098.271155595995, 2062.453449835795, 2244.5847518209184, 2325.7092863392077, 2325.7209134903837, 2331.5686597979343, 1977.4265463015709, 2365.9881700591473, 2366.692329997098, 2369.3247424484025, 2372.240882917467, 2281.6577513372963, 2375.92872213833, 2378.8096952243777, 2381.4641705616273, 2383.5471260460545, 2324.0703718512646, 2328.382978510696, 2366.183666635008, 2369.2300002499082, 2369.6919400478, 2328.52265285616, 2348.2720356689483, 2356.658284548731, 2357.39886328985, 2364.0780095762666, 7456.7390141344795, 8550.973883134002, 8705.564721763887, 8713.738587842376, 8789.692360767407, 5149.150390185615, 5237.16205407133, 5329.733513324319, 5335.226392173152, 5344.278522399504, 3464.861405543775, 3599.9460008099636, 3669.412893936984, 3672.320620685155, 3689.797061161635, 1020.7958408318366, 1082.2045796339685, 1104.1222228328497, 1104.5471356743697, 1106.6845948904524, 11676.883231167665, 11684.12369072323, 11685.474153663106, 11696.415179241005, 11713.765724685543, 3925.391564307537, 4347.347897815353, 4514.255147900625, 4706.576408123216, 4732.621390288768, 9893.307468477282, 9912.405255684682, 9915.80168396628, 9916.4545949972, 9916.95871933451, 5292.417834038252, 5295.781970146888, 5305.230741542455, 5326.067938110815, 5330.813421530305, 4738.620910327166, 4807.543283388072, 4859.392575928009, 4878.341423907798, 4883.8290659826735, 3638.9090272742865, 3797.7911214882893, 3798.5441746989986, 3809.580946095927, 3811.6378944000276, 1201.783678937782, 1243.160866415166, 1280.8334916460801, 1284.6852521132382, 1285.7042880137622, 760.444875241077, 863.1799790199336, 865.7878819689216, 878.3851304087165, 904.1455824246025, 4755.738434386073, 5300.549453296436, 5782.335031471434, 5834.27071616048, 5862.912056319158, 887.3389324952951, 902.7697937026147, 911.3256190419042, 947.7772723409993, 967.4002118686459, 3745.606711295329, 3750.268697603941, 3752.962470375316, 3840.558335791364, 3862.517185351831, 10230.335028223142, 10620.946895265473, 10698.62554810578, 10700.518476668569, 10700.571977120984, 10661.84007239892, 10670.62652807148, 10679.83980240297, 10681.092107170822, 10686.625968091235, 6385.36146385367, 7906.090799558125, 8040.27637512615, 8191.0, 8231.547264900368, 5462.754176062007, 5585.860353491195, 5593.804216852391, 5614.606977511535, 5836.066229403275, 9778.9022109778, 9831.410115393099, 10049.346792458467, 10053.19574434058, 10176.796464070825, 10046.69859904204, 10100.242481813746, 10224.0, 10318.17454603645, 10319.638812641522, 6950.791476255518, 7088.787336379917, 7091.2554181810165, 7091.503594748318, 7094.467914906506, 8090.231428014329, 8166.183381661786, 8235.788042663486, 8245.216704413071, 8253.0, 7293.890591641236, 7329.853402931805, 7350.816229594303, 7482.476226664299, 7492.0, 7239.601821899846, 7277.417806575355, 7295.3798927089665, 7307.525010874276, 7319.304666056716, 6739.887918493394, 6754.223264324665, 6851.45188384918, 6856.554323968926, 6878.105846240157, 4811.975940120277, 4821.9035619288115, 4861.222204447413, 4862.3922009748785, 4902.803887844446, 4787.138315103176, 4806.279058141167, 4863.076015557032, 4933.851984440473, 5049.2930989660745], "value": 4514.271557917054, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.2277618757915, 293.15863471835826, 301.61845265738845, 306.0466646396473, 307.22425874666465, 3865.884023479293, 4774.069056533977, 4851.986425633358, 5366.517013468787, 5442.2380866678695, 4533.637309015271, 4710.293455981596, 5191.974040129794, 5235.240890070937, 5262.9473705262935, 3294.4234758917214, 3839.2897313997, 3990.4612877261634, 3990.8825528851967, 4003.11931375097, 3331.8671651638438, 3361.848716807737, 3658.432942893856, 4044.413560033793, 4049.129437171008, 3134.7304341741633, 3997.820642910334, 4010.598940105995, 4016.8393264269394, 4026.45642838216, 3132.7963682360687, 3837.5586807517216, 3998.6001399860074, 4000.3199456092243, 4032.8386864525382, 3493.493443450711, 3502.5972013218297, 3512.227309991798, 3519.260955199411, 3530.8638273844467, 4619.3994780678295, 4959.479254678279, 5125.436202017775, 5169.689818610896, 5193.688378697252, 3999.8800035998966, 4078.4494093297617, 4444.977775111136, 4495.235809912323, 4601.401817763703, 5330.520253177233, 5564.666120032811, 5570.693611851347, 5587.580931430119, 5589.664620122806, 3735.4396840473873, 3774.735768496206, 3807.257584770971, 3859.9192226176433, 3863.652271295597, 5805.129230615399, 5806.38712579341, 5808.46075789916, 5831.358550559435, 5900.881982360372, 3901.492805935267, 3913.7476007677838, 3936.8622098226424, 3942.3100957332354, 3946.743936252603, 3643.107438677541, 3954.268460334847, 3984.266843922903, 3999.8200080996426, 4003.319435695939, 3992.5807790182193, 4513.074819661962, 4546.749928753884, 4607.3319368691355, 4615.884602884955, 5747.729646789495, 5748.45389687979, 5754.309482862083, 5764.365919748824, 5778.797742079007, 1181.261711430356, 1207.6618546806908, 1246.1775228349245, 1257.2770656872335, 1357.3484727330936, 728.4318231779221, 835.4502398051527, 860.7761981884755, 894.932880034, 915.8351496730519, 5160.406553246425, 5259.263703081571, 5279.653688309533, 5307.6019298552765, 5311.380029091087, 4345.956540434619, 5432.565394768409, 5456.345238571397, 5734.39788822176, 5764.538836893039, 4580.381648477412, 5022.89954200914, 5354.277172581729, 5378.892422151535, 5406.324209473816, 2978.9722545721693, 3017.879284828582, 3026.530887712408, 3044.862981165853, 3059.7552195824283, 5118.97440512795, 5156.974215128976, 5252.579793616502, 5593.105103183471, 6000.819975400745, 4937.5556199942175, 5437.13005919051, 5729.567608097976, 5928.436798504135, 5987.700614969234, 5357.571394288526, 5996.0, 6030.216071910711, 6040.909386359208, 6090.451859332681, 5050.72221027847, 5050.898982020265, 5838.357780644041, 5858.882822343615, 5871.94128058714, 5083.364579427572, 5780.682062486436, 5802.82591522255, 5819.5053420459735, 5822.70886455667, 5309.062192299705, 5796.971015144897, 5990.6705131218205, 6033.487153591818, 6091.725872335667, 3354.8825791097192, 3386.0, 3541.3802584547084, 3725.9254814903006, 4089.483044581717, 5174.14626586624, 5811.0, 5826.805504871576, 5829.766809327749, 5895.702945352015, 4977.253411988231, 5264.420913699567, 5303.946960530499, 5711.3431955324, 5715.771369145354, 5644.548436125182, 5648.039833228443, 5654.3497497788285, 5656.1798539212405, 5657.60396772222, 5257.605679574123, 5427.674339539641, 5442.6462279951675, 5443.237946687546, 5566.916496252478, 5450.645708028966, 5470.507654311131, 5611.915821262603, 5650.378458369494, 5674.2623458950075, 5219.269302297757, 5468.3711373191, 5495.230667706604, 5534.501894829485, 5604.30993123295], "value": 4069.443243452082, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.7049649904519, 717.0678118446019, 735.9889601655973, 750.8122969257686, 827.1769589258687, 15323.770143447844, 15676.451324203656, 15845.762313565307, 15899.602509937258, 15948.36206551738, 13111.6722081948, 13207.537736179236, 13278.800817987738, 13281.933590332059, 13297.867021329808, 736.5985537881854, 852.4373913217278, 855.7646647172015, 855.9101294364095, 856.9801935696532, 678.8302924268933, 696.8815301398774, 824.6701319472229, 859.922606965373, 860.5009094725044, 859.4886042804521, 877.9912200878006, 903.3405613901856, 905.9943462756336, 906.8413027720155, 897.8473659477904, 899.2086963472103, 901.0583939782877, 901.5582364641311, 901.8376692195402, 522.3470661672909, 538.4480907070246, 647.3073811022208, 665.9001149827516, 671.4829581222432, 15161.545153645298, 15212.011219270822, 15240.695186096216, 15249.161296128708, 15249.695006099815, 13635.590932271949, 14037.298135093306, 14211.147331160164, 14426.422943082263, 14448.205348705817, 12587.24476531411, 12685.0, 12737.745245095139, 12868.549600764021, 12872.210447575115, 4507.188706032913, 4553.0, 4553.635709143261, 4621.537846215385, 4760.238361862085, 104997.47501262445, 106155.0, 107843.0, 108854.99999999921, 110391.44804276008, 11028.2280240384, 11103.777924441465, 11160.441977901073, 11296.71758206035, 11531.365774882463, 22292.0, 22316.995735191955, 22325.441863953532, 22343.0, 22383.32850014502, 6770.729170833111, 7836.608169591497, 7912.446128771046, 7966.0, 8215.973003374578, 4316.266234740102, 4389.517153113155, 4459.464864216315, 4516.051629157913, 4595.9080818383445, 3466.046837119812, 3612.5303710517464, 3615.9819200903826, 3617.3127105849803, 3617.4212126059706, 660.6531570925303, 671.7145213284334, 679.3071067511111, 680.3706571421416, 680.7515256931283, 602.9758809647652, 617.1791517282094, 621.5773274173605, 625.7403177681302, 644.223710428932, 8457.619407126695, 8623.223909848144, 8882.955585222162, 9108.499032553273, 9254.444733316022, 561.5423429904615, 614.1923370767427, 677.3497442455298, 684.5071548485083, 693.4591019005184, 4128.814203360856, 4560.88597785058, 4611.354410382549, 4647.674662773641, 4791.712497250176, 85206.0, 104022.95977040133, 119671.40164299123, 121256.0, 133108.0, 118929.40535297268, 120394.0, 120468.0, 123940.0, 124067.0, 60660.0, 60770.392296076476, 60923.69538152281, 61963.38036619666, 83291.5835420819, 8696.391252612257, 8953.418027828171, 8979.640814367613, 9086.500242486462, 9226.40028398152, 53114.734426327625, 58332.70833645974, 58856.4114358868, 59594.70202648959, 60254.397456024875, 56830.715846420506, 57391.139132912205, 59177.0, 88825.66761498453, 91087.51512242803, 11663.708407289885, 11746.882531174579, 11982.70043248891, 12116.151869369412, 12457.314847683465, 13200.273984930536, 13211.405486752927, 13357.933210333886, 13497.797533037208, 13633.522826701017, 12102.346514924582, 12465.688357791129, 12479.750404992032, 12601.534577991126, 12650.177738446971, 11496.597619083292, 12245.632631021084, 12266.509339626315, 12378.133530652767, 12485.12604117703, 11995.340256285988, 12065.879341206826, 12285.136501516698, 12519.812202817147, 12526.561570344995, 9423.764405889908, 10243.84634230502, 10436.895631043591, 10788.244822862323, 11049.447527623426, 10418.270721049454, 10729.892701073202, 10747.838782418426, 10758.8386174209, 10784.299020563638], "value": 7447.350947334211, "unit": "score"}], "name": "dromaeo_css", "value": 7258.882679560492, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 110.0, 112.0, 112.0, 112.0, 113.0, 112.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 113.0, 113.0, 150.0, 112.0, 114.0, 113.0, 114.0, 146.0, 113.0], "value": 123.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 259.0, 185.0, 191.0, 215.0, 184.0, 186.0, 184.0, 178.0, 184.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 59.0, 89.0, 62.0, 59.0, 61.0, 59.0, 82.0, 57.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 91.0, 69.0, 68.0, 69.0, 92.0, 69.0, 70.0, 89.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 91.0, 130.0, 88.0, 84.0, 89.0, 85.0, 87.0, 84.0], "value": 90.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 86.0, 86.0, 84.0, 87.0, 85.0, 84.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 57.0, 58.0, 58.0, 56.0, 55.0, 55.0, 54.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 66.0, 82.0, 73.0, 73.0, 69.0, 69.0, 69.0, 69.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 84.0, 83.0, 84.0, 88.0, 89.0, 87.0, 87.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 148.0, 145.0, 156.0, 151.0, 157.0, 145.0, 172.0, 144.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 173.0, 172.0, 172.0, 174.0, 168.0, 171.0, 172.0, 171.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 68.0, 67.0, 68.0, 68.0, 69.0, 68.0, 66.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1450.3, "alertThreshold": 2.0}]} 15:59:45 INFO - Return code: 0 15:59:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:59:45 INFO - # TBPL SUCCESS # 15:59:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:59:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:59:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:59:45 INFO - 2016-10-03 15:59:45.717 screenresolution[1950:15932] starting screenresolution argv=screenresolution get 15:59:45 INFO - 2016-10-03 15:59:45.720 screenresolution[1950:15932] Display 0: 1600x1200x32@60 15:59:45 INFO - 2016-10-03 15:59:45.726 screenresolution[1951:15935] starting screenresolution argv=screenresolution list 15:59:45 INFO - Available Modes on Display 0 15:59:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:59:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:59:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:59:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:59:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:59:45 INFO - Intel Iris: 15:59:45 INFO - Chipset Model: Intel Iris 15:59:45 INFO - Type: GPU 15:59:45 INFO - Bus: Built-In 15:59:45 INFO - VRAM (Dynamic, Max): 1536 MB 15:59:45 INFO - Vendor: Intel (0x8086) 15:59:45 INFO - Device ID: 0x0a2e 15:59:45 INFO - Revision ID: 0x0009 15:59:45 INFO - Displays: 15:59:45 INFO - Display: 15:59:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:59:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:59:45 INFO - Display Serial Number: 1600x1200 60 15:59:45 INFO - Main Display: Yes 15:59:45 INFO - Mirror: Off 15:59:45 INFO - Online: Yes 15:59:45 INFO - Rotation: Supported 15:59:45 INFO - Return code: 0 15:59:45 INFO - Running post-action listener: _resource_record_post_action 15:59:45 INFO - [mozharness: 2016-10-03 22:59:45.971528Z] Finished run-tests step (success) 15:59:45 INFO - Running post-run listener: _resource_record_post_run 15:59:46 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 1097728; Write bytes: 706663936; Read time: 13; Write time: 1936 15:59:46 INFO - TinderboxPrint: CPU usage
53.5% 15:59:46 INFO - TinderboxPrint: I/O read bytes / time
1,097,728 / 13 15:59:46 INFO - TinderboxPrint: I/O write bytes / time
706,663,936 / 1,936 15:59:46 INFO - TinderboxPrint: CPU idle
2,258.4 (46.5%) 15:59:46 INFO - TinderboxPrint: CPU system
972.0 (20.0%) 15:59:46 INFO - TinderboxPrint: CPU user
1,623.3 (33.4%) 15:59:46 INFO - TinderboxPrint: Swap in / out
409,088,000 / 0 15:59:46 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393646592; Write bytes: 389306368; Read time: 29377; Write time: 716 15:59:46 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 1097728; Write bytes: 295149056; Read time: 13; Write time: 1173 15:59:46 INFO - Running post-run listener: _upload_blobber_files 15:59:46 INFO - Blob upload gear active. 15:59:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:59:46 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 15:59:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:59:46 INFO - (blobuploader) - INFO - Open directory for files ... 15:59:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:59:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:59:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:47 INFO - (blobuploader) - INFO - Done attempting. 15:59:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:59:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:59:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:48 INFO - (blobuploader) - INFO - Done attempting. 15:59:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:59:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:59:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:51 INFO - (blobuploader) - INFO - Done attempting. 15:59:51 INFO - (blobuploader) - INFO - Iteration through files over. 15:59:51 INFO - Return code: 0 15:59:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:59:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:59:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de751072470d989ea812506e25a2c0bc847a457f4cb283c83c481a990b0ac76d97a7def8d69126724d7cb0eb1fdad2563b56d17acb3a8105202d7012e5f254cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0212bc2c12977e866b76ce1a759d100ec2337e59ed34668eb18af2c78b887da11be828aeab01ae8f89840e60180b828db4f9b0ec25e984ffdc32b62df1699e54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6c8966303c4c6e236f51163731e052c486f86d6f4a3549d2e4e29be4aad038ac99a393b2dd7e2130023f43473a148bcef0a38cefc972a7b1d221d8ef06f1bde"} 15:59:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:59:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:59:51 INFO - Contents: 15:59:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de751072470d989ea812506e25a2c0bc847a457f4cb283c83c481a990b0ac76d97a7def8d69126724d7cb0eb1fdad2563b56d17acb3a8105202d7012e5f254cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0212bc2c12977e866b76ce1a759d100ec2337e59ed34668eb18af2c78b887da11be828aeab01ae8f89840e60180b828db4f9b0ec25e984ffdc32b62df1699e54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6c8966303c4c6e236f51163731e052c486f86d6f4a3549d2e4e29be4aad038ac99a393b2dd7e2130023f43473a148bcef0a38cefc972a7b1d221d8ef06f1bde"} 15:59:51 INFO - Running post-run listener: copy_logs_to_upload_dir 15:59:51 INFO - Copying logs to upload dir... 15:59:51 INFO - mkdir: /builds/slave/test/build/upload/logs 15:59:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.514107 ========= master_lag: 2.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-03 15:59:53.751515) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 15:59:53.756011) ========= 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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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/Fx-Team/sha512/de751072470d989ea812506e25a2c0bc847a457f4cb283c83c481a990b0ac76d97a7def8d69126724d7cb0eb1fdad2563b56d17acb3a8105202d7012e5f254cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0212bc2c12977e866b76ce1a759d100ec2337e59ed34668eb18af2c78b887da11be828aeab01ae8f89840e60180b828db4f9b0ec25e984ffdc32b62df1699e54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6c8966303c4c6e236f51163731e052c486f86d6f4a3549d2e4e29be4aad038ac99a393b2dd7e2130023f43473a148bcef0a38cefc972a7b1d221d8ef06f1bde"} build_url:https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010933 build_url: 'https://queue.taskcluster.net/v1/task/EB6QlCZrT7asv8WAyNDwjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de751072470d989ea812506e25a2c0bc847a457f4cb283c83c481a990b0ac76d97a7def8d69126724d7cb0eb1fdad2563b56d17acb3a8105202d7012e5f254cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0212bc2c12977e866b76ce1a759d100ec2337e59ed34668eb18af2c78b887da11be828aeab01ae8f89840e60180b828db4f9b0ec25e984ffdc32b62df1699e54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6c8966303c4c6e236f51163731e052c486f86d6f4a3549d2e4e29be4aad038ac99a393b2dd7e2130023f43473a148bcef0a38cefc972a7b1d221d8ef06f1bde"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 15:59:53.796871) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:59:53.801323) ========= 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.Q17ZeMe2gG/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.fuYPf3IAK5/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.005232 ========= master_lag: 0.84 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:59:54.641679) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-10-03 15:59:54.642047) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-10-03 15:59:57.832902) ========= ========= Total master_lag: 3.24 =========