builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0115 starttime: 1478531424.45 results: success (0) buildid: 20161107041537 builduid: a04dca672c164725a9b45b381a1f895c revision: 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.452762) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.453166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.453437) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.872838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.873171) ========= 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.Q6bMWJursn/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.V6O8asWSfO/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-11-07 07:10:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.0M=0.001s 2016-11-07 07:10:25 (15.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.546835 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:25.461641) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:25.461935) ========= 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.Q6bMWJursn/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.V6O8asWSfO/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.026441 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:25.513529) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-07 07:10:25.513859) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q6bMWJursn/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.V6O8asWSfO/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-11-07 07:10:25,867 truncating revision to first 12 chars 2016-11-07 07:10:25,867 Setting DEBUG logging. 2016-11-07 07:10:25,867 attempt 1/10 2016-11-07 07:10:25,867 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/36b139f0c130?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-07 07:10:27,283 unpacking tar archive at: cedar-36b139f0c130/testing/mozharness/ program finished with exit code 0 elapsedTime=1.647625 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-07 07:10:27.185782) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:27.189076) ========= script_repo_revision: 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:27.189451) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:27.189715) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:27.216408) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-07 07:10:27.216677) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q6bMWJursn/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.V6O8asWSfO/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:10:27 INFO - MultiFileLogger online at 20161107 07:10:27 in /builds/slave/test 07:10:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 07:10:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:10:27 INFO - {'append_to_log': False, 07:10:27 INFO - 'base_work_dir': '/builds/slave/test', 07:10:27 INFO - 'blob_upload_branch': 'Cedar', 07:10:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:10:27 INFO - 'branch': 'Cedar', 07:10:27 INFO - 'buildbot_json_path': 'buildprops.json', 07:10:27 INFO - 'config_files': ('talos/mac_config.py',), 07:10:27 INFO - 'default_actions': ('clobber', 07:10:27 INFO - 'read-buildbot-config', 07:10:27 INFO - 'download-and-extract', 07:10:27 INFO - 'populate-webroot', 07:10:27 INFO - 'create-virtualenv', 07:10:27 INFO - 'install', 07:10:27 INFO - 'run-tests'), 07:10:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:10:27 INFO - 'download_minidump_stackwalk': True, 07:10:27 INFO - 'download_symbols': 'ondemand', 07:10:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:10:27 INFO - 'tooltool.py': '/tools/tooltool.py', 07:10:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:10:27 INFO - '/tools/misc-python/virtualenv.py')}, 07:10:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:10:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:10:27 INFO - 'installer_path': 'installer.exe', 07:10:27 INFO - 'log_level': 'info', 07:10:27 INFO - 'log_name': 'talos', 07:10:27 INFO - 'log_to_console': True, 07:10:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:10:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:10:27 INFO - 'opt_config_files': (), 07:10:27 INFO - 'pip_index': False, 07:10:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:10:27 INFO - 'cmd': ('bash', 07:10:27 INFO - '-c', 07:10:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:10:27 INFO - 'enabled': True, 07:10:27 INFO - 'halt_on_failure': False, 07:10:27 INFO - 'name': 'check_screen_resolution'},), 07:10:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:10:27 INFO - 'cmd': ('bash', 07:10:27 INFO - '-c', 07:10:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:10:27 INFO - 'enabled': True, 07:10:27 INFO - 'halt_on_failure': False, 07:10:27 INFO - 'name': 'check_screen_resolution'},), 07:10:27 INFO - 'run_cmd_checks_enabled': True, 07:10:27 INFO - 'sps_profile': False, 07:10:27 INFO - 'sps_profile_interval': 0, 07:10:27 INFO - 'suite': 'dromaeojs-e10s', 07:10:27 INFO - 'system_bits': '32', 07:10:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:10:27 INFO - 'title': 't-yosemite-r7-0115', 07:10:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:10:27 INFO - 'use_talos_json': True, 07:10:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:10:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:10:27 INFO - 'work_dir': 'build'} 07:10:27 INFO - [mozharness: 2016-11-07 15:10:27.699482Z] Running clobber step. 07:10:27 INFO - Running pre-action listener: _resource_record_pre_action 07:10:27 INFO - Running main action method: clobber 07:10:27 INFO - rmtree: /builds/slave/test/build 07:10:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:10:30 INFO - Running post-action listener: _resource_record_post_action 07:10:30 INFO - [mozharness: 2016-11-07 15:10:30.127083Z] Finished clobber step (success) 07:10:30 INFO - [mozharness: 2016-11-07 15:10:30.127213Z] Running read-buildbot-config step. 07:10:30 INFO - Running pre-action listener: _resource_record_pre_action 07:10:30 INFO - Running main action method: read_buildbot_config 07:10:30 INFO - Using buildbot properties: 07:10:30 INFO - { 07:10:30 INFO - "project": "", 07:10:30 INFO - "product": "firefox", 07:10:30 INFO - "script_repo_revision": "default", 07:10:30 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 07:10:30 INFO - "repository": "", 07:10:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 07:10:30 INFO - "buildid": "20161107041537", 07:10:30 INFO - "pgo_build": "False", 07:10:30 INFO - "basedir": "/builds/slave/test", 07:10:30 INFO - "buildnumber": 2, 07:10:30 INFO - "slavename": "t-yosemite-r7-0115", 07:10:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:10:30 INFO - "platform": "yosemite_r7", 07:10:30 INFO - "scheduler": "tests-cedar-macosx64-talos", 07:10:30 INFO - "branch": "cedar", 07:10:30 INFO - "repo_path": "projects/cedar", 07:10:30 INFO - "revision": "36b139f0c1300e703ab80a1ef58f56bbc55d87e4", 07:10:30 INFO - "stage_platform": "macosx64", 07:10:30 INFO - "builduid": "a04dca672c164725a9b45b381a1f895c", 07:10:30 INFO - "slavebuilddir": "test" 07:10:30 INFO - } 07:10:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:10:30 INFO - Running post-action listener: _resource_record_post_action 07:10:30 INFO - [mozharness: 2016-11-07 15:10:30.131351Z] Finished read-buildbot-config step (success) 07:10:30 INFO - [mozharness: 2016-11-07 15:10:30.131473Z] Running download-and-extract step. 07:10:30 INFO - Running pre-action listener: _resource_record_pre_action 07:10:30 INFO - Running main action method: download_and_extract 07:10:30 INFO - mkdir: /builds/slave/test/build/tests 07:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:30 INFO - trying https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:10:30 INFO - Downloading https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/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 07:10:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/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 07:10:31 INFO - Downloaded 1199 bytes. 07:10:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:10:31 INFO - Using the following test package requirements: 07:10:31 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:10:31 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:31 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:10:31 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:31 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:10:31 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:31 INFO - u'jsshell-mac.zip'], 07:10:31 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:31 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:10:31 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:10:31 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:31 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:10:31 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:31 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:10:31 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:31 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:10:31 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:31 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:10:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:10:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:10:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:10:31 INFO - Fetch https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:10:32 INFO - Http code: 200 07:10:32 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 07:10:32 INFO - x-amz-cf-id: BvZPVxtZHgYZhp4vjoKg6JCXhG0L4TBmgwnnhkeQVZ6jsWQjJkV5gQ== 07:10:32 INFO - x-amz-version-id: 8K4TreKjo0dV8yc6WlJaJU04rO1DioPM 07:10:32 INFO - Expected file size: 16308131 07:10:32 INFO - Obtained file size: 16308131 07:10:39 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 07:10:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:10:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:10:39 INFO - Fetch https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:10:39 INFO - Http code: 200 07:10:39 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 07:10:39 INFO - x-amz-cf-id: J9Un0MZKYtEpqMeOobvv46qrh6QvRtswu_kZ_JmCOWt2THQLOQmH2g== 07:10:39 INFO - x-amz-version-id: 8K4TreKjo0dV8yc6WlJaJU04rO1DioPM 07:10:40 INFO - Expected file size: 16308131 07:10:40 INFO - Obtained file size: 16308131 07:10:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:10:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:10:46 INFO - Fetch https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:10:47 INFO - Http code: 200 07:10:47 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 07:10:47 INFO - x-amz-cf-id: HHmhs120JgUHMTQM3ooE1tiTkC08gq76y69aKhbhcjBWPdrOzJhazA== 07:10:47 INFO - x-amz-version-id: XfTi1OKEYzqpg35SjLIGRLAjTQqEXnil 07:10:47 INFO - Expected file size: 14409038 07:10:47 INFO - Obtained file size: 14409038 07:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:47 INFO - trying https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:10:47 INFO - Downloading https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:10:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/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 07:10:50 INFO - Downloaded 111588017 bytes. 07:10:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:10:50 INFO - mkdir: /builds/slave/test/properties 07:10:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:10:50 INFO - Writing to file /builds/slave/test/properties/build_url 07:10:50 INFO - Contents: 07:10:50 INFO - build_url:https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:10:51 INFO - Running post-action listener: _resource_record_post_action 07:10:51 INFO - Running post-action listener: set_extra_try_arguments 07:10:51 INFO - [mozharness: 2016-11-07 15:10:51.637702Z] Finished download-and-extract step (success) 07:10:51 INFO - [mozharness: 2016-11-07 15:10:51.637850Z] Running populate-webroot step. 07:10:51 INFO - Running pre-action listener: _resource_record_pre_action 07:10:51 INFO - Running main action method: populate_webroot 07:10:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:10:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:10:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:10:51 INFO - '--tppagecycles', 07:10:51 INFO - '7'], 07:10:51 INFO - 'tests': ['tsvgm']}}, 07:10:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:10:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:10:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:10:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:10:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:51 INFO - 'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:10:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:10:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:51 INFO - 'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['damp', 'tps']}, 07:10:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:51 INFO - 'tests': ['damp', 'tps']}, 07:10:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['dromaeo_dom']}, 07:10:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:10:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['basic_compositor_video']}, 07:10:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:10:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['a11yr', 07:10:51 INFO - 'ts_paint', 07:10:51 INFO - 'tpaint', 07:10:51 INFO - 'sessionrestore', 07:10:51 INFO - 'sessionrestore_no_auto_restore', 07:10:51 INFO - 'tabpaint']}, 07:10:51 INFO - 'other-e10s': {'tests': ['a11yr', 07:10:51 INFO - 'ts_paint', 07:10:51 INFO - 'tpaint', 07:10:51 INFO - 'sessionrestore', 07:10:51 INFO - 'sessionrestore_no_auto_restore', 07:10:51 INFO - 'tabpaint']}, 07:10:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:10:51 INFO - 'ts_paint', 07:10:51 INFO - 'tpaint', 07:10:51 INFO - 'sessionrestore', 07:10:51 INFO - 'sessionrestore_no_auto_restore', 07:10:51 INFO - 'tabpaint']}, 07:10:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:10:51 INFO - 'ts_paint', 07:10:51 INFO - 'tpaint', 07:10:51 INFO - 'sessionrestore', 07:10:51 INFO - 'sessionrestore_no_auto_restore', 07:10:51 INFO - 'tabpaint']}, 07:10:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['a11yr', 07:10:51 INFO - 'ts_paint', 07:10:51 INFO - 'tpaint', 07:10:51 INFO - 'sessionrestore', 07:10:51 INFO - 'sessionrestore_no_auto_restore', 07:10:51 INFO - 'tabpaint']}, 07:10:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['a11yr', 07:10:51 INFO - 'ts_paint', 07:10:51 INFO - 'tpaint', 07:10:51 INFO - 'sessionrestore', 07:10:51 INFO - 'sessionrestore_no_auto_restore', 07:10:51 INFO - 'tabpaint']}, 07:10:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['tsvgx', 07:10:51 INFO - 'tsvgr_opacity', 07:10:51 INFO - 'tart', 07:10:51 INFO - 'tscrollx', 07:10:51 INFO - 'cart']}, 07:10:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:10:51 INFO - 'tsvgr_opacity', 07:10:51 INFO - 'tart', 07:10:51 INFO - 'tscrollx', 07:10:51 INFO - 'cart']}, 07:10:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:51 INFO - 'talos_options': ['--disable-e10s'], 07:10:51 INFO - 'tests': ['tp5o']}, 07:10:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:51 INFO - 'tests': ['tp5o']}, 07:10:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:51 INFO - 'talos_options': ['--disable-e10s', 07:10:51 INFO - '--xperf_path', 07:10:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:10:51 INFO - 'tests': ['tp5n']}, 07:10:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:51 INFO - 'talos_options': ['--xperf_path', 07:10:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:10:51 INFO - 'tests': ['tp5n']}}, 07:10:51 INFO - 'talos.zip': {'path': '', 07:10:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:10:51 INFO - Running post-action listener: _resource_record_post_action 07:10:51 INFO - [mozharness: 2016-11-07 15:10:51.657083Z] Finished populate-webroot step (success) 07:10:51 INFO - [mozharness: 2016-11-07 15:10:51.657221Z] Running create-virtualenv step. 07:10:51 INFO - Running pre-action listener: _resource_record_pre_action 07:10:51 INFO - Running main action method: create_virtualenv 07:10:51 INFO - Creating virtualenv /builds/slave/test/build/venv 07:10:51 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 07:10:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:10:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:10:51 INFO - Using real prefix '/tools/python27' 07:10:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:10:52 INFO - Installing distribute.............................................................................................................................................................................................done. 07:10:55 INFO - Installing pip.................done. 07:10:55 INFO - Return code: 0 07:10:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:10:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:10:55 INFO - Reading from file tmpfile_stdout 07:10:55 INFO - Output received: 07:10:55 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) 07:10:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:55 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 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:10:55 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 07:10:55 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 07:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:55 INFO - 'HOME': '/Users/cltbld', 07:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:55 INFO - 'LOGNAME': 'cltbld', 07:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:55 INFO - 'MOZ_NO_REMOTE': '1', 07:10:55 INFO - 'NO_EM_RESTART': '1', 07:10:55 INFO - 'PAGER': '/bin/cat', 07:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:55 INFO - 'PWD': '/builds/slave/test', 07:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:55 INFO - 'SHELL': '/bin/bash', 07:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:10:55 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:55 INFO - 'USER': 'cltbld', 07:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:55 INFO - 'XPC_FLAGS': '0x0', 07:10:55 INFO - 'XPC_SERVICE_NAME': '0', 07:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:55 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 07:10:55 INFO - Cleaning up... 07:10:55 INFO - Return code: 0 07:10:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:55 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 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:10:55 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 07:10:55 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 07:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:55 INFO - 'HOME': '/Users/cltbld', 07:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:55 INFO - 'LOGNAME': 'cltbld', 07:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:55 INFO - 'MOZ_NO_REMOTE': '1', 07:10:55 INFO - 'NO_EM_RESTART': '1', 07:10:55 INFO - 'PAGER': '/bin/cat', 07:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:55 INFO - 'PWD': '/builds/slave/test', 07:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:55 INFO - 'SHELL': '/bin/bash', 07:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:10:55 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:55 INFO - 'USER': 'cltbld', 07:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:55 INFO - 'XPC_FLAGS': '0x0', 07:10:55 INFO - 'XPC_SERVICE_NAME': '0', 07:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:56 INFO - Downloading/unpacking psutil>=3.1.1 07:10:56 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 07:10:56 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 07:10:56 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 07:10:56 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 07:10:56 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 07:10:56 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 07:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:11:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:00 INFO - Installing collected packages: psutil 07:11:00 INFO - Running setup.py install for psutil 07:11:00 INFO - building 'psutil._psutil_osx' extension 07:11:00 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 07:11:00 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 07:11:00 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 07:11:00 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 07:11:00 INFO - building 'psutil._psutil_posix' extension 07:11:00 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 07:11:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:11:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:11:01 INFO - ^ 07:11:01 INFO - 1 warning generated. 07:11:01 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 07:11:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:01 INFO - Successfully installed psutil 07:11:01 INFO - Cleaning up... 07:11:01 INFO - Return code: 0 07:11:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:11:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:11:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:01 INFO - 'HOME': '/Users/cltbld', 07:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:01 INFO - 'LOGNAME': 'cltbld', 07:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:01 INFO - 'MOZ_NO_REMOTE': '1', 07:11:01 INFO - 'NO_EM_RESTART': '1', 07:11:01 INFO - 'PAGER': '/bin/cat', 07:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:01 INFO - 'PWD': '/builds/slave/test', 07:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:01 INFO - 'SHELL': '/bin/bash', 07:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:01 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:01 INFO - 'USER': 'cltbld', 07:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:01 INFO - 'XPC_FLAGS': '0x0', 07:11:01 INFO - 'XPC_SERVICE_NAME': '0', 07:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:11:01 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 07:11:01 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 07:11:01 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 07:11:01 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 07:11:01 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 07:11:01 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 07:11:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:11:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:11:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:11:04 INFO - Installing collected packages: mozsystemmonitor 07:11:04 INFO - Running setup.py install for mozsystemmonitor 07:11:04 INFO - Successfully installed mozsystemmonitor 07:11:04 INFO - Cleaning up... 07:11:04 INFO - Return code: 0 07:11:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:11:04 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')]} 07:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:04 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')]} 07:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:04 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 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:11:04 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 07:11:04 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 07:11:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:04 INFO - 'HOME': '/Users/cltbld', 07:11:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:04 INFO - 'LOGNAME': 'cltbld', 07:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:04 INFO - 'MOZ_NO_REMOTE': '1', 07:11:04 INFO - 'NO_EM_RESTART': '1', 07:11:04 INFO - 'PAGER': '/bin/cat', 07:11:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:04 INFO - 'PWD': '/builds/slave/test', 07:11:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:04 INFO - 'SHELL': '/bin/bash', 07:11:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:04 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:04 INFO - 'USER': 'cltbld', 07:11:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:04 INFO - 'XPC_FLAGS': '0x0', 07:11:04 INFO - 'XPC_SERVICE_NAME': '0', 07:11:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:04 INFO - Downloading/unpacking jsonschema==2.5.1 07:11:04 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 07:11:04 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 07:11:04 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 07:11:04 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 07:11:04 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 07:11:04 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 07:11:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:11:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:11:07 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 07:11:07 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 07:11:07 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 07:11:07 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 07:11:07 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 07:11:07 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 07:11:07 INFO - Downloading functools32-3.2.3-2.tar.gz 07:11:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:11:07 INFO - warning: no files found matching '*.txt' 07:11:07 INFO - no previously-included directories found matching 'build' 07:11:07 INFO - no previously-included directories found matching 'dist' 07:11:07 INFO - no previously-included directories found matching '.git*' 07:11:07 INFO - Installing collected packages: jsonschema, functools32 07:11:07 INFO - Running setup.py install for functools32 07:11:07 INFO - warning: no files found matching '*.txt' 07:11:07 INFO - no previously-included directories found matching 'build' 07:11:07 INFO - no previously-included directories found matching 'dist' 07:11:07 INFO - no previously-included directories found matching '.git*' 07:11:07 INFO - Successfully installed jsonschema functools32 07:11:07 INFO - Cleaning up... 07:11:07 INFO - Return code: 0 07:11:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:11:07 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')]} 07:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:07 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')]} 07:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:07 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 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:11:07 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 07:11:07 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 07:11:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:07 INFO - 'HOME': '/Users/cltbld', 07:11:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:07 INFO - 'LOGNAME': 'cltbld', 07:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:07 INFO - 'MOZ_NO_REMOTE': '1', 07:11:07 INFO - 'NO_EM_RESTART': '1', 07:11:07 INFO - 'PAGER': '/bin/cat', 07:11:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:07 INFO - 'PWD': '/builds/slave/test', 07:11:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:07 INFO - 'SHELL': '/bin/bash', 07:11:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:07 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:07 INFO - 'USER': 'cltbld', 07:11:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:07 INFO - 'XPC_FLAGS': '0x0', 07:11:07 INFO - 'XPC_SERVICE_NAME': '0', 07:11:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:11:08 INFO - Cleaning up... 07:11:08 INFO - Return code: 0 07:11:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:08 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 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:11:08 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 07:11:08 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 07:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:08 INFO - 'HOME': '/Users/cltbld', 07:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:08 INFO - 'LOGNAME': 'cltbld', 07:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:08 INFO - 'MOZ_NO_REMOTE': '1', 07:11:08 INFO - 'NO_EM_RESTART': '1', 07:11:08 INFO - 'PAGER': '/bin/cat', 07:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:08 INFO - 'PWD': '/builds/slave/test', 07:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:08 INFO - 'SHELL': '/bin/bash', 07:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:08 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:08 INFO - 'USER': 'cltbld', 07:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:08 INFO - 'XPC_FLAGS': '0x0', 07:11:08 INFO - 'XPC_SERVICE_NAME': '0', 07:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:08 INFO - Downloading/unpacking blobuploader==1.2.4 07:11:08 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 07:11:08 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 07:11:08 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 07:11:08 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 07:11:08 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 07:11:08 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 07:11:10 INFO - Downloading blobuploader-1.2.4.tar.gz 07:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:11:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:11:11 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 07:11:11 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 07:11:11 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 07:11:11 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 07:11:11 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 07:11:11 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 07:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:11:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:11:11 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 07:11:11 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 07:11:11 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 07:11:11 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 07:11:11 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 07:11:11 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 07:11:12 INFO - Downloading docopt-0.6.1.tar.gz 07:11:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:11:12 INFO - Installing collected packages: blobuploader, requests, docopt 07:11:12 INFO - Running setup.py install for blobuploader 07:11:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:11:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:11:12 INFO - Running setup.py install for requests 07:11:12 INFO - Running setup.py install for docopt 07:11:13 INFO - Successfully installed blobuploader requests docopt 07:11:13 INFO - Cleaning up... 07:11:13 INFO - Return code: 0 07:11:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:13 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')]} 07:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:13 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')]} 07:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:13 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 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:11:13 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 07:11:13 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 07:11:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:13 INFO - 'HOME': '/Users/cltbld', 07:11:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:13 INFO - 'LOGNAME': 'cltbld', 07:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:13 INFO - 'MOZ_NO_REMOTE': '1', 07:11:13 INFO - 'NO_EM_RESTART': '1', 07:11:13 INFO - 'PAGER': '/bin/cat', 07:11:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:13 INFO - 'PWD': '/builds/slave/test', 07:11:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:13 INFO - 'SHELL': '/bin/bash', 07:11:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:13 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:13 INFO - 'USER': 'cltbld', 07:11:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:13 INFO - 'XPC_FLAGS': '0x0', 07:11:13 INFO - 'XPC_SERVICE_NAME': '0', 07:11:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:13 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Ee_BwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:13 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Cf8YK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:13 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-d4sdCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:13 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-6q5ViC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:13 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-5fjkH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:13 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-MhMNJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:13 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xCnvRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xajM_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-K3Xh72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-cZ6_gV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-GMYUnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-5TO4K3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-LfJnfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-1SMU_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-vNxz7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:14 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-BDl__L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:15 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-3AD0BE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:11:15 INFO - Running setup.py install for manifestparser 07:11:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Running setup.py install for mozcrash 07:11:15 INFO - Running setup.py install for mozdebug 07:11:15 INFO - Running setup.py install for mozdevice 07:11:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Running setup.py install for mozfile 07:11:15 INFO - Running setup.py install for mozhttpd 07:11:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Running setup.py install for mozinfo 07:11:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Running setup.py install for mozInstall 07:11:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Running setup.py install for mozleak 07:11:16 INFO - Running setup.py install for mozlog 07:11:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Running setup.py install for moznetwork 07:11:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Running setup.py install for mozprocess 07:11:16 INFO - Running setup.py install for mozprofile 07:11:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:11:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:11:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:11:17 INFO - Running setup.py install for mozrunner 07:11:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:11:17 INFO - Running setup.py install for mozscreenshot 07:11:17 INFO - Running setup.py install for moztest 07:11:17 INFO - Running setup.py install for mozversion 07:11:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:11:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:11:17 INFO - Cleaning up... 07:11:17 INFO - Return code: 0 07:11:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:17 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')]} 07:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:17 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')]} 07:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:17 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 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:11:17 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 07:11:17 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 07:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:17 INFO - 'HOME': '/Users/cltbld', 07:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:17 INFO - 'LOGNAME': 'cltbld', 07:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:17 INFO - 'MOZ_NO_REMOTE': '1', 07:11:17 INFO - 'NO_EM_RESTART': '1', 07:11:17 INFO - 'PAGER': '/bin/cat', 07:11:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:17 INFO - 'PWD': '/builds/slave/test', 07:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:17 INFO - 'SHELL': '/bin/bash', 07:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:17 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:17 INFO - 'USER': 'cltbld', 07:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:17 INFO - 'XPC_FLAGS': '0x0', 07:11:17 INFO - 'XPC_SERVICE_NAME': '0', 07:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:17 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-fn9AVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-OiubmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-9X4LKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-O8m9XV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-83FERF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-fAbRSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ybnQP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Zxm__4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-09PNEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:18 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-tlRLOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:19 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)) 07:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:19 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-WTnHay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:19 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)) 07:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:19 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-RSnPGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:19 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)) 07:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:19 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ZN93Bz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:19 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)) 07:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:19 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-uf0teD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:19 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)) 07:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:19 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-a_ZQO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:19 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)) 07:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:19 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-XrkLV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:19 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)) 07:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:19 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-jUgFG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:19 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)) 07:11:19 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)) 07:11:19 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)) 07:11:19 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)) 07:11:19 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)) 07:11:19 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)) 07:11:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:11: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 07:11: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 07:11: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 07:11: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 07:11: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 07:11: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 07:11:22 INFO - Downloading blessings-1.6.tar.gz 07:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:11:22 INFO - Installing collected packages: blessings 07:11:22 INFO - Running setup.py install for blessings 07:11:22 INFO - Successfully installed blessings 07:11:22 INFO - Cleaning up... 07:11:22 INFO - Return code: 0 07:11:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:11:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:22 INFO - Reading from file tmpfile_stdout 07:11:22 INFO - Current package versions: 07:11:22 INFO - blessings == 1.6 07:11:22 INFO - blobuploader == 1.2.4 07:11:22 INFO - docopt == 0.6.1 07:11:22 INFO - functools32 == 3.2.3-2 07:11:22 INFO - jsonschema == 2.5.1 07:11:22 INFO - manifestparser == 1.1 07:11:22 INFO - mozInstall == 1.12 07:11:22 INFO - mozcrash == 0.17 07:11:22 INFO - mozdebug == 0.1 07:11:22 INFO - mozdevice == 0.48 07:11:22 INFO - mozfile == 1.2 07:11:22 INFO - mozhttpd == 0.7 07:11:22 INFO - mozinfo == 0.9 07:11:22 INFO - mozleak == 0.1 07:11:22 INFO - mozlog == 3.4 07:11:22 INFO - moznetwork == 0.27 07:11:22 INFO - mozprocess == 0.23 07:11:22 INFO - mozprofile == 0.28 07:11:22 INFO - mozrunner == 6.12 07:11:22 INFO - mozscreenshot == 0.1 07:11:22 INFO - mozsystemmonitor == 0.3 07:11:22 INFO - moztest == 0.7 07:11:22 INFO - mozversion == 1.4 07:11:22 INFO - psutil == 3.1.1 07:11:22 INFO - requests == 1.2.3 07:11:22 INFO - wsgiref == 0.1.2 07:11:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:22 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')]} 07:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:22 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')]} 07:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:22 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 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:11:22 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 07:11:22 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 07:11:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:22 INFO - 'HOME': '/Users/cltbld', 07:11:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:22 INFO - 'LOGNAME': 'cltbld', 07:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:22 INFO - 'MOZ_NO_REMOTE': '1', 07:11:22 INFO - 'NO_EM_RESTART': '1', 07:11:22 INFO - 'PAGER': '/bin/cat', 07:11:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:22 INFO - 'PWD': '/builds/slave/test', 07:11:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:22 INFO - 'SHELL': '/bin/bash', 07:11:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:22 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:22 INFO - 'USER': 'cltbld', 07:11:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:22 INFO - 'XPC_FLAGS': '0x0', 07:11:22 INFO - 'XPC_SERVICE_NAME': '0', 07:11:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 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)) 07:11:23 INFO - Cleaning up... 07:11:23 INFO - Return code: 0 07:11:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ecc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cc84d6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, '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.Q6bMWJursn/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.V6O8asWSfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:23 INFO - 'HOME': '/Users/cltbld', 07:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:23 INFO - 'LOGNAME': 'cltbld', 07:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:23 INFO - 'MOZ_NO_REMOTE': '1', 07:11:23 INFO - 'NO_EM_RESTART': '1', 07:11:23 INFO - 'PAGER': '/bin/cat', 07:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:23 INFO - 'PWD': '/builds/slave/test', 07:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:23 INFO - 'SHELL': '/bin/bash', 07:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:23 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:23 INFO - 'USER': 'cltbld', 07:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:23 INFO - 'XPC_FLAGS': '0x0', 07:11:23 INFO - 'XPC_SERVICE_NAME': '0', 07:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:11:23 INFO - Cleaning up... 07:11:23 INFO - Return code: 0 07:11:23 INFO - Running post-action listener: _resource_record_post_action 07:11:23 INFO - Running post-action listener: _start_resource_monitoring 07:11:23 INFO - Starting resource monitoring. 07:11:23 INFO - [mozharness: 2016-11-07 15:11:23.515086Z] Finished create-virtualenv step (success) 07:11:23 INFO - [mozharness: 2016-11-07 15:11:23.516038Z] Running install step. 07:11:23 INFO - Running pre-action listener: _resource_record_pre_action 07:11:23 INFO - Running main action method: install 07:11:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:23 INFO - Reading from file tmpfile_stdout 07:11:23 INFO - Detecting whether we're running mozinstall >=1.0... 07:11:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:11:23 INFO - Reading from file tmpfile_stdout 07:11:23 INFO - Output received: 07:11:23 INFO - Usage: mozinstall [options] installer 07:11:23 INFO - Options: 07:11:23 INFO - -h, --help show this help message and exit 07:11:23 INFO - -d DEST, --destination=DEST 07:11:23 INFO - Directory to install application into. [default: 07:11:23 INFO - "/builds/slave/test"] 07:11:23 INFO - --app=APP Application being installed. [default: firefox] 07:11:23 INFO - mkdir: /builds/slave/test/build/application 07:11:23 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'] 07:11:23 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 07:11:58 INFO - Reading from file tmpfile_stdout 07:11:58 INFO - Output received: 07:11:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:11:58 INFO - Running post-action listener: _resource_record_post_action 07:11:58 INFO - [mozharness: 2016-11-07 15:11:58.162401Z] Finished install step (success) 07:11:58 INFO - [mozharness: 2016-11-07 15:11:58.162546Z] Running run-tests step. 07:11:58 INFO - Running pre-action listener: _resource_record_pre_action 07:11:58 INFO - Running main action method: run_tests 07:11:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:11:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:11:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:11:58 INFO - 2016-11-07 07:11:58.176 screenresolution[1646:8723] starting screenresolution argv=screenresolution get 07:11:58 INFO - 2016-11-07 07:11:58.179 screenresolution[1646:8723] Display 0: 1600x1200x32@60 07:11:58 INFO - 2016-11-07 07:11:58.185 screenresolution[1647:8725] starting screenresolution argv=screenresolution list 07:11:58 INFO - Available Modes on Display 0 07:11:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:11:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:11:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:11:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:11:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:11:58 INFO - Intel Iris: 07:11:58 INFO - Chipset Model: Intel Iris 07:11:58 INFO - Type: GPU 07:11:58 INFO - Bus: Built-In 07:11:58 INFO - VRAM (Dynamic, Max): 1536 MB 07:11:58 INFO - Vendor: Intel (0x8086) 07:11:58 INFO - Device ID: 0x0a2e 07:11:58 INFO - Revision ID: 0x0009 07:11:58 INFO - Displays: 07:11:58 INFO - Display: 07:11:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:11:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:11:58 INFO - Display Serial Number: 1600x1200 60 07:11:58 INFO - Main Display: Yes 07:11:58 INFO - Mirror: Off 07:11:58 INFO - Online: Yes 07:11:58 INFO - Rotation: Supported 07:11:58 INFO - Return code: 0 07:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:11:58 INFO - Python 2.7.3 07:11:58 INFO - Return code: 0 07:11:58 INFO - grabbing minidump binary from tooltool 07:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:58 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 0x100d6ad30>, '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 0x100b90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d62b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:11:58 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 07:11:58 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 07:11:58 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 07:11:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:11:58 INFO - Return code: 0 07:11:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:11:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:11:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:11:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:11:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0115 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bMWJursn/Render', 07:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:58 INFO - 'HOME': '/Users/cltbld', 07:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:58 INFO - 'LOGNAME': 'cltbld', 07:11:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:11:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:58 INFO - 'MOZ_NO_REMOTE': '1', 07:11:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:11:58 INFO - 'NO_EM_RESTART': '1', 07:11:58 INFO - 'PAGER': '/bin/cat', 07:11:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:58 INFO - 'PWD': '/builds/slave/test', 07:11:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:58 INFO - 'SHELL': '/bin/bash', 07:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V6O8asWSfO/Listeners', 07:11:58 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:58 INFO - 'USER': 'cltbld', 07:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:58 INFO - 'XPC_FLAGS': '0x0', 07:11:58 INFO - 'XPC_SERVICE_NAME': '0', 07:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:11:58 INFO - mozversion application_buildid: 20161107041537 07:11:58 INFO - mozversion application_changeset: 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 07:11:58 INFO - mozversion application_display_name: Nightly 07:11:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:11:58 INFO - mozversion application_name: Firefox 07:11:58 INFO - mozversion application_remotingname: firefox 07:11:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 07:11:58 INFO - mozversion application_vendor: Mozilla 07:11:58 INFO - mozversion application_version: 52.0a1 07:11:58 INFO - mozversion platform_buildid: 20161107041537 07:11:58 INFO - mozversion platform_changeset: 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 07:11:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 07:11:58 INFO - mozversion platform_version: 52.0a1 07:11:58 INFO - using testdate: 1478531518 07:11:58 INFO - actual date: 1478531518 07:11:58 INFO - starting webserver on 'localhost:49272' 07:11:58 INFO - SUITE-START | Running 2 tests 07:11:58 INFO - TEST-START | dromaeo_css 07:11:58 INFO - operating with platform_type : mac_ 07:11:58 INFO - Initialising browser for dromaeo_css test... 07:11:58 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpkiTIov/profile http://localhost:49272/getInfo.html) 07:12:01 INFO - PROCESS | 1657 | 2016-11-07 07:12:01.619 plugin-container[1659:8881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 07:12:01 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:12:01 INFO - PROCESS | 1657 | 2016-11-07 07:12:01.622 plugin-container[1659:8881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 07:12:01 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:12:01 INFO - PROCESS | 1657 | 2016-11-07 07:12:01.622 plugin-container[1659:8881] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:12:01 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 07:12:01 INFO - PROCESS | 1657 | colorDepth:24 07:12:01 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 07:12:01 INFO - PROCESS | 1657 | __metrics 07:12:01 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 07:12:02 INFO - TEST-INFO | 1657: exit 0 07:12:02 INFO - Browser initialized. 07:12:02 INFO - Running cycle 1/1 for dromaeo_css test... 07:12:02 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpkiTIov/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:12:05 INFO - PROCESS | 1661 | 2016-11-07 07:12:05.063 plugin-container[1662:9003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8737, name = 'com.apple.tsm.portname' 07:12:05 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:12:05 INFO - PROCESS | 1661 | 2016-11-07 07:12:05.065 plugin-container[1662:9003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 07:12:05 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:12:05 INFO - PROCESS | 1661 | 2016-11-07 07:12:05.065 plugin-container[1662:9003] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:12:14 INFO - PROCESS | 1661 | RSS: Main: 238694400 07:12:14 INFO - PROCESS | 1661 | 07:13:04 INFO - PROCESS | 1661 | 1478531584315 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] 07:15:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 07:15:18 INFO - PROCESS | 1661 | RSS: Main: 251744256 07:15:18 INFO - PROCESS | 1661 | 07:18:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 07:18:21 INFO - PROCESS | 1661 | RSS: Main: 263639040 07:18:21 INFO - PROCESS | 1661 | 07:21:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 07:21:24 INFO - PROCESS | 1661 | RSS: Main: 267927552 07:21:24 INFO - PROCESS | 1661 | 07:24:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 07:24:26 INFO - PROCESS | 1661 | RSS: Main: 266563584 07:24:26 INFO - PROCESS | 1661 | 07:27:29 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 07:27:30 INFO - PROCESS | 1661 | RSS: Main: 270950400 07:27:30 INFO - PROCESS | 1661 | 07:30:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 07:30:33 INFO - PROCESS | 1661 | RSS: Main: 273195008 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - PROCESS | 1661 | __start_tp_report 07:30:33 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 07:30:33 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 07:30:33 INFO - PROCESS | 1661 | |i|pagename|runs| 07:30:33 INFO - PROCESS | 1661 | |0;dojo.html;2896.7827412944034;3285.1294406982147;3359.9920023992813;3427.228873503462;3429.3141371725656;5613.915791263124;5690.60165788395;5719.370869204384;5725.513331366834;5820.272465941757;4375.3874457575885;4876.536729010747;5660.858478538039;5744;5842.47417732404;5183.611229157809;5204.011237864794;5300.337457817773;5336.066188417031;5346.599005074635;5147.48525147486;5337.2794672719265;5349.598780091489;5360.544353729918;5377.11277639189;4972.378452693413;5145.6398052136365;5292;5298.36419629643;5325.600579956499;3948.7758794773713;4818.373611430492;5135.38375394955;5220.008198442321;5261.316028916254;5153.098207813655;5159.793608255665;5162.277281180638;5197.324347834795;5251.76367063483;4869.902601947977;5134.332536770196;5617.354004289518;5640.492355688008;5645.096784514458;6108.949222962717;6149.831532008904;6201.906971395432;6209.099680546341;6237.750489980395;5839.919614871263;5841.591088623798;6146.907796383058;6168.1056246843955;6195.690215489252;4878.4633690294395;5701.315842098975;5765.1928729977835;5933.643981361089;5941.960156972513;5689.975804355256;5943.31651860033;6136.825445958321;6145.784897528654;6260.718267677966;5239.423663397023;6165.514832464296;6167.599106058092;6167.753289868445;6178.505719542426;6012.939870601325;6068.939310606926;6203;6210.037444196157;6218.440340369389;6107.188815080102;6111.652512877656;6119.8681629442035;6130.581587307925;6147.862645410613;6171.938280617136;6180.443760061616;6187.752489900444;6193.659348735863;6240.53196010292;4789.688670236424;4962.478939711351;4968.826091086795;4973.651844370932;4989.201727723619;4330.523642399334;4365.08333250021;4376.234159022159;4420.447444069491;4485.775711214426;5787.826365209051;5841.970790146022;5896.882062358729;5940.34656187819;5970.850728731816;6168.167297414791;6182.69086545671;6191.00943848982;6195.721192546346;6195.783147589813;5701.230333904869;5801.3328467225965;5841.649501029951;5862.296524417012;5899.321578018583;5456.727163641882;5468.945310546923;5469.453054694578;5482.479164479368;5494.36814766299;6289.245290565162;6311.526635502356;6324.810255692337;6327.303996560467;6368.904466433008;6118.345080237391;6135.877282454237;6157.568970172045;6214.471769899609;6220.7511699531515;6193.473554747717;6247.312795592572;6287.245530536365;6317.399847014526;6330.651814150082;6165.5375846810775;6179.16581261524;6185.134081228722;6191.102290167991;6250.156228909221;6079.483243924139;6188.040853667509;6189.102580125947;6192.969035154795;6217;5033.421156566895;6064.727087280995;6084.269887613515;6102.359252278625;6114.60152118864;6084.878302433838;6106.2061931948565;6117.051856962178;6121.326654068138;6138.202033735586;5653.971730141323;5699.658020518782;5933.881322373615;5958.553108516965;5966.791162309472;6081.400734030944;6135.631862088289;6151.169592105009;6153.292371377349;6187.257529096357;5980.910286345621;6012.248468941382;6018.21763170785;6023.548233882389;6055.122007308827 07:30:33 INFO - PROCESS | 1661 | |1;ext.html;451.3297752837037;519.1978393382225;523.4974424552432;537.6693333599837;547.0181025060017;37287.44068838966;38987.80506097466;38993.99999999996;39036;40950.38574421386;37294.88115356544;40542.79728601353;40603;40901;40903;9218.539073046355;9378.437293762361;9385.95307023464;9410.858837117448;9430.717078487656;9199.080091990782;9274.860877086816;9311.208547273456;9313.44119352841;9331;9297.721068367926;9322.813543729122;9328.160465558098;9355.391899526543;9355.859662105075;9245;9315.254779617682;9319.34764566484;9333;9349.813003739955;9084.091590840862;9088.50013249271;9101.544922753836;9176.174144326977;9203.953980230122;38166;38248.61751382506;38341.23317533634;38492.615073849185;38576.61423385758;3200.759943004284;3287;3338.749593780476;3416.180116771957;3615.7107431405425;29084.854575727197;29312.26719332034;29455.70544294551;30247.697523024708;30264.54603180954;3147.9055628330957;3177.729892959101;3199.7120259176554;3214.5981752280964;3282.556854824592;445735;452727;453067;453417;454358;53293.733531332095;53854.19218711722;53929;54145;54350.728246358514;55509;55590.72204639032;56118.43881561132;56237.7188114065;56620.15069773957;13830.308484575731;13973.720525589431;13981.300934953211;13982;14041.929790351187;10691.380611750172;10759.89240107589;10768.138548916068;10771.407572583583;10796.244262901522;13410.195388276734;14177.362018709182;14350.712985740227;14534.563963081124;14704;3183.2041989502623;3260.2827377977283;5602.4957753802355;6739.494537909676;6755.324467553205;826.8635675113659;990.757264470195;998.6953979036464;1058.8888166742381;1065.2277099103244;29287.853560732485;29383.265418364714;29564.704352956196;29592.351785866133;29894.25264368408;914.9771255718528;967.5355829201882;991.9156871665989;1015.8069966706303;1029.8352263637782;13939.930300348433;14288.357023933948;14294.428222870969;14594.124352538669;14703.897207709464;586540;594521;595876;599571;603567;51187;51723;51743;51818.48181518212;51830.48169518332;1291.4704970962048;1300.3043371796232;1322.9813043955908;1336.4253371050347;1341.8523962364131;26345.60481592816;27320.316992075357;27731.861340693973;27781.58327625047;27788.44423111486;45398.54601453943;46181.76909115433;46246.53753462422;46314.07371852612;46493.76753116213;577487;579205;580205;580753;584752;17996.460106196835;21491.247806326704;21921.67117493207;21983.780162198174;22094.558108838053;39207;41342;41492.377614336416;42300.57699422966;42814.715558533295;34562.654373455945;35043;35150.12124696903;35516.82241588776;36284.09727221763;35236;35239.94740604863;35713.64286357103;36193.81903090468;36205;28375;34252;34634.82682586571;34934.30131397409;35093.82453087718;26038.609420858324;29329.413411732072;31263.374732505676;31339.373212536077;31493.370132597676;23269.41826454352;23593.52812943766;23646.88176559106;24049.398765031016;24103.63844542298 07:30:33 INFO - PROCESS | 1661 | |2;jquery.html;341.2526566818667;367.80873945548313;372.8937252882929;381.18236382958537;396.13839898221363;18120.818791812093;18634.34779782706;19698.212071517122;19873.304434344802;19982.503936614256;19042.33351832686;19253.22987080515;19340.93624850636;19459.416217513462;19632.901835490804;5530.446955304467;5653.519450846662;5690.943090569103;5812.1281807728965;5817.069268916975;4961.6278779091535;5465.9726701366435;5630.662160270397;5683.943160568383;5724.7996320128805;5181.859990802036;5657.745401456924;5658.094704847225;5664.320281566218;5673;4610.654200934927;5559.694216818073;5623.100303951389;5667.489925906688;5675.063614503602;4984.651074424792;4988.700677959334;4996.600271978233;5004.949950500485;5012.122878496249;21879.67180492294;22954.426139346484;23018.539629207324;23173.536529269488;23434.06263749448;2028.3610662641368;2078.646630072891;2098.045389347843;2130.957380852374;2356.705411823522;9679.032096790335;9898.257630677728;13858.930705346509;14221.715565688628;14386.84905207581;1775.8135395783386;1817.9909100454545;1856.1647258733624;1867.9813201868078;1877.8497720182352;118990;125296.74703253033;125837;126492;127554;10571.735706607396;11321.490532926038;11338.355938388888;11389.373584452935;11510.079193664487;13904.86095139056;14074.929625352013;14081.295935203403;14082.859171408154;14091.154530728189;7522.210167932289;7659.540427574363;7777.72777952769;7904.379602181539;7962.283394494523;7385.66764495599;7403.938118104085;7443.441741869381;7555.357794587411;7577;4452.220861349251;4464.665150113755;4465.218586747306;4478.440194975628;4520;1887.734718338;1943.640426521098;1950.3953774330002;1950.8829470231547;1955.7946415626154;811.5455345006815;833.3957880536562;903.7243640689653;913.830941275866;946.2855544064188;19625.313114041226;20402.38792836217;20483.795162048486;20576.794232057488;20629.22781115187;799.3405440511601;873.9038705742363;895.54327293081;901.2339511415375;921.4195057114109;10365.689029329133;10621.150307975344;10795.352278863293;10819.296745711503;10846.18653600983;547088;558079;558785;560608;565597;89811.55094224618;110945.44527277313;111609.88390116158;117760.8223917767;122254.3887280558;929.9395539289925;947.3652652722692;997.33677104726;998.5905778630727;1002.478711070254;18071.367502137364;18345;18641.44075677732;18745.625087498447;18901.338453154072;51253;51841.7407912958;53431;53584;55115;72830.63584682043;78137.60931195536;79492.20507794849;80233.59883200546;81938.59030704809;7462.291082347168;7593.924060759543;7692.19231980634;7716.729914453192;7800.45396822217;10378.481075946323;11606.361650109326;11745.17783755129;12027.75944481123;12151.513939442677;8720.78198045054;9931.801363972536;10418.843717344398;10564.683059508227;10645.520951557353;8531.317494600542;9384.718458446258;10214.642487512901;10288.537015834156;10425.270231083752;10470.528826202686;10619.469026548797;10646.627368042391;10832.837507437238;10876.238663293494;5257.763400646903;6021.789237376671;6080.118382834553;6762.594244345355;6927.965360173167;6417.7753778617525;10209.9489502552;10353.223508236762;10640.8935910643;10680.83978740304 07:30:33 INFO - PROCESS | 1661 | |3;mootools.html;1496.169625857649;1514.545636309107;1713.9801817918335;1776.2184638758954;1818.245428147319;7382.852342953144;7639.083310002808;7899.368050555956;8157.836843263139;8651.134886511354;7566.810829729259;8139.715109971152;8206.179382061791;8447.831043379128;8454.492730436163;2289.553537060278;2291.072115793098;2332.6734257204007;2333.4982978659586;2336.6378211377264;1927.6024881960536;2347.2606129069372;2349.882505874708;2353.094058787362;2354.411397150712;2293.8508996915148;2294.7819957104134;2297.058206135485;2317.351141680332;2326.5463234669246;2272.375096848372;2290.1755368067484;2301.4476525633895;2313.768623137673;2318.40880575453;2284.3603790938564;2343.414146463384;2345.9296221113395;2351.6472529120597;2357.57563638545;7845.333146682538;8199.754007379728;8591.44155629882;8656.870146947738;8673.262772664331;5151.484851514856;5304.283921670563;5305.73471326436;5311.62818602698;5316.38861530925;3239.96760032399;3445.3970555153;3477.3083721277653;3580.3913334733156;3587.192881601645;982.302565178724;1005.1506477026901;1020.4132623741305;1024.9897501025043;1067.8878717734603;11092.33445993243;11100.61147859821;11213.887861121366;11226;11256.831147532794;4465.151621191963;4600;4689.577937985529;4693.671442999025;4776.713397196179;9479.24166066713;9570.99504552012;9578.090081442251;9598.616055357847;9599.136077753035;5273.683578985273;5285.101532739367;5285.471452854761;5287.286216360742;5294.311739473844;4326.372675962031;4337.783110844445;4788.569028787426;4834.371531700856;4834.806607735722;3755.7370984030854;3829.1767270037076;3845.538535375773;3898.629630185128;3915.0799562102884;1271.0467149637773;1284.678830292427;1289.265118882228;1292.3602816605653;1293.3145432920567;843.2326582809613;859.1880672764303;872.6989188729877;891.6968230801558;902.8420026495337;5605.7197140142825;5704.572157088152;5757.568182386338;5799.246098007232;6000.519958403317;903.3992395057345;909.88171537701;927.3786563002805;934.9766255843658;961.2790407194605;3316.9668303316657;3654.8903532894055;3692.150805314739;3798.4682144499793;3912.383387602656;9816.803663926681;10309.432981185957;10437.112845408225;10439.269251152346;10448.582056717647;10087.293889427816;10285.9485702571;10412.68761937143;10421.218408619385;10432.217583681255;6743.865122697519;7904.288614024766;8021.598920053974;8057.798555036171;8078.030636323561;5488.56091512686;5497.47773961473;5551.333839939233;5618.494335509824;5624.775008999594;9512.714618561455;9532.952335238557;9600.32797704182;9717.708468745948;9730.659426920023;9227.723168304961;9494.335396522454;9595.23238140961;9723.611055557698;9741.220702343937;5863.501602363676;6168.568200225941;6434.453071488976;6727.889898166944;7447.366973807287;7940.960295198718;8234.464759790595;8240.50556966584;8243.917560824315;8260.463069900437;7166.677499512431;7193.172785129777;7231.4937954342695;7413.073365829271;7530;7283.963580182277;7405.592692401754;7464.365528929885;7472.925270747223;7621.275978782007;6557.245916719447;6731.124953756176;6775.525713200029;6802.761903333359;6805.421539169028;4366.476022877275;4837.395325584302;4906.288588154626;5027.32131162303;5040.143175660073;4235.322348424237;4346.217680817484;4868.610511159028;5022.4977502249485;5159.922601161061 07:30:33 INFO - PROCESS | 1661 | |4;prototype.html;279.3546906645649;301.65309893622344;312.37213201465056;315.4290733771875;317.67438375664943;4501.999999999995;5395.81114660987;5487.122060470326;5534.861628459291;5648.293963254593;5308.513616187473;5413.431589683076;5510.862228444291;5555.694336888913;5593.244911936888;4185.33034714445;4198.244316023115;4216.957830421703;4232.280512312914;4236.724612900167;3499.562554680665;3536.522569453117;4204.516480604724;4237.936430953539;4264.573542645726;3537.8761743338987;4172.77049762263;4191.538930717618;4208.011117387411;4209.747415155085;3516.859325626972;4187.267228235047;4195.244855925932;4207.410962465257;4224.598663127013;3348.313595712873;3514.6836784689244;3515.156362472988;3521.506989021539;3641.6176301488226;5321.414644389114;5359.651622644555;5373.543248823855;5395.217693434469;5412.891742165135;4716.198246298103;4719.244920812654;4723.960728639694;4759.833405830814;4785.521447855222;4529.071540334224;4920.8769780755765;5034.748262586892;5294.444083371229;5779.248697669318;3582.9104272393397;3893.805309734502;3907.882763517099;3907.990847047786;3941.3693808990424;5676.914846277309;6021.488173505258;6036.788712395089;6043.516518678452;6059.848503787441;3391.745619078579;3421.5209870617637;3773.8301126650827;3941.2708648900043;3947.210557888434;3682.7846810552455;3848.07646164924;3989.5611482736876;4006.979965100156;4048.36856043574;3801.847926082926;4725.40932383452;4730.408698912636;4734.313524538991;4744.857654270378;5163.612729045337;5465.180222966586;5714.828555143353;5898.911516327258;5906.734196961147;1106.8284415915546;1138.703936976392;1187.3054263256092;1258.2953546014262;1303.6154334471353;725.9382952448996;740.2412527159704;886.499127992682;887.8623813308948;889.3641046651675;4668.265387689902;5019.67372120811;5149.716765577854;5234.685918844882;5274.235235890775;5312.282841816383;5457.499187723329;5562.276904002454;5664.68844213564;5775.451332123442;4290.27841168416;4772.3557319761385;5012.423571289349;5047.697138171721;5274.835098940682;2594.7804531869992;2829.628224393276;2925.9268518287213;3002.489576771954;3036.9848150759103;5287.867803304948;5571.860703482364;5888.969430349672;5971.4772732952815;5992.700364981733;4956.702597844141;4975.154223781966;5738.282714660668;5847.596576821617;5891.970540147272;5925.644651181378;5951.851203719942;5959.404059594092;6000.759969601254;6013.6692481913915;4914.533119353656;5447.836564903059;5561.555075593862;5751.884962300815;5767.509761670305;4853.927191092207;4973.52751488608;5092.872678183075;5289.841304760864;5640.520555752807;5323.387810401853;5551.444855514416;5724.484796368347;5931.6441013539325;5951.49412299942;3163.6272795403447;3560.3413368526903;3768.4724138620068;3778.471014057979;3783.697304215711;5075.467075956972;5189.9360631071295;5476.589255805751;5696.202531645656;5733.391306955242;5644.717764111696;5741.62679425836;5815.8659061484295;5845.824625261249;5901.763929442946;4566.977165114153;4873.829415970424;5844.100667283065;5888.4700376966275;5917.408259174048;4830.855074347775;5676.9432305678065;5702.344230413555;5725.828225153252;5757.683327417032;4968;5838.970805145947;5850.414958504286;5859.150423188699;5873.324567674772;5552.222688823649;5729.742161602821;5733.655980641175;5750.377262324709;5751.281089863767 07:30:33 INFO - PROCESS | 1661 | |5;yui.html;549.6784381137035;648.1929997153542;734.2840730287974;763.6978950888736;794.3953214506697;14153.64615884602;14336.928315358422;14595.927020364896;14802.555923322318;14958.700825983473;13314.600561983156;13637;13643.795343069836;13770.655733606665;13788.862111378883;807.1807115777506;821.6713314674118;822.2229992656942;822.9218224268685;824.1387749801441;651.654623049782;672.9764458243963;787.7164220880478;811.922867327603;816.2245866426889;863.7754183912197;868.0321441592641;872.1801506583822;872.5680788009912;874.2961915657893;856.8886044814196;863.1541089732067;864.0797550608581;866.3415803988951;868.6785889220997;638.3074364314724;639.8528338482175;641.3426238105932;642.7107801489302;642.775028739942;13718.314084295846;13741.519046833413;13784.793228101587;13805.585832424942;13811.654708632264;13071.41178646963;13305.866941330656;13368.197908125447;13412.195268283935;13495.527656531975;11517.654470365818;11554.59558915434;11611.303321800635;11686.649400517997;11900;3595.7662751921043;3642.653947874948;3667.1932174921476;3775.0562359410146;3784.6404591563755;89179.55410222973;90333;92133.53933230358;92404;94374;10319.94840025795;10331.01855323743;10374.325668831501;10439.94780026095;10749;20088.99555022243;20272.797272027386;20285.898570507346;20311.4922126948;20564.074616642287;7616.504927179715;7624.580648064299;7653.961730191313;7703.3067023968115;7731.265529774662;4234.830606775756;4335.956640433618;4363.236433624166;4364.672649551297;4377.496587892433;3337.9499307510405;3449.379111759857;3459.481077838294;3530.135116896377;3748.475213470116;680.9080774095532;681.2540268406087;683.9829004274933;686.1834417043801;698.2214830463996;517.7961240116744;522.3835873669107;572.819561838028;654.6105067484839;660.1319265166387;8517.148285171557;8812.427192232613;8920.598573064228;9088.409253398508;9145.954270228607;606.8452544601098;626.2109741725459;628.7485005997637;662.3934816497099;667.5293917787948;3394.9830250848586;3651.634836516327;4046.5346485153996;4116.8711779048135;4168.936921085104;72700.63649681718;104140.95859041464;107476;113812;116798;80081.59959200167;92608.0739192613;95663;102719;105212.42181367133;54479.72760136253;55220.447795522334;58444.7077764617;87884;93035.53482232546;9135.58889849945;9359.344845860724;9423.575939082895;9430.905690943277;9566.330356874952;30350.84824575863;42285.1542969145;42975.9256018602;54848;58008.4199158003;59571;79400.20599793928;82675.3464930686;83581.16418835733;86112.56943715242;11560.421978900853;11659;11696.70758231051;11708.180427370344;11718.414079296172;10586.8411973822;12534.561290354794;12755;12774;12806.807897881354;12820.61538153857;12972.416241268977;13134.60596182116;13308.134971226838;13741.03812733099;13067.477300907858;13215.867841321848;13334.26661533625;13436.93281533586;13676.111052781538;12681.619551413472;12954.870451295743;12980.805287920877;13004.479820807062;13435.529756458476;11009.339439633648;11120.487763468751;11168;11203.775924481628;11563.710907227387;9655.613775448903;9899.851502227328;10232.846507302247;11341.376224307412;11405.942970285427 07:30:33 INFO - PROCESS | 1661 | __end_tp_report 07:30:33 INFO - PROCESS | 1661 | __start_cc_report 07:30:33 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,379 07:30:33 INFO - PROCESS | 1661 | __end_cc_report 07:30:33 INFO - PROCESS | 1661 | __startTimestamp1478532633216__endTimestamp 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - PROCESS | 1661 | ------- Summary: start ------- 07:30:33 INFO - PROCESS | 1661 | Number of tests: 6 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5688.19 Median:5943.32 stddev:669.21 (11.3%) stddev-sans-first:634.43 07:30:33 INFO - PROCESS | 1661 | Values: 2896.8 3285.1 3360.0 3427.2 3429.3 5613.9 5690.6 5719.4 5725.5 5820.3 4375.4 4876.5 5660.9 5744.0 5842.5 5183.6 5204.0 5300.3 5336.1 5346.6 5147.5 5337.3 5349.6 5360.5 5377.1 4972.4 5145.6 5292.0 5298.4 5325.6 3948.8 4818.4 5135.4 5220.0 5261.3 5153.1 5159.8 5162.3 5197.3 5251.8 4869.9 5134.3 5617.4 5640.5 5645.1 6108.9 6149.8 6201.9 6209.1 6237.8 5839.9 5841.6 6146.9 6168.1 6195.7 4878.5 5701.3 5765.2 5933.6 5942.0 5690.0 5943.3 6136.8 6145.8 6260.7 5239.4 6165.5 6167.6 6167.8 6178.5 6012.9 6068.9 6203.0 6210.0 6218.4 6107.2 6111.7 6119.9 6130.6 6147.9 6171.9 6180.4 6187.8 6193.7 6240.5 4789.7 4962.5 4968.8 4973.7 4989.2 4330.5 4365.1 4376.2 4420.4 4485.8 5787.8 5842.0 5896.9 5940.3 5970.9 6168.2 6182.7 6191.0 6195.7 6195.8 5701.2 5801.3 5841.6 5862.3 5899.3 5456.7 5468.9 5469.5 5482.5 5494.4 6289.2 6311.5 6324.8 6327.3 6368.9 6118.3 6135.9 6157.6 6214.5 6220.8 6193.5 6247.3 6287.2 6317.4 6330.7 6165.5 6179.2 6185.1 6191.1 6250.2 6079.5 6188.0 6189.1 6193.0 6217.0 5033.4 6064.7 6084.3 6102.4 6114.6 6084.9 6106.2 6117.1 6121.3 6138.2 5654.0 5699.7 5933.9 5958.6 5966.8 6081.4 6135.6 6151.2 6153.3 6187.3 5980.9 6012.2 6018.2 6023.5 6055.1 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:66000.73 Median:38416.92 stddev:146278.71 (380.8%) stddev-sans-first:146606.27 07:30:33 INFO - PROCESS | 1661 | Values: 451.3 519.2 523.5 537.7 547.0 37287.4 38987.8 38994.0 39036.0 40950.4 37294.9 40542.8 40603.0 40901.0 40903.0 9218.5 9378.4 9386.0 9410.9 9430.7 9199.1 9274.9 9311.2 9313.4 9331.0 9297.7 9322.8 9328.2 9355.4 9355.9 9245.0 9315.3 9319.3 9333.0 9349.8 9084.1 9088.5 9101.5 9176.2 9204.0 38166.0 38248.6 38341.2 38492.6 38576.6 3200.8 3287.0 3338.7 3416.2 3615.7 29084.9 29312.3 29455.7 30247.7 30264.5 3147.9 3177.7 3199.7 3214.6 3282.6 445735.0 452727.0 453067.0 453417.0 454358.0 53293.7 53854.2 53929.0 54145.0 54350.7 55509.0 55590.7 56118.4 56237.7 56620.2 13830.3 13973.7 13981.3 13982.0 14041.9 10691.4 10759.9 10768.1 10771.4 10796.2 13410.2 14177.4 14350.7 14534.6 14704.0 3183.2 3260.3 5602.5 6739.5 6755.3 826.9 990.8 998.7 1058.9 1065.2 29287.9 29383.3 29564.7 29592.4 29894.3 915.0 967.5 991.9 1015.8 1029.8 13939.9 14288.4 14294.4 14594.1 14703.9 586540.0 594521.0 595876.0 599571.0 603567.0 51187.0 51723.0 51743.0 51818.5 51830.5 1291.5 1300.3 1323.0 1336.4 1341.9 26345.6 27320.3 27731.9 27781.6 27788.4 45398.5 46181.8 46246.5 46314.1 46493.8 577487.0 579205.0 580205.0 580753.0 584752.0 17996.5 21491.2 21921.7 21983.8 22094.6 39207.0 41342.0 41492.4 42300.6 42814.7 34562.7 35043.0 35150.1 35516.8 36284.1 35236.0 35239.9 35713.6 36193.8 36205.0 28375.0 34252.0 34634.8 34934.3 35093.8 26038.6 29329.4 31263.4 31339.4 31493.4 23269.4 23593.5 23646.9 24049.4 24103.6 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:33200.32 Median:377.04 stddev:93389.19 (24769.2%) stddev-sans-first:93618.58 07:30:33 INFO - PROCESS | 1661 | Values: 341.3 367.8 372.9 381.2 396.1 18120.8 18634.3 19698.2 19873.3 19982.5 19042.3 19253.2 19340.9 19459.4 19632.9 5530.4 5653.5 5690.9 5812.1 5817.1 4961.6 5466.0 5630.7 5683.9 5724.8 5181.9 5657.7 5658.1 5664.3 5673.0 4610.7 5559.7 5623.1 5667.5 5675.1 4984.7 4988.7 4996.6 5004.9 5012.1 21879.7 22954.4 23018.5 23173.5 23434.1 2028.4 2078.6 2098.0 2131.0 2356.7 9679.0 9898.3 13858.9 14221.7 14386.8 1775.8 1818.0 1856.2 1868.0 1877.8 118990.0 125296.7 125837.0 126492.0 127554.0 10571.7 11321.5 11338.4 11389.4 11510.1 13904.9 14074.9 14081.3 14082.9 14091.2 7522.2 7659.5 7777.7 7904.4 7962.3 7385.7 7403.9 7443.4 7555.4 7577.0 4452.2 4464.7 4465.2 4478.4 4520.0 1887.7 1943.6 1950.4 1950.9 1955.8 811.5 833.4 903.7 913.8 946.3 19625.3 20402.4 20483.8 20576.8 20629.2 799.3 873.9 895.5 901.2 921.4 10365.7 10621.2 10795.4 10819.3 10846.2 547088.0 558079.0 558785.0 560608.0 565597.0 89811.6 110945.4 111609.9 117760.8 122254.4 929.9 947.4 997.3 998.6 1002.5 18071.4 18345.0 18641.4 18745.6 18901.3 51253.0 51841.7 53431.0 53584.0 55115.0 72830.6 78137.6 79492.2 80233.6 81938.6 7462.3 7593.9 7692.2 7716.7 7800.5 10378.5 11606.4 11745.2 12027.8 12151.5 8720.8 9931.8 10418.8 10564.7 10645.5 8531.3 9384.7 10214.6 10288.5 10425.3 10470.5 10619.5 10646.6 10832.8 10876.2 5257.8 6021.8 6080.1 6762.6 6928.0 6417.8 10209.9 10353.2 10640.9 10680.8 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5500.38 Median:5285.29 stddev:3046.13 (57.6%) stddev-sans-first:3039.82 07:30:33 INFO - PROCESS | 1661 | Values: 1496.2 1514.5 1714.0 1776.2 1818.2 7382.9 7639.1 7899.4 8157.8 8651.1 7566.8 8139.7 8206.2 8447.8 8454.5 2289.6 2291.1 2332.7 2333.5 2336.6 1927.6 2347.3 2349.9 2353.1 2354.4 2293.9 2294.8 2297.1 2317.4 2326.5 2272.4 2290.2 2301.4 2313.8 2318.4 2284.4 2343.4 2345.9 2351.6 2357.6 7845.3 8199.8 8591.4 8656.9 8673.3 5151.5 5304.3 5305.7 5311.6 5316.4 3240.0 3445.4 3477.3 3580.4 3587.2 982.3 1005.2 1020.4 1025.0 1067.9 11092.3 11100.6 11213.9 11226.0 11256.8 4465.2 4600.0 4689.6 4693.7 4776.7 9479.2 9571.0 9578.1 9598.6 9599.1 5273.7 5285.1 5285.5 5287.3 5294.3 4326.4 4337.8 4788.6 4834.4 4834.8 3755.7 3829.2 3845.5 3898.6 3915.1 1271.0 1284.7 1289.3 1292.4 1293.3 843.2 859.2 872.7 891.7 902.8 5605.7 5704.6 5757.6 5799.2 6000.5 903.4 909.9 927.4 935.0 961.3 3317.0 3654.9 3692.2 3798.5 3912.4 9816.8 10309.4 10437.1 10439.3 10448.6 10087.3 10285.9 10412.7 10421.2 10432.2 6743.9 7904.3 8021.6 8057.8 8078.0 5488.6 5497.5 5551.3 5618.5 5624.8 9512.7 9533.0 9600.3 9717.7 9730.7 9227.7 9494.3 9595.2 9723.6 9741.2 5863.5 6168.6 6434.5 6727.9 7447.4 7941.0 8234.5 8240.5 8243.9 8260.5 7166.7 7193.2 7231.5 7413.1 7530.0 7284.0 7405.6 7464.4 7472.9 7621.3 6557.2 6731.1 6775.5 6802.8 6805.4 4366.5 4837.4 4906.3 5027.3 5040.1 4235.3 4346.2 4868.6 5022.5 5159.9 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4579.26 Median:5121.29 stddev:1431.87 (28.0%) stddev-sans-first:1399.04 07:30:33 INFO - PROCESS | 1661 | Values: 279.4 301.7 312.4 315.4 317.7 4502.0 5395.8 5487.1 5534.9 5648.3 5308.5 5413.4 5510.9 5555.7 5593.2 4185.3 4198.2 4217.0 4232.3 4236.7 3499.6 3536.5 4204.5 4237.9 4264.6 3537.9 4172.8 4191.5 4208.0 4209.7 3516.9 4187.3 4195.2 4207.4 4224.6 3348.3 3514.7 3515.2 3521.5 3641.6 5321.4 5359.7 5373.5 5395.2 5412.9 4716.2 4719.2 4724.0 4759.8 4785.5 4529.1 4920.9 5034.7 5294.4 5779.2 3582.9 3893.8 3907.9 3908.0 3941.4 5676.9 6021.5 6036.8 6043.5 6059.8 3391.7 3421.5 3773.8 3941.3 3947.2 3682.8 3848.1 3989.6 4007.0 4048.4 3801.8 4725.4 4730.4 4734.3 4744.9 5163.6 5465.2 5714.8 5898.9 5906.7 1106.8 1138.7 1187.3 1258.3 1303.6 725.9 740.2 886.5 887.9 889.4 4668.3 5019.7 5149.7 5234.7 5274.2 5312.3 5457.5 5562.3 5664.7 5775.5 4290.3 4772.4 5012.4 5047.7 5274.8 2594.8 2829.6 2925.9 3002.5 3037.0 5287.9 5571.9 5889.0 5971.5 5992.7 4956.7 4975.2 5738.3 5847.6 5892.0 5925.6 5951.9 5959.4 6000.8 6013.7 4914.5 5447.8 5561.6 5751.9 5767.5 4853.9 4973.5 5092.9 5289.8 5640.5 5323.4 5551.4 5724.5 5931.6 5951.5 3163.6 3560.3 3768.5 3778.5 3783.7 5075.5 5189.9 5476.6 5696.2 5733.4 5644.7 5741.6 5815.9 5845.8 5901.8 4567.0 4873.8 5844.1 5888.5 5917.4 4830.9 5676.9 5702.3 5725.8 5757.7 4968.0 5839.0 5850.4 5859.2 5873.3 5552.2 5729.7 5733.7 5750.4 5751.3 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:19848.50 Median:23259.99 stddev:29217.24 (125.6%) stddev-sans-first:29263.26 07:30:33 INFO - PROCESS | 1661 | Values: 549.7 648.2 734.3 763.7 794.4 14153.6 14336.9 14595.9 14802.6 14958.7 13314.6 13637.0 13643.8 13770.7 13788.9 807.2 821.7 822.2 822.9 824.1 651.7 673.0 787.7 811.9 816.2 863.8 868.0 872.2 872.6 874.3 856.9 863.2 864.1 866.3 868.7 638.3 639.9 641.3 642.7 642.8 13718.3 13741.5 13784.8 13805.6 13811.7 13071.4 13305.9 13368.2 13412.2 13495.5 11517.7 11554.6 11611.3 11686.6 11900.0 3595.8 3642.7 3667.2 3775.1 3784.6 89179.6 90333.0 92133.5 92404.0 94374.0 10319.9 10331.0 10374.3 10439.9 10749.0 20089.0 20272.8 20285.9 20311.5 20564.1 7616.5 7624.6 7654.0 7703.3 7731.3 4234.8 4336.0 4363.2 4364.7 4377.5 3337.9 3449.4 3459.5 3530.1 3748.5 680.9 681.3 684.0 686.2 698.2 517.8 522.4 572.8 654.6 660.1 8517.1 8812.4 8920.6 9088.4 9146.0 606.8 626.2 628.7 662.4 667.5 3395.0 3651.6 4046.5 4116.9 4168.9 72700.6 104141.0 107476.0 113812.0 116798.0 80081.6 92608.1 95663.0 102719.0 105212.4 54479.7 55220.4 58444.7 87884.0 93035.5 9135.6 9359.3 9423.6 9430.9 9566.3 30350.8 42285.2 42975.9 54848.0 58008.4 59571.0 79400.2 82675.3 83581.2 86112.6 11560.4 11659.0 11696.7 11708.2 11718.4 10586.8 12534.6 12755.0 12774.0 12806.8 12820.6 12972.4 13134.6 13308.1 13741.0 13067.5 13215.9 13334.3 13436.9 13676.1 12681.6 12954.9 12980.8 13004.5 13435.5 11009.3 11120.5 11168.0 11203.8 11563.7 9655.6 9899.9 10232.8 11341.4 11405.9 07:30:33 INFO - PROCESS | 1661 | -------- Summary: end -------- 07:30:33 INFO - PROCESS | 1661 | 07:30:33 INFO - TEST-INFO | 1661: exit 0 07:30:33 INFO - TEST-OK | dromaeo_css | took 1115196ms 07:30:33 INFO - TEST-START | kraken 07:30:33 INFO - operating with platform_type : mac_ 07:30:33 INFO - Initialising browser for kraken test... 07:30:33 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp_tKrZ0/profile http://localhost:49272/getInfo.html) 07:30:36 INFO - PROCESS | 1671 | 2016-11-07 07:30:36.654 plugin-container[1672:11036] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 07:30:36 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:30:36 INFO - PROCESS | 1671 | 2016-11-07 07:30:36.658 plugin-container[1672:11036] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 07:30:36 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:30:36 INFO - PROCESS | 1671 | 2016-11-07 07:30:36.658 plugin-container[1672:11036] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:30:36 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 07:30:36 INFO - PROCESS | 1671 | colorDepth:24 07:30:36 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 07:30:36 INFO - PROCESS | 1671 | __metrics 07:30:36 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 07:30:37 INFO - TEST-INFO | 1671: exit 0 07:30:37 INFO - Browser initialized. 07:30:37 INFO - Running cycle 1/1 for kraken test... 07:30:37 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp_tKrZ0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:30:39 INFO - PROCESS | 1673 | 2016-11-07 07:30:39.745 plugin-container[1674:11150] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 07:30:39 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:30:39 INFO - PROCESS | 1673 | 2016-11-07 07:30:39.746 plugin-container[1674:11150] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 07:30:39 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:30:39 INFO - PROCESS | 1673 | 2016-11-07 07:30:39.746 plugin-container[1674:11150] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:30:49 INFO - PROCESS | 1673 | RSS: Main: 238702592 07:30:49 INFO - PROCESS | 1673 | 07:30:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 07:30:53 INFO - PROCESS | 1673 | RSS: Main: 251211776 07:30:53 INFO - PROCESS | 1673 | 07:30:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 07:30:56 INFO - PROCESS | 1673 | RSS: Main: 259592192 07:30:56 INFO - PROCESS | 1673 | 07:30:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 07:31:00 INFO - PROCESS | 1673 | RSS: Main: 261775360 07:31:00 INFO - PROCESS | 1673 | 07:31:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 07:31:03 INFO - PROCESS | 1673 | RSS: Main: 262647808 07:31:03 INFO - PROCESS | 1673 | 07:31:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:31:05 INFO - PROCESS | 1673 | RSS: Main: 264228864 07:31:05 INFO - PROCESS | 1673 | 07:31:08 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 07:31:09 INFO - PROCESS | 1673 | RSS: Main: 264421376 07:31:09 INFO - PROCESS | 1673 | 07:31:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 07:31:13 INFO - PROCESS | 1673 | RSS: Main: 264515584 07:31:13 INFO - PROCESS | 1673 | 07:31:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 07:31:17 INFO - PROCESS | 1673 | RSS: Main: 264634368 07:31:17 INFO - PROCESS | 1673 | 07:31:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:31:19 INFO - PROCESS | 1673 | RSS: Main: 264663040 07:31:19 INFO - PROCESS | 1673 | 07:31:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:31:21 INFO - PROCESS | 1673 | RSS: Main: 264761344 07:31:21 INFO - PROCESS | 1673 | 07:31:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:31:24 INFO - PROCESS | 1673 | RSS: Main: 264601600 07:31:24 INFO - PROCESS | 1673 | 07:31:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:31:28 INFO - PROCESS | 1673 | RSS: Main: 264904704 07:31:28 INFO - PROCESS | 1673 | 07:31:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:31:32 INFO - PROCESS | 1673 | RSS: Main: 264810496 07:31:32 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 07:31:34 INFO - PROCESS | 1673 | RSS: Main: 266530816 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | __start_tp_report 07:31:34 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 07:31:34 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 07:31:34 INFO - PROCESS | 1673 | |i|pagename|runs| 07:31:34 INFO - PROCESS | 1673 | |0;ai-astar;105;118;117;128;112;118;115;118;117;119 07:31:34 INFO - PROCESS | 1673 | |1;audio-beat-detection;153;111;116;149;116;113;118;116;144;115 07:31:34 INFO - PROCESS | 1673 | |2;audio-dft;196;209;207;236;164;211;165;212;196;251 07:31:34 INFO - PROCESS | 1673 | |3;audio-fft;83;58;52;98;76;84;84;84;66;73 07:31:34 INFO - PROCESS | 1673 | |4;audio-oscillator;83;93;91;64;83;100;82;68;91;121 07:31:34 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;90;89;93;129;135;86;108;110;132;88 07:31:34 INFO - PROCESS | 1673 | |6;imaging-darkroom;98;99;100;118;172;114;101;169;98;171 07:31:34 INFO - PROCESS | 1673 | |7;imaging-desaturate;103;141;122;91;83;132;143;128;87;72 07:31:34 INFO - PROCESS | 1673 | |8;json-parse-financial;55;56;55;55;57;57;57;59;81;87 07:31:34 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;71;76;75;82;80;81;93;70;83;108 07:31:34 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;80;100;110;86;88;87;115;88;92;91 07:31:34 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;149;173;166;159;148;162;159;154;223;197 07:31:34 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;216;239;188;180;173;173;183;199;224;238 07:31:34 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;68;69;69;68;73;67;91;71;70;95 07:31:34 INFO - PROCESS | 1673 | __end_tp_report 07:31:34 INFO - PROCESS | 1673 | __start_cc_report 07:31:34 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,940 07:31:34 INFO - PROCESS | 1673 | __end_cc_report 07:31:34 INFO - PROCESS | 1673 | __startTimestamp1478532694590__endTimestamp 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | ------- Summary: start ------- 07:31:34 INFO - PROCESS | 1673 | Number of tests: 14 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:116.70 Median:118.00 stddev:5.77 (4.9%) stddev-sans-first:4.30 07:31:34 INFO - PROCESS | 1673 | Values: 105.0 118.0 117.0 128.0 112.0 118.0 115.0 118.0 117.0 119.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:117.00 stddev:16.51 (14.1%) stddev-sans-first:14.09 07:31:34 INFO - PROCESS | 1673 | Values: 153.0 111.0 116.0 149.0 116.0 113.0 118.0 116.0 144.0 115.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:204.70 Median:210.00 stddev:27.13 (12.9%) stddev-sans-first:28.59 07:31:34 INFO - PROCESS | 1673 | Values: 196.0 209.0 207.0 236.0 164.0 211.0 165.0 212.0 196.0 251.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:75.80 Median:83.50 stddev:13.88 (16.6%) stddev-sans-first:14.47 07:31:34 INFO - PROCESS | 1673 | Values: 83.0 58.0 52.0 98.0 76.0 84.0 84.0 84.0 66.0 73.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:83.00 stddev:16.11 (19.4%) stddev-sans-first:17.00 07:31:34 INFO - PROCESS | 1673 | Values: 83.0 93.0 91.0 64.0 83.0 100.0 82.0 68.0 91.0 121.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:106.00 Median:87.00 stddev:19.73 (22.7%) stddev-sans-first:20.06 07:31:34 INFO - PROCESS | 1673 | Values: 90.0 89.0 93.0 129.0 135.0 86.0 108.0 110.0 132.0 88.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:124.00 Median:171.50 stddev:32.92 (19.2%) stddev-sans-first:33.55 07:31:34 INFO - PROCESS | 1673 | Values: 98.0 99.0 100.0 118.0 172.0 114.0 101.0 169.0 98.0 171.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:110.20 Median:107.50 stddev:26.06 (24.2%) stddev-sans-first:27.51 07:31:34 INFO - PROCESS | 1673 | Values: 103.0 141.0 122.0 91.0 83.0 132.0 143.0 128.0 87.0 72.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:57.00 stddev:11.80 (20.7%) stddev-sans-first:12.25 07:31:34 INFO - PROCESS | 1673 | Values: 55.0 56.0 55.0 55.0 57.0 57.0 57.0 59.0 81.0 87.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:81.90 Median:80.50 stddev:11.32 (14.1%) stddev-sans-first:11.30 07:31:34 INFO - PROCESS | 1673 | Values: 71.0 76.0 75.0 82.0 80.0 81.0 93.0 70.0 83.0 108.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:87.50 stddev:11.19 (12.8%) stddev-sans-first:10.71 07:31:34 INFO - PROCESS | 1673 | Values: 80.0 100.0 110.0 86.0 88.0 87.0 115.0 88.0 92.0 91.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:169.00 Median:164.00 stddev:23.66 (14.4%) stddev-sans-first:23.97 07:31:34 INFO - PROCESS | 1673 | Values: 149.0 173.0 166.0 159.0 148.0 162.0 159.0 154.0 223.0 197.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.30 Median:207.50 stddev:25.97 (12.5%) stddev-sans-first:27.00 07:31:34 INFO - PROCESS | 1673 | Values: 216.0 239.0 188.0 180.0 173.0 173.0 183.0 199.0 224.0 238.0 07:31:34 INFO - PROCESS | 1673 | 07:31:34 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:70.50 stddev:10.15 (14.4%) stddev-sans-first:10.52 07:31:34 INFO - PROCESS | 1673 | Values: 68.0 69.0 69.0 68.0 73.0 67.0 91.0 71.0 70.0 95.0 07:31:34 INFO - PROCESS | 1673 | -------- Summary: end -------- 07:31:34 INFO - PROCESS | 1673 | 07:31:35 INFO - TEST-INFO | 1673: exit 0 07:31:35 INFO - TEST-OK | kraken | took 61705ms 07:31:35 INFO - SUITE-END | took 1176s 07:31:35 INFO - Completed test suite (00:19:36) 07:31:35 INFO - javascript benchmark 07:31:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7055.278942706962, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2896.7827412944034, 3285.1294406982147, 3359.9920023992813, 3427.228873503462, 3429.3141371725656, 5613.915791263124, 5690.60165788395, 5719.370869204384, 5725.513331366834, 5820.272465941757, 4375.3874457575885, 4876.536729010747, 5660.858478538039, 5744.0, 5842.47417732404, 5183.611229157809, 5204.011237864794, 5300.337457817773, 5336.066188417031, 5346.599005074635, 5147.48525147486, 5337.2794672719265, 5349.598780091489, 5360.544353729918, 5377.11277639189, 4972.378452693413, 5145.6398052136365, 5292.0, 5298.36419629643, 5325.600579956499, 3948.7758794773713, 4818.373611430492, 5135.38375394955, 5220.008198442321, 5261.316028916254, 5153.098207813655, 5159.793608255665, 5162.277281180638, 5197.324347834795, 5251.76367063483, 4869.902601947977, 5134.332536770196, 5617.354004289518, 5640.492355688008, 5645.096784514458, 6108.949222962717, 6149.831532008904, 6201.906971395432, 6209.099680546341, 6237.750489980395, 5839.919614871263, 5841.591088623798, 6146.907796383058, 6168.1056246843955, 6195.690215489252, 4878.4633690294395, 5701.315842098975, 5765.1928729977835, 5933.643981361089, 5941.960156972513, 5689.975804355256, 5943.31651860033, 6136.825445958321, 6145.784897528654, 6260.718267677966, 5239.423663397023, 6165.514832464296, 6167.599106058092, 6167.753289868445, 6178.505719542426, 6012.939870601325, 6068.939310606926, 6203.0, 6210.037444196157, 6218.440340369389, 6107.188815080102, 6111.652512877656, 6119.8681629442035, 6130.581587307925, 6147.862645410613, 6171.938280617136, 6180.443760061616, 6187.752489900444, 6193.659348735863, 6240.53196010292, 4789.688670236424, 4962.478939711351, 4968.826091086795, 4973.651844370932, 4989.201727723619, 4330.523642399334, 4365.08333250021, 4376.234159022159, 4420.447444069491, 4485.775711214426, 5787.826365209051, 5841.970790146022, 5896.882062358729, 5940.34656187819, 5970.850728731816, 6168.167297414791, 6182.69086545671, 6191.00943848982, 6195.721192546346, 6195.783147589813, 5701.230333904869, 5801.3328467225965, 5841.649501029951, 5862.296524417012, 5899.321578018583, 5456.727163641882, 5468.945310546923, 5469.453054694578, 5482.479164479368, 5494.36814766299, 6289.245290565162, 6311.526635502356, 6324.810255692337, 6327.303996560467, 6368.904466433008, 6118.345080237391, 6135.877282454237, 6157.568970172045, 6214.471769899609, 6220.7511699531515, 6193.473554747717, 6247.312795592572, 6287.245530536365, 6317.399847014526, 6330.651814150082, 6165.5375846810775, 6179.16581261524, 6185.134081228722, 6191.102290167991, 6250.156228909221, 6079.483243924139, 6188.040853667509, 6189.102580125947, 6192.969035154795, 6217.0, 5033.421156566895, 6064.727087280995, 6084.269887613515, 6102.359252278625, 6114.60152118864, 6084.878302433838, 6106.2061931948565, 6117.051856962178, 6121.326654068138, 6138.202033735586, 5653.971730141323, 5699.658020518782, 5933.881322373615, 5958.553108516965, 5966.791162309472, 6081.400734030944, 6135.631862088289, 6151.169592105009, 6153.292371377349, 6187.257529096357, 5980.910286345621, 6012.248468941382, 6018.21763170785, 6023.548233882389, 6055.122007308827], "value": 5645.538341534607, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.3297752837037, 519.1978393382225, 523.4974424552432, 537.6693333599837, 547.0181025060017, 37287.44068838966, 38987.80506097466, 38993.99999999996, 39036.0, 40950.38574421386, 37294.88115356544, 40542.79728601353, 40603.0, 40901.0, 40903.0, 9218.539073046355, 9378.437293762361, 9385.95307023464, 9410.858837117448, 9430.717078487656, 9199.080091990782, 9274.860877086816, 9311.208547273456, 9313.44119352841, 9331.0, 9297.721068367926, 9322.813543729122, 9328.160465558098, 9355.391899526543, 9355.859662105075, 9245.0, 9315.254779617682, 9319.34764566484, 9333.0, 9349.813003739955, 9084.091590840862, 9088.50013249271, 9101.544922753836, 9176.174144326977, 9203.953980230122, 38166.0, 38248.61751382506, 38341.23317533634, 38492.615073849185, 38576.61423385758, 3200.759943004284, 3287.0, 3338.749593780476, 3416.180116771957, 3615.7107431405425, 29084.854575727197, 29312.26719332034, 29455.70544294551, 30247.697523024708, 30264.54603180954, 3147.9055628330957, 3177.729892959101, 3199.7120259176554, 3214.5981752280964, 3282.556854824592, 445735.0, 452727.0, 453067.0, 453417.0, 454358.0, 53293.733531332095, 53854.19218711722, 53929.0, 54145.0, 54350.728246358514, 55509.0, 55590.72204639032, 56118.43881561132, 56237.7188114065, 56620.15069773957, 13830.308484575731, 13973.720525589431, 13981.300934953211, 13982.0, 14041.929790351187, 10691.380611750172, 10759.89240107589, 10768.138548916068, 10771.407572583583, 10796.244262901522, 13410.195388276734, 14177.362018709182, 14350.712985740227, 14534.563963081124, 14704.0, 3183.2041989502623, 3260.2827377977283, 5602.4957753802355, 6739.494537909676, 6755.324467553205, 826.8635675113659, 990.757264470195, 998.6953979036464, 1058.8888166742381, 1065.2277099103244, 29287.853560732485, 29383.265418364714, 29564.704352956196, 29592.351785866133, 29894.25264368408, 914.9771255718528, 967.5355829201882, 991.9156871665989, 1015.8069966706303, 1029.8352263637782, 13939.930300348433, 14288.357023933948, 14294.428222870969, 14594.124352538669, 14703.897207709464, 586540.0, 594521.0, 595876.0, 599571.0, 603567.0, 51187.0, 51723.0, 51743.0, 51818.48181518212, 51830.48169518332, 1291.4704970962048, 1300.3043371796232, 1322.9813043955908, 1336.4253371050347, 1341.8523962364131, 26345.60481592816, 27320.316992075357, 27731.861340693973, 27781.58327625047, 27788.44423111486, 45398.54601453943, 46181.76909115433, 46246.53753462422, 46314.07371852612, 46493.76753116213, 577487.0, 579205.0, 580205.0, 580753.0, 584752.0, 17996.460106196835, 21491.247806326704, 21921.67117493207, 21983.780162198174, 22094.558108838053, 39207.0, 41342.0, 41492.377614336416, 42300.57699422966, 42814.715558533295, 34562.654373455945, 35043.0, 35150.12124696903, 35516.82241588776, 36284.09727221763, 35236.0, 35239.94740604863, 35713.64286357103, 36193.81903090468, 36205.0, 28375.0, 34252.0, 34634.82682586571, 34934.30131397409, 35093.82453087718, 26038.609420858324, 29329.413411732072, 31263.374732505676, 31339.373212536077, 31493.370132597676, 23269.41826454352, 23593.52812943766, 23646.88176559106, 24049.398765031016, 24103.63844542298], "value": 18381.972391326843, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.2526566818667, 367.80873945548313, 372.8937252882929, 381.18236382958537, 396.13839898221363, 18120.818791812093, 18634.34779782706, 19698.212071517122, 19873.304434344802, 19982.503936614256, 19042.33351832686, 19253.22987080515, 19340.93624850636, 19459.416217513462, 19632.901835490804, 5530.446955304467, 5653.519450846662, 5690.943090569103, 5812.1281807728965, 5817.069268916975, 4961.6278779091535, 5465.9726701366435, 5630.662160270397, 5683.943160568383, 5724.7996320128805, 5181.859990802036, 5657.745401456924, 5658.094704847225, 5664.320281566218, 5673.0, 4610.654200934927, 5559.694216818073, 5623.100303951389, 5667.489925906688, 5675.063614503602, 4984.651074424792, 4988.700677959334, 4996.600271978233, 5004.949950500485, 5012.122878496249, 21879.67180492294, 22954.426139346484, 23018.539629207324, 23173.536529269488, 23434.06263749448, 2028.3610662641368, 2078.646630072891, 2098.045389347843, 2130.957380852374, 2356.705411823522, 9679.032096790335, 9898.257630677728, 13858.930705346509, 14221.715565688628, 14386.84905207581, 1775.8135395783386, 1817.9909100454545, 1856.1647258733624, 1867.9813201868078, 1877.8497720182352, 118990.0, 125296.74703253033, 125837.0, 126492.0, 127554.0, 10571.735706607396, 11321.490532926038, 11338.355938388888, 11389.373584452935, 11510.079193664487, 13904.86095139056, 14074.929625352013, 14081.295935203403, 14082.859171408154, 14091.154530728189, 7522.210167932289, 7659.540427574363, 7777.72777952769, 7904.379602181539, 7962.283394494523, 7385.66764495599, 7403.938118104085, 7443.441741869381, 7555.357794587411, 7577.0, 4452.220861349251, 4464.665150113755, 4465.218586747306, 4478.440194975628, 4520.0, 1887.734718338, 1943.640426521098, 1950.3953774330002, 1950.8829470231547, 1955.7946415626154, 811.5455345006815, 833.3957880536562, 903.7243640689653, 913.830941275866, 946.2855544064188, 19625.313114041226, 20402.38792836217, 20483.795162048486, 20576.794232057488, 20629.22781115187, 799.3405440511601, 873.9038705742363, 895.54327293081, 901.2339511415375, 921.4195057114109, 10365.689029329133, 10621.150307975344, 10795.352278863293, 10819.296745711503, 10846.18653600983, 547088.0, 558079.0, 558785.0, 560608.0, 565597.0, 89811.55094224618, 110945.44527277313, 111609.88390116158, 117760.8223917767, 122254.3887280558, 929.9395539289925, 947.3652652722692, 997.33677104726, 998.5905778630727, 1002.478711070254, 18071.367502137364, 18345.0, 18641.44075677732, 18745.625087498447, 18901.338453154072, 51253.0, 51841.7407912958, 53431.0, 53584.0, 55115.0, 72830.63584682043, 78137.60931195536, 79492.20507794849, 80233.59883200546, 81938.59030704809, 7462.291082347168, 7593.924060759543, 7692.19231980634, 7716.729914453192, 7800.45396822217, 10378.481075946323, 11606.361650109326, 11745.17783755129, 12027.75944481123, 12151.513939442677, 8720.78198045054, 9931.801363972536, 10418.843717344398, 10564.683059508227, 10645.520951557353, 8531.317494600542, 9384.718458446258, 10214.642487512901, 10288.537015834156, 10425.270231083752, 10470.528826202686, 10619.469026548797, 10646.627368042391, 10832.837507437238, 10876.238663293494, 5257.763400646903, 6021.789237376671, 6080.118382834553, 6762.594244345355, 6927.965360173167, 6417.7753778617525, 10209.9489502552, 10353.223508236762, 10640.8935910643, 10680.83978740304], "value": 9087.145181483153, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.169625857649, 1514.545636309107, 1713.9801817918335, 1776.2184638758954, 1818.245428147319, 7382.852342953144, 7639.083310002808, 7899.368050555956, 8157.836843263139, 8651.134886511354, 7566.810829729259, 8139.715109971152, 8206.179382061791, 8447.831043379128, 8454.492730436163, 2289.553537060278, 2291.072115793098, 2332.6734257204007, 2333.4982978659586, 2336.6378211377264, 1927.6024881960536, 2347.2606129069372, 2349.882505874708, 2353.094058787362, 2354.411397150712, 2293.8508996915148, 2294.7819957104134, 2297.058206135485, 2317.351141680332, 2326.5463234669246, 2272.375096848372, 2290.1755368067484, 2301.4476525633895, 2313.768623137673, 2318.40880575453, 2284.3603790938564, 2343.414146463384, 2345.9296221113395, 2351.6472529120597, 2357.57563638545, 7845.333146682538, 8199.754007379728, 8591.44155629882, 8656.870146947738, 8673.262772664331, 5151.484851514856, 5304.283921670563, 5305.73471326436, 5311.62818602698, 5316.38861530925, 3239.96760032399, 3445.3970555153, 3477.3083721277653, 3580.3913334733156, 3587.192881601645, 982.302565178724, 1005.1506477026901, 1020.4132623741305, 1024.9897501025043, 1067.8878717734603, 11092.33445993243, 11100.61147859821, 11213.887861121366, 11226.0, 11256.831147532794, 4465.151621191963, 4600.0, 4689.577937985529, 4693.671442999025, 4776.713397196179, 9479.24166066713, 9570.99504552012, 9578.090081442251, 9598.616055357847, 9599.136077753035, 5273.683578985273, 5285.101532739367, 5285.471452854761, 5287.286216360742, 5294.311739473844, 4326.372675962031, 4337.783110844445, 4788.569028787426, 4834.371531700856, 4834.806607735722, 3755.7370984030854, 3829.1767270037076, 3845.538535375773, 3898.629630185128, 3915.0799562102884, 1271.0467149637773, 1284.678830292427, 1289.265118882228, 1292.3602816605653, 1293.3145432920567, 843.2326582809613, 859.1880672764303, 872.6989188729877, 891.6968230801558, 902.8420026495337, 5605.7197140142825, 5704.572157088152, 5757.568182386338, 5799.246098007232, 6000.519958403317, 903.3992395057345, 909.88171537701, 927.3786563002805, 934.9766255843658, 961.2790407194605, 3316.9668303316657, 3654.8903532894055, 3692.150805314739, 3798.4682144499793, 3912.383387602656, 9816.803663926681, 10309.432981185957, 10437.112845408225, 10439.269251152346, 10448.582056717647, 10087.293889427816, 10285.9485702571, 10412.68761937143, 10421.218408619385, 10432.217583681255, 6743.865122697519, 7904.288614024766, 8021.598920053974, 8057.798555036171, 8078.030636323561, 5488.56091512686, 5497.47773961473, 5551.333839939233, 5618.494335509824, 5624.775008999594, 9512.714618561455, 9532.952335238557, 9600.32797704182, 9717.708468745948, 9730.659426920023, 9227.723168304961, 9494.335396522454, 9595.23238140961, 9723.611055557698, 9741.220702343937, 5863.501602363676, 6168.568200225941, 6434.453071488976, 6727.889898166944, 7447.366973807287, 7940.960295198718, 8234.464759790595, 8240.50556966584, 8243.917560824315, 8260.463069900437, 7166.677499512431, 7193.172785129777, 7231.4937954342695, 7413.073365829271, 7530.0, 7283.963580182277, 7405.592692401754, 7464.365528929885, 7472.925270747223, 7621.275978782007, 6557.245916719447, 6731.124953756176, 6775.525713200029, 6802.761903333359, 6805.421539169028, 4366.476022877275, 4837.395325584302, 4906.288588154626, 5027.32131162303, 5040.143175660073, 4235.322348424237, 4346.217680817484, 4868.610511159028, 5022.4977502249485, 5159.922601161061], "value": 4447.491044272026, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.3546906645649, 301.65309893622344, 312.37213201465056, 315.4290733771875, 317.67438375664943, 4501.999999999995, 5395.81114660987, 5487.122060470326, 5534.861628459291, 5648.293963254593, 5308.513616187473, 5413.431589683076, 5510.862228444291, 5555.694336888913, 5593.244911936888, 4185.33034714445, 4198.244316023115, 4216.957830421703, 4232.280512312914, 4236.724612900167, 3499.562554680665, 3536.522569453117, 4204.516480604724, 4237.936430953539, 4264.573542645726, 3537.8761743338987, 4172.77049762263, 4191.538930717618, 4208.011117387411, 4209.747415155085, 3516.859325626972, 4187.267228235047, 4195.244855925932, 4207.410962465257, 4224.598663127013, 3348.313595712873, 3514.6836784689244, 3515.156362472988, 3521.506989021539, 3641.6176301488226, 5321.414644389114, 5359.651622644555, 5373.543248823855, 5395.217693434469, 5412.891742165135, 4716.198246298103, 4719.244920812654, 4723.960728639694, 4759.833405830814, 4785.521447855222, 4529.071540334224, 4920.8769780755765, 5034.748262586892, 5294.444083371229, 5779.248697669318, 3582.9104272393397, 3893.805309734502, 3907.882763517099, 3907.990847047786, 3941.3693808990424, 5676.914846277309, 6021.488173505258, 6036.788712395089, 6043.516518678452, 6059.848503787441, 3391.745619078579, 3421.5209870617637, 3773.8301126650827, 3941.2708648900043, 3947.210557888434, 3682.7846810552455, 3848.07646164924, 3989.5611482736876, 4006.979965100156, 4048.36856043574, 3801.847926082926, 4725.40932383452, 4730.408698912636, 4734.313524538991, 4744.857654270378, 5163.612729045337, 5465.180222966586, 5714.828555143353, 5898.911516327258, 5906.734196961147, 1106.8284415915546, 1138.703936976392, 1187.3054263256092, 1258.2953546014262, 1303.6154334471353, 725.9382952448996, 740.2412527159704, 886.499127992682, 887.8623813308948, 889.3641046651675, 4668.265387689902, 5019.67372120811, 5149.716765577854, 5234.685918844882, 5274.235235890775, 5312.282841816383, 5457.499187723329, 5562.276904002454, 5664.68844213564, 5775.451332123442, 4290.27841168416, 4772.3557319761385, 5012.423571289349, 5047.697138171721, 5274.835098940682, 2594.7804531869992, 2829.628224393276, 2925.9268518287213, 3002.489576771954, 3036.9848150759103, 5287.867803304948, 5571.860703482364, 5888.969430349672, 5971.4772732952815, 5992.700364981733, 4956.702597844141, 4975.154223781966, 5738.282714660668, 5847.596576821617, 5891.970540147272, 5925.644651181378, 5951.851203719942, 5959.404059594092, 6000.759969601254, 6013.6692481913915, 4914.533119353656, 5447.836564903059, 5561.555075593862, 5751.884962300815, 5767.509761670305, 4853.927191092207, 4973.52751488608, 5092.872678183075, 5289.841304760864, 5640.520555752807, 5323.387810401853, 5551.444855514416, 5724.484796368347, 5931.6441013539325, 5951.49412299942, 3163.6272795403447, 3560.3413368526903, 3768.4724138620068, 3778.471014057979, 3783.697304215711, 5075.467075956972, 5189.9360631071295, 5476.589255805751, 5696.202531645656, 5733.391306955242, 5644.717764111696, 5741.62679425836, 5815.8659061484295, 5845.824625261249, 5901.763929442946, 4566.977165114153, 4873.829415970424, 5844.100667283065, 5888.4700376966275, 5917.408259174048, 4830.855074347775, 5676.9432305678065, 5702.344230413555, 5725.828225153252, 5757.683327417032, 4968.0, 5838.970805145947, 5850.414958504286, 5859.150423188699, 5873.324567674772, 5552.222688823649, 5729.742161602821, 5733.655980641175, 5750.377262324709, 5751.281089863767], "value": 4109.684662932598, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.6784381137035, 648.1929997153542, 734.2840730287974, 763.6978950888736, 794.3953214506697, 14153.64615884602, 14336.928315358422, 14595.927020364896, 14802.555923322318, 14958.700825983473, 13314.600561983156, 13637.0, 13643.795343069836, 13770.655733606665, 13788.862111378883, 807.1807115777506, 821.6713314674118, 822.2229992656942, 822.9218224268685, 824.1387749801441, 651.654623049782, 672.9764458243963, 787.7164220880478, 811.922867327603, 816.2245866426889, 863.7754183912197, 868.0321441592641, 872.1801506583822, 872.5680788009912, 874.2961915657893, 856.8886044814196, 863.1541089732067, 864.0797550608581, 866.3415803988951, 868.6785889220997, 638.3074364314724, 639.8528338482175, 641.3426238105932, 642.7107801489302, 642.775028739942, 13718.314084295846, 13741.519046833413, 13784.793228101587, 13805.585832424942, 13811.654708632264, 13071.41178646963, 13305.866941330656, 13368.197908125447, 13412.195268283935, 13495.527656531975, 11517.654470365818, 11554.59558915434, 11611.303321800635, 11686.649400517997, 11900.0, 3595.7662751921043, 3642.653947874948, 3667.1932174921476, 3775.0562359410146, 3784.6404591563755, 89179.55410222973, 90333.0, 92133.53933230358, 92404.0, 94374.0, 10319.94840025795, 10331.01855323743, 10374.325668831501, 10439.94780026095, 10749.0, 20088.99555022243, 20272.797272027386, 20285.898570507346, 20311.4922126948, 20564.074616642287, 7616.504927179715, 7624.580648064299, 7653.961730191313, 7703.3067023968115, 7731.265529774662, 4234.830606775756, 4335.956640433618, 4363.236433624166, 4364.672649551297, 4377.496587892433, 3337.9499307510405, 3449.379111759857, 3459.481077838294, 3530.135116896377, 3748.475213470116, 680.9080774095532, 681.2540268406087, 683.9829004274933, 686.1834417043801, 698.2214830463996, 517.7961240116744, 522.3835873669107, 572.819561838028, 654.6105067484839, 660.1319265166387, 8517.148285171557, 8812.427192232613, 8920.598573064228, 9088.409253398508, 9145.954270228607, 606.8452544601098, 626.2109741725459, 628.7485005997637, 662.3934816497099, 667.5293917787948, 3394.9830250848586, 3651.634836516327, 4046.5346485153996, 4116.8711779048135, 4168.936921085104, 72700.63649681718, 104140.95859041464, 107476.0, 113812.0, 116798.0, 80081.59959200167, 92608.0739192613, 95663.0, 102719.0, 105212.42181367133, 54479.72760136253, 55220.447795522334, 58444.7077764617, 87884.0, 93035.53482232546, 9135.58889849945, 9359.344845860724, 9423.575939082895, 9430.905690943277, 9566.330356874952, 30350.84824575863, 42285.1542969145, 42975.9256018602, 54848.0, 58008.4199158003, 59571.0, 79400.20599793928, 82675.3464930686, 83581.16418835733, 86112.56943715242, 11560.421978900853, 11659.0, 11696.70758231051, 11708.180427370344, 11718.414079296172, 10586.8411973822, 12534.561290354794, 12755.0, 12774.0, 12806.807897881354, 12820.61538153857, 12972.416241268977, 13134.60596182116, 13308.134971226838, 13741.03812733099, 13067.477300907858, 13215.867841321848, 13334.26661533625, 13436.93281533586, 13676.111052781538, 12681.619551413472, 12954.870451295743, 12980.805287920877, 13004.479820807062, 13435.529756458476, 11009.339439633648, 11120.487763468751, 11168.0, 11203.775924481628, 11563.710907227387, 9655.613775448903, 9899.851502227328, 10232.846507302247, 11341.376224307412, 11405.942970285427], "value": 7154.73564248326, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1632.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 117.0, 128.0, 112.0, 118.0, 115.0, 118.0, 117.0, 119.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 111.0, 116.0, 149.0, 116.0, 113.0, 118.0, 116.0, 144.0, 115.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 209.0, 207.0, 236.0, 164.0, 211.0, 165.0, 212.0, 196.0, 251.0], "value": 204.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 52.0, 98.0, 76.0, 84.0, 84.0, 84.0, 66.0, 73.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 93.0, 91.0, 64.0, 83.0, 100.0, 82.0, 68.0, 91.0, 121.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 93.0, 129.0, 135.0, 86.0, 108.0, 110.0, 132.0, 88.0], "value": 106.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 100.0, 118.0, 172.0, 114.0, 101.0, 169.0, 98.0, 171.0], "value": 124.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 141.0, 122.0, 91.0, 83.0, 132.0, 143.0, 128.0, 87.0, 72.0], "value": 110.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 55.0, 57.0, 57.0, 57.0, 59.0, 81.0, 87.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 75.0, 82.0, 80.0, 81.0, 93.0, 70.0, 83.0, 108.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 100.0, 110.0, 86.0, 88.0, 87.0, 115.0, 88.0, 92.0, 91.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 173.0, 166.0, 159.0, 148.0, 162.0, 159.0, 154.0, 223.0, 197.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 239.0, 188.0, 180.0, 173.0, 173.0, 183.0, 199.0, 224.0, 238.0], "value": 201.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 68.0, 73.0, 67.0, 91.0, 71.0, 70.0, 95.0], "value": 74.1, "unit": "score"}]}]} 07:31:35 INFO - Return code: 0 07:31:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:31:35 ERROR - # TBPL SUCCESS # 07:31:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:31:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:31:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:31:35 INFO - 2016-11-07 07:31:35.862 screenresolution[1678:11546] starting screenresolution argv=screenresolution get 07:31:35 INFO - 2016-11-07 07:31:35.864 screenresolution[1678:11546] Display 0: 1600x1200x32@60 07:31:35 INFO - 2016-11-07 07:31:35.869 screenresolution[1679:11549] starting screenresolution argv=screenresolution list 07:31:35 INFO - Available Modes on Display 0 07:31:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:31:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:31:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:31:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:31:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:31:36 INFO - Intel Iris: 07:31:36 INFO - Chipset Model: Intel Iris 07:31:36 INFO - Type: GPU 07:31:36 INFO - Bus: Built-In 07:31:36 INFO - VRAM (Dynamic, Max): 1536 MB 07:31:36 INFO - Vendor: Intel (0x8086) 07:31:36 INFO - Device ID: 0x0a2e 07:31:36 INFO - Revision ID: 0x0009 07:31:36 INFO - Displays: 07:31:36 INFO - Display: 07:31:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:31:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:31:36 INFO - Display Serial Number: 1600x1200 60 07:31:36 INFO - Main Display: Yes 07:31:36 INFO - Mirror: Off 07:31:36 INFO - Online: Yes 07:31:36 INFO - Rotation: Supported 07:31:36 INFO - Return code: 0 07:31:36 INFO - Running post-action listener: _resource_record_post_action 07:31:36 INFO - [mozharness: 2016-11-07 15:31:36.114199Z] Finished run-tests step (success) 07:31:36 INFO - Running post-run listener: _resource_record_post_run 07:31:36 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 32554496; Write bytes: 710907904; Read time: 298; Write time: 1848 07:31:36 INFO - TinderboxPrint: CPU usage
54.7% 07:31:36 INFO - TinderboxPrint: I/O read bytes / time
32,554,496 / 298 07:31:36 INFO - TinderboxPrint: I/O write bytes / time
710,907,904 / 1,848 07:31:36 INFO - TinderboxPrint: CPU idle
2,197.2 (45.3%) 07:31:36 INFO - TinderboxPrint: CPU system
978.8 (20.2%) 07:31:36 INFO - TinderboxPrint: CPU user
1,673.8 (34.5%) 07:31:36 INFO - TinderboxPrint: Swap in / out
434,495,488 / 0 07:31:36 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392561152; Write bytes: 389058560; Read time: 29400; Write time: 690 07:31:36 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 30760448; Write bytes: 301697024; Read time: 280; Write time: 1117 07:31:36 INFO - Running post-run listener: _upload_blobber_files 07:31:36 INFO - Blob upload gear active. 07:31:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:31:36 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 07:31:36 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:31:36 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:31:37 INFO - (blobuploader) - INFO - Open directory for files ... 07:31:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:31:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:31:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:38 INFO - (blobuploader) - INFO - Done attempting. 07:31:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:31:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:31:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:39 INFO - (blobuploader) - INFO - Done attempting. 07:31:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:31:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:41 INFO - (blobuploader) - INFO - Done attempting. 07:31:41 INFO - (blobuploader) - INFO - Iteration through files over. 07:31:41 INFO - Return code: 0 07:31:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:31:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:31:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/58f965d36c57ae2e4511bbb6d841e87e7ba996dc15bd0745932457110833499a86b1e0e56f0353d0a295d68e76c73ec64c3aaa0dc48570c1e818881ed34f3bc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d7e660eb597507507db297e2e8784e45fbffc846e7cf347d8dd167467e5c1d17db1ad9f4032f5087403dc390f162d33bc7799bc5a22ae16f24c10bd5bffec23b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c4290fb509e1606435241cadaf06d5d511c5c61331c1d71e8b791152fd8864176f3acd43951420bd12f2819c484def8c3e13c12c6d27e8605e8b47751a476ebb"} 07:31:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:31:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:31:41 INFO - Contents: 07:31:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/58f965d36c57ae2e4511bbb6d841e87e7ba996dc15bd0745932457110833499a86b1e0e56f0353d0a295d68e76c73ec64c3aaa0dc48570c1e818881ed34f3bc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d7e660eb597507507db297e2e8784e45fbffc846e7cf347d8dd167467e5c1d17db1ad9f4032f5087403dc390f162d33bc7799bc5a22ae16f24c10bd5bffec23b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c4290fb509e1606435241cadaf06d5d511c5c61331c1d71e8b791152fd8864176f3acd43951420bd12f2819c484def8c3e13c12c6d27e8605e8b47751a476ebb"} 07:31:41 INFO - Running post-run listener: copy_logs_to_upload_dir 07:31:41 INFO - Copying logs to upload dir... 07:31:41 INFO - mkdir: /builds/slave/test/build/upload/logs 07:31:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.054885 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-07 07:31:41.343402) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-07 07:31:41.348453) ========= 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.Q6bMWJursn/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.V6O8asWSfO/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/58f965d36c57ae2e4511bbb6d841e87e7ba996dc15bd0745932457110833499a86b1e0e56f0353d0a295d68e76c73ec64c3aaa0dc48570c1e818881ed34f3bc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d7e660eb597507507db297e2e8784e45fbffc846e7cf347d8dd167467e5c1d17db1ad9f4032f5087403dc390f162d33bc7799bc5a22ae16f24c10bd5bffec23b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c4290fb509e1606435241cadaf06d5d511c5c61331c1d71e8b791152fd8864176f3acd43951420bd12f2819c484def8c3e13c12c6d27e8605e8b47751a476ebb"} build_url:https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010983 build_url: 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/58f965d36c57ae2e4511bbb6d841e87e7ba996dc15bd0745932457110833499a86b1e0e56f0353d0a295d68e76c73ec64c3aaa0dc48570c1e818881ed34f3bc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d7e660eb597507507db297e2e8784e45fbffc846e7cf347d8dd167467e5c1d17db1ad9f4032f5087403dc390f162d33bc7799bc5a22ae16f24c10bd5bffec23b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c4290fb509e1606435241cadaf06d5d511c5c61331c1d71e8b791152fd8864176f3acd43951420bd12f2819c484def8c3e13c12c6d27e8605e8b47751a476ebb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-07 07:31:41.383265) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:31:41.383598) ========= 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.Q6bMWJursn/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.V6O8asWSfO/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.005147 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:31:41.431408) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-07 07:31:41.431714) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-07 07:31:41.435017) ========= ========= Total master_lag: 0.23 =========