builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0392 starttime: 1483485058.69 results: success (0) buildid: 20170103123839 builduid: 1c2b18bb8e7c44219fea461122b93b77 revision: 236b90305efbbc79f66ca1453be47f3688b26c7a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 15:10:58.692129) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 15:10:58.692605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 15:10:58.692889) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 15:10:58.720896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 15:10:58.721286) ========= 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.RS61RIAU6E/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.qnBzGP766e/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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-03 15:10:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.0M=0.001s 2017-01-03 15:10:58 (18.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078236 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 15:10:58.823324) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 15:10:58.825907) ========= 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.RS61RIAU6E/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.qnBzGP766e/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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.024239 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 15:10:58.875217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 15:10:58.875590) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 236b90305efbbc79f66ca1453be47f3688b26c7a --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 236b90305efbbc79f66ca1453be47f3688b26c7a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RS61RIAU6E/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.qnBzGP766e/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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-03 15:10:58,971 truncating revision to first 12 chars 2017-01-03 15:10:58,971 Setting DEBUG logging. 2017-01-03 15:10:58,971 attempt 1/10 2017-01-03 15:10:58,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/236b90305efb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-03 15:11:00,073 unpacking tar archive at: mozilla-aurora-236b90305efb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.353398 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 15:11:00.255392) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 15:11:00.255720) ========= script_repo_revision: 236b90305efbbc79f66ca1453be47f3688b26c7a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 15:11:00.256126) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 15:11:00.256502) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 15:11:00.276317) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-03 15:11:00.276739) ========= /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.RS61RIAU6E/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.qnBzGP766e/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:11:00 INFO - MultiFileLogger online at 20170103 15:11:00 in /builds/slave/test 15:11:00 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 15:11:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:11:00 INFO - {'append_to_log': False, 15:11:00 INFO - 'base_work_dir': '/builds/slave/test', 15:11:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:11:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:11:00 INFO - 'branch': 'Mozilla-Aurora', 15:11:00 INFO - 'buildbot_json_path': 'buildprops.json', 15:11:00 INFO - 'config_files': ('talos/mac_config.py',), 15:11:00 INFO - 'default_actions': ('clobber', 15:11:00 INFO - 'read-buildbot-config', 15:11:00 INFO - 'download-and-extract', 15:11:00 INFO - 'populate-webroot', 15:11:00 INFO - 'create-virtualenv', 15:11:00 INFO - 'install', 15:11:00 INFO - 'run-tests'), 15:11:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:11:00 INFO - 'download_minidump_stackwalk': True, 15:11:00 INFO - 'download_symbols': 'ondemand', 15:11:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:11:00 INFO - 'tooltool.py': '/tools/tooltool.py', 15:11:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:11:00 INFO - '/tools/misc-python/virtualenv.py')}, 15:11:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:11:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:11:00 INFO - 'installer_path': 'installer.exe', 15:11:00 INFO - 'log_level': 'info', 15:11:00 INFO - 'log_name': 'talos', 15:11:00 INFO - 'log_to_console': True, 15:11:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:11:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:11:00 INFO - 'opt_config_files': (), 15:11:00 INFO - 'pip_index': False, 15:11:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:11:00 INFO - 'cmd': ('bash', 15:11:00 INFO - '-c', 15:11:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:11:00 INFO - 'enabled': True, 15:11:00 INFO - 'halt_on_failure': False, 15:11:00 INFO - 'name': 'check_screen_resolution'},), 15:11:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:11:00 INFO - 'cmd': ('bash', 15:11:00 INFO - '-c', 15:11:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:11:00 INFO - 'enabled': True, 15:11:00 INFO - 'halt_on_failure': False, 15:11:00 INFO - 'name': 'check_screen_resolution'},), 15:11:00 INFO - 'run_cmd_checks_enabled': True, 15:11:00 INFO - 'sps_profile': False, 15:11:00 INFO - 'sps_profile_interval': 0, 15:11:00 INFO - 'suite': 'dromaeojs-e10s', 15:11:00 INFO - 'system_bits': '32', 15:11:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:11:00 INFO - 'title': 't-yosemite-r7-0392', 15:11:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:11:00 INFO - 'use_talos_json': True, 15:11:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:11:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:11:00 INFO - 'work_dir': 'build'} 15:11:00 INFO - [mozharness: 2017-01-03 23:11:00.478016Z] Running clobber step. 15:11:00 INFO - Running pre-action listener: _resource_record_pre_action 15:11:00 INFO - Running main action method: clobber 15:11:00 INFO - rmtree: /builds/slave/test/build 15:11:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:11:02 INFO - Running post-action listener: _resource_record_post_action 15:11:02 INFO - [mozharness: 2017-01-03 23:11:02.607136Z] Finished clobber step (success) 15:11:02 INFO - [mozharness: 2017-01-03 23:11:02.607274Z] Running read-buildbot-config step. 15:11:02 INFO - Running pre-action listener: _resource_record_pre_action 15:11:02 INFO - Running main action method: read_buildbot_config 15:11:02 INFO - Using buildbot properties: 15:11:02 INFO - { 15:11:02 INFO - "project": "", 15:11:02 INFO - "product": "firefox", 15:11:02 INFO - "script_repo_revision": "production", 15:11:02 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:11:02 INFO - "repository": "", 15:11:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:11:02 INFO - "buildid": "20170103123839", 15:11:02 INFO - "pgo_build": "False", 15:11:02 INFO - "basedir": "/builds/slave/test", 15:11:02 INFO - "buildnumber": 59, 15:11:02 INFO - "slavename": "t-yosemite-r7-0392", 15:11:02 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:11:02 INFO - "platform": "yosemite_r7", 15:11:02 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:11:02 INFO - "branch": "mozilla-aurora", 15:11:02 INFO - "repo_path": "releases/mozilla-aurora", 15:11:02 INFO - "revision": "236b90305efbbc79f66ca1453be47f3688b26c7a", 15:11:02 INFO - "stage_platform": "macosx64", 15:11:02 INFO - "builduid": "1c2b18bb8e7c44219fea461122b93b77", 15:11:02 INFO - "slavebuilddir": "test" 15:11:02 INFO - } 15:11:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:11:02 INFO - Running post-action listener: _resource_record_post_action 15:11:02 INFO - [mozharness: 2017-01-03 23:11:02.611408Z] Finished read-buildbot-config step (success) 15:11:02 INFO - [mozharness: 2017-01-03 23:11:02.611552Z] Running download-and-extract step. 15:11:02 INFO - Running pre-action listener: _resource_record_pre_action 15:11:02 INFO - Running main action method: download_and_extract 15:11:02 INFO - mkdir: /builds/slave/test/build/tests 15:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:02 INFO - trying https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:11:02 INFO - Downloading https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/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 15:11:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/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 15:11:03 INFO - Downloaded 1199 bytes. 15:11:03 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:11:03 INFO - Using the following test package requirements: 15:11:03 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:11:03 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:03 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:11:03 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:03 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:11:03 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:03 INFO - u'jsshell-mac.zip'], 15:11:03 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:03 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:11:03 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:11:03 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:03 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:11:03 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:03 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:11:03 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:03 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:11:03 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:11:03 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:11:03 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:11:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:11:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:11:03 INFO - Fetch https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:11:04 INFO - Http code: 200 15:11:04 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 15:11:04 INFO - x-amz-cf-id: dhvhXPvJOWQBcV9dn_T4-81Jvhdfzg1cZuXAWRlkIv3PgVHLFt4wOQ== 15:11:04 INFO - x-amz-version-id: mVP6VlV_9ELOPxsMxVzTn8C_fumCIpxj 15:11:04 INFO - Expected file size: 16269878 15:11:04 INFO - Obtained file size: 16269878 15:11:11 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 15:11:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:11:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:11:11 INFO - Fetch https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:11:11 INFO - Http code: 200 15:11:11 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 15:11:11 INFO - x-amz-cf-id: paYslfftICnVaDZjoGEzY_iDPoaZkwfunis1P34nRIrLKSo0k1PMGw== 15:11:11 INFO - x-amz-version-id: mVP6VlV_9ELOPxsMxVzTn8C_fumCIpxj 15:11:12 INFO - Expected file size: 16269878 15:11:12 INFO - Obtained file size: 16269878 15:11:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 15:11:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:11:18 INFO - Fetch https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 15:11:19 INFO - Http code: 200 15:11:19 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 15:11:19 INFO - x-amz-cf-id: wBajLkF5SZf0Y87fUFwJDq-FUZcFEENzhOtBdmuv64XzNPSF37ETag== 15:11:19 INFO - x-amz-version-id: 3y5HG1N0E5AN0JkV2SUnS.m5JvucQ56P 15:11:19 INFO - Expected file size: 14409038 15:11:19 INFO - Obtained file size: 14409038 15:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:20 INFO - trying https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:20 INFO - Downloading https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 15:11:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/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 15:11:22 INFO - Downloaded 109832005 bytes. 15:11:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:22 INFO - mkdir: /builds/slave/test/properties 15:11:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:11:22 INFO - Writing to file /builds/slave/test/properties/build_url 15:11:22 INFO - Contents: 15:11:22 INFO - build_url:https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:11:23 INFO - Running post-action listener: _resource_record_post_action 15:11:23 INFO - Running post-action listener: set_extra_try_arguments 15:11:23 INFO - [mozharness: 2017-01-03 23:11:23.785808Z] Finished download-and-extract step (success) 15:11:23 INFO - [mozharness: 2017-01-03 23:11:23.785906Z] Running populate-webroot step. 15:11:23 INFO - Running pre-action listener: _resource_record_pre_action 15:11:23 INFO - Running main action method: populate_webroot 15:11:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:11:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:11:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:11:23 INFO - '--tppagecycles', 15:11:23 INFO - '7'], 15:11:23 INFO - 'tests': ['tsvgm']}}, 15:11:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:11:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:11:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:11:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:11:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:23 INFO - 'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:23 INFO - 'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['damp', 'tps']}, 15:11:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:23 INFO - 'tests': ['damp', 'tps']}, 15:11:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['dromaeo_dom']}, 15:11:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:11:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['basic_compositor_video']}, 15:11:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:11:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['a11yr', 15:11:23 INFO - 'ts_paint', 15:11:23 INFO - 'tpaint', 15:11:23 INFO - 'sessionrestore', 15:11:23 INFO - 'sessionrestore_no_auto_restore', 15:11:23 INFO - 'tabpaint']}, 15:11:23 INFO - 'other-e10s': {'tests': ['a11yr', 15:11:23 INFO - 'ts_paint', 15:11:23 INFO - 'tpaint', 15:11:23 INFO - 'sessionrestore', 15:11:23 INFO - 'sessionrestore_no_auto_restore', 15:11:23 INFO - 'tabpaint']}, 15:11:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:11:23 INFO - 'ts_paint', 15:11:23 INFO - 'tpaint', 15:11:23 INFO - 'sessionrestore', 15:11:23 INFO - 'sessionrestore_no_auto_restore', 15:11:23 INFO - 'tabpaint']}, 15:11:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:11:23 INFO - 'ts_paint', 15:11:23 INFO - 'tpaint', 15:11:23 INFO - 'sessionrestore', 15:11:23 INFO - 'sessionrestore_no_auto_restore', 15:11:23 INFO - 'tabpaint']}, 15:11:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['a11yr', 15:11:23 INFO - 'ts_paint', 15:11:23 INFO - 'tpaint', 15:11:23 INFO - 'sessionrestore', 15:11:23 INFO - 'sessionrestore_no_auto_restore', 15:11:23 INFO - 'tabpaint']}, 15:11:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['a11yr', 15:11:23 INFO - 'ts_paint', 15:11:23 INFO - 'tpaint', 15:11:23 INFO - 'sessionrestore', 15:11:23 INFO - 'sessionrestore_no_auto_restore', 15:11:23 INFO - 'tabpaint']}, 15:11:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['tsvgx', 15:11:23 INFO - 'tsvgr_opacity', 15:11:23 INFO - 'tart', 15:11:23 INFO - 'tscrollx', 15:11:23 INFO - 'cart']}, 15:11:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:11:23 INFO - 'tsvgr_opacity', 15:11:23 INFO - 'tart', 15:11:23 INFO - 'tscrollx', 15:11:23 INFO - 'cart']}, 15:11:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:23 INFO - 'talos_options': ['--disable-e10s'], 15:11:23 INFO - 'tests': ['tp5o']}, 15:11:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:23 INFO - 'tests': ['tp5o']}, 15:11:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:23 INFO - 'talos_options': ['--disable-e10s', 15:11:23 INFO - '--xperf_path', 15:11:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:23 INFO - 'tests': ['tp5n']}, 15:11:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:23 INFO - 'talos_options': ['--xperf_path', 15:11:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:23 INFO - 'tests': ['tp5n']}}, 15:11:23 INFO - 'talos.zip': {'path': '', 15:11:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:11:23 INFO - Running post-action listener: _resource_record_post_action 15:11:23 INFO - [mozharness: 2017-01-03 23:11:23.804081Z] Finished populate-webroot step (success) 15:11:23 INFO - [mozharness: 2017-01-03 23:11:23.804212Z] Running create-virtualenv step. 15:11:23 INFO - Running pre-action listener: _resource_record_pre_action 15:11:23 INFO - Running main action method: create_virtualenv 15:11:23 INFO - Creating virtualenv /builds/slave/test/build/venv 15:11:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:11:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:11:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:11:23 INFO - Using real prefix '/tools/python27' 15:11:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:11:24 INFO - Installing distribute.............................................................................................................................................................................................done. 15:11:27 INFO - Installing pip.................done. 15:11:27 INFO - Return code: 0 15:11:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:11:27 INFO - Reading from file tmpfile_stdout 15:11:27 INFO - Output received: 15:11:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:11:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:27 INFO - 'HOME': '/Users/cltbld', 15:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:27 INFO - 'LOGNAME': 'cltbld', 15:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:27 INFO - 'MOZ_NO_REMOTE': '1', 15:11:27 INFO - 'NO_EM_RESTART': '1', 15:11:27 INFO - 'PAGER': '/bin/cat', 15:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:27 INFO - 'PWD': '/builds/slave/test', 15:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:27 INFO - 'SHELL': '/bin/bash', 15:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:27 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:27 INFO - 'USER': 'cltbld', 15:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:27 INFO - 'XPC_FLAGS': '0x0', 15:11:27 INFO - 'XPC_SERVICE_NAME': '0', 15:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:11:28 INFO - Cleaning up... 15:11:28 INFO - Return code: 0 15:11:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:28 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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:28 INFO - 'HOME': '/Users/cltbld', 15:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:28 INFO - 'LOGNAME': 'cltbld', 15:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:28 INFO - 'MOZ_NO_REMOTE': '1', 15:11:28 INFO - 'NO_EM_RESTART': '1', 15:11:28 INFO - 'PAGER': '/bin/cat', 15:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:28 INFO - 'PWD': '/builds/slave/test', 15:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:28 INFO - 'SHELL': '/bin/bash', 15:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:28 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:28 INFO - 'USER': 'cltbld', 15:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:28 INFO - 'XPC_FLAGS': '0x0', 15:11:28 INFO - 'XPC_SERVICE_NAME': '0', 15:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:28 INFO - Downloading/unpacking psutil>=3.1.1 15:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:11:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:31 INFO - Installing collected packages: psutil 15:11:31 INFO - Running setup.py install for psutil 15:11:31 INFO - building 'psutil._psutil_osx' extension 15:11:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:11:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:11:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:11:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:11:32 INFO - building 'psutil._psutil_posix' extension 15:11:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:11:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:11:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:11:32 INFO - ^ 15:11:32 INFO - 1 warning generated. 15:11:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:11:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:32 INFO - Successfully installed psutil 15:11:32 INFO - Cleaning up... 15:11:32 INFO - Return code: 0 15:11:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:32 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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:11:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:32 INFO - 'HOME': '/Users/cltbld', 15:11:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:32 INFO - 'LOGNAME': 'cltbld', 15:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:32 INFO - 'MOZ_NO_REMOTE': '1', 15:11:32 INFO - 'NO_EM_RESTART': '1', 15:11:32 INFO - 'PAGER': '/bin/cat', 15:11:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:32 INFO - 'PWD': '/builds/slave/test', 15:11:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:32 INFO - 'SHELL': '/bin/bash', 15:11:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:32 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:32 INFO - 'USER': 'cltbld', 15:11:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:32 INFO - 'XPC_FLAGS': '0x0', 15:11:32 INFO - 'XPC_SERVICE_NAME': '0', 15:11:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:11:35 INFO - Installing collected packages: mozsystemmonitor 15:11:35 INFO - Running setup.py install for mozsystemmonitor 15:11:35 INFO - Successfully installed mozsystemmonitor 15:11:35 INFO - Cleaning up... 15:11:36 INFO - Return code: 0 15:11:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:36 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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:11:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:36 INFO - 'HOME': '/Users/cltbld', 15:11:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:36 INFO - 'LOGNAME': 'cltbld', 15:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:36 INFO - 'MOZ_NO_REMOTE': '1', 15:11:36 INFO - 'NO_EM_RESTART': '1', 15:11:36 INFO - 'PAGER': '/bin/cat', 15:11:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:36 INFO - 'PWD': '/builds/slave/test', 15:11:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:36 INFO - 'SHELL': '/bin/bash', 15:11:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:36 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:36 INFO - 'USER': 'cltbld', 15:11:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:36 INFO - 'XPC_FLAGS': '0x0', 15:11:36 INFO - 'XPC_SERVICE_NAME': '0', 15:11:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:36 INFO - Downloading/unpacking jsonschema==2.5.1 15:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:11:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:11: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 15:11: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 15:11: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 15:11: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 15:11: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 15:11: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 15:11:39 INFO - Downloading functools32-3.2.3-2.tar.gz 15:11:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:11:39 INFO - warning: no files found matching '*.txt' 15:11:39 INFO - no previously-included directories found matching 'build' 15:11:39 INFO - no previously-included directories found matching 'dist' 15:11:39 INFO - no previously-included directories found matching '.git*' 15:11:39 INFO - Installing collected packages: jsonschema, functools32 15:11:39 INFO - Running setup.py install for functools32 15:11:39 INFO - warning: no files found matching '*.txt' 15:11:39 INFO - no previously-included directories found matching 'build' 15:11:39 INFO - no previously-included directories found matching 'dist' 15:11:39 INFO - no previously-included directories found matching '.git*' 15:11:39 INFO - Successfully installed jsonschema functools32 15:11:39 INFO - Cleaning up... 15:11:39 INFO - Return code: 0 15:11:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:11: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')]} 15:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11: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')]} 15:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:11:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:39 INFO - 'HOME': '/Users/cltbld', 15:11:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:39 INFO - 'LOGNAME': 'cltbld', 15:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:39 INFO - 'MOZ_NO_REMOTE': '1', 15:11:39 INFO - 'NO_EM_RESTART': '1', 15:11:39 INFO - 'PAGER': '/bin/cat', 15:11:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:39 INFO - 'PWD': '/builds/slave/test', 15:11:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:39 INFO - 'SHELL': '/bin/bash', 15:11:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:39 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:39 INFO - 'USER': 'cltbld', 15:11:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:39 INFO - 'XPC_FLAGS': '0x0', 15:11:39 INFO - 'XPC_SERVICE_NAME': '0', 15:11:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:11:39 INFO - Cleaning up... 15:11:39 INFO - Return code: 0 15:11:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:11: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')]} 15:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11: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')]} 15:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:11:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:39 INFO - 'HOME': '/Users/cltbld', 15:11:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:39 INFO - 'LOGNAME': 'cltbld', 15:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:39 INFO - 'MOZ_NO_REMOTE': '1', 15:11:39 INFO - 'NO_EM_RESTART': '1', 15:11:39 INFO - 'PAGER': '/bin/cat', 15:11:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:39 INFO - 'PWD': '/builds/slave/test', 15:11:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:39 INFO - 'SHELL': '/bin/bash', 15:11:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:39 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:39 INFO - 'USER': 'cltbld', 15:11:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:39 INFO - 'XPC_FLAGS': '0x0', 15:11:39 INFO - 'XPC_SERVICE_NAME': '0', 15:11:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:40 INFO - Downloading/unpacking blobuploader==1.2.4 15:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:42 INFO - Downloading blobuploader-1.2.4.tar.gz 15:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:11:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:11:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:11: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 15:11: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 15:11: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 15:11: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 15:11: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 15:11: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 15:11:43 INFO - Downloading docopt-0.6.1.tar.gz 15:11:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:11:44 INFO - Installing collected packages: blobuploader, requests, docopt 15:11:44 INFO - Running setup.py install for blobuploader 15:11:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:11:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:11:44 INFO - Running setup.py install for requests 15:11:44 INFO - Running setup.py install for docopt 15:11:45 INFO - Successfully installed blobuploader requests docopt 15:11:45 INFO - Cleaning up... 15:11:45 INFO - Return code: 0 15:11:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:45 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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:45 INFO - 'HOME': '/Users/cltbld', 15:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:45 INFO - 'LOGNAME': 'cltbld', 15:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:45 INFO - 'MOZ_NO_REMOTE': '1', 15:11:45 INFO - 'NO_EM_RESTART': '1', 15:11:45 INFO - 'PAGER': '/bin/cat', 15:11:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:45 INFO - 'PWD': '/builds/slave/test', 15:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:45 INFO - 'SHELL': '/bin/bash', 15:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:45 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:45 INFO - 'USER': 'cltbld', 15:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:45 INFO - 'XPC_FLAGS': '0x0', 15:11:45 INFO - 'XPC_SERVICE_NAME': '0', 15:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:11:45 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-zon1RI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:11:45 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-yLNcG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:11:45 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-EL3qcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:11:45 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-kjTRyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:11:45 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-ga1IgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:11:45 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-KldoaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-nXnzXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-0_nRGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-z3UUrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-lwCkh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-uKHquf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-uZzaBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-5aoMcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-u5Ccil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-Mp0AVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:11:46 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-MWCl33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:11:47 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-tI80ND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:11:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:11:47 INFO - Running setup.py install for manifestparser 15:11:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:11:47 INFO - Running setup.py install for mozcrash 15:11:47 INFO - Running setup.py install for mozdebug 15:11:47 INFO - Running setup.py install for mozdevice 15:11:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:11:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:11:47 INFO - Running setup.py install for mozfile 15:11:47 INFO - Running setup.py install for mozhttpd 15:11:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:11:48 INFO - Running setup.py install for mozinfo 15:11:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:11:48 INFO - Running setup.py install for mozInstall 15:11:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:11:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:11:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:11:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:11:48 INFO - Running setup.py install for mozleak 15:11:48 INFO - Running setup.py install for mozlog 15:11:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:11:48 INFO - Running setup.py install for moznetwork 15:11:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:11:48 INFO - Running setup.py install for mozprocess 15:11:48 INFO - Running setup.py install for mozprofile 15:11:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:11:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:11:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:11:49 INFO - Running setup.py install for mozrunner 15:11:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:11:49 INFO - Running setup.py install for mozscreenshot 15:11:49 INFO - Running setup.py install for moztest 15:11:49 INFO - Running setup.py install for mozversion 15:11:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:11:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:11:49 INFO - Cleaning up... 15:11:49 INFO - Return code: 0 15:11:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:49 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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:49 INFO - 'HOME': '/Users/cltbld', 15:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:49 INFO - 'LOGNAME': 'cltbld', 15:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:49 INFO - 'MOZ_NO_REMOTE': '1', 15:11:49 INFO - 'NO_EM_RESTART': '1', 15:11:49 INFO - 'PAGER': '/bin/cat', 15:11:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:49 INFO - 'PWD': '/builds/slave/test', 15:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:49 INFO - 'SHELL': '/bin/bash', 15:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:49 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:49 INFO - 'USER': 'cltbld', 15:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:49 INFO - 'XPC_FLAGS': '0x0', 15:11:49 INFO - 'XPC_SERVICE_NAME': '0', 15:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-e6sJPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-H9IRzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:11:50 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)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-mYYhCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-IsC8SM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-VeFUY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-81gG2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-XOCEDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-rnYTfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-iQJRqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:11:50 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-X4ZH6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:11:51 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-K095na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:11:51 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-4J9B7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:11:51 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-RL7L7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:11:51 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-SnpzAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:11:51 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)) 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:51 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-R_Y4TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:11:51 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-HIpYc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:11:51 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)) 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:11:51 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-N7emtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:11:51 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)) 15:11:51 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)) 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:54 INFO - Downloading blessings-1.6.tar.gz 15:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:11:54 INFO - Installing collected packages: blessings 15:11:54 INFO - Running setup.py install for blessings 15:11:54 INFO - Successfully installed blessings 15:11:54 INFO - Cleaning up... 15:11:54 INFO - Return code: 0 15:11:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:11:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:11:55 INFO - Reading from file tmpfile_stdout 15:11:55 INFO - Current package versions: 15:11:55 INFO - blessings == 1.6 15:11:55 INFO - blobuploader == 1.2.4 15:11:55 INFO - docopt == 0.6.1 15:11:55 INFO - functools32 == 3.2.3-2 15:11:55 INFO - jsonschema == 2.5.1 15:11:55 INFO - manifestparser == 1.1 15:11:55 INFO - mozInstall == 1.12 15:11:55 INFO - mozcrash == 1.0 15:11:55 INFO - mozdebug == 0.1 15:11:55 INFO - mozdevice == 0.48 15:11:55 INFO - mozfile == 1.2 15:11:55 INFO - mozhttpd == 0.7 15:11:55 INFO - mozinfo == 0.9 15:11:55 INFO - mozleak == 0.1 15:11:55 INFO - mozlog == 3.4 15:11:55 INFO - moznetwork == 0.27 15:11:55 INFO - mozprocess == 0.23 15:11:55 INFO - mozprofile == 0.28 15:11:55 INFO - mozrunner == 6.13 15:11:55 INFO - mozscreenshot == 0.1 15:11:55 INFO - mozsystemmonitor == 0.3 15:11:55 INFO - moztest == 0.8 15:11:55 INFO - mozversion == 1.4 15:11:55 INFO - psutil == 3.1.1 15:11:55 INFO - requests == 1.2.3 15:11:55 INFO - wsgiref == 0.1.2 15:11:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11: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')]} 15:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11: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')]} 15:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:55 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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:55 INFO - 'HOME': '/Users/cltbld', 15:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:55 INFO - 'LOGNAME': 'cltbld', 15:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:55 INFO - 'MOZ_NO_REMOTE': '1', 15:11:55 INFO - 'NO_EM_RESTART': '1', 15:11:55 INFO - 'PAGER': '/bin/cat', 15:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:55 INFO - 'PWD': '/builds/slave/test', 15:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:55 INFO - 'SHELL': '/bin/bash', 15:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:55 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:55 INFO - 'USER': 'cltbld', 15:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:55 INFO - 'XPC_FLAGS': '0x0', 15:11:55 INFO - 'XPC_SERVICE_NAME': '0', 15:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:11:56 INFO - Cleaning up... 15:11:56 INFO - Return code: 0 15:11:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:56 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 0x106b7f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106af5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5c054da90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, '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.RS61RIAU6E/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.qnBzGP766e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:11:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:11:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:11:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:56 INFO - 'HOME': '/Users/cltbld', 15:11:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:56 INFO - 'LOGNAME': 'cltbld', 15:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:56 INFO - 'MOZ_NO_REMOTE': '1', 15:11:56 INFO - 'NO_EM_RESTART': '1', 15:11:56 INFO - 'PAGER': '/bin/cat', 15:11:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:56 INFO - 'PWD': '/builds/slave/test', 15:11:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:56 INFO - 'SHELL': '/bin/bash', 15:11:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:11:56 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:11:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:56 INFO - 'USER': 'cltbld', 15:11:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:56 INFO - 'XPC_FLAGS': '0x0', 15:11:56 INFO - 'XPC_SERVICE_NAME': '0', 15:11:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:11:56 INFO - Cleaning up... 15:11:56 INFO - Return code: 0 15:11:56 INFO - Running post-action listener: _resource_record_post_action 15:11:56 INFO - Running post-action listener: _start_resource_monitoring 15:11:56 INFO - Starting resource monitoring. 15:11:56 INFO - [mozharness: 2017-01-03 23:11:56.822757Z] Finished create-virtualenv step (success) 15:11:56 INFO - [mozharness: 2017-01-03 23:11:56.823724Z] Running install step. 15:11:56 INFO - Running pre-action listener: _resource_record_pre_action 15:11:56 INFO - Running main action method: install 15:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:11:57 INFO - Reading from file tmpfile_stdout 15:11:57 INFO - Detecting whether we're running mozinstall >=1.0... 15:11:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:11:57 INFO - Reading from file tmpfile_stdout 15:11:57 INFO - Output received: 15:11:57 INFO - Usage: mozinstall [options] installer 15:11:57 INFO - Options: 15:11:57 INFO - -h, --help show this help message and exit 15:11:57 INFO - -d DEST, --destination=DEST 15:11:57 INFO - Directory to install application into. [default: 15:11:57 INFO - "/builds/slave/test"] 15:11:57 INFO - --app=APP Application being installed. [default: firefox] 15:11:57 INFO - mkdir: /builds/slave/test/build/application 15:11:57 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'] 15:11:57 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 15:12:27 INFO - Reading from file tmpfile_stdout 15:12:27 INFO - Output received: 15:12:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:12:27 INFO - Running post-action listener: _resource_record_post_action 15:12:27 INFO - [mozharness: 2017-01-03 23:12:27.926761Z] Finished install step (success) 15:12:27 INFO - [mozharness: 2017-01-03 23:12:27.926897Z] Running run-tests step. 15:12:27 INFO - Running pre-action listener: _resource_record_pre_action 15:12:27 INFO - Running main action method: run_tests 15:12:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:12:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:12:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:12:27 INFO - 2017-01-03 15:12:27.940 screenresolution[1621:7406] starting screenresolution argv=screenresolution get 15:12:27 INFO - 2017-01-03 15:12:27.942 screenresolution[1621:7406] Display 0: 1600x1200x32@60 15:12:27 INFO - 2017-01-03 15:12:27.948 screenresolution[1622:7409] starting screenresolution argv=screenresolution list 15:12:27 INFO - Available Modes on Display 0 15:12:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:12:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:12:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:12:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:12:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:12:28 INFO - Intel Iris: 15:12:28 INFO - Chipset Model: Intel Iris 15:12:28 INFO - Type: GPU 15:12:28 INFO - Bus: Built-In 15:12:28 INFO - VRAM (Dynamic, Max): 1536 MB 15:12:28 INFO - Vendor: Intel (0x8086) 15:12:28 INFO - Device ID: 0x0a2e 15:12:28 INFO - Revision ID: 0x0009 15:12:28 INFO - Displays: 15:12:28 INFO - Display: 15:12:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:12:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:12:28 INFO - Display Serial Number: 1600x1200 60 15:12:28 INFO - Main Display: Yes 15:12:28 INFO - Mirror: Off 15:12:28 INFO - Online: Yes 15:12:28 INFO - Rotation: Supported 15:12:28 INFO - Return code: 0 15:12:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:12:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:12:28 INFO - Python 2.7.3 15:12:28 INFO - Return code: 0 15:12:28 INFO - grabbing minidump binary from tooltool 15:12:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:28 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 0x106993d30>, '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 0x1067f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10698bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:12:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:12:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:12:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:12:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:12:28 INFO - Return code: 0 15:12:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:12:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:12:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:12:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:12:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:12:28 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/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0392', '--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 15:12:28 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/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0392 --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 15:12:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS61RIAU6E/Render', 15:12:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:28 INFO - 'HOME': '/Users/cltbld', 15:12:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:28 INFO - 'LOGNAME': 'cltbld', 15:12:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:12:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:28 INFO - 'MOZ_NO_REMOTE': '1', 15:12:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:12:28 INFO - 'NO_EM_RESTART': '1', 15:12:28 INFO - 'PAGER': '/bin/cat', 15:12:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:28 INFO - 'PWD': '/builds/slave/test', 15:12:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:12:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:28 INFO - 'SHELL': '/bin/bash', 15:12:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnBzGP766e/Listeners', 15:12:28 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 15:12:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:28 INFO - 'USER': 'cltbld', 15:12:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:28 INFO - 'XPC_FLAGS': '0x0', 15:12:28 INFO - 'XPC_SERVICE_NAME': '0', 15:12:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:28 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/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0392', '--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 15:12:28 INFO - mozversion application_buildid: 20170103123839 15:12:28 INFO - mozversion application_changeset: 236b90305efbbc79f66ca1453be47f3688b26c7a 15:12:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:12:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:12:28 INFO - mozversion application_name: Firefox 15:12:28 INFO - mozversion application_remotingname: firefox-dev 15:12:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:12:28 INFO - mozversion application_vendor: Mozilla 15:12:28 INFO - mozversion application_version: 52.0a2 15:12:28 INFO - mozversion platform_buildid: 20170103123839 15:12:28 INFO - mozversion platform_changeset: 236b90305efbbc79f66ca1453be47f3688b26c7a 15:12:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:12:28 INFO - mozversion platform_version: 52.0a2 15:12:28 INFO - using testdate: 1483485148 15:12:28 INFO - actual date: 1483485148 15:12:28 INFO - starting webserver on 'localhost:49242' 15:12:28 INFO - SUITE-START | Running 2 tests 15:12:28 INFO - TEST-START | dromaeo_css 15:12:28 INFO - operating with platform_type : mac_ 15:12:28 INFO - Initialising browser for dromaeo_css test... 15:12:28 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/tmpQqnxn4/profile http://localhost:49242/getInfo.html) 15:12:31 INFO - PROCESS | 1632 | 2017-01-03 15:12:31.583 plugin-container[1634:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a2f, name = 'com.apple.tsm.portname' 15:12:31 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:12:31 INFO - PROCESS | 1632 | 2017-01-03 15:12:31.585 plugin-container[1634:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 15:12:31 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:12:31 INFO - PROCESS | 1632 | 2017-01-03 15:12:31.586 plugin-container[1634:7582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:12:31 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 15:12:31 INFO - PROCESS | 1632 | colorDepth:24 15:12:31 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 15:12:31 INFO - PROCESS | 1632 | __metrics 15:12:31 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 15:12:32 INFO - TEST-INFO | 1632: exit 0 15:12:32 INFO - Browser initialized. 15:12:32 INFO - Running cycle 1/1 for dromaeo_css test... 15:12:32 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/tmpQqnxn4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:12:35 INFO - PROCESS | 1635 | 2017-01-03 15:12:35.387 plugin-container[1636:7709] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 15:12:35 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:12:35 INFO - PROCESS | 1635 | 2017-01-03 15:12:35.390 plugin-container[1636:7709] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 15:12:35 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:12:35 INFO - PROCESS | 1635 | 2017-01-03 15:12:35.390 plugin-container[1636:7709] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:12:45 INFO - PROCESS | 1635 | RSS: Main: 229875712 15:12:45 INFO - PROCESS | 1635 | 15:15:47 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 15:15:50 INFO - PROCESS | 1635 | RSS: Main: 232841216 15:15:50 INFO - PROCESS | 1635 | 15:18:52 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 15:18:53 INFO - PROCESS | 1635 | RSS: Main: 243654656 15:18:53 INFO - PROCESS | 1635 | 15:21:55 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 15:21:55 INFO - PROCESS | 1635 | RSS: Main: 246427648 15:21:55 INFO - PROCESS | 1635 | 15:24:57 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 15:24:58 INFO - PROCESS | 1635 | RSS: Main: 245624832 15:24:58 INFO - PROCESS | 1635 | 15:28:01 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 15:28:01 INFO - PROCESS | 1635 | RSS: Main: 249073664 15:28:01 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 15:31:04 INFO - PROCESS | 1635 | RSS: Main: 250568704 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | __start_tp_report 15:31:04 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 15:31:04 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 15:31:04 INFO - PROCESS | 1635 | |i|pagename|runs| 15:31:04 INFO - PROCESS | 1635 | |0;dojo.html;2769.363046499306;3068.907932762017;3264.9715339668;3368.4442067058935;3410.0963244740146;5598.524125449337;5627.6904770237625;5631.549476041917;5650.29371328584;5672.12082127271;5464.972675136619;5499.862503437406;5501.697406642643;5504.422035686255;5522.66863988161;5005.724685142316;5013.949860501394;5100.617453690988;5130.820421285256;5173.456787037363;5070.239464080399;5106.514881086309;5121.8463446096675;5139.331886854722;5187.636865419422;5116.309298244745;5134.152864777326;5140.5373516383515;5148.124818780816;5184.818531351403;5154.639175257752;5228.954209158183;5252.8161514347;5268.894622107575;5323.22813192085;4464.92698108689;5213.191955246905;5235.240890070956;5237.869053273661;5241.606879484054;5457.2359869618285;5649.293838270216;5649.406812284692;5659.236003139565;5715.3998830122655;5990.341062483123;6072.483838873701;6079.088136779475;6109.070667813081;6130.662813545253;6156.415140561639;6164.6917654117115;6193.318734939153;6202.3797620238065;6222.62664240147;5681.9431805681825;5682.374938756733;5689.68706721126;5708.77164913403;5711.314642242957;6246.094316324198;6332.430081292706;6339.619622822645;6345.587536810092;6346.30190679025;5856.941430585724;5889.087191485327;5992.280926288873;6003.459688627957;6078;6206.844828879224;6219.875602487925;6225.128482012522;6229.906551401733;6235.314115447297;6098.817035488943;6146.846328841725;6148.78479253224;6149.692515374213;6199.1011303361665;5901.439363260483;5949.345571987078;6082.726277317532;6133.457080270857;6221.346758590374;4816.036792641416;4884.169691152441;4892.143874821892;4901.50984901507;4934.654574179773;4364.388985542027;4365.301551751768;4375.409319741858;4387.868363949086;4402.361657559636;5611.270534830446;5753.942460575425;5834.533237341003;5919.6152250103605;5965.13505541694;5141.691498510101;6162.075688646739;6169.784057557964;6179.0422484514975;6199.907001394983;4360.760158191267;5349.331333583302;5945.61353512029;5959.225300710967;5964.135200395918;5230.006298803215;5266.447023062523;5304.602154838325;5340.652857564323;5357.678539287655;6148.200733904564;6277.246730392199;6410.519211059189;6433.549651524442;6436;5431.728413579384;6089.939100608937;6202.162708034358;6240.375962403723;6253.6560489173535;6328.430441260124;6338.397852204218;6364.363563643598;6405.903911441425;6443.323451037573;5839.678817665069;5960.552958528041;6104.053871649724;6110.694465276807;6168.1672974149715;4738.407699037621;6120.843333897766;6145.402195429131;6161.353057929032;6164.198654175108;6023.36754640774;6053.757849685963;6068.7269072892705;6081.6351018939;6104.4200800923845;5876.324222714443;5931.822045338647;6020.6989650516425;6068.271807383142;6088.025915853442;5692.1461780733225;5726.599138060463;5731.398203188605;5741.971290143522;5782.306123265235;5949.167116603592;5958.255218097738;5959.791407300723;5988.461038506555;5990.31111422192;5849.239598852122;5881.5588830837005;5883.911741323969;5914.615549989237;5916.230889984275 15:31:04 INFO - PROCESS | 1635 | |1;ext.html;494.290692855752;497.18213538728816;511.2254933775331;527.3671594087097;553.7729530892333;34573.481397779025;37810.621893781055;37835;37895.81052094736;38143;33189.5021574676;37836.054098647546;39198.21603567927;39524;39595.81212563621;8810.955945220265;8812.4712517249;8973.910260897374;8989.146031127066;9003.459792412443;9320.114589114024;9431.717048488556;9433.858492122623;9466.337356385056;9492.52537373132;9331.346805723602;9424.293178011641;9453.763655908588;9468.66859659912;9477.715668529956;9308.34841561091;9339;9344.813103737955;9367.578458969363;9420.057994200593;9227.26181905446;9309.53452327381;9318.161365477072;9327.813443731155;9343.112404321579;37963.24073518542;38195.61804381976;38204;38261.04347391338;38449.615503844885;3191;3340.5323254744353;3353.832308384571;3758.3047136279874;3905.7326236226086;28132.578011329846;28464.85767571149;28561.285967850763;29244.41511169786;29914.850425747733;3150.763692723032;3200.647928727861;3220.6940340667597;3225.96774032259;3238.676132386766;494135;502175;503622;504782.4760876209;505294;56084;56209.71895140498;56315.71842140763;56502.43497564972;56566.151507727416;49404.51786446412;56046.439535604935;57835.843283134105;58431;58604;15715.21423928799;15876.44432444882;15964.281607327699;15978.920105399398;16030.438934637232;10599.470026498644;12264.0188784897;12463.750724985632;12504.3747812609;12524.373781310898;14367.497137600134;14738.778918316233;14739.484118055816;14825;14958.925205373904;2993.236724635204;3291.325278317939;6158.137860699506;6719.932800672029;6887.345702158287;804.2078552625659;851.5486792000249;868.3660927522943;947.4362754161263;983.8622592837008;28408.289792755346;29181.416371672873;29288.707112928598;29978.40043199124;30310.939117130794;1029.5688992300709;1066.8773091094467;1083.3878858444953;1098.357460885375;1117.776444711061;15303.158326291938;15315.31081101353;15336.003159794578;15378.000429972017;15761.921190394203;682047;689836;690762;691702;693463;56089;56194.438055619736;56696.43303566994;56837.99999999917;56967.14549281764;1353.620986123887;1359.8164247826614;1380.5582213691523;1382.9446822127204;1384.0726713102247;27843.860780695966;28176.872925083586;28972.42055158927;29033.564496532148;29518.114456566338;47288;52765;53768.999999998436;53815;57849.71075144597;609734;617602;619948;619958;620386;41206.587934120274;41311.760647180636;41397.586024139375;41613.79193104015;41847.37228941507;45975;46155.846103847674;46162.538374617165;46560.301595475416;46601;36885.26229475449;37657.623423766505;37716.05709857275;39045.6095439042;40422;37318.25363492661;37781.4332785014;37931;38815.80592097135;38927;36841.63158368382;37693;37965;37998.62001379951;38205.235895282494;32054;33516.97386986218;33924;34486.137846553036;34591.13522161966;19191.90404047971;19470;25491.9803207877;25492.8725356372;25950 15:31:04 INFO - PROCESS | 1635 | |2;jquery.html;301.391189796611;337.6522182152383;352.8747294710376;382.6728147433943;401.9408857660065;18312.816871831277;18904.527386815338;19795.50511237218;20033.499162520944;20050.89974550125;17089.57276068096;19139.80860191401;19214.84710917346;19907.40277791665;20037.699434508493;4508.481524624661;5565.805196818092;5567.47109024642;5571.470710282517;5583.441655834425;5425.83722488326;5622.465865742768;5928.1404196391495;5983.521318294548;6043.637381757108;5931.436513531207;5996.310424301196;6018.067199584071;6046.032634778437;6048.1834952281315;5545.334559852823;5809.3900140485275;5890.70546472679;5927.318358388754;5928.377520360386;5532.806351777709;5686.402927692616;5712.942870571283;5718.771249150029;5732.713364331808;13094.410751516205;13133.934330328288;13136.802947955788;13330.600081997458;13402.731945361038;2035.847311451647;2332.043862016565;2338.7895089441863;2431.817613678981;2568.505935936687;9658.3722058066;9842.852357214648;9911.207103431709;10262.179025677928;10342.37945723259;1795.8742887997846;1819.927202911882;1890.1210936914401;1901.3440363074856;1919.1555715485142;112783;114915;115697;116813;122301.77698222993;11447.198696091195;11603;11770.117241206945;11801.52793888235;11905.047596192284;14562.927185364006;14572.85427145715;14576.416943322149;14587.41650333996;14597.270136493133;6863.828404289833;8313;8605.26855217301;8662.523561204194;8678.479291242546;6863.347981941708;8417.789555261044;8454.15458454162;8622.353323500762;8636.827263454696;4475.194464996332;4508.458984921765;4520.932186017212;4574.222382195035;4578.427696537933;1801.4325487471667;1891.6122194950003;1944.5318784317749;1951.463347579427;1954.6970219615982;799.3804801279056;835.8256649407592;886.1891369397058;887.8978917424579;985.1576901749045;20335.898320508302;21219.68170477444;21447.892760536408;21452.89273553622;21463;905.922996545301;938.8310104181177;948.2129832239225;950.695777351241;976.2287792643732;10833.079188269085;11223.38271395081;11236.887631123584;11241.550337986553;11294.887051129548;605319;610820;614943;618199;621791;119099.40450297693;120324;128758;131981;136679.6332036667;901.7069452427987;927.6845872403416;981.3523074770617;984.2322988069318;999.7500624843789;14764;15601.609959751097;15601.921990389976;18998.905005474884;19325.51686207856;57754.689812407036;58530.82938341294;58611.70694146502;59414.70292648682;60464.39535604764;73854.63072684602;77103.61448192723;77589.61205194164;79313;79648.60175699084;7702.730404435818;8047.396445266745;8130.02439707239;11711.472983715583;11820;12938.547150849676;13029.348532573524;13223;13299.468021279428;13960.860391395956;10835.458227088771;10938.890611094106;10974.670759877217;11630.185886987829;11748.765024699629;11486.82769758471;11569.6529104127;11593.59422420245;11758.529658813552;12092.093093017882;8790.725344825092;9513.381630194015;11619.883801161881;11934.641960741192;12188.939055304667;12194.75610487803;12293.631191064282;12462.189957653087;12482.875171248172;12572.491301043934;11614.88385116138;11705.707357315794;11756.764864702829;11841.5855445059;12049.397530123633 15:31:04 INFO - PROCESS | 1635 | |3;mootools.html;1456.9562913112607;1494.469463340514;1671.9884469895712;1766.4965484836807;1811.3388613156203;6447.613143211404;7515.511491753045;7736.1877002914725;8520.573971301426;8550.786230344245;6464.612123272595;8221.876671849926;8264.21489958453;8355.37334699897;8369.748907532768;2327.860328380294;2339.228054741931;2345.3784747042046;2351.6354964980455;2353.670486131943;2329.7670232976734;2365.8698771567642;2377.4888398994212;2384.201292566992;2388.5581167483983;2324.3840382298704;2327.906883724649;2336.9766302337016;2342.16853017179;2347.295811256616;2298.896549655261;2316.571434284646;2330.3824486511003;2334.5097529518653;2340.2862127051244;2336.6144586143264;2364.042562762084;2364.6571247169063;2370.8103351731993;2375.5248950210025;7100.822479438004;8368.665253389858;8374.204450577185;8411.285040771541;8412.495250285003;3912.4913761211146;3993.7803420811842;4132.5454200037975;5262.000219958196;5294.549963253128;2945.764338852887;3333.28334408101;3400.2009527760956;3459.2043829919257;3531.487934249545;991.9206463482905;1040.9843852342221;1068.9518971646294;1092.2790957967782;1101.7906597746403;10866.185036122322;10952.561897524089;10960.451977401177;10996.945015274872;11002.614908478165;3905.2775236580756;4686.695364801277;4761.119192949315;4763.261694437368;4784.569388755029;9364.859527107099;9450.763730906645;9467.384619999679;9490.667826626035;9501;5221.138512145453;5238.476152384731;5241.0828105081455;5259.631825772236;5274.446183150714;4309.698321117555;4341.60925516705;4760.59534939534;4817.759112044384;4821.228603423436;3006.2484378905274;3497.440409534462;3790.0524868782804;3891.805409729502;3918.1184233547224;1256.8931640810451;1258.5972488803677;1267.6830792301926;1272.2620879889641;1277.118788036252;742.4468770765706;845.4969293270617;895.359817730326;904.3082042237694;921.3918813583138;5615.438456154434;5649.915251271234;5702.629329093678;5746.2529871116485;5887.322957859901;952.1763674421576;965.4448692002127;969.7478655549608;978.515634760798;983.685220729374;3774.7923864187183;3839.251345987506;3886.6113388660906;3891.280113179071;3893.922121557553;8593.484390936439;9932.950335248277;10329.225308101923;10329.638462653771;10345.327553708985;10191.286609937233;10219.284650074573;10247.7438064049;10270.97290270967;10324.483775811179;7479.364254038468;7838.764837054783;7848;8011.879821802678;8015.158408367154;5553.333599967868;5674.687892165971;5811.767529298781;5910;5936.554758393051;9172;9313.767155821159;9372.578233979624;9456.810863782548;9497.620095196115;9462.6098392012;9507.857382139411;9546.236301095756;9553.952230239081;9582;6623.602583844792;6653.767118150841;7165.928340716735;7287.307705767944;7323.597202153933;7933.484323518953;7953.642086106023;8021.558814265271;8032.236937490929;8040.67837286502;7058.54119482232;7141.071660684078;7172.139343278631;7180.60506672138;7306.305900939402;6311.779087731908;6544.770933017321;7058.047163632844;7083;7168.964155179191;6564.770233041821;6665.133532640726;6712.966435167793;6729.1924969002075;6731.966340168267;4784.330193772944;4786.856394308176;4790.928136077892;4849.369581954465;4924.162892308388;4798.448178459522;4806.134895718804;4864.732439715708;5006.899862002812;5013.124403436306 15:31:04 INFO - PROCESS | 1635 | |4;prototype.html;249.26218393555075;303.3947275186004;307.3606897652882;311.52803502693416;313.861900763664;4841.111966333128;5121.487851214877;5211.166213405856;5268.631195816294;5345.946540534598;4332.610065094141;5027.472115427882;5058.898822023557;5226;5256.132738098218;4067.5118985721606;4096.651784598313;4099.540275834505;4100.179964007196;4117.670586353084;3349.480830471281;3703.129764505339;4106.733062350952;4117.958820411787;4126.876193714178;3989.2221016901717;4022.356422972325;4027.556968733437;4055.3308704063793;4096.713230073896;4036.941160008402;4050.574689657573;4055.918881622366;4060.4924384451942;4084.2035803018425;3522.43641017436;3553.223388305847;3574.463830425431;3585.5338805955057;3591.3356029134434;4825.252085926687;4986.085922550817;4997.800087996476;5094.4650811664615;5161.457917033024;3427.280271143063;3639.325096054424;4556.635469162459;4622.2835460503675;4637;4245.957540424587;4712.162256719882;5071.771770270347;5580.944190558083;5614.494695477386;2844.9288767780763;3213.357328534279;3726.3851464508325;3922.941155882664;3926.3521518949337;5047.87380315495;5782.884342313172;5786.566007549409;5795.986087234614;5809.82570522885;3757.9812100939866;3968.8412463501136;3978.0959899273694;3980.56699588148;3990.660793832557;3027.5307327364294;3378.8648454061563;3996.7002474814503;4048.1903619276263;4054.533728621246;3971.72197946141;4063.593640635972;4369.875538989638;4576.8626941191815;4580.519045500241;4850.199717046718;4943.885352228145;5603.439656034364;5618.550515958714;5655.462731040545;1150.465033759306;1282.3011458755002;1291.625428625688;1299.4996926183298;1308.7513372459202;807.7294106474279;832.3216578488633;876.8246350729879;900.3247564326755;901.5942825728448;4316.050468896838;4488.304312831517;4516.9159401744;5048.469910659402;5086.745662716849;4351.390805287262;4435.534268901719;4827.396575428072;5432.891342173213;5434.619576629598;4575.213227235882;4699.600533954584;5030.496950305013;5071.41678706946;5106.565941894981;2186.4424571734107;2710.6340644012807;2822.2379957411795;2832.4759919414564;2842.474142283684;5007.624428167902;5445.891082178334;5475.5072043516275;5753.654780713171;5761.95450615034;5157.174852023658;5261.921071183935;5514.228008078872;5529.253550770675;5534.557235421156;4644.256918892961;4819.59033482158;5348.652337598043;5689.260396148391;5729.369769325371;4822.9871726936335;4920.015996800583;5418.647787893774;5420.566354691694;5476.315460567429;4336.653067754509;4765.713257420546;4902.313676085279;5241.842744717665;5323.840284791462;4635.559621835921;5306.628536002597;5692.182369726846;5705.429457054262;5728.8567785804025;3382.8985130446126;3571.839152275573;3604.846449136251;3799.772013679188;3826.6032897991477;4388.407564978687;4801.927971080507;5260.815871444481;5474.753636086306;5792.653975140075;5126.769295381791;5570.913671834115;5635.8591035224445;5638.464345887134;5643.71781410936;5307.4427185145005;5507.642003269935;5649.717514124195;5738.311402631711;5753.884922301614;4570.908581828411;4985.277134815504;5172.870678232924;5566.638168519034;5607.383187849415;5003.54968052877;5060.924086138784;5409.2427060212385;5688.715564221855;5707.800226992035;4563.908721825478;5216.496081880794;5573.944260557342;5577.414371490935;5639.238702775073 15:31:04 INFO - PROCESS | 1635 | |5;yui.html;647.964361960092;688.8105770912998;697.1599222936366;746.5483382553555;799.388467822116;13913.026088173832;14872.47946321879;15780.210989450525;16356.263968121433;16445.91777041113;14985.475508357209;15306.540803775877;15330.846691533081;15471.071735695867;15480.612984675388;816.9346452283833;837.7779888329597;839.8404303182406;840.2857571064577;841.0790184747691;650.5413683353248;671.6507416143602;804.1275216390223;824.8515267251893;836.4186890111375;862.1938487514194;869.3914260017981;870.9259712924409;871.9258862996653;874.1433395272637;857.275602116217;861.8836457078309;864.3301441382915;864.9178328058824;865.5455885660016;639.5714871036371;640.5420124610879;642.1042645509535;642.8039447968367;647.4690753582065;14049.297535123305;14255.358508867126;14545.636359091002;14549.854501454956;14699.92650036743;13104.017198710135;13260.46958121674;13300.60098197046;13323.866761332456;13487.527936522272;11252.212345135844;11315.207935444521;11416.657500275005;11682.065434765198;11774.05807535395;3602.35157671619;3676.117731744389;3697.1681371691425;3732.9440058399146;3741.02733289344;71275;85752.57123714405;87322;87349;88040;10337;10490.423026733602;10501;10550.419726914939;10577.36535807854;19985.400437986882;20010.099545520487;20095.99520023993;20098.69851952222;20180.69728954067;7438.6652600632015;7584.734534291273;7654.73208437702;7704.576248306397;7809.570473624005;4214.768187749642;4241.236577416094;4333.6316413104605;4359.455068116486;4364.952411421236;3363.5627368441947;3431.125063108941;3471.409860323751;3486;3495.563054618173;608.8812681527148;612.2591664086416;613.7943788830703;613.9539534534928;614.1800696070707;617.5584457113185;633.2654121219364;643.1799455694028;654.0679531667355;661.890788019981;8378.12029736881;8987.730368088967;9134.771630709285;9236.261099112055;9460.148586627236;578.2511647416605;590.7755053079802;610.3743662745633;639.8496353356958;642.6465444005778;3666.9816650916573;4331.870043898688;4432.778361081933;4497.8425755098415;4534.818607255739;107133;111871.44064279627;112458.43770781094;114940;115887;73863;96825;97169.5141524302;99644.50177749064;100402;87872.56063719641;88067.55966220128;89976.10023899678;90199;91030.81392151242;7361.742339018108;8338.499690018618;8569.400141990001;8736.213740763362;8901.955490222508;56217.15674264971;56388;56712.716436417555;57492.71253643705;58853.93752749697;56359;60997.69501152466;80608.59695701484;81987.5900620493;82571;9804.754881128029;10079;10277.691669249934;10330.793384132425;10339.844902326622;12370.567030153901;12462;12535.811962820382;12738.68153296175;12794;11827.52689892429;11943.402829858645;11955.820662690241;11982.820257695967;12264.141510094565;9738.318317717974;11677.124215683687;11741.765164696488;11976.221545599508;12075.27548347102;8997.415168014059;10210.795784084425;11382.54469821198;11585.131115166228;11925;7467.6639551221415;10170.8474372883;10216.744581385525;10230.641927532799;10296;8700.260477859452;9956.954519775609;9958.452285124387;10344;10429.791404172025 15:31:04 INFO - PROCESS | 1635 | __end_tp_report 15:31:04 INFO - PROCESS | 1635 | __start_cc_report 15:31:04 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,374 15:31:04 INFO - PROCESS | 1635 | __end_cc_report 15:31:04 INFO - PROCESS | 1635 | __startTimestamp1483486264330__endTimestamp 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | ------- Summary: start ------- 15:31:04 INFO - PROCESS | 1635 | Number of tests: 6 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5650.88 Median:5881.56 stddev:670.89 (11.4%) stddev-sans-first:633.72 15:31:04 INFO - PROCESS | 1635 | Values: 2769.4 3068.9 3265.0 3368.4 3410.1 5598.5 5627.7 5631.5 5650.3 5672.1 5465.0 5499.9 5501.7 5504.4 5522.7 5005.7 5013.9 5100.6 5130.8 5173.5 5070.2 5106.5 5121.8 5139.3 5187.6 5116.3 5134.2 5140.5 5148.1 5184.8 5154.6 5229.0 5252.8 5268.9 5323.2 4464.9 5213.2 5235.2 5237.9 5241.6 5457.2 5649.3 5649.4 5659.2 5715.4 5990.3 6072.5 6079.1 6109.1 6130.7 6156.4 6164.7 6193.3 6202.4 6222.6 5681.9 5682.4 5689.7 5708.8 5711.3 6246.1 6332.4 6339.6 6345.6 6346.3 5856.9 5889.1 5992.3 6003.5 6078.0 6206.8 6219.9 6225.1 6229.9 6235.3 6098.8 6146.8 6148.8 6149.7 6199.1 5901.4 5949.3 6082.7 6133.5 6221.3 4816.0 4884.2 4892.1 4901.5 4934.7 4364.4 4365.3 4375.4 4387.9 4402.4 5611.3 5753.9 5834.5 5919.6 5965.1 5141.7 6162.1 6169.8 6179.0 6199.9 4360.8 5349.3 5945.6 5959.2 5964.1 5230.0 5266.4 5304.6 5340.7 5357.7 6148.2 6277.2 6410.5 6433.5 6436.0 5431.7 6089.9 6202.2 6240.4 6253.7 6328.4 6338.4 6364.4 6405.9 6443.3 5839.7 5960.6 6104.1 6110.7 6168.2 4738.4 6120.8 6145.4 6161.4 6164.2 6023.4 6053.8 6068.7 6081.6 6104.4 5876.3 5931.8 6020.7 6068.3 6088.0 5692.1 5726.6 5731.4 5742.0 5782.3 5949.2 5958.3 5959.8 5988.5 5990.3 5849.2 5881.6 5883.9 5914.6 5916.2 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:72518.90 Median:38986.30 stddev:162897.74 (417.8%) stddev-sans-first:163264.95 15:31:04 INFO - PROCESS | 1635 | Values: 494.3 497.2 511.2 527.4 553.8 34573.5 37810.6 37835.0 37895.8 38143.0 33189.5 37836.1 39198.2 39524.0 39595.8 8811.0 8812.5 8973.9 8989.1 9003.5 9320.1 9431.7 9433.9 9466.3 9492.5 9331.3 9424.3 9453.8 9468.7 9477.7 9308.3 9339.0 9344.8 9367.6 9420.1 9227.3 9309.5 9318.2 9327.8 9343.1 37963.2 38195.6 38204.0 38261.0 38449.6 3191.0 3340.5 3353.8 3758.3 3905.7 28132.6 28464.9 28561.3 29244.4 29914.9 3150.8 3200.6 3220.7 3226.0 3238.7 494135.0 502175.0 503622.0 504782.5 505294.0 56084.0 56209.7 56315.7 56502.4 56566.2 49404.5 56046.4 57835.8 58431.0 58604.0 15715.2 15876.4 15964.3 15978.9 16030.4 10599.5 12264.0 12463.8 12504.4 12524.4 14367.5 14738.8 14739.5 14825.0 14958.9 2993.2 3291.3 6158.1 6719.9 6887.3 804.2 851.5 868.4 947.4 983.9 28408.3 29181.4 29288.7 29978.4 30310.9 1029.6 1066.9 1083.4 1098.4 1117.8 15303.2 15315.3 15336.0 15378.0 15761.9 682047.0 689836.0 690762.0 691702.0 693463.0 56089.0 56194.4 56696.4 56838.0 56967.1 1353.6 1359.8 1380.6 1382.9 1384.1 27843.9 28176.9 28972.4 29033.6 29518.1 47288.0 52765.0 53769.0 53815.0 57849.7 609734.0 617602.0 619948.0 619958.0 620386.0 41206.6 41311.8 41397.6 41613.8 41847.4 45975.0 46155.8 46162.5 46560.3 46601.0 36885.3 37657.6 37716.1 39045.6 40422.0 37318.3 37781.4 37931.0 38815.8 38927.0 36841.6 37693.0 37965.0 37998.6 38205.2 32054.0 33517.0 33924.0 34486.1 34591.1 19191.9 19470.0 25492.0 25492.9 25950.0 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:35314.74 Median:21450.39 stddev:102380.09 (477.3%) stddev-sans-first:102633.53 15:31:04 INFO - PROCESS | 1635 | Values: 301.4 337.7 352.9 382.7 401.9 18312.8 18904.5 19795.5 20033.5 20050.9 17089.6 19139.8 19214.8 19907.4 20037.7 4508.5 5565.8 5567.5 5571.5 5583.4 5425.8 5622.5 5928.1 5983.5 6043.6 5931.4 5996.3 6018.1 6046.0 6048.2 5545.3 5809.4 5890.7 5927.3 5928.4 5532.8 5686.4 5712.9 5718.8 5732.7 13094.4 13133.9 13136.8 13330.6 13402.7 2035.8 2332.0 2338.8 2431.8 2568.5 9658.4 9842.9 9911.2 10262.2 10342.4 1795.9 1819.9 1890.1 1901.3 1919.2 112783.0 114915.0 115697.0 116813.0 122301.8 11447.2 11603.0 11770.1 11801.5 11905.0 14562.9 14572.9 14576.4 14587.4 14597.3 6863.8 8313.0 8605.3 8662.5 8678.5 6863.3 8417.8 8454.2 8622.4 8636.8 4475.2 4508.5 4520.9 4574.2 4578.4 1801.4 1891.6 1944.5 1951.5 1954.7 799.4 835.8 886.2 887.9 985.2 20335.9 21219.7 21447.9 21452.9 21463.0 905.9 938.8 948.2 950.7 976.2 10833.1 11223.4 11236.9 11241.6 11294.9 605319.0 610820.0 614943.0 618199.0 621791.0 119099.4 120324.0 128758.0 131981.0 136679.6 901.7 927.7 981.4 984.2 999.8 14764.0 15601.6 15601.9 18998.9 19325.5 57754.7 58530.8 58611.7 59414.7 60464.4 73854.6 77103.6 77589.6 79313.0 79648.6 7702.7 8047.4 8130.0 11711.5 11820.0 12938.5 13029.3 13223.0 13299.5 13960.9 10835.5 10938.9 10974.7 11630.2 11748.8 11486.8 11569.7 11593.6 11758.5 12092.1 8790.7 9513.4 11619.9 11934.6 12188.9 12194.8 12293.6 12462.2 12482.9 12572.5 11614.9 11705.7 11756.8 11841.6 12049.4 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5426.07 Median:5229.81 stddev:2980.39 (57.0%) stddev-sans-first:2973.82 15:31:04 INFO - PROCESS | 1635 | Values: 1457.0 1494.5 1672.0 1766.5 1811.3 6447.6 7515.5 7736.2 8520.6 8550.8 6464.6 8221.9 8264.2 8355.4 8369.7 2327.9 2339.2 2345.4 2351.6 2353.7 2329.8 2365.9 2377.5 2384.2 2388.6 2324.4 2327.9 2337.0 2342.2 2347.3 2298.9 2316.6 2330.4 2334.5 2340.3 2336.6 2364.0 2364.7 2370.8 2375.5 7100.8 8368.7 8374.2 8411.3 8412.5 3912.5 3993.8 4132.5 5262.0 5294.5 2945.8 3333.3 3400.2 3459.2 3531.5 991.9 1041.0 1069.0 1092.3 1101.8 10866.2 10952.6 10960.5 10996.9 11002.6 3905.3 4686.7 4761.1 4763.3 4784.6 9364.9 9450.8 9467.4 9490.7 9501.0 5221.1 5238.5 5241.1 5259.6 5274.4 4309.7 4341.6 4760.6 4817.8 4821.2 3006.2 3497.4 3790.1 3891.8 3918.1 1256.9 1258.6 1267.7 1272.3 1277.1 742.4 845.5 895.4 904.3 921.4 5615.4 5649.9 5702.6 5746.3 5887.3 952.2 965.4 969.7 978.5 983.7 3774.8 3839.3 3886.6 3891.3 3893.9 8593.5 9933.0 10329.2 10329.6 10345.3 10191.3 10219.3 10247.7 10271.0 10324.5 7479.4 7838.8 7848.0 8011.9 8015.2 5553.3 5674.7 5811.8 5910.0 5936.6 9172.0 9313.8 9372.6 9456.8 9497.6 9462.6 9507.9 9546.2 9554.0 9582.0 6623.6 6653.8 7165.9 7287.3 7323.6 7933.5 7953.6 8021.6 8032.2 8040.7 7058.5 7141.1 7172.1 7180.6 7306.3 6311.8 6544.8 7058.0 7083.0 7169.0 6564.8 6665.1 6713.0 6729.2 6732.0 4784.3 4786.9 4790.9 4849.4 4924.2 4798.4 4806.1 4864.7 5006.9 5013.1 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4374.85 Median:4826.32 stddev:1337.10 (27.7%) stddev-sans-first:1304.50 15:31:04 INFO - PROCESS | 1635 | Values: 249.3 303.4 307.4 311.5 313.9 4841.1 5121.5 5211.2 5268.6 5345.9 4332.6 5027.5 5058.9 5226.0 5256.1 4067.5 4096.7 4099.5 4100.2 4117.7 3349.5 3703.1 4106.7 4118.0 4126.9 3989.2 4022.4 4027.6 4055.3 4096.7 4036.9 4050.6 4055.9 4060.5 4084.2 3522.4 3553.2 3574.5 3585.5 3591.3 4825.3 4986.1 4997.8 5094.5 5161.5 3427.3 3639.3 4556.6 4622.3 4637.0 4246.0 4712.2 5071.8 5580.9 5614.5 2844.9 3213.4 3726.4 3922.9 3926.4 5047.9 5782.9 5786.6 5796.0 5809.8 3758.0 3968.8 3978.1 3980.6 3990.7 3027.5 3378.9 3996.7 4048.2 4054.5 3971.7 4063.6 4369.9 4576.9 4580.5 4850.2 4943.9 5603.4 5618.6 5655.5 1150.5 1282.3 1291.6 1299.5 1308.8 807.7 832.3 876.8 900.3 901.6 4316.1 4488.3 4516.9 5048.5 5086.7 4351.4 4435.5 4827.4 5432.9 5434.6 4575.2 4699.6 5030.5 5071.4 5106.6 2186.4 2710.6 2822.2 2832.5 2842.5 5007.6 5445.9 5475.5 5753.7 5762.0 5157.2 5261.9 5514.2 5529.3 5534.6 4644.3 4819.6 5348.7 5689.3 5729.4 4823.0 4920.0 5418.6 5420.6 5476.3 4336.7 4765.7 4902.3 5241.8 5323.8 4635.6 5306.6 5692.2 5705.4 5728.9 3382.9 3571.8 3604.8 3799.8 3826.6 4388.4 4801.9 5260.8 5474.8 5792.7 5126.8 5570.9 5635.9 5638.5 5643.7 5307.4 5507.6 5649.7 5738.3 5753.9 4570.9 4985.3 5172.9 5566.6 5607.4 5003.5 5060.9 5409.2 5688.7 5707.8 4563.9 5216.5 5573.9 5577.4 5639.2 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20375.37 Median:56373.50 stddev:30332.12 (53.8%) stddev-sans-first:30381.04 15:31:04 INFO - PROCESS | 1635 | Values: 648.0 688.8 697.2 746.5 799.4 13913.0 14872.5 15780.2 16356.3 16445.9 14985.5 15306.5 15330.8 15471.1 15480.6 816.9 837.8 839.8 840.3 841.1 650.5 671.7 804.1 824.9 836.4 862.2 869.4 870.9 871.9 874.1 857.3 861.9 864.3 864.9 865.5 639.6 640.5 642.1 642.8 647.5 14049.3 14255.4 14545.6 14549.9 14699.9 13104.0 13260.5 13300.6 13323.9 13487.5 11252.2 11315.2 11416.7 11682.1 11774.1 3602.4 3676.1 3697.2 3732.9 3741.0 71275.0 85752.6 87322.0 87349.0 88040.0 10337.0 10490.4 10501.0 10550.4 10577.4 19985.4 20010.1 20096.0 20098.7 20180.7 7438.7 7584.7 7654.7 7704.6 7809.6 4214.8 4241.2 4333.6 4359.5 4365.0 3363.6 3431.1 3471.4 3486.0 3495.6 608.9 612.3 613.8 614.0 614.2 617.6 633.3 643.2 654.1 661.9 8378.1 8987.7 9134.8 9236.3 9460.1 578.3 590.8 610.4 639.8 642.6 3667.0 4331.9 4432.8 4497.8 4534.8 107133.0 111871.4 112458.4 114940.0 115887.0 73863.0 96825.0 97169.5 99644.5 100402.0 87872.6 88067.6 89976.1 90199.0 91030.8 7361.7 8338.5 8569.4 8736.2 8902.0 56217.2 56388.0 56712.7 57492.7 58853.9 56359.0 60997.7 80608.6 81987.6 82571.0 9804.8 10079.0 10277.7 10330.8 10339.8 12370.6 12462.0 12535.8 12738.7 12794.0 11827.5 11943.4 11955.8 11982.8 12264.1 9738.3 11677.1 11741.8 11976.2 12075.3 8997.4 10210.8 11382.5 11585.1 11925.0 7467.7 10170.8 10216.7 10230.6 10296.0 8700.3 9957.0 9958.5 10344.0 10429.8 15:31:04 INFO - PROCESS | 1635 | -------- Summary: end -------- 15:31:04 INFO - PROCESS | 1635 | 15:31:04 INFO - TEST-INFO | 1635: exit 0 15:31:04 INFO - TEST-OK | dromaeo_css | took 1116431ms 15:31:04 INFO - TEST-START | kraken 15:31:04 INFO - operating with platform_type : mac_ 15:31:04 INFO - Initialising browser for kraken test... 15:31:04 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/tmp6kYCBl/profile http://localhost:49242/getInfo.html) 15:31:07 INFO - PROCESS | 1647 | 2017-01-03 15:31:07.890 plugin-container[1648:10123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 15:31:07 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:31:07 INFO - PROCESS | 1647 | 2017-01-03 15:31:07.892 plugin-container[1648:10123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 15:31:07 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:31:07 INFO - PROCESS | 1647 | 2017-01-03 15:31:07.893 plugin-container[1648:10123] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:31:07 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 15:31:07 INFO - PROCESS | 1647 | colorDepth:24 15:31:07 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 15:31:07 INFO - PROCESS | 1647 | __metrics 15:31:08 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 15:31:08 INFO - TEST-INFO | 1647: exit 0 15:31:08 INFO - Browser initialized. 15:31:08 INFO - Running cycle 1/1 for kraken test... 15:31:08 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/tmp6kYCBl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:11 INFO - PROCESS | 1649 | 2017-01-03 15:31:11.730 plugin-container[1650:10247] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x423f, name = 'com.apple.tsm.portname' 15:31:11 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:31:11 INFO - PROCESS | 1649 | 2017-01-03 15:31:11.732 plugin-container[1650:10247] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 15:31:11 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:31:11 INFO - PROCESS | 1649 | 2017-01-03 15:31:11.732 plugin-container[1650:10247] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:31:21 INFO - PROCESS | 1649 | RSS: Main: 229974016 15:31:21 INFO - PROCESS | 1649 | 15:31:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 15:31:25 INFO - PROCESS | 1649 | RSS: Main: 242110464 15:31:25 INFO - PROCESS | 1649 | 15:31:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 15:31:28 INFO - PROCESS | 1649 | RSS: Main: 249585664 15:31:28 INFO - PROCESS | 1649 | 15:31:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 15:31:32 INFO - PROCESS | 1649 | RSS: Main: 253538304 15:31:32 INFO - PROCESS | 1649 | 15:31:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 15:31:34 INFO - PROCESS | 1649 | RSS: Main: 253771776 15:31:34 INFO - PROCESS | 1649 | 15:31:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:31:37 INFO - PROCESS | 1649 | RSS: Main: 253689856 15:31:37 INFO - PROCESS | 1649 | 15:31:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 15:31:40 INFO - PROCESS | 1649 | RSS: Main: 259633152 15:31:40 INFO - PROCESS | 1649 | 15:31:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 15:31:44 INFO - PROCESS | 1649 | RSS: Main: 259760128 15:31:44 INFO - PROCESS | 1649 | 15:31:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 15:31:48 INFO - PROCESS | 1649 | RSS: Main: 258760704 15:31:48 INFO - PROCESS | 1649 | 15:31:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:31:50 INFO - PROCESS | 1649 | RSS: Main: 258007040 15:31:50 INFO - PROCESS | 1649 | 15:31:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:31:53 INFO - PROCESS | 1649 | RSS: Main: 257994752 15:31:53 INFO - PROCESS | 1649 | 15:31:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:31:56 INFO - PROCESS | 1649 | RSS: Main: 258035712 15:31:56 INFO - PROCESS | 1649 | 15:31:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:32:00 INFO - PROCESS | 1649 | RSS: Main: 258383872 15:32:00 INFO - PROCESS | 1649 | 15:32:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:32:03 INFO - PROCESS | 1649 | RSS: Main: 259084288 15:32:03 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 15:32:05 INFO - PROCESS | 1649 | RSS: Main: 260456448 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | __start_tp_report 15:32:05 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 15:32:05 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 15:32:05 INFO - PROCESS | 1649 | |i|pagename|runs| 15:32:05 INFO - PROCESS | 1649 | |0;ai-astar;106;119;120;120;115;116;120;120;121;143 15:32:05 INFO - PROCESS | 1649 | |1;audio-beat-detection;141;110;113;143;110;115;113;113;141;113 15:32:05 INFO - PROCESS | 1649 | |2;audio-dft;196;199;197;193;224;262;199;197;197;193 15:32:05 INFO - PROCESS | 1649 | |3;audio-fft;81;59;61;86;65;51;71;84;103;84 15:32:05 INFO - PROCESS | 1649 | |4;audio-oscillator;78;70;92;103;98;83;86;69;70;89 15:32:05 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;88;88;87;90;85;97;88;89;87 15:32:05 INFO - PROCESS | 1649 | |6;imaging-darkroom;99;100;98;109;176;125;173;172;101;99 15:32:05 INFO - PROCESS | 1649 | |7;imaging-desaturate;89;91;88;87;129;136;118;91;89;91 15:32:05 INFO - PROCESS | 1649 | |8;json-parse-financial;60;55;48;84;96;94;64;52;82;95 15:32:05 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;70;75;89;109;70;115;105;67;84;99 15:32:05 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;109;103;85;84;96;83;85;84;92;109 15:32:05 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;156;154;140;151;152;153;160;144;224;133 15:32:05 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;226;169;166;196;153;201;163;204;235;235 15:32:05 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;69;70;66;68;68;68;66;68;67;74 15:32:05 INFO - PROCESS | 1649 | __end_tp_report 15:32:05 INFO - PROCESS | 1649 | __start_cc_report 15:32:05 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,956 15:32:05 INFO - PROCESS | 1649 | __end_cc_report 15:32:05 INFO - PROCESS | 1649 | __startTimestamp1483486325764__endTimestamp 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | ------- Summary: start ------- 15:32:05 INFO - PROCESS | 1649 | Number of tests: 14 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:120.00 Median:120.00 stddev:9.24 (7.7%) stddev-sans-first:8.29 15:32:05 INFO - PROCESS | 1649 | Values: 106.0 119.0 120.0 120.0 115.0 116.0 120.0 120.0 121.0 143.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:114.00 stddev:14.21 (12.5%) stddev-sans-first:13.14 15:32:05 INFO - PROCESS | 1649 | Values: 141.0 110.0 113.0 143.0 110.0 115.0 113.0 113.0 141.0 113.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:205.70 Median:198.00 stddev:21.70 (11.0%) stddev-sans-first:22.73 15:32:05 INFO - PROCESS | 1649 | Values: 196.0 199.0 197.0 193.0 224.0 262.0 199.0 197.0 197.0 193.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:74.50 Median:76.00 stddev:15.79 (20.8%) stddev-sans-first:16.57 15:32:05 INFO - PROCESS | 1649 | Values: 81.0 59.0 61.0 86.0 65.0 51.0 71.0 84.0 103.0 84.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:83.80 Median:84.50 stddev:12.04 (14.2%) stddev-sans-first:12.58 15:32:05 INFO - PROCESS | 1649 | Values: 78.0 70.0 92.0 103.0 98.0 83.0 86.0 69.0 70.0 89.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:88.50 stddev:3.19 (3.6%) stddev-sans-first:3.38 15:32:05 INFO - PROCESS | 1649 | Values: 89.0 88.0 88.0 87.0 90.0 85.0 97.0 88.0 89.0 87.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:125.20 Median:174.50 stddev:34.40 (19.7%) stddev-sans-first:35.16 15:32:05 INFO - PROCESS | 1649 | Values: 99.0 100.0 98.0 109.0 176.0 125.0 173.0 172.0 101.0 99.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:100.90 Median:89.00 stddev:19.01 (21.4%) stddev-sans-first:19.66 15:32:05 INFO - PROCESS | 1649 | Values: 89.0 91.0 88.0 87.0 129.0 136.0 118.0 91.0 89.0 91.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:73.00 Median:83.00 stddev:19.14 (23.1%) stddev-sans-first:19.71 15:32:05 INFO - PROCESS | 1649 | Values: 60.0 55.0 48.0 84.0 96.0 94.0 64.0 52.0 82.0 95.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:88.30 Median:72.50 stddev:17.81 (24.6%) stddev-sans-first:17.61 15:32:05 INFO - PROCESS | 1649 | Values: 70.0 75.0 89.0 109.0 70.0 115.0 105.0 67.0 84.0 99.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:84.50 stddev:10.60 (12.5%) stddev-sans-first:9.54 15:32:05 INFO - PROCESS | 1649 | Values: 109.0 103.0 85.0 84.0 96.0 83.0 85.0 84.0 92.0 109.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:156.70 Median:153.50 stddev:24.98 (16.3%) stddev-sans-first:26.50 15:32:05 INFO - PROCESS | 1649 | Values: 156.0 154.0 140.0 151.0 152.0 153.0 160.0 144.0 224.0 133.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.80 Median:202.50 stddev:30.88 (15.3%) stddev-sans-first:30.62 15:32:05 INFO - PROCESS | 1649 | Values: 226.0 169.0 166.0 196.0 153.0 201.0 163.0 204.0 235.0 235.0 15:32:05 INFO - PROCESS | 1649 | 15:32:05 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:2.32 (3.4%) stddev-sans-first:2.45 15:32:05 INFO - PROCESS | 1649 | Values: 69.0 70.0 66.0 68.0 68.0 68.0 66.0 68.0 67.0 74.0 15:32:05 INFO - PROCESS | 1649 | -------- Summary: end -------- 15:32:05 INFO - PROCESS | 1649 | 15:32:06 INFO - TEST-INFO | 1649: exit 0 15:32:06 INFO - TEST-OK | kraken | took 61821ms 15:32:06 INFO - SUITE-END | took 1178s 15:32:06 INFO - Completed test suite (00:19:38) 15:32:06 INFO - javascript benchmark 15:32:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7106.559982345789, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2769.363046499306, 3068.907932762017, 3264.9715339668, 3368.4442067058935, 3410.0963244740146, 5598.524125449337, 5627.6904770237625, 5631.549476041917, 5650.29371328584, 5672.12082127271, 5464.972675136619, 5499.862503437406, 5501.697406642643, 5504.422035686255, 5522.66863988161, 5005.724685142316, 5013.949860501394, 5100.617453690988, 5130.820421285256, 5173.456787037363, 5070.239464080399, 5106.514881086309, 5121.8463446096675, 5139.331886854722, 5187.636865419422, 5116.309298244745, 5134.152864777326, 5140.5373516383515, 5148.124818780816, 5184.818531351403, 5154.639175257752, 5228.954209158183, 5252.8161514347, 5268.894622107575, 5323.22813192085, 4464.92698108689, 5213.191955246905, 5235.240890070956, 5237.869053273661, 5241.606879484054, 5457.2359869618285, 5649.293838270216, 5649.406812284692, 5659.236003139565, 5715.3998830122655, 5990.341062483123, 6072.483838873701, 6079.088136779475, 6109.070667813081, 6130.662813545253, 6156.415140561639, 6164.6917654117115, 6193.318734939153, 6202.3797620238065, 6222.62664240147, 5681.9431805681825, 5682.374938756733, 5689.68706721126, 5708.77164913403, 5711.314642242957, 6246.094316324198, 6332.430081292706, 6339.619622822645, 6345.587536810092, 6346.30190679025, 5856.941430585724, 5889.087191485327, 5992.280926288873, 6003.459688627957, 6078.0, 6206.844828879224, 6219.875602487925, 6225.128482012522, 6229.906551401733, 6235.314115447297, 6098.817035488943, 6146.846328841725, 6148.78479253224, 6149.692515374213, 6199.1011303361665, 5901.439363260483, 5949.345571987078, 6082.726277317532, 6133.457080270857, 6221.346758590374, 4816.036792641416, 4884.169691152441, 4892.143874821892, 4901.50984901507, 4934.654574179773, 4364.388985542027, 4365.301551751768, 4375.409319741858, 4387.868363949086, 4402.361657559636, 5611.270534830446, 5753.942460575425, 5834.533237341003, 5919.6152250103605, 5965.13505541694, 5141.691498510101, 6162.075688646739, 6169.784057557964, 6179.0422484514975, 6199.907001394983, 4360.760158191267, 5349.331333583302, 5945.61353512029, 5959.225300710967, 5964.135200395918, 5230.006298803215, 5266.447023062523, 5304.602154838325, 5340.652857564323, 5357.678539287655, 6148.200733904564, 6277.246730392199, 6410.519211059189, 6433.549651524442, 6436.0, 5431.728413579384, 6089.939100608937, 6202.162708034358, 6240.375962403723, 6253.6560489173535, 6328.430441260124, 6338.397852204218, 6364.363563643598, 6405.903911441425, 6443.323451037573, 5839.678817665069, 5960.552958528041, 6104.053871649724, 6110.694465276807, 6168.1672974149715, 4738.407699037621, 6120.843333897766, 6145.402195429131, 6161.353057929032, 6164.198654175108, 6023.36754640774, 6053.757849685963, 6068.7269072892705, 6081.6351018939, 6104.4200800923845, 5876.324222714443, 5931.822045338647, 6020.6989650516425, 6068.271807383142, 6088.025915853442, 5692.1461780733225, 5726.599138060463, 5731.398203188605, 5741.971290143522, 5782.306123265235, 5949.167116603592, 5958.255218097738, 5959.791407300723, 5988.461038506555, 5990.31111422192, 5849.239598852122, 5881.5588830837005, 5883.911741323969, 5914.615549989237, 5916.230889984275], "value": 5606.90252124716, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.290692855752, 497.18213538728816, 511.2254933775331, 527.3671594087097, 553.7729530892333, 34573.481397779025, 37810.621893781055, 37835.0, 37895.81052094736, 38143.0, 33189.5021574676, 37836.054098647546, 39198.21603567927, 39524.0, 39595.81212563621, 8810.955945220265, 8812.4712517249, 8973.910260897374, 8989.146031127066, 9003.459792412443, 9320.114589114024, 9431.717048488556, 9433.858492122623, 9466.337356385056, 9492.52537373132, 9331.346805723602, 9424.293178011641, 9453.763655908588, 9468.66859659912, 9477.715668529956, 9308.34841561091, 9339.0, 9344.813103737955, 9367.578458969363, 9420.057994200593, 9227.26181905446, 9309.53452327381, 9318.161365477072, 9327.813443731155, 9343.112404321579, 37963.24073518542, 38195.61804381976, 38204.0, 38261.04347391338, 38449.615503844885, 3191.0, 3340.5323254744353, 3353.832308384571, 3758.3047136279874, 3905.7326236226086, 28132.578011329846, 28464.85767571149, 28561.285967850763, 29244.41511169786, 29914.850425747733, 3150.763692723032, 3200.647928727861, 3220.6940340667597, 3225.96774032259, 3238.676132386766, 494135.0, 502175.0, 503622.0, 504782.4760876209, 505294.0, 56084.0, 56209.71895140498, 56315.71842140763, 56502.43497564972, 56566.151507727416, 49404.51786446412, 56046.439535604935, 57835.843283134105, 58431.0, 58604.0, 15715.21423928799, 15876.44432444882, 15964.281607327699, 15978.920105399398, 16030.438934637232, 10599.470026498644, 12264.0188784897, 12463.750724985632, 12504.3747812609, 12524.373781310898, 14367.497137600134, 14738.778918316233, 14739.484118055816, 14825.0, 14958.925205373904, 2993.236724635204, 3291.325278317939, 6158.137860699506, 6719.932800672029, 6887.345702158287, 804.2078552625659, 851.5486792000249, 868.3660927522943, 947.4362754161263, 983.8622592837008, 28408.289792755346, 29181.416371672873, 29288.707112928598, 29978.40043199124, 30310.939117130794, 1029.5688992300709, 1066.8773091094467, 1083.3878858444953, 1098.357460885375, 1117.776444711061, 15303.158326291938, 15315.31081101353, 15336.003159794578, 15378.000429972017, 15761.921190394203, 682047.0, 689836.0, 690762.0, 691702.0, 693463.0, 56089.0, 56194.438055619736, 56696.43303566994, 56837.99999999917, 56967.14549281764, 1353.620986123887, 1359.8164247826614, 1380.5582213691523, 1382.9446822127204, 1384.0726713102247, 27843.860780695966, 28176.872925083586, 28972.42055158927, 29033.564496532148, 29518.114456566338, 47288.0, 52765.0, 53768.999999998436, 53815.0, 57849.71075144597, 609734.0, 617602.0, 619948.0, 619958.0, 620386.0, 41206.587934120274, 41311.760647180636, 41397.586024139375, 41613.79193104015, 41847.37228941507, 45975.0, 46155.846103847674, 46162.538374617165, 46560.301595475416, 46601.0, 36885.26229475449, 37657.623423766505, 37716.05709857275, 39045.6095439042, 40422.0, 37318.25363492661, 37781.4332785014, 37931.0, 38815.80592097135, 38927.0, 36841.63158368382, 37693.0, 37965.0, 37998.62001379951, 38205.235895282494, 32054.0, 33516.97386986218, 33924.0, 34486.137846553036, 34591.13522161966, 19191.90404047971, 19470.0, 25491.9803207877, 25492.8725356372, 25950.0], "value": 19480.83228381555, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.391189796611, 337.6522182152383, 352.8747294710376, 382.6728147433943, 401.9408857660065, 18312.816871831277, 18904.527386815338, 19795.50511237218, 20033.499162520944, 20050.89974550125, 17089.57276068096, 19139.80860191401, 19214.84710917346, 19907.40277791665, 20037.699434508493, 4508.481524624661, 5565.805196818092, 5567.47109024642, 5571.470710282517, 5583.441655834425, 5425.83722488326, 5622.465865742768, 5928.1404196391495, 5983.521318294548, 6043.637381757108, 5931.436513531207, 5996.310424301196, 6018.067199584071, 6046.032634778437, 6048.1834952281315, 5545.334559852823, 5809.3900140485275, 5890.70546472679, 5927.318358388754, 5928.377520360386, 5532.806351777709, 5686.402927692616, 5712.942870571283, 5718.771249150029, 5732.713364331808, 13094.410751516205, 13133.934330328288, 13136.802947955788, 13330.600081997458, 13402.731945361038, 2035.847311451647, 2332.043862016565, 2338.7895089441863, 2431.817613678981, 2568.505935936687, 9658.3722058066, 9842.852357214648, 9911.207103431709, 10262.179025677928, 10342.37945723259, 1795.8742887997846, 1819.927202911882, 1890.1210936914401, 1901.3440363074856, 1919.1555715485142, 112783.0, 114915.0, 115697.0, 116813.0, 122301.77698222993, 11447.198696091195, 11603.0, 11770.117241206945, 11801.52793888235, 11905.047596192284, 14562.927185364006, 14572.85427145715, 14576.416943322149, 14587.41650333996, 14597.270136493133, 6863.828404289833, 8313.0, 8605.26855217301, 8662.523561204194, 8678.479291242546, 6863.347981941708, 8417.789555261044, 8454.15458454162, 8622.353323500762, 8636.827263454696, 4475.194464996332, 4508.458984921765, 4520.932186017212, 4574.222382195035, 4578.427696537933, 1801.4325487471667, 1891.6122194950003, 1944.5318784317749, 1951.463347579427, 1954.6970219615982, 799.3804801279056, 835.8256649407592, 886.1891369397058, 887.8978917424579, 985.1576901749045, 20335.898320508302, 21219.68170477444, 21447.892760536408, 21452.89273553622, 21463.0, 905.922996545301, 938.8310104181177, 948.2129832239225, 950.695777351241, 976.2287792643732, 10833.079188269085, 11223.38271395081, 11236.887631123584, 11241.550337986553, 11294.887051129548, 605319.0, 610820.0, 614943.0, 618199.0, 621791.0, 119099.40450297693, 120324.0, 128758.0, 131981.0, 136679.6332036667, 901.7069452427987, 927.6845872403416, 981.3523074770617, 984.2322988069318, 999.7500624843789, 14764.0, 15601.609959751097, 15601.921990389976, 18998.905005474884, 19325.51686207856, 57754.689812407036, 58530.82938341294, 58611.70694146502, 59414.70292648682, 60464.39535604764, 73854.63072684602, 77103.61448192723, 77589.61205194164, 79313.0, 79648.60175699084, 7702.730404435818, 8047.396445266745, 8130.02439707239, 11711.472983715583, 11820.0, 12938.547150849676, 13029.348532573524, 13223.0, 13299.468021279428, 13960.860391395956, 10835.458227088771, 10938.890611094106, 10974.670759877217, 11630.185886987829, 11748.765024699629, 11486.82769758471, 11569.6529104127, 11593.59422420245, 11758.529658813552, 12092.093093017882, 8790.725344825092, 9513.381630194015, 11619.883801161881, 11934.641960741192, 12188.939055304667, 12194.75610487803, 12293.631191064282, 12462.189957653087, 12482.875171248172, 12572.491301043934, 11614.88385116138, 11705.707357315794, 11756.764864702829, 11841.5855445059, 12049.397530123633], "value": 9507.747753811735, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1456.9562913112607, 1494.469463340514, 1671.9884469895712, 1766.4965484836807, 1811.3388613156203, 6447.613143211404, 7515.511491753045, 7736.1877002914725, 8520.573971301426, 8550.786230344245, 6464.612123272595, 8221.876671849926, 8264.21489958453, 8355.37334699897, 8369.748907532768, 2327.860328380294, 2339.228054741931, 2345.3784747042046, 2351.6354964980455, 2353.670486131943, 2329.7670232976734, 2365.8698771567642, 2377.4888398994212, 2384.201292566992, 2388.5581167483983, 2324.3840382298704, 2327.906883724649, 2336.9766302337016, 2342.16853017179, 2347.295811256616, 2298.896549655261, 2316.571434284646, 2330.3824486511003, 2334.5097529518653, 2340.2862127051244, 2336.6144586143264, 2364.042562762084, 2364.6571247169063, 2370.8103351731993, 2375.5248950210025, 7100.822479438004, 8368.665253389858, 8374.204450577185, 8411.285040771541, 8412.495250285003, 3912.4913761211146, 3993.7803420811842, 4132.5454200037975, 5262.000219958196, 5294.549963253128, 2945.764338852887, 3333.28334408101, 3400.2009527760956, 3459.2043829919257, 3531.487934249545, 991.9206463482905, 1040.9843852342221, 1068.9518971646294, 1092.2790957967782, 1101.7906597746403, 10866.185036122322, 10952.561897524089, 10960.451977401177, 10996.945015274872, 11002.614908478165, 3905.2775236580756, 4686.695364801277, 4761.119192949315, 4763.261694437368, 4784.569388755029, 9364.859527107099, 9450.763730906645, 9467.384619999679, 9490.667826626035, 9501.0, 5221.138512145453, 5238.476152384731, 5241.0828105081455, 5259.631825772236, 5274.446183150714, 4309.698321117555, 4341.60925516705, 4760.59534939534, 4817.759112044384, 4821.228603423436, 3006.2484378905274, 3497.440409534462, 3790.0524868782804, 3891.805409729502, 3918.1184233547224, 1256.8931640810451, 1258.5972488803677, 1267.6830792301926, 1272.2620879889641, 1277.118788036252, 742.4468770765706, 845.4969293270617, 895.359817730326, 904.3082042237694, 921.3918813583138, 5615.438456154434, 5649.915251271234, 5702.629329093678, 5746.2529871116485, 5887.322957859901, 952.1763674421576, 965.4448692002127, 969.7478655549608, 978.515634760798, 983.685220729374, 3774.7923864187183, 3839.251345987506, 3886.6113388660906, 3891.280113179071, 3893.922121557553, 8593.484390936439, 9932.950335248277, 10329.225308101923, 10329.638462653771, 10345.327553708985, 10191.286609937233, 10219.284650074573, 10247.7438064049, 10270.97290270967, 10324.483775811179, 7479.364254038468, 7838.764837054783, 7848.0, 8011.879821802678, 8015.158408367154, 5553.333599967868, 5674.687892165971, 5811.767529298781, 5910.0, 5936.554758393051, 9172.0, 9313.767155821159, 9372.578233979624, 9456.810863782548, 9497.620095196115, 9462.6098392012, 9507.857382139411, 9546.236301095756, 9553.952230239081, 9582.0, 6623.602583844792, 6653.767118150841, 7165.928340716735, 7287.307705767944, 7323.597202153933, 7933.484323518953, 7953.642086106023, 8021.558814265271, 8032.236937490929, 8040.67837286502, 7058.54119482232, 7141.071660684078, 7172.139343278631, 7180.60506672138, 7306.305900939402, 6311.779087731908, 6544.770933017321, 7058.047163632844, 7083.0, 7168.964155179191, 6564.770233041821, 6665.133532640726, 6712.966435167793, 6729.1924969002075, 6731.966340168267, 4784.330193772944, 4786.856394308176, 4790.928136077892, 4849.369581954465, 4924.162892308388, 4798.448178459522, 4806.134895718804, 4864.732439715708, 5006.899862002812, 5013.124403436306], "value": 4412.47974735926, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [249.26218393555075, 303.3947275186004, 307.3606897652882, 311.52803502693416, 313.861900763664, 4841.111966333128, 5121.487851214877, 5211.166213405856, 5268.631195816294, 5345.946540534598, 4332.610065094141, 5027.472115427882, 5058.898822023557, 5226.0, 5256.132738098218, 4067.5118985721606, 4096.651784598313, 4099.540275834505, 4100.179964007196, 4117.670586353084, 3349.480830471281, 3703.129764505339, 4106.733062350952, 4117.958820411787, 4126.876193714178, 3989.2221016901717, 4022.356422972325, 4027.556968733437, 4055.3308704063793, 4096.713230073896, 4036.941160008402, 4050.574689657573, 4055.918881622366, 4060.4924384451942, 4084.2035803018425, 3522.43641017436, 3553.223388305847, 3574.463830425431, 3585.5338805955057, 3591.3356029134434, 4825.252085926687, 4986.085922550817, 4997.800087996476, 5094.4650811664615, 5161.457917033024, 3427.280271143063, 3639.325096054424, 4556.635469162459, 4622.2835460503675, 4637.0, 4245.957540424587, 4712.162256719882, 5071.771770270347, 5580.944190558083, 5614.494695477386, 2844.9288767780763, 3213.357328534279, 3726.3851464508325, 3922.941155882664, 3926.3521518949337, 5047.87380315495, 5782.884342313172, 5786.566007549409, 5795.986087234614, 5809.82570522885, 3757.9812100939866, 3968.8412463501136, 3978.0959899273694, 3980.56699588148, 3990.660793832557, 3027.5307327364294, 3378.8648454061563, 3996.7002474814503, 4048.1903619276263, 4054.533728621246, 3971.72197946141, 4063.593640635972, 4369.875538989638, 4576.8626941191815, 4580.519045500241, 4850.199717046718, 4943.885352228145, 5603.439656034364, 5618.550515958714, 5655.462731040545, 1150.465033759306, 1282.3011458755002, 1291.625428625688, 1299.4996926183298, 1308.7513372459202, 807.7294106474279, 832.3216578488633, 876.8246350729879, 900.3247564326755, 901.5942825728448, 4316.050468896838, 4488.304312831517, 4516.9159401744, 5048.469910659402, 5086.745662716849, 4351.390805287262, 4435.534268901719, 4827.396575428072, 5432.891342173213, 5434.619576629598, 4575.213227235882, 4699.600533954584, 5030.496950305013, 5071.41678706946, 5106.565941894981, 2186.4424571734107, 2710.6340644012807, 2822.2379957411795, 2832.4759919414564, 2842.474142283684, 5007.624428167902, 5445.891082178334, 5475.5072043516275, 5753.654780713171, 5761.95450615034, 5157.174852023658, 5261.921071183935, 5514.228008078872, 5529.253550770675, 5534.557235421156, 4644.256918892961, 4819.59033482158, 5348.652337598043, 5689.260396148391, 5729.369769325371, 4822.9871726936335, 4920.015996800583, 5418.647787893774, 5420.566354691694, 5476.315460567429, 4336.653067754509, 4765.713257420546, 4902.313676085279, 5241.842744717665, 5323.840284791462, 4635.559621835921, 5306.628536002597, 5692.182369726846, 5705.429457054262, 5728.8567785804025, 3382.8985130446126, 3571.839152275573, 3604.846449136251, 3799.772013679188, 3826.6032897991477, 4388.407564978687, 4801.927971080507, 5260.815871444481, 5474.753636086306, 5792.653975140075, 5126.769295381791, 5570.913671834115, 5635.8591035224445, 5638.464345887134, 5643.71781410936, 5307.4427185145005, 5507.642003269935, 5649.717514124195, 5738.311402631711, 5753.884922301614, 4570.908581828411, 4985.277134815504, 5172.870678232924, 5566.638168519034, 5607.383187849415, 5003.54968052877, 5060.924086138784, 5409.2427060212385, 5688.715564221855, 5707.800226992035, 4563.908721825478, 5216.496081880794, 5573.944260557342, 5577.414371490935, 5639.238702775073], "value": 3951.4834319390816, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.964361960092, 688.8105770912998, 697.1599222936366, 746.5483382553555, 799.388467822116, 13913.026088173832, 14872.47946321879, 15780.210989450525, 16356.263968121433, 16445.91777041113, 14985.475508357209, 15306.540803775877, 15330.846691533081, 15471.071735695867, 15480.612984675388, 816.9346452283833, 837.7779888329597, 839.8404303182406, 840.2857571064577, 841.0790184747691, 650.5413683353248, 671.6507416143602, 804.1275216390223, 824.8515267251893, 836.4186890111375, 862.1938487514194, 869.3914260017981, 870.9259712924409, 871.9258862996653, 874.1433395272637, 857.275602116217, 861.8836457078309, 864.3301441382915, 864.9178328058824, 865.5455885660016, 639.5714871036371, 640.5420124610879, 642.1042645509535, 642.8039447968367, 647.4690753582065, 14049.297535123305, 14255.358508867126, 14545.636359091002, 14549.854501454956, 14699.92650036743, 13104.017198710135, 13260.46958121674, 13300.60098197046, 13323.866761332456, 13487.527936522272, 11252.212345135844, 11315.207935444521, 11416.657500275005, 11682.065434765198, 11774.05807535395, 3602.35157671619, 3676.117731744389, 3697.1681371691425, 3732.9440058399146, 3741.02733289344, 71275.0, 85752.57123714405, 87322.0, 87349.0, 88040.0, 10337.0, 10490.423026733602, 10501.0, 10550.419726914939, 10577.36535807854, 19985.400437986882, 20010.099545520487, 20095.99520023993, 20098.69851952222, 20180.69728954067, 7438.6652600632015, 7584.734534291273, 7654.73208437702, 7704.576248306397, 7809.570473624005, 4214.768187749642, 4241.236577416094, 4333.6316413104605, 4359.455068116486, 4364.952411421236, 3363.5627368441947, 3431.125063108941, 3471.409860323751, 3486.0, 3495.563054618173, 608.8812681527148, 612.2591664086416, 613.7943788830703, 613.9539534534928, 614.1800696070707, 617.5584457113185, 633.2654121219364, 643.1799455694028, 654.0679531667355, 661.890788019981, 8378.12029736881, 8987.730368088967, 9134.771630709285, 9236.261099112055, 9460.148586627236, 578.2511647416605, 590.7755053079802, 610.3743662745633, 639.8496353356958, 642.6465444005778, 3666.9816650916573, 4331.870043898688, 4432.778361081933, 4497.8425755098415, 4534.818607255739, 107133.0, 111871.44064279627, 112458.43770781094, 114940.0, 115887.0, 73863.0, 96825.0, 97169.5141524302, 99644.50177749064, 100402.0, 87872.56063719641, 88067.55966220128, 89976.10023899678, 90199.0, 91030.81392151242, 7361.742339018108, 8338.499690018618, 8569.400141990001, 8736.213740763362, 8901.955490222508, 56217.15674264971, 56388.0, 56712.716436417555, 57492.71253643705, 58853.93752749697, 56359.0, 60997.69501152466, 80608.59695701484, 81987.5900620493, 82571.0, 9804.754881128029, 10079.0, 10277.691669249934, 10330.793384132425, 10339.844902326622, 12370.567030153901, 12462.0, 12535.811962820382, 12738.68153296175, 12794.0, 11827.52689892429, 11943.402829858645, 11955.820662690241, 11982.820257695967, 12264.141510094565, 9738.318317717974, 11677.124215683687, 11741.765164696488, 11976.221545599508, 12075.27548347102, 8997.415168014059, 10210.795784084425, 11382.54469821198, 11585.131115166228, 11925.0, 7467.6639551221415, 10170.8474372883, 10216.744581385525, 10230.641927532799, 10296.0, 8700.260477859452, 9956.954519775609, 9958.452285124387, 10344.0, 10429.791404172025], "value": 7113.074713243592, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 120.0, 120.0, 115.0, 116.0, 120.0, 120.0, 121.0, 143.0], "value": 120.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 113.0, 143.0, 110.0, 115.0, 113.0, 113.0, 141.0, 113.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 197.0, 193.0, 224.0, 262.0, 199.0, 197.0, 197.0, 193.0], "value": 205.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 61.0, 86.0, 65.0, 51.0, 71.0, 84.0, 103.0, 84.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 70.0, 92.0, 103.0, 98.0, 83.0, 86.0, 69.0, 70.0, 89.0], "value": 83.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 87.0, 90.0, 85.0, 97.0, 88.0, 89.0, 87.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 98.0, 109.0, 176.0, 125.0, 173.0, 172.0, 101.0, 99.0], "value": 125.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 88.0, 87.0, 129.0, 136.0, 118.0, 91.0, 89.0, 91.0], "value": 100.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 55.0, 48.0, 84.0, 96.0, 94.0, 64.0, 52.0, 82.0, 95.0], "value": 73.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 89.0, 109.0, 70.0, 115.0, 105.0, 67.0, 84.0, 99.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 103.0, 85.0, 84.0, 96.0, 83.0, 85.0, 84.0, 92.0, 109.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 140.0, 151.0, 152.0, 153.0, 160.0, 144.0, 224.0, 133.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 169.0, 166.0, 196.0, 153.0, 201.0, 163.0, 204.0, 235.0, 235.0], "value": 194.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 66.0, 68.0, 68.0, 68.0, 66.0, 68.0, 67.0, 74.0], "value": 68.4, "unit": "score"}]}]} 15:32:07 INFO - Return code: 0 15:32:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:32:07 ERROR - # TBPL SUCCESS # 15:32:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:32:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:32:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:32:07 INFO - 2017-01-03 15:32:07.096 screenresolution[1654:10635] starting screenresolution argv=screenresolution get 15:32:07 INFO - 2017-01-03 15:32:07.101 screenresolution[1654:10635] Display 0: 1600x1200x32@60 15:32:07 INFO - 2017-01-03 15:32:07.107 screenresolution[1655:10638] starting screenresolution argv=screenresolution list 15:32:07 INFO - Available Modes on Display 0 15:32:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:32:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:32:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:32:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:32:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:32:07 INFO - Intel Iris: 15:32:07 INFO - Chipset Model: Intel Iris 15:32:07 INFO - Type: GPU 15:32:07 INFO - Bus: Built-In 15:32:07 INFO - VRAM (Dynamic, Max): 1536 MB 15:32:07 INFO - Vendor: Intel (0x8086) 15:32:07 INFO - Device ID: 0x0a2e 15:32:07 INFO - Revision ID: 0x0009 15:32:07 INFO - Displays: 15:32:07 INFO - Display: 15:32:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:32:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:32:07 INFO - Display Serial Number: 1600x1200 60 15:32:07 INFO - Main Display: Yes 15:32:07 INFO - Mirror: Off 15:32:07 INFO - Online: Yes 15:32:07 INFO - Rotation: Supported 15:32:07 INFO - Return code: 0 15:32:07 INFO - Running post-action listener: _resource_record_post_action 15:32:07 INFO - [mozharness: 2017-01-03 23:32:07.349854Z] Finished run-tests step (success) 15:32:07 INFO - Running post-run listener: _resource_record_post_run 15:32:07 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37706240; Write bytes: 659454464; Read time: 243; Write time: 1925 15:32:07 INFO - TinderboxPrint: CPU usage
54.4% 15:32:07 INFO - TinderboxPrint: I/O read bytes / time
37,706,240 / 243 15:32:07 INFO - TinderboxPrint: I/O write bytes / time
659,454,464 / 1,925 15:32:07 INFO - TinderboxPrint: CPU idle
2,208.4 (45.6%) 15:32:07 INFO - TinderboxPrint: CPU system
976.9 (20.2%) 15:32:07 INFO - TinderboxPrint: CPU user
1,656.3 (34.2%) 15:32:07 INFO - TinderboxPrint: Swap in / out
386,629,632 / 0 15:32:07 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 350046720; Write bytes: 350721536; Read time: 26907; Write time: 728 15:32:07 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35416576; Write bytes: 307393536; Read time: 220; Write time: 1191 15:32:07 INFO - Running post-run listener: _upload_blobber_files 15:32:07 INFO - Blob upload gear active. 15:32:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:32:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:32:07 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'] 15:32:07 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 15:32:08 INFO - (blobuploader) - INFO - Open directory for files ... 15:32:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:32:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:32:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:08 INFO - (blobuploader) - INFO - Done attempting. 15:32:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:32:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:32:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:09 INFO - (blobuploader) - INFO - Done attempting. 15:32:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:32:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:32:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:12 INFO - (blobuploader) - INFO - Done attempting. 15:32:12 INFO - (blobuploader) - INFO - Iteration through files over. 15:32:12 INFO - Return code: 0 15:32:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:32:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:32:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c22b6120e877c9b946f9cb629824a562abdbc27ef21779a015de4d623b2627fbefaf847b6b49e96320ab95c098dbda647f753c282d93371a5993197bffeeb80e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82d4bd097c751ea9d322adfa2d5dd80b0f9faca4464112509d3b4d274a7939ddbbbd7758c851e71e34ae7c1ce6596b1a01843ef524da10905fd6b9ee57c0f120", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a1bbf0cd7e8e42714a5def69ee25b1e1a856299898b3a4040f7dd03aa3563691b8a90c64e3c15356a3411674006aa94827d167b4e903cf7465d9b821d4abc13"} 15:32:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:32:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:32:12 INFO - Contents: 15:32:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c22b6120e877c9b946f9cb629824a562abdbc27ef21779a015de4d623b2627fbefaf847b6b49e96320ab95c098dbda647f753c282d93371a5993197bffeeb80e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82d4bd097c751ea9d322adfa2d5dd80b0f9faca4464112509d3b4d274a7939ddbbbd7758c851e71e34ae7c1ce6596b1a01843ef524da10905fd6b9ee57c0f120", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a1bbf0cd7e8e42714a5def69ee25b1e1a856299898b3a4040f7dd03aa3563691b8a90c64e3c15356a3411674006aa94827d167b4e903cf7465d9b821d4abc13"} 15:32:12 INFO - Running post-run listener: copy_logs_to_upload_dir 15:32:12 INFO - Copying logs to upload dir... 15:32:12 INFO - mkdir: /builds/slave/test/build/upload/logs 15:32:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.234273 ========= master_lag: 3.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-03 15:32:15.685506) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 15:32:15.688777) ========= 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.RS61RIAU6E/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.qnBzGP766e/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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/c22b6120e877c9b946f9cb629824a562abdbc27ef21779a015de4d623b2627fbefaf847b6b49e96320ab95c098dbda647f753c282d93371a5993197bffeeb80e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82d4bd097c751ea9d322adfa2d5dd80b0f9faca4464112509d3b4d274a7939ddbbbd7758c851e71e34ae7c1ce6596b1a01843ef524da10905fd6b9ee57c0f120", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a1bbf0cd7e8e42714a5def69ee25b1e1a856299898b3a4040f7dd03aa3563691b8a90c64e3c15356a3411674006aa94827d167b4e903cf7465d9b821d4abc13"} build_url:https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010894 build_url: 'https://queue.taskcluster.net/v1/task/cPaMZgr5Q2O_JsGgcFF3mg/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/c22b6120e877c9b946f9cb629824a562abdbc27ef21779a015de4d623b2627fbefaf847b6b49e96320ab95c098dbda647f753c282d93371a5993197bffeeb80e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82d4bd097c751ea9d322adfa2d5dd80b0f9faca4464112509d3b4d274a7939ddbbbd7758c851e71e34ae7c1ce6596b1a01843ef524da10905fd6b9ee57c0f120", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a1bbf0cd7e8e42714a5def69ee25b1e1a856299898b3a4040f7dd03aa3563691b8a90c64e3c15356a3411674006aa94827d167b4e903cf7465d9b821d4abc13"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 15:32:15.722910) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 15:32:15.723243) ========= 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.RS61RIAU6E/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.qnBzGP766e/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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.004650 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 15:32:15.771282) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 15:32:15.771630) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 15:32:15.771943) ========= ========= Total master_lag: 3.32 =========