builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0238 starttime: 1483607829.67 results: success (0) buildid: 20170104235539 builduid: 7acb914dcc3b458f836721abc0bdf45e revision: 75a0d7ccab1c43dc878ef1082117a30161f52d91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.674432) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.674862) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.675134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.701072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.701419) ========= 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.KPpataFYDX/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.qzVtnpMpVD/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-05 01:17:09-- 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% 138M=0s 2017-01-05 01:17:09 (138 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109254 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.834430) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.837761) ========= 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.KPpataFYDX/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.qzVtnpMpVD/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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.025299 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.886355) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:09.889715) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 75a0d7ccab1c43dc878ef1082117a30161f52d91 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 75a0d7ccab1c43dc878ef1082117a30161f52d91 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KPpataFYDX/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.qzVtnpMpVD/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-05 01:17:09,987 truncating revision to first 12 chars 2017-01-05 01:17:09,987 Setting DEBUG logging. 2017-01-05 01:17:09,988 attempt 1/10 2017-01-05 01:17:09,988 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/75a0d7ccab1c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 01:17:10,472 unpacking tar archive at: mozilla-aurora-75a0d7ccab1c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.768106 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:10.681112) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:10.681471) ========= script_repo_revision: 75a0d7ccab1c43dc878ef1082117a30161f52d91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:10.684900) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:10.685167) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 01:17:10.704771) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-05 01:17:10.705103) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KPpataFYDX/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.qzVtnpMpVD/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:17:10 INFO - MultiFileLogger online at 20170105 01:17:10 in /builds/slave/test 01:17:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:17:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:17:10 INFO - {'append_to_log': False, 01:17:10 INFO - 'base_work_dir': '/builds/slave/test', 01:17:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:17:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:17:10 INFO - 'branch': 'Mozilla-Aurora', 01:17:10 INFO - 'buildbot_json_path': 'buildprops.json', 01:17:10 INFO - 'config_files': ('talos/mac_config.py',), 01:17:10 INFO - 'default_actions': ('clobber', 01:17:10 INFO - 'read-buildbot-config', 01:17:10 INFO - 'download-and-extract', 01:17:10 INFO - 'populate-webroot', 01:17:10 INFO - 'create-virtualenv', 01:17:10 INFO - 'install', 01:17:10 INFO - 'run-tests'), 01:17:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:17:10 INFO - 'download_minidump_stackwalk': True, 01:17:10 INFO - 'download_symbols': 'ondemand', 01:17:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:17:10 INFO - 'tooltool.py': '/tools/tooltool.py', 01:17:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:17:10 INFO - '/tools/misc-python/virtualenv.py')}, 01:17:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:17:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:17:10 INFO - 'installer_path': 'installer.exe', 01:17:10 INFO - 'log_level': 'info', 01:17:10 INFO - 'log_name': 'talos', 01:17:10 INFO - 'log_to_console': True, 01:17:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:17:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:17:10 INFO - 'opt_config_files': (), 01:17:10 INFO - 'pip_index': False, 01:17:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:17:10 INFO - 'cmd': ('bash', 01:17:10 INFO - '-c', 01:17:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:17:10 INFO - 'enabled': True, 01:17:10 INFO - 'halt_on_failure': False, 01:17:10 INFO - 'name': 'check_screen_resolution'},), 01:17:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:17:10 INFO - 'cmd': ('bash', 01:17:10 INFO - '-c', 01:17:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:17:10 INFO - 'enabled': True, 01:17:10 INFO - 'halt_on_failure': False, 01:17:10 INFO - 'name': 'check_screen_resolution'},), 01:17:10 INFO - 'run_cmd_checks_enabled': True, 01:17:10 INFO - 'sps_profile': False, 01:17:10 INFO - 'sps_profile_interval': 0, 01:17:10 INFO - 'suite': 'dromaeojs-e10s', 01:17:10 INFO - 'system_bits': '32', 01:17:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:17:10 INFO - 'title': 't-yosemite-r7-0238', 01:17:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:17:10 INFO - 'use_talos_json': True, 01:17:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:17:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:17:10 INFO - 'work_dir': 'build'} 01:17:10 INFO - [mozharness: 2017-01-05 09:17:10.905491Z] Running clobber step. 01:17:10 INFO - Running pre-action listener: _resource_record_pre_action 01:17:10 INFO - Running main action method: clobber 01:17:10 INFO - rmtree: /builds/slave/test/build 01:17:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:17:13 INFO - Running post-action listener: _resource_record_post_action 01:17:13 INFO - [mozharness: 2017-01-05 09:17:13.165746Z] Finished clobber step (success) 01:17:13 INFO - [mozharness: 2017-01-05 09:17:13.165867Z] Running read-buildbot-config step. 01:17:13 INFO - Running pre-action listener: _resource_record_pre_action 01:17:13 INFO - Running main action method: read_buildbot_config 01:17:13 INFO - Using buildbot properties: 01:17:13 INFO - { 01:17:13 INFO - "project": "", 01:17:13 INFO - "product": "firefox", 01:17:13 INFO - "script_repo_revision": "production", 01:17:13 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 01:17:13 INFO - "repository": "", 01:17:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 01:17:13 INFO - "buildid": "20170104235539", 01:17:13 INFO - "pgo_build": "False", 01:17:13 INFO - "basedir": "/builds/slave/test", 01:17:13 INFO - "buildnumber": 23, 01:17:13 INFO - "slavename": "t-yosemite-r7-0238", 01:17:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 01:17:13 INFO - "platform": "yosemite_r7", 01:17:13 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:17:13 INFO - "branch": "mozilla-aurora", 01:17:13 INFO - "repo_path": "releases/mozilla-aurora", 01:17:13 INFO - "revision": "75a0d7ccab1c43dc878ef1082117a30161f52d91", 01:17:13 INFO - "stage_platform": "macosx64", 01:17:13 INFO - "builduid": "7acb914dcc3b458f836721abc0bdf45e", 01:17:13 INFO - "slavebuilddir": "test" 01:17:13 INFO - } 01:17:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 01:17:13 INFO - Running post-action listener: _resource_record_post_action 01:17:13 INFO - [mozharness: 2017-01-05 09:17:13.169229Z] Finished read-buildbot-config step (success) 01:17:13 INFO - [mozharness: 2017-01-05 09:17:13.169312Z] Running download-and-extract step. 01:17:13 INFO - Running pre-action listener: _resource_record_pre_action 01:17:13 INFO - Running main action method: download_and_extract 01:17:13 INFO - mkdir: /builds/slave/test/build/tests 01:17: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')]} 01:17:13 INFO - trying https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 01:17:13 INFO - Downloading https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 01:17:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 01:17:14 INFO - Downloaded 1199 bytes. 01:17:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 01:17:14 INFO - Using the following test package requirements: 01:17:14 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 01:17:14 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 01:17:14 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 01:17:14 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 01:17:14 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 01:17:14 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 01:17:14 INFO - u'jsshell-mac.zip'], 01:17:14 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 01:17:14 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 01:17:14 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 01:17:14 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 01:17:14 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 01:17:14 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 01:17:14 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 01:17:14 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 01:17:14 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 01:17:14 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 01:17:14 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 01:17:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:17:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 01:17:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:17:14 INFO - Fetch https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 01:17:15 INFO - Http code: 200 01:17:15 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 01:17:15 INFO - x-amz-cf-id: f-f9E2qbZiORk3mAXNpTLA1x8AfNCSphzFQuQ3Iwvl8adpT_dvNjxQ== 01:17:15 INFO - x-amz-version-id: mbxgY7dtI4JmMMVAhFTdOocSzkyVMKjz 01:17:15 INFO - Expected file size: 16270020 01:17:15 INFO - Obtained file size: 16270020 01:17:21 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:17:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 01:17:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:17:21 INFO - Fetch https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 01:17:22 INFO - Http code: 200 01:17:22 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 01:17:22 INFO - x-amz-cf-id: bnNuZDnDB0j8FSokHGKJQDF0Tdv0AVV3VX8v20aaAJvJcdb17eyfAw== 01:17:22 INFO - x-amz-version-id: mbxgY7dtI4JmMMVAhFTdOocSzkyVMKjz 01:17:22 INFO - Expected file size: 16270020 01:17:22 INFO - Obtained file size: 16270020 01:17:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 01:17:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:17:29 INFO - Fetch https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 01:17:29 INFO - Http code: 200 01:17:29 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 01:17:29 INFO - x-amz-cf-id: shFuzGbDlOwXWXfNCbHwawNe5_0aISCbQK_e2yMUSebh4pWOUDzbVw== 01:17:29 INFO - x-amz-version-id: SKwdrenB8ASnw3MMPjQViEa6abYW3.cG 01:17:30 INFO - Expected file size: 14409038 01:17:30 INFO - Obtained file size: 14409038 01:17: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')]} 01:17:30 INFO - trying https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 01:17:30 INFO - Downloading https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 01:17:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 01:17:33 INFO - Downloaded 109838044 bytes. 01:17:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 01:17:33 INFO - mkdir: /builds/slave/test/properties 01:17:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:17:33 INFO - Writing to file /builds/slave/test/properties/build_url 01:17:33 INFO - Contents: 01:17:33 INFO - build_url:https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 01:17:34 INFO - Running post-action listener: _resource_record_post_action 01:17:34 INFO - Running post-action listener: set_extra_try_arguments 01:17:34 INFO - [mozharness: 2017-01-05 09:17:34.190733Z] Finished download-and-extract step (success) 01:17:34 INFO - [mozharness: 2017-01-05 09:17:34.190855Z] Running populate-webroot step. 01:17:34 INFO - Running pre-action listener: _resource_record_pre_action 01:17:34 INFO - Running main action method: populate_webroot 01:17:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:17:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:17:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:17:34 INFO - '--tppagecycles', 01:17:34 INFO - '7'], 01:17:34 INFO - 'tests': ['tsvgm']}}, 01:17:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:17:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:17:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:17:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:17:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:34 INFO - 'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:17:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:17:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:34 INFO - 'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['damp', 'tps']}, 01:17:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:34 INFO - 'tests': ['damp', 'tps']}, 01:17:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['dromaeo_dom']}, 01:17:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:17:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['basic_compositor_video']}, 01:17:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:17:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['a11yr', 01:17:34 INFO - 'ts_paint', 01:17:34 INFO - 'tpaint', 01:17:34 INFO - 'sessionrestore', 01:17:34 INFO - 'sessionrestore_no_auto_restore', 01:17:34 INFO - 'tabpaint']}, 01:17:34 INFO - 'other-e10s': {'tests': ['a11yr', 01:17:34 INFO - 'ts_paint', 01:17:34 INFO - 'tpaint', 01:17:34 INFO - 'sessionrestore', 01:17:34 INFO - 'sessionrestore_no_auto_restore', 01:17:34 INFO - 'tabpaint']}, 01:17:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:17:34 INFO - 'ts_paint', 01:17:34 INFO - 'tpaint', 01:17:34 INFO - 'sessionrestore', 01:17:34 INFO - 'sessionrestore_no_auto_restore', 01:17:34 INFO - 'tabpaint']}, 01:17:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:17:34 INFO - 'ts_paint', 01:17:34 INFO - 'tpaint', 01:17:34 INFO - 'sessionrestore', 01:17:34 INFO - 'sessionrestore_no_auto_restore', 01:17:34 INFO - 'tabpaint']}, 01:17:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['a11yr', 01:17:34 INFO - 'ts_paint', 01:17:34 INFO - 'tpaint', 01:17:34 INFO - 'sessionrestore', 01:17:34 INFO - 'sessionrestore_no_auto_restore', 01:17:34 INFO - 'tabpaint']}, 01:17:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['a11yr', 01:17:34 INFO - 'ts_paint', 01:17:34 INFO - 'tpaint', 01:17:34 INFO - 'sessionrestore', 01:17:34 INFO - 'sessionrestore_no_auto_restore', 01:17:34 INFO - 'tabpaint']}, 01:17:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['tsvgx', 01:17:34 INFO - 'tsvgr_opacity', 01:17:34 INFO - 'tart', 01:17:34 INFO - 'tscrollx', 01:17:34 INFO - 'cart']}, 01:17:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:17:34 INFO - 'tsvgr_opacity', 01:17:34 INFO - 'tart', 01:17:34 INFO - 'tscrollx', 01:17:34 INFO - 'cart']}, 01:17:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:34 INFO - 'talos_options': ['--disable-e10s'], 01:17:34 INFO - 'tests': ['tp5o']}, 01:17:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:34 INFO - 'tests': ['tp5o']}, 01:17:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:34 INFO - 'talos_options': ['--disable-e10s', 01:17:34 INFO - '--xperf_path', 01:17:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:17:34 INFO - 'tests': ['tp5n']}, 01:17:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:34 INFO - 'talos_options': ['--xperf_path', 01:17:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:17:34 INFO - 'tests': ['tp5n']}}, 01:17:34 INFO - 'talos.zip': {'path': '', 01:17:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:17:34 INFO - Running post-action listener: _resource_record_post_action 01:17:34 INFO - [mozharness: 2017-01-05 09:17:34.209844Z] Finished populate-webroot step (success) 01:17:34 INFO - [mozharness: 2017-01-05 09:17:34.209956Z] Running create-virtualenv step. 01:17:34 INFO - Running pre-action listener: _resource_record_pre_action 01:17:34 INFO - Running main action method: create_virtualenv 01:17:34 INFO - Creating virtualenv /builds/slave/test/build/venv 01:17:34 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 01:17:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:17:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:17:34 INFO - Using real prefix '/tools/python27' 01:17:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:17:35 INFO - Installing distribute.............................................................................................................................................................................................done. 01:17:37 INFO - Installing pip.................done. 01:17:37 INFO - Return code: 0 01:17:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:17:38 INFO - Reading from file tmpfile_stdout 01:17:38 INFO - Output received: 01:17:38 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) 01:17:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:38 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:17:38 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 01:17:38 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 01:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:38 INFO - 'HOME': '/Users/cltbld', 01:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:38 INFO - 'LOGNAME': 'cltbld', 01:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:38 INFO - 'MOZ_NO_REMOTE': '1', 01:17:38 INFO - 'NO_EM_RESTART': '1', 01:17:38 INFO - 'PAGER': '/bin/cat', 01:17:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:38 INFO - 'PWD': '/builds/slave/test', 01:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:38 INFO - 'SHELL': '/bin/bash', 01:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:17:38 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:38 INFO - 'USER': 'cltbld', 01:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:38 INFO - 'XPC_FLAGS': '0x0', 01:17:38 INFO - 'XPC_SERVICE_NAME': '0', 01:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:38 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 01:17:38 INFO - Cleaning up... 01:17:38 INFO - Return code: 0 01:17:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:38 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:17:38 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 01:17:38 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 01:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:38 INFO - 'HOME': '/Users/cltbld', 01:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:38 INFO - 'LOGNAME': 'cltbld', 01:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:38 INFO - 'MOZ_NO_REMOTE': '1', 01:17:38 INFO - 'NO_EM_RESTART': '1', 01:17:38 INFO - 'PAGER': '/bin/cat', 01:17:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:38 INFO - 'PWD': '/builds/slave/test', 01:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:38 INFO - 'SHELL': '/bin/bash', 01:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:17:38 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:38 INFO - 'USER': 'cltbld', 01:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:38 INFO - 'XPC_FLAGS': '0x0', 01:17:38 INFO - 'XPC_SERVICE_NAME': '0', 01:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:38 INFO - Downloading/unpacking psutil>=3.1.1 01:17:38 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 01:17:38 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 01:17:38 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 01:17:38 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 01:17:38 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 01:17:38 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 01:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:17:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:17:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:17:42 INFO - Installing collected packages: psutil 01:17:42 INFO - Running setup.py install for psutil 01:17:42 INFO - building 'psutil._psutil_osx' extension 01:17:42 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 01:17:42 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 01:17:42 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 01:17:42 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 01:17:43 INFO - building 'psutil._psutil_posix' extension 01:17:43 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 01:17:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:17:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:17:43 INFO - ^ 01:17:43 INFO - 1 warning generated. 01:17:43 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 01:17:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:17:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:17:43 INFO - Successfully installed psutil 01:17:43 INFO - Cleaning up... 01:17:43 INFO - Return code: 0 01:17:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:17:43 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')]} 01:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:43 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')]} 01:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:43 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:17:43 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 01:17:43 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 01:17:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:17:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:43 INFO - 'HOME': '/Users/cltbld', 01:17:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:43 INFO - 'LOGNAME': 'cltbld', 01:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:43 INFO - 'MOZ_NO_REMOTE': '1', 01:17:43 INFO - 'NO_EM_RESTART': '1', 01:17:43 INFO - 'PAGER': '/bin/cat', 01:17:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:43 INFO - 'PWD': '/builds/slave/test', 01:17:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:43 INFO - 'SHELL': '/bin/bash', 01:17:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:17:43 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:17:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:43 INFO - 'USER': 'cltbld', 01:17:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:43 INFO - 'XPC_FLAGS': '0x0', 01:17:43 INFO - 'XPC_SERVICE_NAME': '0', 01:17:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:17:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:17:46 INFO - Installing collected packages: mozsystemmonitor 01:17:46 INFO - Running setup.py install for mozsystemmonitor 01:17:46 INFO - Successfully installed mozsystemmonitor 01:17:46 INFO - Cleaning up... 01:17:46 INFO - Return code: 0 01:17:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:17:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:17:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:46 INFO - 'HOME': '/Users/cltbld', 01:17:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:46 INFO - 'LOGNAME': 'cltbld', 01:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:46 INFO - 'MOZ_NO_REMOTE': '1', 01:17:46 INFO - 'NO_EM_RESTART': '1', 01:17:46 INFO - 'PAGER': '/bin/cat', 01:17:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:46 INFO - 'PWD': '/builds/slave/test', 01:17:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:46 INFO - 'SHELL': '/bin/bash', 01:17:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:17:46 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:17:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:46 INFO - 'USER': 'cltbld', 01:17:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:46 INFO - 'XPC_FLAGS': '0x0', 01:17:46 INFO - 'XPC_SERVICE_NAME': '0', 01:17:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:46 INFO - Downloading/unpacking jsonschema==2.5.1 01:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:17:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:49 INFO - Downloading functools32-3.2.3-2.tar.gz 01:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:17:49 INFO - warning: no files found matching '*.txt' 01:17:49 INFO - no previously-included directories found matching 'build' 01:17:49 INFO - no previously-included directories found matching 'dist' 01:17:49 INFO - no previously-included directories found matching '.git*' 01:17:49 INFO - Installing collected packages: jsonschema, functools32 01:17:49 INFO - Running setup.py install for functools32 01:17:50 INFO - warning: no files found matching '*.txt' 01:17:50 INFO - no previously-included directories found matching 'build' 01:17:50 INFO - no previously-included directories found matching 'dist' 01:17:50 INFO - no previously-included directories found matching '.git*' 01:17:50 INFO - Successfully installed jsonschema functools32 01:17:50 INFO - Cleaning up... 01:17:50 INFO - Return code: 0 01:17:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:17:50 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')]} 01:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:50 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')]} 01:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:50 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:17:50 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 01:17:50 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 01:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:50 INFO - 'HOME': '/Users/cltbld', 01:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:50 INFO - 'LOGNAME': 'cltbld', 01:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:50 INFO - 'MOZ_NO_REMOTE': '1', 01:17:50 INFO - 'NO_EM_RESTART': '1', 01:17:50 INFO - 'PAGER': '/bin/cat', 01:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:50 INFO - 'PWD': '/builds/slave/test', 01:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:50 INFO - 'SHELL': '/bin/bash', 01:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:17:50 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:50 INFO - 'USER': 'cltbld', 01:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:50 INFO - 'XPC_FLAGS': '0x0', 01:17:50 INFO - 'XPC_SERVICE_NAME': '0', 01:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:17:50 INFO - Cleaning up... 01:17:50 INFO - Return code: 0 01:17:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:17:50 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')]} 01:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:50 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')]} 01:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:50 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:17:50 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 01:17:50 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 01:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:50 INFO - 'HOME': '/Users/cltbld', 01:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:50 INFO - 'LOGNAME': 'cltbld', 01:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:50 INFO - 'MOZ_NO_REMOTE': '1', 01:17:50 INFO - 'NO_EM_RESTART': '1', 01:17:50 INFO - 'PAGER': '/bin/cat', 01:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:50 INFO - 'PWD': '/builds/slave/test', 01:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:50 INFO - 'SHELL': '/bin/bash', 01:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:17:50 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:50 INFO - 'USER': 'cltbld', 01:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:50 INFO - 'XPC_FLAGS': '0x0', 01:17:50 INFO - 'XPC_SERVICE_NAME': '0', 01:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:50 INFO - Downloading/unpacking blobuploader==1.2.4 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:53 INFO - Downloading blobuploader-1.2.4.tar.gz 01:17:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:17:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:17:53 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 01:17:53 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 01:17:53 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 01:17:53 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 01:17:53 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 01:17:53 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 01:17:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:17:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:17:54 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 01:17:54 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 01:17:54 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 01:17:54 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 01:17:54 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 01:17:54 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 01:17:54 INFO - Downloading docopt-0.6.1.tar.gz 01:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:17:54 INFO - Installing collected packages: blobuploader, requests, docopt 01:17:54 INFO - Running setup.py install for blobuploader 01:17:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:17:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:17:54 INFO - Running setup.py install for requests 01:17:55 INFO - Running setup.py install for docopt 01:17:55 INFO - Successfully installed blobuploader requests docopt 01:17:55 INFO - Cleaning up... 01:17:55 INFO - Return code: 0 01:17:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:17: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')]} 01:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17: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')]} 01:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:55 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:17:55 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 01:17:55 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 01:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:55 INFO - 'HOME': '/Users/cltbld', 01:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:55 INFO - 'LOGNAME': 'cltbld', 01:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:55 INFO - 'MOZ_NO_REMOTE': '1', 01:17:55 INFO - 'NO_EM_RESTART': '1', 01:17:55 INFO - 'PAGER': '/bin/cat', 01:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:55 INFO - 'PWD': '/builds/slave/test', 01:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:55 INFO - 'SHELL': '/bin/bash', 01:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:17:55 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:55 INFO - 'USER': 'cltbld', 01:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:55 INFO - 'XPC_FLAGS': '0x0', 01:17:55 INFO - 'XPC_SERVICE_NAME': '0', 01:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:17:55 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-pmmxCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:17:55 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-FYmvkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-F6gLVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-n4EX_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-4bu7z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-OMVC5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-OGUwPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-Yda16h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-g8VFn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-35gKLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:17:56 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-mbmfVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:17:57 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-lAQS3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:17:57 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-8u1A_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:17:57 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-nod9oG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:57 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-gkLxF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:17:57 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-jCBNAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:17:57 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-kEbbb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:17:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:17:57 INFO - Running setup.py install for manifestparser 01:17:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:17:57 INFO - Running setup.py install for mozcrash 01:17:57 INFO - Running setup.py install for mozdebug 01:17:58 INFO - Running setup.py install for mozdevice 01:17:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:17:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:17:58 INFO - Running setup.py install for mozfile 01:17:58 INFO - Running setup.py install for mozhttpd 01:17:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:17:58 INFO - Running setup.py install for mozinfo 01:17:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:17:58 INFO - Running setup.py install for mozInstall 01:17:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:17:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:17:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:17:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:17:58 INFO - Running setup.py install for mozleak 01:17:58 INFO - Running setup.py install for mozlog 01:17:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:17:59 INFO - Running setup.py install for moznetwork 01:17:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:17:59 INFO - Running setup.py install for mozprocess 01:17:59 INFO - Running setup.py install for mozprofile 01:17:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:17:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:17:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:17:59 INFO - Running setup.py install for mozrunner 01:17:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:17:59 INFO - Running setup.py install for mozscreenshot 01:17:59 INFO - Running setup.py install for moztest 01:18:00 INFO - Running setup.py install for mozversion 01:18:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:18:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:18:00 INFO - Cleaning up... 01:18:00 INFO - Return code: 0 01:18:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:18:00 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')]} 01:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:18:00 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')]} 01:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:18:00 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:18:00 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 01:18:00 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 01:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:18:00 INFO - 'HOME': '/Users/cltbld', 01:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:18:00 INFO - 'LOGNAME': 'cltbld', 01:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:18:00 INFO - 'MOZ_NO_REMOTE': '1', 01:18:00 INFO - 'NO_EM_RESTART': '1', 01:18:00 INFO - 'PAGER': '/bin/cat', 01:18:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:18:00 INFO - 'PWD': '/builds/slave/test', 01:18:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:18:00 INFO - 'SHELL': '/bin/bash', 01:18:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:18:00 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:18:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:18:00 INFO - 'USER': 'cltbld', 01:18:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:18:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:18:00 INFO - 'XPC_FLAGS': '0x0', 01:18:00 INFO - 'XPC_SERVICE_NAME': '0', 01:18:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:18:00 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-itjEQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:18:00 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)) 01:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:18:00 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-jsk6QO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:18:00 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-tF_3Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:18:00 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)) 01:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:18:00 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-i5pgWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:18:00 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)) 01:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:18:00 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-BIB2Oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:18:00 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)) 01:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-nB4Hr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:18:01 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)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-h3FGM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:18:01 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)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-OWTtDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:18:01 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)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-E_W56h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:18:01 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)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-cYnnJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:18:01 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)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-tlEney-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:18:01 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)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-RfEPDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:18:01 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)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-crMueL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:18:01 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)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-zmYnUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:18:01 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-m9Ccu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:18:02 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)) 01:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:18:02 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-8Om49z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:18:02 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-Grv9XN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:18:02 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)) 01:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:18:02 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)) 01:18:02 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)) 01:18:02 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)) 01:18:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:18:02 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 01:18:02 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 01:18:02 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 01:18:02 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 01:18:02 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 01:18:02 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 01:18:04 INFO - Downloading blessings-1.6.tar.gz 01:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:18:04 INFO - Installing collected packages: blessings 01:18:04 INFO - Running setup.py install for blessings 01:18:05 INFO - Successfully installed blessings 01:18:05 INFO - Cleaning up... 01:18:05 INFO - Return code: 0 01:18:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:18:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:18:05 INFO - Reading from file tmpfile_stdout 01:18:05 INFO - Current package versions: 01:18:05 INFO - blessings == 1.6 01:18:05 INFO - blobuploader == 1.2.4 01:18:05 INFO - docopt == 0.6.1 01:18:05 INFO - functools32 == 3.2.3-2 01:18:05 INFO - jsonschema == 2.5.1 01:18:05 INFO - manifestparser == 1.1 01:18:05 INFO - mozInstall == 1.12 01:18:05 INFO - mozcrash == 1.0 01:18:05 INFO - mozdebug == 0.1 01:18:05 INFO - mozdevice == 0.48 01:18:05 INFO - mozfile == 1.2 01:18:05 INFO - mozhttpd == 0.7 01:18:05 INFO - mozinfo == 0.9 01:18:05 INFO - mozleak == 0.1 01:18:05 INFO - mozlog == 3.4 01:18:05 INFO - moznetwork == 0.27 01:18:05 INFO - mozprocess == 0.23 01:18:05 INFO - mozprofile == 0.28 01:18:05 INFO - mozrunner == 6.13 01:18:05 INFO - mozscreenshot == 0.1 01:18:05 INFO - mozsystemmonitor == 0.3 01:18:05 INFO - moztest == 0.8 01:18:05 INFO - mozversion == 1.4 01:18:05 INFO - psutil == 3.1.1 01:18:05 INFO - requests == 1.2.3 01:18:05 INFO - wsgiref == 0.1.2 01:18:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:18:06 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:18:06 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 01:18:06 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 01:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:18:06 INFO - 'HOME': '/Users/cltbld', 01:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:18:06 INFO - 'LOGNAME': 'cltbld', 01:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:18:06 INFO - 'MOZ_NO_REMOTE': '1', 01:18:06 INFO - 'NO_EM_RESTART': '1', 01:18:06 INFO - 'PAGER': '/bin/cat', 01:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:18:06 INFO - 'PWD': '/builds/slave/test', 01:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:18:06 INFO - 'SHELL': '/bin/bash', 01:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:18:06 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:18:06 INFO - 'USER': 'cltbld', 01:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:18:06 INFO - 'XPC_FLAGS': '0x0', 01:18:06 INFO - 'XPC_SERVICE_NAME': '0', 01:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 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)) 01:18:06 INFO - Cleaning up... 01:18:06 INFO - Return code: 0 01:18:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:18:06 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 0x103f82490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ef8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713c775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, '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.KPpataFYDX/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.qzVtnpMpVD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 01:18:06 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 01:18:06 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 01:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:18:06 INFO - 'HOME': '/Users/cltbld', 01:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:18:06 INFO - 'LOGNAME': 'cltbld', 01:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:18:06 INFO - 'MOZ_NO_REMOTE': '1', 01:18:06 INFO - 'NO_EM_RESTART': '1', 01:18:06 INFO - 'PAGER': '/bin/cat', 01:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:18:06 INFO - 'PWD': '/builds/slave/test', 01:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:18:06 INFO - 'SHELL': '/bin/bash', 01:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:18:06 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:18:06 INFO - 'USER': 'cltbld', 01:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:18:06 INFO - 'XPC_FLAGS': '0x0', 01:18:06 INFO - 'XPC_SERVICE_NAME': '0', 01:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:18:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:18:07 INFO - Cleaning up... 01:18:07 INFO - Return code: 0 01:18:07 INFO - Running post-action listener: _resource_record_post_action 01:18:07 INFO - Running post-action listener: _start_resource_monitoring 01:18:07 INFO - Starting resource monitoring. 01:18:07 INFO - [mozharness: 2017-01-05 09:18:07.289638Z] Finished create-virtualenv step (success) 01:18:07 INFO - [mozharness: 2017-01-05 09:18:07.290668Z] Running install step. 01:18:07 INFO - Running pre-action listener: _resource_record_pre_action 01:18:07 INFO - Running main action method: install 01:18:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:18:07 INFO - Reading from file tmpfile_stdout 01:18:07 INFO - Detecting whether we're running mozinstall >=1.0... 01:18:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:18:07 INFO - Reading from file tmpfile_stdout 01:18:07 INFO - Output received: 01:18:07 INFO - Usage: mozinstall [options] installer 01:18:07 INFO - Options: 01:18:07 INFO - -h, --help show this help message and exit 01:18:07 INFO - -d DEST, --destination=DEST 01:18:07 INFO - Directory to install application into. [default: 01:18:07 INFO - "/builds/slave/test"] 01:18:07 INFO - --app=APP Application being installed. [default: firefox] 01:18:07 INFO - mkdir: /builds/slave/test/build/application 01:18:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:18:38 INFO - Reading from file tmpfile_stdout 01:18:38 INFO - Output received: 01:18:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:18:38 INFO - Running post-action listener: _resource_record_post_action 01:18:38 INFO - [mozharness: 2017-01-05 09:18:38.949635Z] Finished install step (success) 01:18:38 INFO - [mozharness: 2017-01-05 09:18:38.949783Z] Running run-tests step. 01:18:38 INFO - Running pre-action listener: _resource_record_pre_action 01:18:38 INFO - Running main action method: run_tests 01:18:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:18:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:18:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:18:38 INFO - 2017-01-05 01:18:38.964 screenresolution[1630:7350] starting screenresolution argv=screenresolution get 01:18:38 INFO - 2017-01-05 01:18:38.966 screenresolution[1630:7350] Display 0: 1600x1200x32@60 01:18:38 INFO - 2017-01-05 01:18:38.972 screenresolution[1631:7352] starting screenresolution argv=screenresolution list 01:18:38 INFO - Available Modes on Display 0 01:18:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:18:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:18:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:18:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:18:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:18:39 INFO - Intel Iris: 01:18:39 INFO - Chipset Model: Intel Iris 01:18:39 INFO - Type: GPU 01:18:39 INFO - Bus: Built-In 01:18:39 INFO - VRAM (Dynamic, Max): 1536 MB 01:18:39 INFO - Vendor: Intel (0x8086) 01:18:39 INFO - Device ID: 0x0a2e 01:18:39 INFO - Revision ID: 0x0009 01:18:39 INFO - Displays: 01:18:39 INFO - Display: 01:18:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:18:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:18:39 INFO - Display Serial Number: 1600x1200 60 01:18:39 INFO - Main Display: Yes 01:18:39 INFO - Mirror: Off 01:18:39 INFO - Online: Yes 01:18:39 INFO - Rotation: Supported 01:18:39 INFO - Return code: 0 01:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:18:39 INFO - Python 2.7.3 01:18:39 INFO - Return code: 0 01:18:39 INFO - grabbing minidump binary from tooltool 01:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:18:39 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 0x103d96d30>, '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 0x103bfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d8eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:18:39 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 01:18:39 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 01:18:39 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 01:18:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:18:39 INFO - Return code: 0 01:18:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:18:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:18:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:18:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:18:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0238', '--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 01:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0238 --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 01:18:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPpataFYDX/Render', 01:18:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:18:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:18:39 INFO - 'HOME': '/Users/cltbld', 01:18:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:18:39 INFO - 'LOGNAME': 'cltbld', 01:18:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:18:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:18:39 INFO - 'MOZ_NO_REMOTE': '1', 01:18:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:18:39 INFO - 'NO_EM_RESTART': '1', 01:18:39 INFO - 'PAGER': '/bin/cat', 01:18:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:18:39 INFO - 'PWD': '/builds/slave/test', 01:18:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:18:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:18:39 INFO - 'SHELL': '/bin/bash', 01:18:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qzVtnpMpVD/Listeners', 01:18:39 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 01:18:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:18:39 INFO - 'USER': 'cltbld', 01:18:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:18:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:18:39 INFO - 'XPC_FLAGS': '0x0', 01:18:39 INFO - 'XPC_SERVICE_NAME': '0', 01:18:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:18:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0238', '--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 01:18:39 INFO - mozversion application_buildid: 20170104235539 01:18:39 INFO - mozversion application_changeset: 75a0d7ccab1c43dc878ef1082117a30161f52d91 01:18:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:18:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:18:39 INFO - mozversion application_name: Firefox 01:18:39 INFO - mozversion application_remotingname: firefox-dev 01:18:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:18:39 INFO - mozversion application_vendor: Mozilla 01:18:39 INFO - mozversion application_version: 52.0a2 01:18:39 INFO - mozversion platform_buildid: 20170104235539 01:18:39 INFO - mozversion platform_changeset: 75a0d7ccab1c43dc878ef1082117a30161f52d91 01:18:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:18:39 INFO - mozversion platform_version: 52.0a2 01:18:39 INFO - using testdate: 1483607919 01:18:39 INFO - actual date: 1483607919 01:18:39 INFO - starting webserver on 'localhost:49249' 01:18:39 INFO - SUITE-START | Running 2 tests 01:18:39 INFO - TEST-START | dromaeo_css 01:18:39 INFO - operating with platform_type : mac_ 01:18:39 INFO - Initialising browser for dromaeo_css test... 01:18:39 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpDIfboU/profile http://localhost:49249/getInfo.html) 01:18:42 INFO - PROCESS | 1641 | 2017-01-05 01:18:42.713 plugin-container[1643:7528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 01:18:42 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:18:42 INFO - PROCESS | 1641 | 2017-01-05 01:18:42.715 plugin-container[1643:7528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 01:18:42 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:18:42 INFO - PROCESS | 1641 | 2017-01-05 01:18:42.715 plugin-container[1643:7528] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:18:42 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 01:18:42 INFO - PROCESS | 1641 | colorDepth:24 01:18:42 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 01:18:42 INFO - PROCESS | 1641 | __metrics 01:18:42 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 01:18:43 INFO - TEST-INFO | 1641: exit 0 01:18:43 INFO - Browser initialized. 01:18:43 INFO - Running cycle 1/1 for dromaeo_css test... 01:18:43 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpDIfboU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:18:46 INFO - PROCESS | 1644 | 2017-01-05 01:18:46.581 plugin-container[1645:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.tsm.portname' 01:18:46 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:18:46 INFO - PROCESS | 1644 | 2017-01-05 01:18:46.581 plugin-container[1645:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 01:18:46 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:18:46 INFO - PROCESS | 1644 | 2017-01-05 01:18:46.581 plugin-container[1645:7666] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:18:56 INFO - PROCESS | 1644 | RSS: Main: 229687296 01:18:56 INFO - PROCESS | 1644 | 01:21:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 01:22:01 INFO - PROCESS | 1644 | RSS: Main: 234115072 01:22:01 INFO - PROCESS | 1644 | 01:25:03 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 01:25:04 INFO - PROCESS | 1644 | RSS: Main: 243458048 01:25:04 INFO - PROCESS | 1644 | 01:28:06 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 01:28:06 INFO - PROCESS | 1644 | RSS: Main: 247250944 01:28:06 INFO - PROCESS | 1644 | 01:31:08 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 01:31:09 INFO - PROCESS | 1644 | RSS: Main: 246497280 01:31:09 INFO - PROCESS | 1644 | 01:34:12 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 01:34:13 INFO - PROCESS | 1644 | RSS: Main: 253992960 01:34:13 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 01:37:15 INFO - PROCESS | 1644 | RSS: Main: 255586304 01:37:15 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | __start_tp_report 01:37:15 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 01:37:15 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 01:37:15 INFO - PROCESS | 1644 | |i|pagename|runs| 01:37:15 INFO - PROCESS | 1644 | |0;dojo.html;2886.567014947758;3396.5075064115717;3415.573053368329;3421.2131209821723;3473.2011637323417;5051.191809310507;5051.797928082877;5806.419358064191;5866.061430171173;5891.116332550119;5772.826815195541;5855.502282306009;5873.1190321451695;5878.500327472159;5919.37846526115;5278.577713782898;5291.470852914697;5321.813736519222;5396.730163491829;5402.567794576444;5298.788048478056;5307.65500242483;5388.568914486832;5406.665575413067;5448.618596698233;5222.451642577551;5263.315768950012;5265.289185959885;5307.73461326934;5352.250684904077;5192.584593232532;5292.9735351323;5294.973525132388;5301.893962120775;5303.814366497193;4785.760711964388;5159.58723302135;5186.636935414523;5191.454897235812;5237.528622424;5533.667979921218;5766.394528574481;5785.6528608283215;5795.275590551181;5813.854653633651;6249.656268905208;6251.937480625181;6281.24625044993;6281.842953926189;6289.308176100625;6296.716647750863;6327.8734425311695;6339.968300158515;6346.2067241594805;6350.460210882081;4614.630829533629;5076.847694569169;5957.165996760457;5992.250968628921;5998.340182579913;4797.544233297831;5109.897802043938;6269.62382257066;6272.749090036439;6301.936980630227;5920.2895652521975;6223.578208502792;6232.781852635136;6253.062040693932;6274.843128921813;6281.434670879551;6303.590266632654;6308.085327627468;6330.335314791881;6333.841653958596;5208.5312321891215;5744.798932037359;6179.567430279927;6217.4715149212825;6231.532635052389;6229.3770622937345;6257.374262573706;6286.717097730613;6294.8111556653375;6295.496360291166;5024.698518088926;5040.798368065236;5045.924311135336;5050.090983622909;5058.342415485963;4190.868465514294;4259.680523960716;4414.735115893057;4424.933625995613;4458.175237580993;5283.599846040802;5899.262592175978;6021.126936594169;6057.57596968208;6169.96915015422;6267.592606480564;6296.496280297657;6301.401366870232;6336.17629708135;6344.714487848058;5777.220075289866;5816.70916454184;5854.736536855852;5939.762409503658;5943.673097979653;5487.64330318528;5505.532029777513;5507.917381239285;5520.779168833282;5529.225908372831;6425.036244563353;6449.645269510222;6475.222973243241;6481.157449531531;6486.962086066207;6142.345304543789;6168.1056246844855;6215.440610345091;6263.62418254888;6291.842703932438;6479.967600161969;6482.319356467691;6491.902621460587;6496.935030649822;6506.3818937200895;6270.7491700331475;6300.7479700811455;6303.779367722108;6310.337414571404;6316.557840951089;6202.162708034538;6223.782167624111;6237.28271248794;6255.37446255389;6267.0286105653695;5180.870478238075;6095.268567771718;6133.417325354084;6242.157308763441;6259.092431597484;4798.3042458843975;6184.628922264678;6215.378462153749;6239.469645080219;6263.530235232467;5835.912461313169;5871.295444546682;5959.523238141025;6036.124761909587;6073.30157031947;6098.512119030555;6105.755769769159;6128.4790792783115;6134.987697083657;6190.628562286277;6036.6679832609625;6041.969790151021;6075.149479072845;6089.147519347383;6101.816945491642 01:37:15 INFO - PROCESS | 1644 | |1;ext.html;447.577039697486;468.6250999200639;527.7176710459902;531.9468053194681;554.2905081495687;30117.849410752944;31284.68715312849;36258.456123158176;40616.390754138636;40721.59278407215;29415.411691766152;37092.07269818256;38777.030574235585;40037.39943900837;41964;8062.959685201566;9280.582373793162;9460.385074970105;9475.668351607696;9488.146066853982;9340.813183736322;9412.717618471457;9449.480278584675;9450.66922657707;9470.289728270373;9498.335116541844;9500.192483638899;9536.713898583053;9542.379745316532;9550.283728720338;8887.066857979884;9418.058194180561;9480.146786789153;9503.144716975505;9510;8654.567271636393;9334.20659243965;9395.718128456158;9397.530123493798;9409.905900940972;39397.01507462336;39523.60476395228;39573.80213098945;39689;39738.80783576497;3418.743594230418;3516.2967406518796;3937.5471820740695;3944.2900277949957;3946.4080387941754;28544.571831422545;28706.856465717538;29230;30423.543646845315;30584.38831223363;3171.888983885553;3201.6638252983334;3253.658365871573;3277.1807048237943;3303.487959366302;492007;495522;498332;500445;500641;58524.707376463695;58564.7071764647;58691.70654146702;58754.70622646945;59000.70499647474;60147.69926150341;60630;60776;60782.08826867601;60783.6960815202;15782.921085394499;15974.680506389806;15988.280527376295;15999.28003239857;16062.277197526138;12173.878261217274;12313.199642023239;12327.876721232853;12345.567905123278;12389.62831115068;15471.458498952483;15590.532284031497;15790.447334343473;15884.52346429609;15897.887147899535;3210.6949839765653;5506.311711036121;6706.698198581075;6743.797686069426;6752.966235168793;862.5169904853302;1002.9097381235724;1057.0380953332585;1058.6188971970084;1066.8933106689424;27035.1889443317;29813.552796708067;29965.700342996726;30089.855462922966;30540.931067412534;824.0934971531268;886.3352485635774;1025.0876719719456;1046.9371837689764;1060.7772367802847;14857.47998820036;15575.065496070272;15755.4485593006;16029.519114426586;16393;680927;681058.5947070332;682059;684013;687552;57893.710531447075;58071.4192858066;58339.416605834245;58362;58450.83098338095;1337.5786627212397;1346.0712108645005;1391.6520869782555;1393.2615713671973;1395.6022533578084;27846;29955.400891982474;30396.5440518388;30450.26761376093;31061;49517.752411237714;50186.74906625444;50218.748906255234;50258;50351.49648503468;617199;618100;620418;620963;622329;24704;26172.083977060713;26543.33641658897;26578.936842526084;26582.86708566522;44539.55460445484;44802.77598611986;44868;45097.32354014758;45655.54344456646;36565.08587285339;36928.63071369252;37232.576763712255;37345.879623611334;37904;37308;37346.813265933495;37932.620673794016;38355.616443835206;38659;31132;31713.365732685677;35583.46624800578;36304.45543316905;36586.085347866516;26966.191014269603;27421.725782741916;31084.37831243317;34003.319933601684;34257.458414370914;22795.772042280027;23006.654900176178;25480.490390192463;25700.742992569834;25819.225423237334 01:37:15 INFO - PROCESS | 1644 | |2;jquery.html;323.46466597780676;349.470552113548;358.8008655196365;371.079722288724;376.6102084342706;18361.72457413138;18855.622887542257;20015.799842001576;20016.199352025902;20282.797172028277;18902.14940327685;18957.33649322274;19135.521611959673;19164.850108993436;19245.03774811261;5737.913931291024;5939;5981.730822112993;5992.640441573498;5995.940040599604;5548.611597188198;5814.5057670098095;5876.265466816648;5877.441643043925;5888.499477544414;5685.061964775807;5804.651720896739;5824.359320474744;5836.124581312794;5847.44449277318;4858.659893807435;5300.867478313035;5392.624880655447;5682.346530149044;5783.219265399181;3963.7027222958104;4700.929486057712;4744.288356746481;5097.2608971699265;5139.8458046258675;22212;24307.513849723084;24370.634440483405;24576.754232457624;24710.629340559906;2392.3899405651446;2414.770596793302;2425.4057755849753;2851.2301678546883;3029.909102726922;14976;15238.238088095552;15349.232538373148;15636.530904072895;16024.038557686576;1777.7244527098321;1788.8390044895891;1806.6567352203172;1845.1235663060074;1849.029259638687;96510;117888;119192;123814.38092809568;124318.37840810738;10594.205434592437;10665.200109991627;11052.778944421067;11064.502097405637;11120.888791112147;13691.178529288274;13790.65523361924;13819.792703109462;13831.962602804628;13836.30818459073;8211.466254693425;8440.155984401634;8502.319814414832;8570.400071995025;8621.439606425562;8383.916160838435;8445.1977062179;8455.069942306342;8504.617292221865;8618.612162452579;6541.28045914949;6663.0683984569705;6668.799936001928;6679.165104361955;6693.330666933268;1744.546417931347;1813.8844610564672;1841.6371884805149;1873.1102726205215;1924.4034349351746;875.9343049271203;953.3707752883064;977.3793641037992;1019.9949000254951;1025.6820385680462;20962.475938101365;21632.675509867364;21707.782922170893;21865.906704664703;21873.453163671034;916.9174774270348;958.9041095890494;970.4177493503842;979.4612962870393;1004.2275383947339;9658.951705241569;10988.670339889815;11521.308721476738;11656.94171529154;11665.825012624817;434210;592620;606866;617070;617270;106983;107756.46121769342;112730;117533;119811;870.2341939093557;928.4058202750246;942.2650332740474;991.45965448832;1003.9196864250843;18305.90847045756;18778.624427511648;18934.715979260596;18940.3370882024;19141.33357187937;55665;56106.438935610124;56170;57447.13829292481;57808.4219157803;77039;77486.61256693906;78406.6079669598;78413.60793195998;78445;8162.551059691774;8165.061017983008;8232.464889782144;8445.40882138269;8453.239208471266;12087.774795979902;12861.614151575468;13105.934470327587;13184.417869855679;13237.271950042456;11079.50142243585;11080.889191107986;11146.55413783472;11407.657770266906;11414.401983722451;11037;11044.282121662387;11061.778764424505;11233.32600044;11371.033462155809;11087.72280692989;11282;11326.32042077478;11617.244879082833;11776.528938842694;6721.529492935448;7393.371563417169;7534.35957943581;8238.958805205935;8409.53747543891;8449.619767110371;10712.839307410552;10921.945390272998;11089.667309980714;11306.434678266218 01:37:15 INFO - PROCESS | 1644 | |3;mootools.html;1419.0917812599935;1421.5877395555285;1581.9920900395502;1729.688656041912;1739.7738294021767;7550.169481357047;7801.063872335328;8065.8386832263395;8553.016403113645;8585.527795971218;6565.096122124578;7660.233976602337;8028.157043510435;8319.33445324374;8343.207395297452;1959.6668566343685;2299.678045073687;2357.964630530535;2358.9528209435803;2360.4806942472715;2347.319277409549;2374.1809075868796;2382.845115067515;2384.713834339873;2390.0917651292484;1965.2433812982033;2327.1273272522803;2329.1382188590246;2349.95300093998;2353.62342025276;1893.7916829148783;2262.2082271205113;2264.479169790949;2276.3967548599635;2287.7597852225445;2104.4949212189117;2123.490362313049;2280.680704701343;2307.665388518672;2320.431494283894;8378.706745263948;8427.073021967579;8429.32565394767;8438.873416898752;8443.155684431507;5091.898162036776;5241.50205730455;5247.475252474722;5260.421353651095;5272.446393128743;3144.6540880503353;3252.1869532616847;3501.6148223695373;3511.4206155984234;3564.322778672044;1007.2495990487134;1038.5689938675441;1042.525650828871;1047.7328281288299;1055.7782865598233;10460.790784184273;10764.515596798163;10832.537607422975;10856.620018299322;11054.668359949295;4368.213721530092;4424.159409712144;4509.143262780061;4558.703684260512;4558.817647294071;8596.441231319945;8710.433821801562;8716.215540601377;8723.738287851374;8725.040245572982;5011.899762004812;5137.897242055138;5141.305923700327;5152.742362881841;5160.200168973815;3943.8028098594955;4113.71204015722;4276.144771045741;4489.820407183676;4795.112904112751;3567.9643203568153;3570.410882204459;3660.762050466711;3680.4479328100997;3681.3557627415094;991.2317953585884;1088.3469918049266;1111.183280288987;1115.5649296774266;1125.8930401611833;867.4708427859127;884.8584226523725;890.5146695051212;891.8483857744199;909.7816524034316;5163.586913046947;5221.765339933772;5536.501714845684;5586.972065139648;5632.774689012475;421.8903085197871;439.1261389834207;454.42742144897665;454.6998980672806;458.19357930042696;2922.327864591156;3548.982255088708;3571.767835090711;3663.175785448253;3678.5217921670546;8573.399862009599;9836.704898853046;10134.898651013396;10245.077942985166;10248.38509689421;10132.54403551842;10197.18422526196;10199.643012494527;10213.642522511676;10223.182145428347;6547.836304092436;7146.963690264986;7155.105611798525;7204;7989.0812556555575;4648.581627653527;4908.63185261114;5080.796768129233;5334.333208348957;5475.890482190254;9378.296627752808;9447.149756521945;9462.716118516455;9512.904870951203;9542.379745316532;9407.717768466957;9575;9658.75853103678;9760.902390976284;9802.803943921224;6232.781852635136;6457;6635.170603674541;6810.8637827244165;6812.863742725019;7740.496867703581;7941.642626081955;7947.403944704286;8078.111407745261;8094.473859199134;6171.783987560593;7125.714971401086;7204.135503739375;7273.490855640054;7380.520266182681;7086.185088714864;7147.356737893615;7165.426765858614;7195.82010449722;7198.928010720036;6488.610683359014;6642.800715978528;6743.696533655899;6782.762603308861;6817.3182681733415;4758.119747846659;4798.256270278112;4806.423229212517;4835.734034628129;4843.491433399583;4716.528347165256;4737.28940658904;4798.1843086676045;4896.510348965217;4951.554360107608 01:37:15 INFO - PROCESS | 1644 | |4;prototype.html;301.98943036993694;304.1544506272562;306.15959192017914;312.0404755377216;316.13694613704587;4441.378207051015;4783.569478746912;5293.444188360215;5472.50747432731;5496.203050557671;5270.288511051017;5286.3392075990505;5367.570594352453;5369.154348195453;5428.83713488596;3322.80063196209;4134.565870583583;4154.813033413504;4173.56177601352;4174.582541745816;3636.4181730923056;3917.471141395918;4022.678185745148;4035.0833354156835;4137.710360274782;3596.946045809315;3970.924485537133;4062.857799976987;4094.09624524596;4117.938230926538;3029.530422784483;3806.505154329947;3890.6929110256197;4011.939820902689;4112.157007813391;2808.3540785619216;3032.9848350758325;3551.325248202859;3596.6583174598372;3597.6941959933174;4233.666395085538;4970;5244.632875698702;5276.419593844674;5329.307190065306;3506.4564992426212;4490.528494508062;4688.382508034722;4699.530046995274;4718.504557021498;5176.145935920568;5196.272521846944;5526.889462210734;5715.828525144252;5757.1652110444165;3319.717823984964;3954.6045395460224;3968.6626636735623;3970.68234541236;3971.920561588781;5420.728963551846;5951.3751056139345;5971.283445986466;5971.790987315467;5985.9401405986255;3745.0637340664834;3768.6419790119894;4017.055991841973;4027.677785777131;4052.2908491013955;3888.8055597220027;4051.169510250392;4064.7154699171365;4068.6745060395688;4120.381942708557;4135.958640413557;4328.978355108267;4704.059188162381;4716.528347165325;4725.503822098699;5142.537171654494;5665.818659816001;5751.309842818889;5757.99819125712;5819.82540523785;1032.4631191780381;1131.9603813866474;1258.7545428641326;1269.4224128021729;1331.8268625078679;779.8167430653905;796.8884356190138;903.8147179828251;916.5921165081608;919.544825311475;4351.564843515623;4504;4611.492735799059;4885.750975601257;5001.474845141281;4981.726005069756;5439.265699130567;5488.694748205683;5535.750891209906;5544.944550554443;4198.769067701244;4645.814167433333;4856.465788763233;4989.950100498949;5011.974940125276;2294.5640328337563;2453.3989172652814;2565.8575089800224;2903.8548072596286;2925.581093169811;4627.236505976476;4628.884277893012;5494.313210848644;5725.679106118441;5731.971340143273;4436.844710435119;5321.946780532223;5496.835094947158;5669.31968163823;5673.3475650299915;4654.115718013567;4853.476192380013;5275.577953763766;5626.074441290506;5659.858503537444;4576.8626941191815;5097.592192624656;5107.591392688651;5475.370332411664;5539.030669632718;5372.274742909657;5459.617826752089;5489.886393738359;5507.421720719427;5895.14520394549;5439.646422982493;5477.67133971963;6176.66028368444;6207.81376558704;6219.813405597839;3399.9150021249666;3620.9094772630897;3811.2568049230135;4109.958900411078;4116.259073366703;5065.290859279778;5378.1663842104535;5961.463468287875;5968.880622387441;5972.761089556369;4389.956100439083;4852.102361063215;4948.21461581883;5859.007937301338;5884.9705751470965;4950.643433778252;5047.470015648304;5720.599558030898;5834.241548598655;5887.735051922588;4666.976665116653;5338.985592737367;5422.376426710821;5675;5687.1753595729215;5792.333881603669;5794.507466865195;5802.506786923159;5806.767729290781;5833.854153646193;5645.745941432728;5668.178114173339;5669.291338582677;5679.119736440858;5705.001624715575 01:37:15 INFO - PROCESS | 1644 | |5;yui.html;584.0421708398225;677.3429773120072;677.8237658208865;743.9814004649883;772.4091070331195;14183.219922904236;15053.247337633102;15058.247087645601;15068.849311506881;15100.320485578162;13436.92504599632;13490.865091349084;13559.389827457764;13578.388972496237;13611.523596674118;776.809681628002;804.3324041045933;821.2198411509061;822.7984143884756;832.8042909916165;822.5147163173726;823.4030328012203;824.4558591329724;825.9132791056912;827.2141465607667;855.9529225892574;858.7423772868145;860.8149247911698;861.7457849934284;865.8917635295588;846.2764336492272;852.3947996922193;855.7689423855555;856.1267507142679;857.3141486810558;640.3884290502559;640.961542307463;644.24301445801;645.0549944331577;646.1406329581645;13997.3701183447;14274.286285685674;14288;14487.92756036213;14544.927275363661;10131.392116472962;10652.094571961392;12146.028317734648;13205.933970330183;13212;11053.28153670009;11235.494402751814;11317.207795454322;11406.71483212918;11408.657740267723;3513.7540372173958;3583.1937813991904;3650.0692323457347;3662.7619204751604;3766.868159614428;53653;72509.63745181293;81617.18382816155;84395;87376.68934965917;9159.404638698463;10062.798744025225;10202.846957295647;10288.691339259834;10344.741381465523;19860.304889329094;19861;19948.60102797936;20208.595828083355;20321.085551150234;7368.226336234726;7395;7421.591812450259;7433.219511951275;7626.351760100453;4073.5926407359025;4130.339145736668;4220.113776107052;4232.470941132358;4261.232978063917;2761;3313.867445302209;3389.2882494676396;3413.5391722117693;3422.281320922634;616.1097214524968;637.9457746091541;639.5970538560676;644.0243031806816;652.8302641313197;543.5864760463608;643.1028714942722;643.6717274190103;645.1484041065842;686.0669489494384;8690.695825646071;8790.604422800861;8856.24721898633;9128.58921348541;9198.172164505093;517.3403910014762;638.1990601794811;661.388817058864;669.7321071571411;678.4199509419474;4176.935919860051;4423.668224883147;4441.955580444219;4449.57729015743;4491.281394976788;69195.92412227641;101203;104734;112641;114843.42578287222;64297;65575.67212163909;66090;93291.53354233186;93575;74052.62973685097;74062.62968685229;74186.99999999892;74941.25058749452;77742.6112869432;8933.553322333728;9207.987121416505;9595.23238140961;9617.855732164164;9671.316625344787;35978.28043439064;41675.7916210417;49013;56411.15383269172;57123.714381427824;75818;77073;78090.6095469519;80277;80281;11936.880631193577;11993.700157496132;12155.817662735242;12186.006859771223;12294.446749896097;13045.673858153623;13208.735825283633;13610.795838062239;13931.791023134458;13955;12681.619551413472;12864.163829351062;12967.22196668203;13341.86658133445;13383.330833458484;10785.191110666574;11721.355325457182;12440.69372715888;12905.677358066123;12931.482740690268;10276.794464110826;10700.732481688021;12406.751864962469;12525.812112818132;13042.608721738363;10903.400312982862;10976;11059.059979901798;11172.664820055412;11251.7749645005;10779.89220107789;10893.727656808644;11115.777684446428;11137.498812553617;11159.553617855196 01:37:15 INFO - PROCESS | 1644 | __end_tp_report 01:37:15 INFO - PROCESS | 1644 | __start_cc_report 01:37:15 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,362 01:37:15 INFO - PROCESS | 1644 | __end_cc_report 01:37:15 INFO - PROCESS | 1644 | __startTimestamp1483609035823__endTimestamp 01:37:15 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | ------- Summary: start ------- 01:37:15 INFO - PROCESS | 1644 | Number of tests: 6 01:37:15 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5762.02 Median:6021.13 stddev:686.63 (11.4%) stddev-sans-first:650.63 01:37:15 INFO - PROCESS | 1644 | Values: 2886.6 3396.5 3415.6 3421.2 3473.2 5051.2 5051.8 5806.4 5866.1 5891.1 5772.8 5855.5 5873.1 5878.5 5919.4 5278.6 5291.5 5321.8 5396.7 5402.6 5298.8 5307.7 5388.6 5406.7 5448.6 5222.5 5263.3 5265.3 5307.7 5352.3 5192.6 5293.0 5295.0 5301.9 5303.8 4785.8 5159.6 5186.6 5191.5 5237.5 5533.7 5766.4 5785.7 5795.3 5813.9 6249.7 6251.9 6281.2 6281.8 6289.3 6296.7 6327.9 6340.0 6346.2 6350.5 4614.6 5076.8 5957.2 5992.3 5998.3 4797.5 5109.9 6269.6 6272.7 6301.9 5920.3 6223.6 6232.8 6253.1 6274.8 6281.4 6303.6 6308.1 6330.3 6333.8 5208.5 5744.8 6179.6 6217.5 6231.5 6229.4 6257.4 6286.7 6294.8 6295.5 5024.7 5040.8 5045.9 5050.1 5058.3 4190.9 4259.7 4414.7 4424.9 4458.2 5283.6 5899.3 6021.1 6057.6 6170.0 6267.6 6296.5 6301.4 6336.2 6344.7 5777.2 5816.7 5854.7 5939.8 5943.7 5487.6 5505.5 5507.9 5520.8 5529.2 6425.0 6449.6 6475.2 6481.2 6487.0 6142.3 6168.1 6215.4 6263.6 6291.8 6480.0 6482.3 6491.9 6496.9 6506.4 6270.7 6300.7 6303.8 6310.3 6316.6 6202.2 6223.8 6237.3 6255.4 6267.0 5180.9 6095.3 6133.4 6242.2 6259.1 4798.3 6184.6 6215.4 6239.5 6263.5 5835.9 5871.3 5959.5 6036.1 6073.3 6098.5 6105.8 6128.5 6135.0 6190.6 6036.7 6042.0 6075.1 6089.1 6101.8 01:37:15 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:71905.50 Median:21357.29 stddev:162187.85 (759.4%) stddev-sans-first:162554.09 01:37:15 INFO - PROCESS | 1644 | Values: 447.6 468.6 527.7 531.9 554.3 30117.8 31284.7 36258.5 40616.4 40721.6 29415.4 37092.1 38777.0 40037.4 41964.0 8063.0 9280.6 9460.4 9475.7 9488.1 9340.8 9412.7 9449.5 9450.7 9470.3 9498.3 9500.2 9536.7 9542.4 9550.3 8887.1 9418.1 9480.1 9503.1 9510.0 8654.6 9334.2 9395.7 9397.5 9409.9 39397.0 39523.6 39573.8 39689.0 39738.8 3418.7 3516.3 3937.5 3944.3 3946.4 28544.6 28706.9 29230.0 30423.5 30584.4 3171.9 3201.7 3253.7 3277.2 3303.5 492007.0 495522.0 498332.0 500445.0 500641.0 58524.7 58564.7 58691.7 58754.7 59000.7 60147.7 60630.0 60776.0 60782.1 60783.7 15782.9 15974.7 15988.3 15999.3 16062.3 12173.9 12313.2 12327.9 12345.6 12389.6 15471.5 15590.5 15790.4 15884.5 15897.9 3210.7 5506.3 6706.7 6743.8 6753.0 862.5 1002.9 1057.0 1058.6 1066.9 27035.2 29813.6 29965.7 30089.9 30540.9 824.1 886.3 1025.1 1046.9 1060.8 14857.5 15575.1 15755.4 16029.5 16393.0 680927.0 681058.6 682059.0 684013.0 687552.0 57893.7 58071.4 58339.4 58362.0 58450.8 1337.6 1346.1 1391.7 1393.3 1395.6 27846.0 29955.4 30396.5 30450.3 31061.0 49517.8 50186.7 50218.7 50258.0 50351.5 617199.0 618100.0 620418.0 620963.0 622329.0 24704.0 26172.1 26543.3 26578.9 26582.9 44539.6 44802.8 44868.0 45097.3 45655.5 36565.1 36928.6 37232.6 37345.9 37904.0 37308.0 37346.8 37932.6 38355.6 38659.0 31132.0 31713.4 35583.5 36304.5 36586.1 26966.2 27421.7 31084.4 34003.3 34257.5 22795.8 23006.7 25480.5 25700.7 25819.2 01:37:15 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34047.62 Median:24339.07 stddev:96286.81 (395.6%) stddev-sans-first:96523.62 01:37:15 INFO - PROCESS | 1644 | Values: 323.5 349.5 358.8 371.1 376.6 18361.7 18855.6 20015.8 20016.2 20282.8 18902.1 18957.3 19135.5 19164.9 19245.0 5737.9 5939.0 5981.7 5992.6 5995.9 5548.6 5814.5 5876.3 5877.4 5888.5 5685.1 5804.7 5824.4 5836.1 5847.4 4858.7 5300.9 5392.6 5682.3 5783.2 3963.7 4700.9 4744.3 5097.3 5139.8 22212.0 24307.5 24370.6 24576.8 24710.6 2392.4 2414.8 2425.4 2851.2 3029.9 14976.0 15238.2 15349.2 15636.5 16024.0 1777.7 1788.8 1806.7 1845.1 1849.0 96510.0 117888.0 119192.0 123814.4 124318.4 10594.2 10665.2 11052.8 11064.5 11120.9 13691.2 13790.7 13819.8 13832.0 13836.3 8211.5 8440.2 8502.3 8570.4 8621.4 8383.9 8445.2 8455.1 8504.6 8618.6 6541.3 6663.1 6668.8 6679.2 6693.3 1744.5 1813.9 1841.6 1873.1 1924.4 875.9 953.4 977.4 1020.0 1025.7 20962.5 21632.7 21707.8 21865.9 21873.5 916.9 958.9 970.4 979.5 1004.2 9659.0 10988.7 11521.3 11656.9 11665.8 434210.0 592620.0 606866.0 617070.0 617270.0 106983.0 107756.5 112730.0 117533.0 119811.0 870.2 928.4 942.3 991.5 1003.9 18305.9 18778.6 18934.7 18940.3 19141.3 55665.0 56106.4 56170.0 57447.1 57808.4 77039.0 77486.6 78406.6 78413.6 78445.0 8162.6 8165.1 8232.5 8445.4 8453.2 12087.8 12861.6 13105.9 13184.4 13237.3 11079.5 11080.9 11146.6 11407.7 11414.4 11037.0 11044.3 11061.8 11233.3 11371.0 11087.7 11282.0 11326.3 11617.2 11776.5 6721.5 7393.4 7534.4 8239.0 8409.5 8449.6 10712.8 10921.9 11089.7 11306.4 01:37:15 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5325.89 Median:4870.00 stddev:2987.45 (61.3%) stddev-sans-first:2981.41 01:37:15 INFO - PROCESS | 1644 | Values: 1419.1 1421.6 1582.0 1729.7 1739.8 7550.2 7801.1 8065.8 8553.0 8585.5 6565.1 7660.2 8028.2 8319.3 8343.2 1959.7 2299.7 2358.0 2359.0 2360.5 2347.3 2374.2 2382.8 2384.7 2390.1 1965.2 2327.1 2329.1 2350.0 2353.6 1893.8 2262.2 2264.5 2276.4 2287.8 2104.5 2123.5 2280.7 2307.7 2320.4 8378.7 8427.1 8429.3 8438.9 8443.2 5091.9 5241.5 5247.5 5260.4 5272.4 3144.7 3252.2 3501.6 3511.4 3564.3 1007.2 1038.6 1042.5 1047.7 1055.8 10460.8 10764.5 10832.5 10856.6 11054.7 4368.2 4424.2 4509.1 4558.7 4558.8 8596.4 8710.4 8716.2 8723.7 8725.0 5011.9 5137.9 5141.3 5152.7 5160.2 3943.8 4113.7 4276.1 4489.8 4795.1 3568.0 3570.4 3660.8 3680.4 3681.4 991.2 1088.3 1111.2 1115.6 1125.9 867.5 884.9 890.5 891.8 909.8 5163.6 5221.8 5536.5 5587.0 5632.8 421.9 439.1 454.4 454.7 458.2 2922.3 3549.0 3571.8 3663.2 3678.5 8573.4 9836.7 10134.9 10245.1 10248.4 10132.5 10197.2 10199.6 10213.6 10223.2 6547.8 7147.0 7155.1 7204.0 7989.1 4648.6 4908.6 5080.8 5334.3 5475.9 9378.3 9447.1 9462.7 9512.9 9542.4 9407.7 9575.0 9658.8 9760.9 9802.8 6232.8 6457.0 6635.2 6810.9 6812.9 7740.5 7941.6 7947.4 8078.1 8094.5 6171.8 7125.7 7204.1 7273.5 7380.5 7086.2 7147.4 7165.4 7195.8 7198.9 6488.6 6642.8 6743.7 6782.8 6817.3 4758.1 4798.3 4806.4 4835.7 4843.5 4716.5 4737.3 4798.2 4896.5 4951.6 01:37:15 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4481.82 Median:4960.32 stddev:1407.51 (28.4%) stddev-sans-first:1376.05 01:37:15 INFO - PROCESS | 1644 | Values: 302.0 304.2 306.2 312.0 316.1 4441.4 4783.6 5293.4 5472.5 5496.2 5270.3 5286.3 5367.6 5369.2 5428.8 3322.8 4134.6 4154.8 4173.6 4174.6 3636.4 3917.5 4022.7 4035.1 4137.7 3596.9 3970.9 4062.9 4094.1 4117.9 3029.5 3806.5 3890.7 4011.9 4112.2 2808.4 3033.0 3551.3 3596.7 3597.7 4233.7 4970.0 5244.6 5276.4 5329.3 3506.5 4490.5 4688.4 4699.5 4718.5 5176.1 5196.3 5526.9 5715.8 5757.2 3319.7 3954.6 3968.7 3970.7 3971.9 5420.7 5951.4 5971.3 5971.8 5985.9 3745.1 3768.6 4017.1 4027.7 4052.3 3888.8 4051.2 4064.7 4068.7 4120.4 4136.0 4329.0 4704.1 4716.5 4725.5 5142.5 5665.8 5751.3 5758.0 5819.8 1032.5 1132.0 1258.8 1269.4 1331.8 779.8 796.9 903.8 916.6 919.5 4351.6 4504.0 4611.5 4885.8 5001.5 4981.7 5439.3 5488.7 5535.8 5544.9 4198.8 4645.8 4856.5 4990.0 5012.0 2294.6 2453.4 2565.9 2903.9 2925.6 4627.2 4628.9 5494.3 5725.7 5732.0 4436.8 5321.9 5496.8 5669.3 5673.3 4654.1 4853.5 5275.6 5626.1 5659.9 4576.9 5097.6 5107.6 5475.4 5539.0 5372.3 5459.6 5489.9 5507.4 5895.1 5439.6 5477.7 6176.7 6207.8 6219.8 3399.9 3620.9 3811.3 4110.0 4116.3 5065.3 5378.2 5961.5 5968.9 5972.8 4390.0 4852.1 4948.2 5859.0 5885.0 4950.6 5047.5 5720.6 5834.2 5887.7 4667.0 5339.0 5422.4 5675.0 5687.2 5792.3 5794.5 5802.5 5806.8 5833.9 5645.7 5668.2 5669.3 5679.1 5705.0 01:37:15 INFO - PROCESS | 1644 | 01:37:15 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:18969.36 Median:4342.45 stddev:27066.67 (623.3%) stddev-sans-first:27107.39 01:37:15 INFO - PROCESS | 1644 | Values: 584.0 677.3 677.8 744.0 772.4 14183.2 15053.2 15058.2 15068.8 15100.3 13436.9 13490.9 13559.4 13578.4 13611.5 776.8 804.3 821.2 822.8 832.8 822.5 823.4 824.5 825.9 827.2 856.0 858.7 860.8 861.7 865.9 846.3 852.4 855.8 856.1 857.3 640.4 641.0 644.2 645.1 646.1 13997.4 14274.3 14288.0 14487.9 14544.9 10131.4 10652.1 12146.0 13205.9 13212.0 11053.3 11235.5 11317.2 11406.7 11408.7 3513.8 3583.2 3650.1 3662.8 3766.9 53653.0 72509.6 81617.2 84395.0 87376.7 9159.4 10062.8 10202.8 10288.7 10344.7 19860.3 19861.0 19948.6 20208.6 20321.1 7368.2 7395.0 7421.6 7433.2 7626.4 4073.6 4130.3 4220.1 4232.5 4261.2 2761.0 3313.9 3389.3 3413.5 3422.3 616.1 637.9 639.6 644.0 652.8 543.6 643.1 643.7 645.1 686.1 8690.7 8790.6 8856.2 9128.6 9198.2 517.3 638.2 661.4 669.7 678.4 4176.9 4423.7 4442.0 4449.6 4491.3 69195.9 101203.0 104734.0 112641.0 114843.4 64297.0 65575.7 66090.0 93291.5 93575.0 74052.6 74062.6 74187.0 74941.3 77742.6 8933.6 9208.0 9595.2 9617.9 9671.3 35978.3 41675.8 49013.0 56411.2 57123.7 75818.0 77073.0 78090.6 80277.0 80281.0 11936.9 11993.7 12155.8 12186.0 12294.4 13045.7 13208.7 13610.8 13931.8 13955.0 12681.6 12864.2 12967.2 13341.9 13383.3 10785.2 11721.4 12440.7 12905.7 12931.5 10276.8 10700.7 12406.8 12525.8 13042.6 10903.4 10976.0 11059.1 11172.7 11251.8 10779.9 10893.7 11115.8 11137.5 11159.6 01:37:15 INFO - PROCESS | 1644 | -------- Summary: end -------- 01:37:15 INFO - PROCESS | 1644 | 01:37:16 INFO - TEST-INFO | 1644: exit 0 01:37:16 INFO - TEST-OK | dromaeo_css | took 1116941ms 01:37:16 INFO - TEST-START | kraken 01:37:16 INFO - operating with platform_type : mac_ 01:37:16 INFO - Initialising browser for kraken test... 01:37:16 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpcKMIGy/profile http://localhost:49249/getInfo.html) 01:37:19 INFO - PROCESS | 1657 | 2017-01-05 01:37:19.323 plugin-container[1658:10074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 01:37:19 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:19 INFO - PROCESS | 1657 | 2017-01-05 01:37:19.326 plugin-container[1658:10074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 01:37:19 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:19 INFO - PROCESS | 1657 | 2017-01-05 01:37:19.326 plugin-container[1658:10074] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:37:19 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 01:37:19 INFO - PROCESS | 1657 | colorDepth:24 01:37:19 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 01:37:19 INFO - PROCESS | 1657 | __metrics 01:37:19 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 01:37:20 INFO - TEST-INFO | 1657: exit 0 01:37:20 INFO - Browser initialized. 01:37:20 INFO - Running cycle 1/1 for kraken test... 01:37:20 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpcKMIGy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:37:23 INFO - PROCESS | 1659 | 2017-01-05 01:37:23.117 plugin-container[1660:10196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 01:37:23 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:23 INFO - PROCESS | 1659 | 2017-01-05 01:37:23.118 plugin-container[1660:10196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1b, name = 'com.apple.CFPasteboardClient' 01:37:23 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:23 INFO - PROCESS | 1659 | 2017-01-05 01:37:23.118 plugin-container[1660:10196] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:37:32 INFO - PROCESS | 1659 | RSS: Main: 229666816 01:37:32 INFO - PROCESS | 1659 | 01:37:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 01:37:36 INFO - PROCESS | 1659 | RSS: Main: 241893376 01:37:36 INFO - PROCESS | 1659 | 01:37:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 01:37:40 INFO - PROCESS | 1659 | RSS: Main: 249401344 01:37:40 INFO - PROCESS | 1659 | 01:37:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 01:37:44 INFO - PROCESS | 1659 | RSS: Main: 253075456 01:37:44 INFO - PROCESS | 1659 | 01:37:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 01:37:46 INFO - PROCESS | 1659 | RSS: Main: 254394368 01:37:46 INFO - PROCESS | 1659 | 01:37:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:37:49 INFO - PROCESS | 1659 | RSS: Main: 254435328 01:37:49 INFO - PROCESS | 1659 | 01:37:52 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 01:37:52 INFO - PROCESS | 1659 | RSS: Main: 258519040 01:37:52 INFO - PROCESS | 1659 | 01:37:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 01:37:56 INFO - PROCESS | 1659 | RSS: Main: 258260992 01:37:56 INFO - PROCESS | 1659 | 01:37:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 01:38:00 INFO - PROCESS | 1659 | RSS: Main: 258359296 01:38:00 INFO - PROCESS | 1659 | 01:38:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:38:02 INFO - PROCESS | 1659 | RSS: Main: 257413120 01:38:02 INFO - PROCESS | 1659 | 01:38:04 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:38:05 INFO - PROCESS | 1659 | RSS: Main: 257425408 01:38:05 INFO - PROCESS | 1659 | 01:38:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:38:08 INFO - PROCESS | 1659 | RSS: Main: 257458176 01:38:08 INFO - PROCESS | 1659 | 01:38:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:38:11 INFO - PROCESS | 1659 | RSS: Main: 257757184 01:38:11 INFO - PROCESS | 1659 | 01:38:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:38:15 INFO - PROCESS | 1659 | RSS: Main: 257671168 01:38:15 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 01:38:17 INFO - PROCESS | 1659 | RSS: Main: 259493888 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | __start_tp_report 01:38:17 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 01:38:17 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 01:38:17 INFO - PROCESS | 1659 | |i|pagename|runs| 01:38:17 INFO - PROCESS | 1659 | |0;ai-astar;105;119;119;121;119;121;131;99;139;139 01:38:17 INFO - PROCESS | 1659 | |1;audio-beat-detection;143;111;130;133;152;144;149;119;133;111 01:38:17 INFO - PROCESS | 1659 | |2;audio-dft;262;250;199;193;197;198;194;200;199;197 01:38:17 INFO - PROCESS | 1659 | |3;audio-fft;81;63;72;101;51;62;80;85;101;82 01:38:17 INFO - PROCESS | 1659 | |4;audio-oscillator;85;71;90;71;70;71;87;69;72;88 01:38:17 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;136;131;90;89;101;87;92;87;92;86 01:38:17 INFO - PROCESS | 1659 | |6;imaging-darkroom;115;99;100;101;99;101;169;170;97;122 01:38:17 INFO - PROCESS | 1659 | |7;imaging-desaturate;96;126;95;135;77;143;146;123;89;87 01:38:17 INFO - PROCESS | 1659 | |8;json-parse-financial;59;59;57;61;63;60;59;61;62;61 01:38:17 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;72;78;109;113;98;102;105;104;72;71 01:38:17 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;86;86;80;83;86;90;84;77;95;80 01:38:17 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;157;142;153;162;158;158;156;153;217;174 01:38:17 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;167;169;166;167;167;168;166;170;165;168 01:38:17 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;70;69;89;90;88;86;78;67;67;68 01:38:17 INFO - PROCESS | 1659 | __end_tp_report 01:38:17 INFO - PROCESS | 1659 | __start_cc_report 01:38:17 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,983 01:38:17 INFO - PROCESS | 1659 | __end_cc_report 01:38:17 INFO - PROCESS | 1659 | __startTimestamp1483609097492__endTimestamp 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | ------- Summary: start ------- 01:38:17 INFO - PROCESS | 1659 | Number of tests: 14 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:121.20 Median:126.00 stddev:12.91 (10.2%) stddev-sans-first:12.29 01:38:17 INFO - PROCESS | 1659 | Values: 105.0 119.0 119.0 121.0 119.0 121.0 131.0 99.0 139.0 139.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:138.00 stddev:14.94 (10.8%) stddev-sans-first:15.35 01:38:17 INFO - PROCESS | 1659 | Values: 143.0 111.0 130.0 133.0 152.0 144.0 149.0 119.0 133.0 111.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:208.90 Median:199.00 stddev:25.08 (12.6%) stddev-sans-first:17.78 01:38:17 INFO - PROCESS | 1659 | Values: 262.0 250.0 199.0 193.0 197.0 198.0 194.0 200.0 199.0 197.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:77.80 Median:76.00 stddev:16.27 (21.4%) stddev-sans-first:17.21 01:38:17 INFO - PROCESS | 1659 | Values: 81.0 63.0 72.0 101.0 51.0 62.0 80.0 85.0 101.0 82.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:78.50 stddev:8.81 (11.2%) stddev-sans-first:8.90 01:38:17 INFO - PROCESS | 1659 | Values: 85.0 71.0 90.0 71.0 70.0 71.0 87.0 69.0 72.0 88.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:99.10 Median:88.00 stddev:18.66 (21.2%) stddev-sans-first:14.23 01:38:17 INFO - PROCESS | 1659 | Values: 136.0 131.0 90.0 89.0 101.0 87.0 92.0 87.0 92.0 86.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:117.30 Median:169.50 stddev:28.66 (16.9%) stddev-sans-first:30.38 01:38:17 INFO - PROCESS | 1659 | Values: 115.0 99.0 100.0 101.0 99.0 101.0 169.0 170.0 97.0 122.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:111.70 Median:82.00 stddev:25.57 (31.2%) stddev-sans-first:26.49 01:38:17 INFO - PROCESS | 1659 | Values: 96.0 126.0 95.0 135.0 77.0 143.0 146.0 123.0 89.0 87.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:61.00 stddev:1.75 (2.9%) stddev-sans-first:1.80 01:38:17 INFO - PROCESS | 1659 | Values: 59.0 59.0 57.0 61.0 63.0 60.0 59.0 61.0 62.0 61.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:92.40 Median:71.50 stddev:17.04 (23.8%) stddev-sans-first:16.40 01:38:17 INFO - PROCESS | 1659 | Values: 72.0 78.0 109.0 113.0 98.0 102.0 105.0 104.0 72.0 71.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:86.00 stddev:5.23 (6.1%) stddev-sans-first:5.53 01:38:17 INFO - PROCESS | 1659 | Values: 86.0 86.0 80.0 83.0 86.0 90.0 84.0 77.0 95.0 80.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:163.00 Median:158.00 stddev:20.59 (13.0%) stddev-sans-first:21.72 01:38:17 INFO - PROCESS | 1659 | Values: 157.0 142.0 153.0 162.0 158.0 158.0 156.0 153.0 217.0 174.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:1.49 (0.9%) stddev-sans-first:1.58 01:38:17 INFO - PROCESS | 1659 | Values: 167.0 169.0 166.0 167.0 167.0 168.0 166.0 170.0 165.0 168.0 01:38:17 INFO - PROCESS | 1659 | 01:38:17 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.20 Median:82.00 stddev:10.05 (12.3%) stddev-sans-first:10.32 01:38:17 INFO - PROCESS | 1659 | Values: 70.0 69.0 89.0 90.0 88.0 86.0 78.0 67.0 67.0 68.0 01:38:17 INFO - PROCESS | 1659 | -------- Summary: end -------- 01:38:17 INFO - PROCESS | 1659 | 01:38:18 INFO - TEST-INFO | 1659: exit 0 01:38:18 INFO - TEST-OK | kraken | took 62076ms 01:38:18 INFO - SUITE-END | took 1179s 01:38:18 INFO - Completed test suite (00:19:39) 01:38:18 INFO - javascript benchmark 01:38:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7103.774264397915, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2886.567014947758, 3396.5075064115717, 3415.573053368329, 3421.2131209821723, 3473.2011637323417, 5051.191809310507, 5051.797928082877, 5806.419358064191, 5866.061430171173, 5891.116332550119, 5772.826815195541, 5855.502282306009, 5873.1190321451695, 5878.500327472159, 5919.37846526115, 5278.577713782898, 5291.470852914697, 5321.813736519222, 5396.730163491829, 5402.567794576444, 5298.788048478056, 5307.65500242483, 5388.568914486832, 5406.665575413067, 5448.618596698233, 5222.451642577551, 5263.315768950012, 5265.289185959885, 5307.73461326934, 5352.250684904077, 5192.584593232532, 5292.9735351323, 5294.973525132388, 5301.893962120775, 5303.814366497193, 4785.760711964388, 5159.58723302135, 5186.636935414523, 5191.454897235812, 5237.528622424, 5533.667979921218, 5766.394528574481, 5785.6528608283215, 5795.275590551181, 5813.854653633651, 6249.656268905208, 6251.937480625181, 6281.24625044993, 6281.842953926189, 6289.308176100625, 6296.716647750863, 6327.8734425311695, 6339.968300158515, 6346.2067241594805, 6350.460210882081, 4614.630829533629, 5076.847694569169, 5957.165996760457, 5992.250968628921, 5998.340182579913, 4797.544233297831, 5109.897802043938, 6269.62382257066, 6272.749090036439, 6301.936980630227, 5920.2895652521975, 6223.578208502792, 6232.781852635136, 6253.062040693932, 6274.843128921813, 6281.434670879551, 6303.590266632654, 6308.085327627468, 6330.335314791881, 6333.841653958596, 5208.5312321891215, 5744.798932037359, 6179.567430279927, 6217.4715149212825, 6231.532635052389, 6229.3770622937345, 6257.374262573706, 6286.717097730613, 6294.8111556653375, 6295.496360291166, 5024.698518088926, 5040.798368065236, 5045.924311135336, 5050.090983622909, 5058.342415485963, 4190.868465514294, 4259.680523960716, 4414.735115893057, 4424.933625995613, 4458.175237580993, 5283.599846040802, 5899.262592175978, 6021.126936594169, 6057.57596968208, 6169.96915015422, 6267.592606480564, 6296.496280297657, 6301.401366870232, 6336.17629708135, 6344.714487848058, 5777.220075289866, 5816.70916454184, 5854.736536855852, 5939.762409503658, 5943.673097979653, 5487.64330318528, 5505.532029777513, 5507.917381239285, 5520.779168833282, 5529.225908372831, 6425.036244563353, 6449.645269510222, 6475.222973243241, 6481.157449531531, 6486.962086066207, 6142.345304543789, 6168.1056246844855, 6215.440610345091, 6263.62418254888, 6291.842703932438, 6479.967600161969, 6482.319356467691, 6491.902621460587, 6496.935030649822, 6506.3818937200895, 6270.7491700331475, 6300.7479700811455, 6303.779367722108, 6310.337414571404, 6316.557840951089, 6202.162708034538, 6223.782167624111, 6237.28271248794, 6255.37446255389, 6267.0286105653695, 5180.870478238075, 6095.268567771718, 6133.417325354084, 6242.157308763441, 6259.092431597484, 4798.3042458843975, 6184.628922264678, 6215.378462153749, 6239.469645080219, 6263.530235232467, 5835.912461313169, 5871.295444546682, 5959.523238141025, 6036.124761909587, 6073.30157031947, 6098.512119030555, 6105.755769769159, 6128.4790792783115, 6134.987697083657, 6190.628562286277, 6036.6679832609625, 6041.969790151021, 6075.149479072845, 6089.147519347383, 6101.816945491642], "value": 5718.885186568117, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.577039697486, 468.6250999200639, 527.7176710459902, 531.9468053194681, 554.2905081495687, 30117.849410752944, 31284.68715312849, 36258.456123158176, 40616.390754138636, 40721.59278407215, 29415.411691766152, 37092.07269818256, 38777.030574235585, 40037.39943900837, 41964.0, 8062.959685201566, 9280.582373793162, 9460.385074970105, 9475.668351607696, 9488.146066853982, 9340.813183736322, 9412.717618471457, 9449.480278584675, 9450.66922657707, 9470.289728270373, 9498.335116541844, 9500.192483638899, 9536.713898583053, 9542.379745316532, 9550.283728720338, 8887.066857979884, 9418.058194180561, 9480.146786789153, 9503.144716975505, 9510.0, 8654.567271636393, 9334.20659243965, 9395.718128456158, 9397.530123493798, 9409.905900940972, 39397.01507462336, 39523.60476395228, 39573.80213098945, 39689.0, 39738.80783576497, 3418.743594230418, 3516.2967406518796, 3937.5471820740695, 3944.2900277949957, 3946.4080387941754, 28544.571831422545, 28706.856465717538, 29230.0, 30423.543646845315, 30584.38831223363, 3171.888983885553, 3201.6638252983334, 3253.658365871573, 3277.1807048237943, 3303.487959366302, 492007.0, 495522.0, 498332.0, 500445.0, 500641.0, 58524.707376463695, 58564.7071764647, 58691.70654146702, 58754.70622646945, 59000.70499647474, 60147.69926150341, 60630.0, 60776.0, 60782.08826867601, 60783.6960815202, 15782.921085394499, 15974.680506389806, 15988.280527376295, 15999.28003239857, 16062.277197526138, 12173.878261217274, 12313.199642023239, 12327.876721232853, 12345.567905123278, 12389.62831115068, 15471.458498952483, 15590.532284031497, 15790.447334343473, 15884.52346429609, 15897.887147899535, 3210.6949839765653, 5506.311711036121, 6706.698198581075, 6743.797686069426, 6752.966235168793, 862.5169904853302, 1002.9097381235724, 1057.0380953332585, 1058.6188971970084, 1066.8933106689424, 27035.1889443317, 29813.552796708067, 29965.700342996726, 30089.855462922966, 30540.931067412534, 824.0934971531268, 886.3352485635774, 1025.0876719719456, 1046.9371837689764, 1060.7772367802847, 14857.47998820036, 15575.065496070272, 15755.4485593006, 16029.519114426586, 16393.0, 680927.0, 681058.5947070332, 682059.0, 684013.0, 687552.0, 57893.710531447075, 58071.4192858066, 58339.416605834245, 58362.0, 58450.83098338095, 1337.5786627212397, 1346.0712108645005, 1391.6520869782555, 1393.2615713671973, 1395.6022533578084, 27846.0, 29955.400891982474, 30396.5440518388, 30450.26761376093, 31061.0, 49517.752411237714, 50186.74906625444, 50218.748906255234, 50258.0, 50351.49648503468, 617199.0, 618100.0, 620418.0, 620963.0, 622329.0, 24704.0, 26172.083977060713, 26543.33641658897, 26578.936842526084, 26582.86708566522, 44539.55460445484, 44802.77598611986, 44868.0, 45097.32354014758, 45655.54344456646, 36565.08587285339, 36928.63071369252, 37232.576763712255, 37345.879623611334, 37904.0, 37308.0, 37346.813265933495, 37932.620673794016, 38355.616443835206, 38659.0, 31132.0, 31713.365732685677, 35583.46624800578, 36304.45543316905, 36586.085347866516, 26966.191014269603, 27421.725782741916, 31084.37831243317, 34003.319933601684, 34257.458414370914, 22795.772042280027, 23006.654900176178, 25480.490390192463, 25700.742992569834, 25819.225423237334], "value": 19364.705675316807, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.46466597780676, 349.470552113548, 358.8008655196365, 371.079722288724, 376.6102084342706, 18361.72457413138, 18855.622887542257, 20015.799842001576, 20016.199352025902, 20282.797172028277, 18902.14940327685, 18957.33649322274, 19135.521611959673, 19164.850108993436, 19245.03774811261, 5737.913931291024, 5939.0, 5981.730822112993, 5992.640441573498, 5995.940040599604, 5548.611597188198, 5814.5057670098095, 5876.265466816648, 5877.441643043925, 5888.499477544414, 5685.061964775807, 5804.651720896739, 5824.359320474744, 5836.124581312794, 5847.44449277318, 4858.659893807435, 5300.867478313035, 5392.624880655447, 5682.346530149044, 5783.219265399181, 3963.7027222958104, 4700.929486057712, 4744.288356746481, 5097.2608971699265, 5139.8458046258675, 22212.0, 24307.513849723084, 24370.634440483405, 24576.754232457624, 24710.629340559906, 2392.3899405651446, 2414.770596793302, 2425.4057755849753, 2851.2301678546883, 3029.909102726922, 14976.0, 15238.238088095552, 15349.232538373148, 15636.530904072895, 16024.038557686576, 1777.7244527098321, 1788.8390044895891, 1806.6567352203172, 1845.1235663060074, 1849.029259638687, 96510.0, 117888.0, 119192.0, 123814.38092809568, 124318.37840810738, 10594.205434592437, 10665.200109991627, 11052.778944421067, 11064.502097405637, 11120.888791112147, 13691.178529288274, 13790.65523361924, 13819.792703109462, 13831.962602804628, 13836.30818459073, 8211.466254693425, 8440.155984401634, 8502.319814414832, 8570.400071995025, 8621.439606425562, 8383.916160838435, 8445.1977062179, 8455.069942306342, 8504.617292221865, 8618.612162452579, 6541.28045914949, 6663.0683984569705, 6668.799936001928, 6679.165104361955, 6693.330666933268, 1744.546417931347, 1813.8844610564672, 1841.6371884805149, 1873.1102726205215, 1924.4034349351746, 875.9343049271203, 953.3707752883064, 977.3793641037992, 1019.9949000254951, 1025.6820385680462, 20962.475938101365, 21632.675509867364, 21707.782922170893, 21865.906704664703, 21873.453163671034, 916.9174774270348, 958.9041095890494, 970.4177493503842, 979.4612962870393, 1004.2275383947339, 9658.951705241569, 10988.670339889815, 11521.308721476738, 11656.94171529154, 11665.825012624817, 434210.0, 592620.0, 606866.0, 617070.0, 617270.0, 106983.0, 107756.46121769342, 112730.0, 117533.0, 119811.0, 870.2341939093557, 928.4058202750246, 942.2650332740474, 991.45965448832, 1003.9196864250843, 18305.90847045756, 18778.624427511648, 18934.715979260596, 18940.3370882024, 19141.33357187937, 55665.0, 56106.438935610124, 56170.0, 57447.13829292481, 57808.4219157803, 77039.0, 77486.61256693906, 78406.6079669598, 78413.60793195998, 78445.0, 8162.551059691774, 8165.061017983008, 8232.464889782144, 8445.40882138269, 8453.239208471266, 12087.774795979902, 12861.614151575468, 13105.934470327587, 13184.417869855679, 13237.271950042456, 11079.50142243585, 11080.889191107986, 11146.55413783472, 11407.657770266906, 11414.401983722451, 11037.0, 11044.282121662387, 11061.778764424505, 11233.32600044, 11371.033462155809, 11087.72280692989, 11282.0, 11326.32042077478, 11617.244879082833, 11776.528938842694, 6721.529492935448, 7393.371563417169, 7534.35957943581, 8238.958805205935, 8409.53747543891, 8449.619767110371, 10712.839307410552, 10921.945390272998, 11089.667309980714, 11306.434678266218], "value": 9632.463954226416, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.0917812599935, 1421.5877395555285, 1581.9920900395502, 1729.688656041912, 1739.7738294021767, 7550.169481357047, 7801.063872335328, 8065.8386832263395, 8553.016403113645, 8585.527795971218, 6565.096122124578, 7660.233976602337, 8028.157043510435, 8319.33445324374, 8343.207395297452, 1959.6668566343685, 2299.678045073687, 2357.964630530535, 2358.9528209435803, 2360.4806942472715, 2347.319277409549, 2374.1809075868796, 2382.845115067515, 2384.713834339873, 2390.0917651292484, 1965.2433812982033, 2327.1273272522803, 2329.1382188590246, 2349.95300093998, 2353.62342025276, 1893.7916829148783, 2262.2082271205113, 2264.479169790949, 2276.3967548599635, 2287.7597852225445, 2104.4949212189117, 2123.490362313049, 2280.680704701343, 2307.665388518672, 2320.431494283894, 8378.706745263948, 8427.073021967579, 8429.32565394767, 8438.873416898752, 8443.155684431507, 5091.898162036776, 5241.50205730455, 5247.475252474722, 5260.421353651095, 5272.446393128743, 3144.6540880503353, 3252.1869532616847, 3501.6148223695373, 3511.4206155984234, 3564.322778672044, 1007.2495990487134, 1038.5689938675441, 1042.525650828871, 1047.7328281288299, 1055.7782865598233, 10460.790784184273, 10764.515596798163, 10832.537607422975, 10856.620018299322, 11054.668359949295, 4368.213721530092, 4424.159409712144, 4509.143262780061, 4558.703684260512, 4558.817647294071, 8596.441231319945, 8710.433821801562, 8716.215540601377, 8723.738287851374, 8725.040245572982, 5011.899762004812, 5137.897242055138, 5141.305923700327, 5152.742362881841, 5160.200168973815, 3943.8028098594955, 4113.71204015722, 4276.144771045741, 4489.820407183676, 4795.112904112751, 3567.9643203568153, 3570.410882204459, 3660.762050466711, 3680.4479328100997, 3681.3557627415094, 991.2317953585884, 1088.3469918049266, 1111.183280288987, 1115.5649296774266, 1125.8930401611833, 867.4708427859127, 884.8584226523725, 890.5146695051212, 891.8483857744199, 909.7816524034316, 5163.586913046947, 5221.765339933772, 5536.501714845684, 5586.972065139648, 5632.774689012475, 421.8903085197871, 439.1261389834207, 454.42742144897665, 454.6998980672806, 458.19357930042696, 2922.327864591156, 3548.982255088708, 3571.767835090711, 3663.175785448253, 3678.5217921670546, 8573.399862009599, 9836.704898853046, 10134.898651013396, 10245.077942985166, 10248.38509689421, 10132.54403551842, 10197.18422526196, 10199.643012494527, 10213.642522511676, 10223.182145428347, 6547.836304092436, 7146.963690264986, 7155.105611798525, 7204.0, 7989.0812556555575, 4648.581627653527, 4908.63185261114, 5080.796768129233, 5334.333208348957, 5475.890482190254, 9378.296627752808, 9447.149756521945, 9462.716118516455, 9512.904870951203, 9542.379745316532, 9407.717768466957, 9575.0, 9658.75853103678, 9760.902390976284, 9802.803943921224, 6232.781852635136, 6457.0, 6635.170603674541, 6810.8637827244165, 6812.863742725019, 7740.496867703581, 7941.642626081955, 7947.403944704286, 8078.111407745261, 8094.473859199134, 6171.783987560593, 7125.714971401086, 7204.135503739375, 7273.490855640054, 7380.520266182681, 7086.185088714864, 7147.356737893615, 7165.426765858614, 7195.82010449722, 7198.928010720036, 6488.610683359014, 6642.800715978528, 6743.696533655899, 6782.762603308861, 6817.3182681733415, 4758.119747846659, 4798.256270278112, 4806.423229212517, 4835.734034628129, 4843.491433399583, 4716.528347165256, 4737.28940658904, 4798.1843086676045, 4896.510348965217, 4951.554360107608], "value": 4228.629680456776, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.98943036993694, 304.1544506272562, 306.15959192017914, 312.0404755377216, 316.13694613704587, 4441.378207051015, 4783.569478746912, 5293.444188360215, 5472.50747432731, 5496.203050557671, 5270.288511051017, 5286.3392075990505, 5367.570594352453, 5369.154348195453, 5428.83713488596, 3322.80063196209, 4134.565870583583, 4154.813033413504, 4173.56177601352, 4174.582541745816, 3636.4181730923056, 3917.471141395918, 4022.678185745148, 4035.0833354156835, 4137.710360274782, 3596.946045809315, 3970.924485537133, 4062.857799976987, 4094.09624524596, 4117.938230926538, 3029.530422784483, 3806.505154329947, 3890.6929110256197, 4011.939820902689, 4112.157007813391, 2808.3540785619216, 3032.9848350758325, 3551.325248202859, 3596.6583174598372, 3597.6941959933174, 4233.666395085538, 4970.0, 5244.632875698702, 5276.419593844674, 5329.307190065306, 3506.4564992426212, 4490.528494508062, 4688.382508034722, 4699.530046995274, 4718.504557021498, 5176.145935920568, 5196.272521846944, 5526.889462210734, 5715.828525144252, 5757.1652110444165, 3319.717823984964, 3954.6045395460224, 3968.6626636735623, 3970.68234541236, 3971.920561588781, 5420.728963551846, 5951.3751056139345, 5971.283445986466, 5971.790987315467, 5985.9401405986255, 3745.0637340664834, 3768.6419790119894, 4017.055991841973, 4027.677785777131, 4052.2908491013955, 3888.8055597220027, 4051.169510250392, 4064.7154699171365, 4068.6745060395688, 4120.381942708557, 4135.958640413557, 4328.978355108267, 4704.059188162381, 4716.528347165325, 4725.503822098699, 5142.537171654494, 5665.818659816001, 5751.309842818889, 5757.99819125712, 5819.82540523785, 1032.4631191780381, 1131.9603813866474, 1258.7545428641326, 1269.4224128021729, 1331.8268625078679, 779.8167430653905, 796.8884356190138, 903.8147179828251, 916.5921165081608, 919.544825311475, 4351.564843515623, 4504.0, 4611.492735799059, 4885.750975601257, 5001.474845141281, 4981.726005069756, 5439.265699130567, 5488.694748205683, 5535.750891209906, 5544.944550554443, 4198.769067701244, 4645.814167433333, 4856.465788763233, 4989.950100498949, 5011.974940125276, 2294.5640328337563, 2453.3989172652814, 2565.8575089800224, 2903.8548072596286, 2925.581093169811, 4627.236505976476, 4628.884277893012, 5494.313210848644, 5725.679106118441, 5731.971340143273, 4436.844710435119, 5321.946780532223, 5496.835094947158, 5669.31968163823, 5673.3475650299915, 4654.115718013567, 4853.476192380013, 5275.577953763766, 5626.074441290506, 5659.858503537444, 4576.8626941191815, 5097.592192624656, 5107.591392688651, 5475.370332411664, 5539.030669632718, 5372.274742909657, 5459.617826752089, 5489.886393738359, 5507.421720719427, 5895.14520394549, 5439.646422982493, 5477.67133971963, 6176.66028368444, 6207.81376558704, 6219.813405597839, 3399.9150021249666, 3620.9094772630897, 3811.2568049230135, 4109.958900411078, 4116.259073366703, 5065.290859279778, 5378.1663842104535, 5961.463468287875, 5968.880622387441, 5972.761089556369, 4389.956100439083, 4852.102361063215, 4948.21461581883, 5859.007937301338, 5884.9705751470965, 4950.643433778252, 5047.470015648304, 5720.599558030898, 5834.241548598655, 5887.735051922588, 4666.976665116653, 5338.985592737367, 5422.376426710821, 5675.0, 5687.1753595729215, 5792.333881603669, 5794.507466865195, 5802.506786923159, 5806.767729290781, 5833.854153646193, 5645.745941432728, 5668.178114173339, 5669.291338582677, 5679.119736440858, 5705.001624715575], "value": 4032.3951783651664, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.0421708398225, 677.3429773120072, 677.8237658208865, 743.9814004649883, 772.4091070331195, 14183.219922904236, 15053.247337633102, 15058.247087645601, 15068.849311506881, 15100.320485578162, 13436.92504599632, 13490.865091349084, 13559.389827457764, 13578.388972496237, 13611.523596674118, 776.809681628002, 804.3324041045933, 821.2198411509061, 822.7984143884756, 832.8042909916165, 822.5147163173726, 823.4030328012203, 824.4558591329724, 825.9132791056912, 827.2141465607667, 855.9529225892574, 858.7423772868145, 860.8149247911698, 861.7457849934284, 865.8917635295588, 846.2764336492272, 852.3947996922193, 855.7689423855555, 856.1267507142679, 857.3141486810558, 640.3884290502559, 640.961542307463, 644.24301445801, 645.0549944331577, 646.1406329581645, 13997.3701183447, 14274.286285685674, 14288.0, 14487.92756036213, 14544.927275363661, 10131.392116472962, 10652.094571961392, 12146.028317734648, 13205.933970330183, 13212.0, 11053.28153670009, 11235.494402751814, 11317.207795454322, 11406.71483212918, 11408.657740267723, 3513.7540372173958, 3583.1937813991904, 3650.0692323457347, 3662.7619204751604, 3766.868159614428, 53653.0, 72509.63745181293, 81617.18382816155, 84395.0, 87376.68934965917, 9159.404638698463, 10062.798744025225, 10202.846957295647, 10288.691339259834, 10344.741381465523, 19860.304889329094, 19861.0, 19948.60102797936, 20208.595828083355, 20321.085551150234, 7368.226336234726, 7395.0, 7421.591812450259, 7433.219511951275, 7626.351760100453, 4073.5926407359025, 4130.339145736668, 4220.113776107052, 4232.470941132358, 4261.232978063917, 2761.0, 3313.867445302209, 3389.2882494676396, 3413.5391722117693, 3422.281320922634, 616.1097214524968, 637.9457746091541, 639.5970538560676, 644.0243031806816, 652.8302641313197, 543.5864760463608, 643.1028714942722, 643.6717274190103, 645.1484041065842, 686.0669489494384, 8690.695825646071, 8790.604422800861, 8856.24721898633, 9128.58921348541, 9198.172164505093, 517.3403910014762, 638.1990601794811, 661.388817058864, 669.7321071571411, 678.4199509419474, 4176.935919860051, 4423.668224883147, 4441.955580444219, 4449.57729015743, 4491.281394976788, 69195.92412227641, 101203.0, 104734.0, 112641.0, 114843.42578287222, 64297.0, 65575.67212163909, 66090.0, 93291.53354233186, 93575.0, 74052.62973685097, 74062.62968685229, 74186.99999999892, 74941.25058749452, 77742.6112869432, 8933.553322333728, 9207.987121416505, 9595.23238140961, 9617.855732164164, 9671.316625344787, 35978.28043439064, 41675.7916210417, 49013.0, 56411.15383269172, 57123.714381427824, 75818.0, 77073.0, 78090.6095469519, 80277.0, 80281.0, 11936.880631193577, 11993.700157496132, 12155.817662735242, 12186.006859771223, 12294.446749896097, 13045.673858153623, 13208.735825283633, 13610.795838062239, 13931.791023134458, 13955.0, 12681.619551413472, 12864.163829351062, 12967.22196668203, 13341.86658133445, 13383.330833458484, 10785.191110666574, 11721.355325457182, 12440.69372715888, 12905.677358066123, 12931.482740690268, 10276.794464110826, 10700.732481688021, 12406.751864962469, 12525.812112818132, 13042.608721738363, 10903.400312982862, 10976.0, 11059.059979901798, 11172.664820055412, 11251.7749645005, 10779.89220107789, 10893.727656808644, 11115.777684446428, 11137.498812553617, 11159.553617855196], "value": 7064.21640262763, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 119.0, 119.0, 121.0, 119.0, 121.0, 131.0, 99.0, 139.0, 139.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 130.0, 133.0, 152.0, 144.0, 149.0, 119.0, 133.0, 111.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [262.0, 250.0, 199.0, 193.0, 197.0, 198.0, 194.0, 200.0, 199.0, 197.0], "value": 208.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 63.0, 72.0, 101.0, 51.0, 62.0, 80.0, 85.0, 101.0, 82.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 90.0, 71.0, 70.0, 71.0, 87.0, 69.0, 72.0, 88.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 131.0, 90.0, 89.0, 101.0, 87.0, 92.0, 87.0, 92.0, 86.0], "value": 99.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 99.0, 100.0, 101.0, 99.0, 101.0, 169.0, 170.0, 97.0, 122.0], "value": 117.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 126.0, 95.0, 135.0, 77.0, 143.0, 146.0, 123.0, 89.0, 87.0], "value": 111.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 57.0, 61.0, 63.0, 60.0, 59.0, 61.0, 62.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 109.0, 113.0, 98.0, 102.0, 105.0, 104.0, 72.0, 71.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 80.0, 83.0, 86.0, 90.0, 84.0, 77.0, 95.0, 80.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 142.0, 153.0, 162.0, 158.0, 158.0, 156.0, 153.0, 217.0, 174.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 166.0, 167.0, 167.0, 168.0, 166.0, 170.0, 165.0, 168.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 89.0, 90.0, 88.0, 86.0, 78.0, 67.0, 67.0, 68.0], "value": 77.2, "unit": "score"}]}]} 01:38:18 INFO - Return code: 0 01:38:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:38:18 ERROR - # TBPL SUCCESS # 01:38:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:38:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:38:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:38:18 INFO - 2017-01-05 01:38:18.822 screenresolution[1664:10575] starting screenresolution argv=screenresolution get 01:38:18 INFO - 2017-01-05 01:38:18.824 screenresolution[1664:10575] Display 0: 1600x1200x32@60 01:38:18 INFO - 2017-01-05 01:38:18.831 screenresolution[1665:10578] starting screenresolution argv=screenresolution list 01:38:18 INFO - Available Modes on Display 0 01:38:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:38:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:38:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:38:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:38:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:38:19 INFO - Intel Iris: 01:38:19 INFO - Chipset Model: Intel Iris 01:38:19 INFO - Type: GPU 01:38:19 INFO - Bus: Built-In 01:38:19 INFO - VRAM (Dynamic, Max): 1536 MB 01:38:19 INFO - Vendor: Intel (0x8086) 01:38:19 INFO - Device ID: 0x0a2e 01:38:19 INFO - Revision ID: 0x0009 01:38:19 INFO - Displays: 01:38:19 INFO - Display: 01:38:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:38:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:38:19 INFO - Display Serial Number: 1600x1200 60 01:38:19 INFO - Main Display: Yes 01:38:19 INFO - Mirror: Off 01:38:19 INFO - Online: Yes 01:38:19 INFO - Rotation: Supported 01:38:19 INFO - Return code: 0 01:38:19 INFO - Running post-action listener: _resource_record_post_action 01:38:19 INFO - [mozharness: 2017-01-05 09:38:19.072799Z] Finished run-tests step (success) 01:38:19 INFO - Running post-run listener: _resource_record_post_run 01:38:19 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38787584; Write bytes: 661085696; Read time: 243; Write time: 1953 01:38:19 INFO - TinderboxPrint: CPU usage
54.2% 01:38:19 INFO - TinderboxPrint: I/O read bytes / time
38,787,584 / 243 01:38:19 INFO - TinderboxPrint: I/O write bytes / time
661,085,696 / 1,953 01:38:19 INFO - TinderboxPrint: CPU idle
2,217.1 (45.8%) 01:38:19 INFO - TinderboxPrint: CPU system
975.1 (20.1%) 01:38:19 INFO - TinderboxPrint: CPU user
1,653.6 (34.1%) 01:38:19 INFO - TinderboxPrint: Swap in / out
388,149,248 / 0 01:38:19 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342966784; Write bytes: 336771072; Read time: 26822; Write time: 725 01:38:19 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36989440; Write bytes: 308039680; Read time: 228; Write time: 1182 01:38:19 INFO - Running post-run listener: _upload_blobber_files 01:38:19 INFO - Blob upload gear active. 01:38:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:38:19 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 01:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:38:19 INFO - (blobuploader) - INFO - Open directory for files ... 01:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:38:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:38:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:38:20 INFO - (blobuploader) - INFO - Done attempting. 01:38:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:38:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:38:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:38:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:38:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:38:21 INFO - (blobuploader) - INFO - Done attempting. 01:38:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:38:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:38:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:38:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:38:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:38:24 INFO - (blobuploader) - INFO - Done attempting. 01:38:24 INFO - (blobuploader) - INFO - Iteration through files over. 01:38:24 INFO - Return code: 0 01:38:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:38:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:38:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f60c6ab5138d9e6709901fb28bbdbff35bf0acdbf780a43ab25a339269221b66cac17264f163dfcecd8489a7284d5e58ba25ab2c5a232db246c84a9f94a0614", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed898427d60efaf9137c170ec0e506efce7dc240a92c00f4a425366f52cf6ea5fcfd4af3854f10b1cc17e10f00d29413a30689048b5ace1e0409883efd516b43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f058d0d36a0e6385abdf668fb5ffc5f063c50ec37de7bf6600af5228e5510e431886d848a4ea02f81992f073d6de0ff2ade09797121180a6aaac18fc28d508ec"} 01:38:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:38:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:38:24 INFO - Contents: 01:38:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f60c6ab5138d9e6709901fb28bbdbff35bf0acdbf780a43ab25a339269221b66cac17264f163dfcecd8489a7284d5e58ba25ab2c5a232db246c84a9f94a0614", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed898427d60efaf9137c170ec0e506efce7dc240a92c00f4a425366f52cf6ea5fcfd4af3854f10b1cc17e10f00d29413a30689048b5ace1e0409883efd516b43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f058d0d36a0e6385abdf668fb5ffc5f063c50ec37de7bf6600af5228e5510e431886d848a4ea02f81992f073d6de0ff2ade09797121180a6aaac18fc28d508ec"} 01:38:24 INFO - Running post-run listener: copy_logs_to_upload_dir 01:38:24 INFO - Copying logs to upload dir... 01:38:24 INFO - mkdir: /builds/slave/test/build/upload/logs 01:38:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.592008 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-05 01:38:24.350464) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 01:38:24.358018) ========= 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.KPpataFYDX/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.qzVtnpMpVD/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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/Mozilla-Aurora/sha512/9f60c6ab5138d9e6709901fb28bbdbff35bf0acdbf780a43ab25a339269221b66cac17264f163dfcecd8489a7284d5e58ba25ab2c5a232db246c84a9f94a0614", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed898427d60efaf9137c170ec0e506efce7dc240a92c00f4a425366f52cf6ea5fcfd4af3854f10b1cc17e10f00d29413a30689048b5ace1e0409883efd516b43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f058d0d36a0e6385abdf668fb5ffc5f063c50ec37de7bf6600af5228e5510e431886d848a4ea02f81992f073d6de0ff2ade09797121180a6aaac18fc28d508ec"} build_url:https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010426 build_url: 'https://queue.taskcluster.net/v1/task/JK7rfWyATpCsREOUc4kBxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f60c6ab5138d9e6709901fb28bbdbff35bf0acdbf780a43ab25a339269221b66cac17264f163dfcecd8489a7284d5e58ba25ab2c5a232db246c84a9f94a0614", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed898427d60efaf9137c170ec0e506efce7dc240a92c00f4a425366f52cf6ea5fcfd4af3854f10b1cc17e10f00d29413a30689048b5ace1e0409883efd516b43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f058d0d36a0e6385abdf668fb5ffc5f063c50ec37de7bf6600af5228e5510e431886d848a4ea02f81992f073d6de0ff2ade09797121180a6aaac18fc28d508ec"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 01:38:24.394835) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 01:38:24.395173) ========= 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.KPpataFYDX/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.qzVtnpMpVD/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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.005040 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 01:38:24.443342) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 01:38:24.446667) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 01:38:24.447013) ========= ========= Total master_lag: 0.19 =========