builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0165 starttime: 1494023968.89 results: success (0) buildid: 20170505140954 builduid: b80f32ec100b42a6839d64efd7f6ff0c revision: ff83fde8be946eabcf27ea97d4676f601c122194 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:28.895151) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:28.895676) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:28.896008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:28.923655) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:28.924163) ========= 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.ik0LzxHPiy/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.S0lBVY7m92/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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-05-05 15:39:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.6M=0s 2017-05-05 15:39:29 (29.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126123 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:29.078533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:29.079277) ========= 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.ik0LzxHPiy/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.S0lBVY7m92/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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.026793 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:29.129773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-05 15:39:29.130128) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ff83fde8be946eabcf27ea97d4676f601c122194 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ff83fde8be946eabcf27ea97d4676f601c122194 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ik0LzxHPiy/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.S0lBVY7m92/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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-05-05 15:39:29,224 truncating revision to first 12 chars 2017-05-05 15:39:29,224 Setting DEBUG logging. 2017-05-05 15:39:29,224 attempt 1/10 2017-05-05 15:39:29,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ff83fde8be94?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 15:39:30,978 unpacking tar archive at: mozilla-central-ff83fde8be94/testing/mozharness/ program finished with exit code 0 elapsedTime=2.035035 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-05 15:39:31.189353) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:31.189688) ========= script_repo_revision: ff83fde8be946eabcf27ea97d4676f601c122194 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:31.190366) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:31.190657) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:31.211190) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-05-05 15:39:31.211501) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ik0LzxHPiy/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.S0lBVY7m92/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:39:31 INFO - MultiFileLogger online at 20170505 15:39:31 in /builds/slave/test 15:39:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:39:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:31 INFO - {'append_to_log': False, 15:39:31 INFO - 'base_work_dir': '/builds/slave/test', 15:39:31 INFO - 'blob_upload_branch': 'Firefox', 15:39:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:31 INFO - 'branch': 'Firefox', 15:39:31 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:31 INFO - 'config_files': ('talos/mac_config.py',), 15:39:31 INFO - 'default_actions': ('clobber', 15:39:31 INFO - 'read-buildbot-config', 15:39:31 INFO - 'download-and-extract', 15:39:31 INFO - 'populate-webroot', 15:39:31 INFO - 'create-virtualenv', 15:39:31 INFO - 'install', 15:39:31 INFO - 'run-tests'), 15:39:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:31 INFO - 'download_minidump_stackwalk': True, 15:39:31 INFO - 'download_symbols': 'ondemand', 15:39:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:31 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:31 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:31 INFO - 'gecko_profile': False, 15:39:31 INFO - 'gecko_profile_interval': 0, 15:39:31 INFO - 'installer_path': 'installer.exe', 15:39:31 INFO - 'log_level': 'info', 15:39:31 INFO - 'log_name': 'talos', 15:39:31 INFO - 'log_to_console': True, 15:39:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:39:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:39:31 INFO - 'opt_config_files': (), 15:39:31 INFO - 'pip_index': False, 15:39:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:31 INFO - 'cmd': ('bash', 15:39:31 INFO - '-c', 15:39:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:31 INFO - 'enabled': True, 15:39:31 INFO - 'halt_on_failure': False, 15:39:31 INFO - 'name': 'check_screen_resolution'},), 15:39:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:31 INFO - 'cmd': ('bash', 15:39:31 INFO - '-c', 15:39:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:31 INFO - 'enabled': True, 15:39:31 INFO - 'halt_on_failure': False, 15:39:31 INFO - 'name': 'check_screen_resolution'},), 15:39:31 INFO - 'run_cmd_checks_enabled': True, 15:39:31 INFO - 'suite': 'dromaeojs', 15:39:31 INFO - 'system_bits': '32', 15:39:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:31 INFO - 'title': 't-yosemite-r7-0165', 15:39:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:31 INFO - 'use_talos_json': True, 15:39:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:31 INFO - 'work_dir': 'build'} 15:39:31 INFO - [mozharness: 2017-05-05 22:39:31.415156Z] Running clobber step. 15:39:31 INFO - Running pre-action listener: _resource_record_pre_action 15:39:31 INFO - Running main action method: clobber 15:39:31 INFO - rmtree: /builds/slave/test/build 15:39:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:33 INFO - Running post-action listener: _resource_record_post_action 15:39:33 INFO - [mozharness: 2017-05-05 22:39:33.967836Z] Finished clobber step (success) 15:39:33 INFO - [mozharness: 2017-05-05 22:39:33.968038Z] Running read-buildbot-config step. 15:39:33 INFO - Running pre-action listener: _resource_record_pre_action 15:39:33 INFO - Running main action method: read_buildbot_config 15:39:33 INFO - Using buildbot properties: 15:39:33 INFO - { 15:39:33 INFO - "project": "", 15:39:33 INFO - "product": "firefox", 15:39:33 INFO - "script_repo_revision": "production", 15:39:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:39:33 INFO - "repository": "", 15:39:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:39:33 INFO - "buildid": "20170505140954", 15:39:33 INFO - "pgo_build": "False", 15:39:33 INFO - "basedir": "/builds/slave/test", 15:39:33 INFO - "buildnumber": 23, 15:39:33 INFO - "slavename": "t-yosemite-r7-0165", 15:39:33 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 15:39:33 INFO - "platform": "yosemite_r7", 15:39:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:39:33 INFO - "branch": "mozilla-central", 15:39:33 INFO - "repo_path": "mozilla-central", 15:39:33 INFO - "revision": "ff83fde8be946eabcf27ea97d4676f601c122194", 15:39:33 INFO - "stage_platform": "macosx64", 15:39:33 INFO - "builduid": "b80f32ec100b42a6839d64efd7f6ff0c", 15:39:33 INFO - "slavebuilddir": "test" 15:39:33 INFO - } 15:39:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:39:33 INFO - Running post-action listener: _resource_record_post_action 15:39:33 INFO - [mozharness: 2017-05-05 22:39:33.973873Z] Finished read-buildbot-config step (success) 15:39:33 INFO - [mozharness: 2017-05-05 22:39:33.974013Z] Running download-and-extract step. 15:39:33 INFO - Running pre-action listener: _resource_record_pre_action 15:39:33 INFO - Running main action method: download_and_extract 15:39:33 INFO - mkdir: /builds/slave/test/build/tests 15:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:33 INFO - trying https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:39:33 INFO - Downloading https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:39:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 15:39:35 INFO - Downloaded 1325 bytes. 15:39:35 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:39:35 INFO - Using the following test package requirements: 15:39:35 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:39:35 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:39:35 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:39:35 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:39:35 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'jsshell-mac.zip'], 15:39:35 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:39:35 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:39:35 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:39:35 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:39:35 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:39:35 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:35 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:39:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:39:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:39:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:39:35 INFO - Fetch https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:39:37 INFO - Content-Length response header: 52703592 15:39:37 INFO - Bytes received: 52703592 15:39:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:39:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:39:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:39:53 INFO - Fetch https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:39:56 INFO - Content-Length response header: 52703592 15:39:56 INFO - Bytes received: 52703592 15:40:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:40:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:40:11 INFO - Fetch https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:40:12 INFO - Content-Length response header: 14408697 15:40:12 INFO - Bytes received: 14408697 15:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:12 INFO - trying https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:40:12 INFO - Downloading https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:40:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 15:40:14 INFO - Downloaded 70882031 bytes. 15:40:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:40:14 INFO - mkdir: /builds/slave/test/properties 15:40:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:40:14 INFO - Writing to file /builds/slave/test/properties/build_url 15:40:14 INFO - Contents: 15:40:14 INFO - build_url:https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:40:15 INFO - Running post-action listener: _resource_record_post_action 15:40:15 INFO - Running post-action listener: set_extra_try_arguments 15:40:15 INFO - [mozharness: 2017-05-05 22:40:15.678447Z] Finished download-and-extract step (success) 15:40:15 INFO - [mozharness: 2017-05-05 22:40:15.678574Z] Running populate-webroot step. 15:40:15 INFO - Running pre-action listener: _resource_record_pre_action 15:40:15 INFO - Running main action method: populate_webroot 15:40:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:40:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:40:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:40:15 INFO - '--tppagecycles', 15:40:15 INFO - '7'], 15:40:15 INFO - 'tests': ['tsvgm']}}, 15:40:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:40:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:40:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:40:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:40:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:40:15 INFO - 'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:40:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:40:15 INFO - 'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['damp', 'tps']}, 15:40:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:40:15 INFO - 'tests': ['damp', 'tps']}, 15:40:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['dromaeo_dom']}, 15:40:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:40:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:40:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:40:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['a11yr', 15:40:15 INFO - 'ts_paint', 15:40:15 INFO - 'tpaint', 15:40:15 INFO - 'sessionrestore', 15:40:15 INFO - 'sessionrestore_no_auto_restore', 15:40:15 INFO - 'tabpaint']}, 15:40:15 INFO - 'other-e10s': {'tests': ['a11yr', 15:40:15 INFO - 'ts_paint', 15:40:15 INFO - 'tpaint', 15:40:15 INFO - 'sessionrestore', 15:40:15 INFO - 'sessionrestore_no_auto_restore', 15:40:15 INFO - 'tabpaint']}, 15:40:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:40:15 INFO - 'ts_paint', 15:40:15 INFO - 'tpaint', 15:40:15 INFO - 'sessionrestore', 15:40:15 INFO - 'sessionrestore_no_auto_restore', 15:40:15 INFO - 'tabpaint']}, 15:40:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:40:15 INFO - 'ts_paint', 15:40:15 INFO - 'tpaint', 15:40:15 INFO - 'sessionrestore', 15:40:15 INFO - 'sessionrestore_no_auto_restore', 15:40:15 INFO - 'tabpaint']}, 15:40:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['a11yr', 15:40:15 INFO - 'ts_paint', 15:40:15 INFO - 'tpaint', 15:40:15 INFO - 'sessionrestore', 15:40:15 INFO - 'sessionrestore_no_auto_restore', 15:40:15 INFO - 'tabpaint']}, 15:40:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['a11yr', 15:40:15 INFO - 'ts_paint', 15:40:15 INFO - 'tpaint', 15:40:15 INFO - 'sessionrestore', 15:40:15 INFO - 'sessionrestore_no_auto_restore', 15:40:15 INFO - 'tabpaint']}, 15:40:15 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:40:15 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:40:15 INFO - 'bloom_basic_ref']}, 15:40:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['tsvgx', 15:40:15 INFO - 'tsvgr_opacity', 15:40:15 INFO - 'tart', 15:40:15 INFO - 'tscrollx', 15:40:15 INFO - 'cart', 15:40:15 INFO - 'tsvg_static']}, 15:40:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:40:15 INFO - 'tsvgr_opacity', 15:40:15 INFO - 'tart', 15:40:15 INFO - 'tscrollx', 15:40:15 INFO - 'cart', 15:40:15 INFO - 'tsvg_static']}, 15:40:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:40:15 INFO - 'talos_options': ['--disable-e10s'], 15:40:15 INFO - 'tests': ['tp5o']}, 15:40:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:40:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:40:15 INFO - 'talos_options': ['--disable-e10s', 15:40:15 INFO - '--xperf_path', 15:40:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:15 INFO - 'tests': ['tp5n']}, 15:40:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:40:15 INFO - 'talos_options': ['--xperf_path', 15:40:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:15 INFO - 'tests': ['tp5n']}}, 15:40:15 INFO - 'talos.zip': {'path': '', 15:40:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:40:15 INFO - Running post-action listener: _resource_record_post_action 15:40:15 INFO - [mozharness: 2017-05-05 22:40:15.698065Z] Finished populate-webroot step (success) 15:40:15 INFO - [mozharness: 2017-05-05 22:40:15.698196Z] Running create-virtualenv step. 15:40:15 INFO - Running pre-action listener: _resource_record_pre_action 15:40:15 INFO - Running main action method: create_virtualenv 15:40:15 INFO - Creating virtualenv /builds/slave/test/build/venv 15:40:15 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:40:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:40:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:40:15 INFO - Using real prefix '/tools/python27' 15:40:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:40:16 INFO - Installing distribute.............................................................................................................................................................................................done. 15:40:19 INFO - Installing pip.................done. 15:40:19 INFO - Return code: 0 15:40:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:40:19 INFO - Reading from file tmpfile_stdout 15:40:19 INFO - Output received: 15:40:19 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:40:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:19 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 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:19 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:40:19 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:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:19 INFO - 'HOME': '/Users/cltbld', 15:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:19 INFO - 'LOGNAME': 'cltbld', 15:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:19 INFO - 'MOZ_NO_REMOTE': '1', 15:40:19 INFO - 'NO_EM_RESTART': '1', 15:40:19 INFO - 'PAGER': '/bin/cat', 15:40:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:19 INFO - 'PWD': '/builds/slave/test', 15:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:19 INFO - 'SHELL': '/bin/bash', 15:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:19 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:19 INFO - 'USER': 'cltbld', 15:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:19 INFO - 'XPC_FLAGS': '0x0', 15:40:19 INFO - 'XPC_SERVICE_NAME': '0', 15:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:19 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:40:19 INFO - Cleaning up... 15:40:19 INFO - Return code: 0 15:40:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:19 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 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:19 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:40:19 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:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:19 INFO - 'HOME': '/Users/cltbld', 15:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:19 INFO - 'LOGNAME': 'cltbld', 15:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:19 INFO - 'MOZ_NO_REMOTE': '1', 15:40:19 INFO - 'NO_EM_RESTART': '1', 15:40:19 INFO - 'PAGER': '/bin/cat', 15:40:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:19 INFO - 'PWD': '/builds/slave/test', 15:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:19 INFO - 'SHELL': '/bin/bash', 15:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:19 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:19 INFO - 'USER': 'cltbld', 15:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:19 INFO - 'XPC_FLAGS': '0x0', 15:40:19 INFO - 'XPC_SERVICE_NAME': '0', 15:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:20 INFO - Downloading/unpacking psutil>=3.1.1 15:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:40:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:22 INFO - Installing collected packages: psutil 15:40:22 INFO - Running setup.py install for psutil 15:40:22 INFO - building 'psutil._psutil_osx' extension 15:40:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:40:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:40:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:40:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:40:23 INFO - building 'psutil._psutil_posix' extension 15:40:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:40:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:40:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:40:23 INFO - ^ 15:40:23 INFO - 1 warning generated. 15:40:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:40:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:23 INFO - Successfully installed psutil 15:40:23 INFO - Cleaning up... 15:40:23 INFO - Return code: 0 15:40:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:40:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:23 INFO - 'HOME': '/Users/cltbld', 15:40:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:23 INFO - 'LOGNAME': 'cltbld', 15:40:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:23 INFO - 'MOZ_NO_REMOTE': '1', 15:40:23 INFO - 'NO_EM_RESTART': '1', 15:40:23 INFO - 'PAGER': '/bin/cat', 15:40:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:23 INFO - 'PWD': '/builds/slave/test', 15:40:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:23 INFO - 'SHELL': '/bin/bash', 15:40:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:23 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:23 INFO - 'USER': 'cltbld', 15:40:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:23 INFO - 'XPC_FLAGS': '0x0', 15:40:23 INFO - 'XPC_SERVICE_NAME': '0', 15:40:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:40:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:40:26 INFO - Installing collected packages: mozsystemmonitor 15:40:26 INFO - Running setup.py install for mozsystemmonitor 15:40:26 INFO - Successfully installed mozsystemmonitor 15:40:26 INFO - Cleaning up... 15:40:26 INFO - Return code: 0 15:40:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:40:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:27 INFO - 'HOME': '/Users/cltbld', 15:40:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:27 INFO - 'LOGNAME': 'cltbld', 15:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:27 INFO - 'MOZ_NO_REMOTE': '1', 15:40:27 INFO - 'NO_EM_RESTART': '1', 15:40:27 INFO - 'PAGER': '/bin/cat', 15:40:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:27 INFO - 'PWD': '/builds/slave/test', 15:40:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:27 INFO - 'SHELL': '/bin/bash', 15:40:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:27 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:27 INFO - 'USER': 'cltbld', 15:40:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:27 INFO - 'XPC_FLAGS': '0x0', 15:40:27 INFO - 'XPC_SERVICE_NAME': '0', 15:40:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:27 INFO - Downloading/unpacking jsonschema==2.5.1 15:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:40:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:40: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:40: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:40: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:40: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:40: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:40: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:40:29 INFO - Downloading functools32-3.2.3-2.tar.gz 15:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:40:29 INFO - warning: no files found matching '*.txt' 15:40:29 INFO - no previously-included directories found matching 'build' 15:40:29 INFO - no previously-included directories found matching 'dist' 15:40:29 INFO - no previously-included directories found matching '.git*' 15:40:29 INFO - Installing collected packages: jsonschema, functools32 15:40:29 INFO - Running setup.py install for functools32 15:40:29 INFO - warning: no files found matching '*.txt' 15:40:29 INFO - no previously-included directories found matching 'build' 15:40:29 INFO - no previously-included directories found matching 'dist' 15:40:29 INFO - no previously-included directories found matching '.git*' 15:40:29 INFO - Successfully installed jsonschema functools32 15:40:29 INFO - Cleaning up... 15:40:29 INFO - Return code: 0 15:40:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:29 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 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:29 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:40:29 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:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:29 INFO - 'HOME': '/Users/cltbld', 15:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:29 INFO - 'LOGNAME': 'cltbld', 15:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:29 INFO - 'MOZ_NO_REMOTE': '1', 15:40:29 INFO - 'NO_EM_RESTART': '1', 15:40:29 INFO - 'PAGER': '/bin/cat', 15:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:29 INFO - 'PWD': '/builds/slave/test', 15:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:29 INFO - 'SHELL': '/bin/bash', 15:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:29 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:29 INFO - 'USER': 'cltbld', 15:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:29 INFO - 'XPC_FLAGS': '0x0', 15:40:29 INFO - 'XPC_SERVICE_NAME': '0', 15:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:40:29 INFO - Cleaning up... 15:40:29 INFO - Return code: 0 15:40:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:29 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 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:29 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:40:29 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:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:29 INFO - 'HOME': '/Users/cltbld', 15:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:29 INFO - 'LOGNAME': 'cltbld', 15:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:29 INFO - 'MOZ_NO_REMOTE': '1', 15:40:29 INFO - 'NO_EM_RESTART': '1', 15:40:29 INFO - 'PAGER': '/bin/cat', 15:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:29 INFO - 'PWD': '/builds/slave/test', 15:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:29 INFO - 'SHELL': '/bin/bash', 15:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:29 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:29 INFO - 'USER': 'cltbld', 15:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:29 INFO - 'XPC_FLAGS': '0x0', 15:40:29 INFO - 'XPC_SERVICE_NAME': '0', 15:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:30 INFO - Downloading/unpacking blobuploader==1.2.4 15:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:31 INFO - Downloading blobuploader-1.2.4.tar.gz 15:40:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:40:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:40:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:32 INFO - Downloading docopt-0.6.1.tar.gz 15:40:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:40:32 INFO - Installing collected packages: blobuploader, requests, docopt 15:40:32 INFO - Running setup.py install for blobuploader 15:40:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:40:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:40:32 INFO - Running setup.py install for requests 15:40:33 INFO - Running setup.py install for docopt 15:40:33 INFO - Successfully installed blobuploader requests docopt 15:40:33 INFO - Cleaning up... 15:40:33 INFO - Return code: 0 15:40:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:33 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 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:33 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:40:33 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:40:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:33 INFO - 'HOME': '/Users/cltbld', 15:40:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:33 INFO - 'LOGNAME': 'cltbld', 15:40:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:33 INFO - 'MOZ_NO_REMOTE': '1', 15:40:33 INFO - 'NO_EM_RESTART': '1', 15:40:33 INFO - 'PAGER': '/bin/cat', 15:40:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:33 INFO - 'PWD': '/builds/slave/test', 15:40:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:33 INFO - 'SHELL': '/bin/bash', 15:40:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:33 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:33 INFO - 'USER': 'cltbld', 15:40:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:33 INFO - 'XPC_FLAGS': '0x0', 15:40:33 INFO - 'XPC_SERVICE_NAME': '0', 15:40:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:33 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-ifDnlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:33 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-skOof2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:33 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-nkLssk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:33 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-zPM82T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:33 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-ZIMAh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:33 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-HKfRuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-MM9yxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-jNRFYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-X2OC11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-R0b9jB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-Iof0Ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-8zWM2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-1iMiUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-e0Deye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:34 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-4oioWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:35 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-C8_EGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:35 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-AUZyqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:35 INFO - Running setup.py install for manifestparser 15:40:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:35 INFO - Running setup.py install for mozcrash 15:40:35 INFO - Running setup.py install for mozdebug 15:40:35 INFO - Running setup.py install for mozdevice 15:40:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:35 INFO - Running setup.py install for mozfile 15:40:35 INFO - Running setup.py install for mozhttpd 15:40:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:36 INFO - Running setup.py install for mozinfo 15:40:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:36 INFO - Running setup.py install for mozInstall 15:40:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:36 INFO - Running setup.py install for mozleak 15:40:36 INFO - Running setup.py install for mozlog 15:40:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:36 INFO - Running setup.py install for moznetwork 15:40:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:36 INFO - Running setup.py install for mozprocess 15:40:36 INFO - Running setup.py install for mozprofile 15:40:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:37 INFO - Running setup.py install for mozrunner 15:40:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:37 INFO - Running setup.py install for mozscreenshot 15:40:37 INFO - Running setup.py install for moztest 15:40:37 INFO - Running setup.py install for mozversion 15:40:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:37 INFO - Cleaning up... 15:40:37 INFO - Return code: 0 15:40:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:37 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 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:37 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:40:37 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:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:37 INFO - 'HOME': '/Users/cltbld', 15:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:37 INFO - 'LOGNAME': 'cltbld', 15:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:37 INFO - 'MOZ_NO_REMOTE': '1', 15:40:37 INFO - 'NO_EM_RESTART': '1', 15:40:37 INFO - 'PAGER': '/bin/cat', 15:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:37 INFO - 'PWD': '/builds/slave/test', 15:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:37 INFO - 'SHELL': '/bin/bash', 15:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:37 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:37 INFO - 'USER': 'cltbld', 15:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:37 INFO - 'XPC_FLAGS': '0x0', 15:40:37 INFO - 'XPC_SERVICE_NAME': '0', 15:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-HMoWnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-E11R4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-jknA6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-raM8aE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-2wBinC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-N7hIx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-p7nLNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-Zrt14i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-oJCAZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:38 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-f13Dpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:39 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-Vure0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:39 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-GqvtE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:39 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-Og8Cmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:39 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-KSvMFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:39 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-xInMDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:39 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-ST0ild-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:39 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-BIhf_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:39 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:40:39 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:40:39 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:40:39 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:40:39 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:39 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:39 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:40:39 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:40:39 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:40:39 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:40:39 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:40:39 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:40:41 INFO - Downloading blessings-1.6.tar.gz 15:40:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:41 INFO - Installing collected packages: blessings 15:40:41 INFO - Running setup.py install for blessings 15:40:41 INFO - Successfully installed blessings 15:40:41 INFO - Cleaning up... 15:40:41 INFO - Return code: 0 15:40:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:41 INFO - Reading from file tmpfile_stdout 15:40:41 INFO - Current package versions: 15:40:41 INFO - blessings == 1.6 15:40:41 INFO - blobuploader == 1.2.4 15:40:41 INFO - docopt == 0.6.1 15:40:41 INFO - functools32 == 3.2.3-2 15:40:41 INFO - jsonschema == 2.5.1 15:40:41 INFO - manifestparser == 1.1 15:40:41 INFO - mozInstall == 1.12 15:40:41 INFO - mozcrash == 1.0 15:40:41 INFO - mozdebug == 0.1 15:40:41 INFO - mozdevice == 0.50 15:40:41 INFO - mozfile == 1.2 15:40:41 INFO - mozhttpd == 0.7 15:40:41 INFO - mozinfo == 0.9 15:40:41 INFO - mozleak == 0.1 15:40:41 INFO - mozlog == 3.4 15:40:41 INFO - moznetwork == 0.27 15:40:41 INFO - mozprocess == 0.25 15:40:41 INFO - mozprofile == 0.28 15:40:41 INFO - mozrunner == 6.13 15:40:41 INFO - mozscreenshot == 0.1 15:40:41 INFO - mozsystemmonitor == 0.3 15:40:41 INFO - moztest == 0.8 15:40:41 INFO - mozversion == 1.4 15:40:41 INFO - psutil == 3.1.1 15:40:41 INFO - requests == 1.2.3 15:40:41 INFO - wsgiref == 0.1.2 15:40:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:41 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 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:41 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:40:41 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:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:41 INFO - 'HOME': '/Users/cltbld', 15:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:41 INFO - 'LOGNAME': 'cltbld', 15:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:41 INFO - 'MOZ_NO_REMOTE': '1', 15:40:41 INFO - 'NO_EM_RESTART': '1', 15:40:41 INFO - 'PAGER': '/bin/cat', 15:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:41 INFO - 'PWD': '/builds/slave/test', 15:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:41 INFO - 'SHELL': '/bin/bash', 15:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:41 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:41 INFO - 'USER': 'cltbld', 15:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:41 INFO - 'XPC_FLAGS': '0x0', 15:40:41 INFO - 'XPC_SERVICE_NAME': '0', 15:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:42 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:40:42 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:40:42 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:40:42 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:40:42 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:40:42 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:40:42 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:40:42 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:40:42 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:40:42 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:40:42 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:40:42 INFO - Cleaning up... 15:40:42 INFO - Return code: 0 15:40:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:42 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 0x10dc74b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc77768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc484b6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, '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.ik0LzxHPiy/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.S0lBVY7m92/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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:40:42 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:40:42 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:40:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:40:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:42 INFO - 'HOME': '/Users/cltbld', 15:40:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:42 INFO - 'LOGNAME': 'cltbld', 15:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:42 INFO - 'MOZ_NO_REMOTE': '1', 15:40:42 INFO - 'NO_EM_RESTART': '1', 15:40:42 INFO - 'PAGER': '/bin/cat', 15:40:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:42 INFO - 'PWD': '/builds/slave/test', 15:40:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:42 INFO - 'SHELL': '/bin/bash', 15:40:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:40:42 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:40:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:42 INFO - 'USER': 'cltbld', 15:40:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:42 INFO - 'XPC_FLAGS': '0x0', 15:40:42 INFO - 'XPC_SERVICE_NAME': '0', 15:40:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:42 INFO - Cleaning up... 15:40:42 INFO - Return code: 0 15:40:42 INFO - Running post-action listener: _resource_record_post_action 15:40:42 INFO - Running post-action listener: _start_resource_monitoring 15:40:42 INFO - Starting resource monitoring. 15:40:42 INFO - [mozharness: 2017-05-05 22:40:42.398686Z] Finished create-virtualenv step (success) 15:40:42 INFO - [mozharness: 2017-05-05 22:40:42.399661Z] Running install step. 15:40:42 INFO - Running pre-action listener: _resource_record_pre_action 15:40:42 INFO - Running main action method: install 15:40:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:42 INFO - Reading from file tmpfile_stdout 15:40:42 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:42 INFO - Reading from file tmpfile_stdout 15:40:42 INFO - Output received: 15:40:42 INFO - Usage: mozinstall [options] installer 15:40:42 INFO - Options: 15:40:42 INFO - -h, --help show this help message and exit 15:40:42 INFO - -d DEST, --destination=DEST 15:40:42 INFO - Directory to install application into. [default: 15:40:42 INFO - "/builds/slave/test"] 15:40:42 INFO - --app=APP Application being installed. [default: firefox] 15:40:42 INFO - mkdir: /builds/slave/test/build/application 15:40:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:41:02 INFO - Reading from file tmpfile_stdout 15:41:02 INFO - Output received: 15:41:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:41:02 INFO - Running post-action listener: _resource_record_post_action 15:41:02 INFO - [mozharness: 2017-05-05 22:41:02.715800Z] Finished install step (success) 15:41:02 INFO - [mozharness: 2017-05-05 22:41:02.715942Z] Running run-tests step. 15:41:02 INFO - Running pre-action listener: _resource_record_pre_action 15:41:02 INFO - Running main action method: run_tests 15:41:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:41:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:41:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:41:02 INFO - 2017-05-05 15:41:02.731 screenresolution[1619:7304] starting screenresolution argv=screenresolution get 15:41:02 INFO - 2017-05-05 15:41:02.733 screenresolution[1619:7304] Display 0: 1600x1200x32@60 15:41:02 INFO - 2017-05-05 15:41:02.739 screenresolution[1620:7307] starting screenresolution argv=screenresolution list 15:41:02 INFO - Available Modes on Display 0 15:41:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:41:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:41:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:41:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:41:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:41:02 INFO - Intel Iris: 15:41:02 INFO - Chipset Model: Intel Iris 15:41:02 INFO - Type: GPU 15:41:02 INFO - Bus: Built-In 15:41:02 INFO - VRAM (Dynamic, Max): 1536 MB 15:41:02 INFO - Vendor: Intel (0x8086) 15:41:02 INFO - Device ID: 0x0a2e 15:41:02 INFO - Revision ID: 0x0009 15:41:02 INFO - Displays: 15:41:02 INFO - Display: 15:41:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:41:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:41:02 INFO - Display Serial Number: 1600x1200 60 15:41:02 INFO - Main Display: Yes 15:41:02 INFO - Mirror: Off 15:41:02 INFO - Online: Yes 15:41:02 INFO - Rotation: Supported 15:41:02 INFO - Return code: 0 15:41:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:41:02 INFO - Python 2.7.3 15:41:02 INFO - Return code: 0 15:41:02 INFO - grabbing minidump binary from tooltool 15:41: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:41:02 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 0x10da52d30>, '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 0x10d978db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da47be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:41:02 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:41:02 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:41:02 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:41:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:41:03 INFO - Return code: 0 15:41:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:41:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:41:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:41:03 INFO - ENV: RUST_BACKTRACE is now 1 15:41:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:41:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:41:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:41:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0165 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:41:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ik0LzxHPiy/Render', 15:41:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:03 INFO - 'HOME': '/Users/cltbld', 15:41:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:03 INFO - 'LOGNAME': 'cltbld', 15:41:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:41:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:03 INFO - 'MOZ_NO_REMOTE': '1', 15:41:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:41:03 INFO - 'NO_EM_RESTART': '1', 15:41:03 INFO - 'PAGER': '/bin/cat', 15:41:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:03 INFO - 'PWD': '/builds/slave/test', 15:41:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:41:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:03 INFO - 'RUST_BACKTRACE': '1', 15:41:03 INFO - 'SHELL': '/bin/bash', 15:41:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0lBVY7m92/Listeners', 15:41:03 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 15:41:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:03 INFO - 'USER': 'cltbld', 15:41:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:03 INFO - 'XPC_FLAGS': '0x0', 15:41:03 INFO - 'XPC_SERVICE_NAME': '0', 15:41:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:41:03 INFO - mozversion application_buildid: 20170505140954 15:41:03 INFO - mozversion application_changeset: ff83fde8be946eabcf27ea97d4676f601c122194 15:41:03 INFO - mozversion application_display_name: Nightly 15:41:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:41:03 INFO - mozversion application_name: Firefox 15:41:03 INFO - mozversion application_remotingname: firefox 15:41:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:41:03 INFO - mozversion application_vendor: Mozilla 15:41:03 INFO - mozversion application_version: 55.0a1 15:41:03 INFO - mozversion platform_buildid: 20170505140954 15:41:03 INFO - mozversion platform_changeset: ff83fde8be946eabcf27ea97d4676f601c122194 15:41:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:41:03 INFO - mozversion platform_version: 55.0a1 15:41:03 INFO - using testdate: 1494024063 15:41:03 INFO - actual date: 1494024063 15:41:03 INFO - starting webserver on 'localhost:49234' 15:41:03 INFO - SUITE-START | Running 2 tests 15:41:03 INFO - TEST-START | dromaeo_css 15:41:03 INFO - operating with platform_type : mac_ 15:41:03 INFO - Initialising browser for dromaeo_css test... 15:41:03 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/tmpFR3lg1/profile http://localhost:49234/getInfo.html) 15:41:06 INFO - PID 1630 | __metrics Screen width/height:1600/1200 15:41:06 INFO - PID 1630 | colorDepth:24 15:41:06 INFO - PID 1630 | Browser inner width/height: 1024/691 15:41:06 INFO - PID 1630 | __metrics 15:41:06 INFO - PID 1630 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:41:06 INFO - PID 1630 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:41:07 INFO - TEST-INFO | 1630: exit 0 15:41:07 INFO - Browser initialized. 15:41:07 INFO - Running cycle 1/1 for dromaeo_css test... 15:41:07 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/tmpFR3lg1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:41:19 INFO - PID 1632 | RSS: Main: 251338752 15:41:19 INFO - PID 1632 | 15:44:23 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49234/tests/dromaeo/cssquery-ext.html) 15:44:25 INFO - PID 1632 | RSS: Main: 421441536 15:44:25 INFO - PID 1632 | 15:47:28 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-ext.html (next: http://localhost:49234/tests/dromaeo/cssquery-jquery.html) 15:47:29 INFO - PID 1632 | RSS: Main: 374292480 15:47:29 INFO - PID 1632 | 15:50:31 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49234/tests/dromaeo/cssquery-mootools.html) 15:50:32 INFO - PID 1632 | RSS: Main: 336007168 15:50:32 INFO - PID 1632 | 15:53:34 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49234/tests/dromaeo/cssquery-prototype.html) 15:53:35 INFO - PID 1632 | RSS: Main: 329330688 15:53:35 INFO - PID 1632 | 15:56:38 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49234/tests/dromaeo/cssquery-yui.html) 15:56:39 INFO - PID 1632 | RSS: Main: 336056320 15:56:39 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-yui.html (next: http://localhost:49234/tests/dromaeo/cssquery-dojo.html) 15:59:41 INFO - PID 1632 | RSS: Main: 380473344 15:59:41 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | __start_tp_report 15:59:41 INFO - PID 1632 | _x_x_mozilla_page_load 15:59:41 INFO - PID 1632 | _x_x_mozilla_page_load_details 15:59:41 INFO - PID 1632 | |i|pagename|runs| 15:59:41 INFO - PID 1632 | |0;dojo.html;3346.748993825463;3531.576210854698;3546.556680414949;3550.254446566221;3599.1002249437643;6014.488768454676;6110.558759196664;6221.160143380643;6261.561690681654;6292.244930608334;6117.309816958462;6199.504039676826;6263.7494500220055;6275.905861412071;6297.18136642237;4844.806207751703;5160.716160611165;5274.709890955995;5320.4679532046675;5352.063388906926;4504.211762941472;5384.946150538483;5413.242146099549;5433.782648694048;5441.619086663935;5281.551068159211;5322.654027488201;5334.333208348957;5342.839714808562;5425.7287135643455;5363.490468405495;5396.1366181410785;5407.161889907071;5438.809641662562;5458.808941687062;5336.706481143536;5347.973260133675;5359.08895487768;5371.301730775013;5419.783208671648;6110.144579758838;6148.493619063313;6153.753849846045;6245.531585131088;6249.468795152417;5575.832725018216;6509.251436084864;6596.208454985384;6607.174103237096;6607.504437167184;6678.332166783332;6697.631630260334;6700.195976482804;6708.563943343667;6710.765123220664;6144.863200307957;6228.37716228378;6296;6341.143945567289;6351.650659213695;6638.369354911276;6670.39966403026;6681.265060843303;6773.762918297836;6797.456203503708;6452.451541619015;6467.223933127961;6468.482521398277;6477.125588045647;6492.64290464029;6577.6053436793945;6581.177352830896;6633.137692100093;6645.136132302867;6648.301928297556;6341.3341599132345;6414.679266036679;6423.100765892778;6544.280129185786;6552.049952756919;6384.52116091295;6395.20059992501;6402.775902843378;6415.711292991827;6495.64273964927;4630.351750754897;4814.037192561502;5256.26412302278;5285.735713214324;5293.179557168645;4523.819047238139;4540.020323988326;4574.862754117382;4587.587117159472;4778.354922085474;6307.022411526221;6313.273973493107;6337.556371053982;6358.872822543523;6366.363363663597;6414.390632889868;6494.6752662367635;6584.30864759203;6633.4693224541925;6699.229588597276;6336.6831658416895;6359.554831161867;6381.202349706286;6388.456981156561;6391.9680401598625;5778.653280803165;5807.5063619591965;5817.941820581825;5820;5870.119482077723;6639.4688424924925;6871.209810871814;6910.20532638753;6927.861442771217;6928.549644273106;6502.707378167899;6504.804855854332;6507.869842603216;6508.707108180049;6512.967435162794;6710.932890671031;6774.220964589135;6885.724571017103;6907.6891539879825;6911.619860907698;6473.158489396348;6529.151210342625;6530.640814755234;6535.738570457129;6554.213494380705;6630.900536492053;6636.9004464932095;6639.535232533676;6648.301928297459;6654.201495820339;6290.150829637941;6356.141920840627;6372.235331760218;6429.03564465334;6431.1317972073175;5221.660592061504;5278.9472105278455;6456.451201647913;6462.741490340334;6474.967625161844;6338.366163383625;6353;6362.6818659067785;6364.936350636434;6376.426121649074;6257.342978987141;6319.96840015797;6467.353264673495;6494.415502604788;6502.902456463252;6272.121902933502;6297.590656607306;6317.431431171217;6333.145025421693;6373.840653983688 15:59:41 INFO - PID 1632 | |1;ext.html;765.8162041110131;807.1242701668689;817.0236567302072;1132.2979752553413;1147.179766466976;26907.461850762997;27488.450230995368;34490.48264276032;37767.81116094423;37844.05389865255;39139.80430097845;39497;39526.60473395265;39569.40645890307;39581.60418395815;8286.254237118657;8464.449810762311;8469.872951905727;8474.406791524596;8485.193906578865;6015.18794962681;6108.083787431877;6116.877662446748;8239.670413183465;8334.416590838644;8433.072362040171;8443.155684431538;8480.151984801501;8481.363897707666;8536.231739143475;8391.70629027984;8456.450330728545;8464.026636936738;8473.661053557851;8475.872861907077;5907.645541267538;5985.910211346833;6008.24896887889;8282.958585207096;8398.118197589287;26651.73348266512;26899.731002689918;36221.27557448862;36432.27135457302;36490.270194596225;3135.294558724269;3611.9638803611892;3632.4732913727626;3637.836297366625;3640.2355505343644;30734.23164420885;30897.53653695174;30905.536416953764;30915.381692366027;30926;3105.6583775784648;3190.1067701043744;3204.006757905057;3209.1816586770456;3217.887373941901;294513;298955;301001;301147;301149;49720.50279497231;49747.751261243466;49792.25311620328;49835.75082124566;49870.25194622084;52102.478975209764;52118.95762084737;52164.73917630463;52178;52193.82449404039;14107.506237281646;14229;14232.28838558068;14239.85760142406;14252.501162459264;11561.88438115625;11587.420628968517;11593.362365069834;11595.188336816342;11603.825942610867;9900.60397584103;10255.435951022766;10860.891391085988;10864.348139111678;10874.531938188402;4337.568602361213;5118.744062796845;5151.845444636667;5158.420789605198;5186.948130518722;859.7119964811733;1038.1539045677748;1038.9740256493496;1041.8385150301715;1077.138289368502;24652.630210546857;24731.752682472947;24765.25704228876;24808.131715389874;24871.87564062168;1137.7895089408487;1141.2068612314363;1173.6596387047832;1174.7298121432118;1193.7373777768878;10783.245172837984;11108.555657773599;12652.240865548096;12699.04757143218;12716.682082948;310032;310055;310395;310552;310616;46879.29681054787;54301.91396172134;54330.913381732935;54418.72790636022;54540.454595453535;1492.6492274315526;1519.574519134644;1563.1324614838873;1565.9217039147998;1595.1784830812142;24085;24122.155724549557;24340;24348.391290217176;24410.51178976375;38020;38051.61948380592;38134.61865381311;38203.42694859524;38260.42609360806;187923;187994;188209;188887;192194;33812;34674.65325346714;34701;34934;34944.65055349516;37499.81250093841;37925.620743792206;38066.619333807415;44336;45137.548624514646;28145.296367590974;30959;31282.21794455157;31448.37103257968;31459.52810707887;29892.953746618765;29923.10230693083;30724.385512289184;30726.38547228998;31128.844355778077;37699;37727;37737;37772;37899.81050094732;29939.80240790344;30827.845860770554;30987.380252395276;31352.21619459532;31782.364352712353;23970;24008.39979000539;24088.879555602813;24441.900114495245;24467 15:59:41 INFO - PID 1632 | |2;jquery.html;372.6404020120585;417.2309739349442;421.2817146764766;433.66824379349794;436.1016306408797;18750.499800079957;20765.9617019149;21038.001389867975;21204.151833926644;21218.681719774177;14003.727674312813;17540.473785786417;21482.140714371446;21901.342959711194;22161.33515994519;5852.4732774050435;6156.076588511737;6160.353162917896;6165.568410211287;6174.629522228658;5951.107333899928;6004.699765011754;6013.428724271187;6035.3059398169335;6117.143599896018;6085.6348619083;6095.7561697532265;6096.542759293048;6104.145419641254;6110.9388906111035;5729.742161602738;5925.377835327272;5982.491488223506;5987.970060149672;6052.092186172065;5166.276721259027;5274.736263186864;5303.4696530346655;5304.867378315535;5315.388730296027;25772.840222190047;26129.346766330804;26173;26525.734742652712;26567.867160664075;2151.580441813847;2174.2824867793747;2237.8545394549465;2279.7150356205475;2281.600719874032;16828.579285517837;16833.579160521083;17333.7399939001;17428.04145771982;17571.648567028715;2069.4929742213103;2135.8718476891436;2164.5238047629496;2174.967375489369;2183.4650510624997;148643;150252;151028;151194.48805511915;153661.9999999989;10426.530806113744;11136.721581960353;11175.21773475849;11751.76496470083;11766.647000589996;15522.068675879484;15545.455909043356;15552;15570.377184912477;15573.688526229638;7689.461737678421;7929.801754956057;7947.125816160334;8374.916250837536;8500.192481714228;7824.569648669378;8099.230573095547;8207.179282071864;8232.259096681328;8310.542920139329;6589.505787065989;7248.818779530448;7434.851302973911;7441.55350678961;7454.068241469817;2113.144176608476;2116.6824976253683;2119.173522326294;2122.310249169026;2158.449595353175;932.6409332407072;939.5020639061308;950.7944006564006;959.2278216036158;984.8916619171886;18609.348672796386;19501.707474387895;19860;19890.403287901387;20148.201302616882;965.9806803864024;966.4877614864133;999.4303247149055;1002.8595996560488;1006.7835415385728;10081.899181008095;10311.84532232017;10330.328528645614;10439.217058720475;10567.94716026415;526767;533940;536794;538122;549758;130166;131054;131914;135324;137534.31232843772;1131.3551275772732;1158.1024705852863;1179.080824088748;1221.181808188498;1221.4747658506928;20669.58660826805;20758.68861967102;20820.583588328453;21060.157593696695;21071.15715371368;57688.71155644195;57937;58110.7094464525;58132.41867581439;58200;58476;74035.51928961498;79421.60289198517;80852;82373.58813205895;8270.297024752963;8408.159184081544;8427.957860210905;8573.356998225283;8581.871271931052;12901.161424507378;13168.868311317148;13264.734705306033;13492.93253533726;13525.458981640624;9913.504324783877;10903.243205449802;11065;11456.968872801488;11573.479193436484;10260.230482713678;10314.071733544017;10356.689299321033;10473.476326183812;10763.892361076601;10025;11143.72140696489;11304.886951130713;11473.311601303949;11547.0762339011;8209.220124088202;8271.793205169919;8275.668973241003;8534.914650853412;8609.354298427517;9866.457344845816;9870.062344077302;10736.51685674131;11793.646190614296;11936.985356244817 15:59:41 INFO - PID 1632 | |3;mootools.html;1283.788174951133;1484.0724547158027;1486.6580686442119;1491.95524134276;1504.0073551456035;11026.28329158605;11513.194076414655;11514.309141451518;11523.596674116408;11529.596464123757;9480.383775054617;11160;11463;11480.483378247978;11484.023857972083;2503.887325070372;2550.132954795369;2550.9489810203786;2551.795856331489;2554.361409647588;2456.349067497115;2491.1281051631872;2496.8127390445698;2498.462830491444;2509.372656835791;2401;2482.553140434721;2495.2763698527406;2496.2386472125995;2499.450120973383;2484.3416494628937;2487.8134139939575;2504.8371855829314;2512.824102312839;2517.647529345893;2475.777180053804;2485.801135909123;2501.1496091328854;2505.260948020338;2506.5738824399714;9138.406003609743;11481.598144064918;11483.023942964857;11492.482838272297;11509.194356395055;6471.482281417475;6714.932850671479;6717.731290748364;6727.091842601235;6739.359760822714;3906.160175562239;4214.641755450791;4299.333603291495;4313.503947046099;4313.741175529478;1221.4471754034112;1268.7969924811987;1320.6236222676587;1323.2986517145928;1327.4026687990442;13291.20252784836;14987.70024599502;15383.00010499325;15390.07659540431;16023.839761602352;5763;6046.123312119806;6076.210092687922;6100.603460775035;6105.114758360013;13349.466021359036;13538.120022198525;13568.389422476012;13581.592552223448;13587.796183057262;6564.343565643493;6660.83347916306;6667.299933507009;6737.461003119836;6740.865182696319;6074.392560743979;6226.968865155736;6234.438900498977;6244.188255526752;6257;4614.377059097046;4627.722336659811;4675.532446755297;4678.906421871611;4711.599514041276;1411.7105993271355;1429.7426463236718;1438.2233593859407;1459.4016453254114;1461.5834487171105;741.4068745003975;804.3831897885293;816.2653611749354;822.5393779483388;825.9427932246733;6065.754821170741;6785.5250132490255;6824.488163387766;6875.5530890491955;7013.333733295329;901.7520181950016;920.2913756407662;922.7554698005034;946.4699768129869;967.7193613851904;4069.8372065117064;4142.730722503027;4181.163767246502;4260.531341552427;4327.740335579875;14181.432742690176;14663.706725865422;14666;14677.706445871236;14756;13742.862571374359;14690.41238350454;14748.115113093249;14765.557033288804;14775.778363324558;8327.125651806595;8510.872336914952;8541.402101852935;8561.101084386175;8563.743087707378;6110.480609148094;6394.26465956421;6512.153420055362;6532.444742196776;6634;10083.14293285079;12976.416061277076;12978.675533111747;12979.156354836905;13102.213867168;12045.939770301093;12342.444589993293;12373.75252494963;12790.104692671424;12864.485420583445;8710.738677839676;9060.81878362416;9062.592183351633;9068.274538037072;9072.319576031692;10290.228232882713;10316.226283028953;10321.690349289533;10359.741006474898;10399.740006499898;8660.228726807361;8791.032986371622;8840.690575829814;8927.776805579653;8949.507777072324;8593.785155371166;8594.828103438022;8617.267532259828;8647.481151130953;8749.99375071875;7875.842483150191;8045.034595848536;8047.396445266745;8049.83900322002;8056.677732890619;5094.439611642791;5867.354590995073;5963.373845746134;5964.671943043;6028.397160284112;4521.79651915658;6054.485368743706;6081;6146.170267013896;6257.43683068526 15:59:41 INFO - PID 1632 | |4;prototype.html;336.9814660193691;390.2955165925504;395.54117224020126;402.1354088709277;404.3389058888718;4873.8294159704465;5840.269966254218;5945.929732648122;5973.163757074013;5976.342602313747;4836.97581512092;5685.681716806263;5769.740361683724;5873.0896711009755;5879.794207202749;3825.61743825618;4410.514843367227;4422.425084738979;4429.46846378435;4454.220511410506;4383.75889326087;4456.309272062836;4464.754438505881;4470.329450582422;4485.439320084989;4056.148208876125;4147.460830092099;4253.29820579604;4364.869053928387;4414.823407063714;3503.8773642922374;3736.532933383327;4246.341817018367;4248.745075295461;4271.78641067947;3048.756099512034;3684.6653778330656;3695.870644527429;3696.482492451059;3715.814209289552;4743.786529606176;5614.466625670555;5738.971305143489;5783.814318064787;5816.534677225812;4155.293600087993;4213.789310534461;4506.864794056183;5083.847484575469;5088.084144853925;5531.806386776484;5559.749811258463;6259;6292.5595208335435;6335.588186767845;4126.360414135814;4202.516710578292;4211.747295162269;4220.683448741357;4264.509581398148;5421.783128674848;6411.647359395231;6560.147180866456;6608.537402381835;6788.626625535593;3578.731595130376;3723.311187430334;4199.223143718422;4224.408582798411;4298.849540266076;3804.0680033391995;4013.090509380734;4359.215341238544;4364.865135064842;4371.584699453547;4052.675785937118;4853.247746599282;4954.47977962316;5081.187010078444;5095.770690318945;5717.371089180187;6315.747370105144;6335.68321583919;6397.168368112116;6438.5171112165835;1449.282605110456;1452.1940323120768;1469.9416420177456;1477.3278158437886;1485.8113509192603;950.6340059077305;970.4613939263635;980;980.0591432225144;986.31451141456;5248.189374665734;5690.601657883908;5823.912641310384;5993.520518358521;6065.878682426327;5321.920171197357;5818.679972601548;6353.428191462791;6638.701258443285;6645.169353830771;6068.393160683896;6076.149339092531;6078.848028799315;6081.604695694766;6110.633361998294;3130.89041883533;3131.389379071105;3210.9197270067966;3339.3655205510877;3339.6159441664036;6767.898481522781;6820.624865632438;6840.41856442208;6853.931460685429;6866.931330686729;6332.651704156316;6740.932590674129;6769.627670478072;6774.458043356618;6796.320367963164;6183.938160618516;6438.678066096676;6506.31683673217;6640.634765087966;6728.831779205559;5879.970600146972;6560.442362399059;6566.9014964776525;6579.769708060195;6590.439812615981;5315.3621565412395;5632.014397480507;5843.561732869966;6318.9052164216655;6488.383603557654;4940.827071052496;6427.839304017437;6624.900626490703;6696;6765.665845283369;4221.8812014815485;4241.300185469363;4243.957560424356;4246.851360202378;4260.913467065879;5248.658837175724;5586.441355864381;6059.212302400836;6244.125822384961;6304.810855674337;6234.906476402948;6406.238284471621;6413.486921046212;6421.5825971311715;6425.8714825704155;6121.908171377344;6401.486951699134;6426;6471.255805582418;6479.708413121515;5198.636095473281;6185.257769067561;6282.371762823681;6288.182536270256;6318.399752023551;6397.008463688136;6412.39082287182;6422.325655806073;6431.453326467303;6434.163558737334;6153.169322141453;6190.969045154746;6221;6234.625922444668;6282.968585157045 15:59:41 INFO - PID 1632 | |5;yui.html;768.6579472134898;836.6736972580699;856.5759948825329;863.8617821148617;880.2210044110964;15965.760513592277;16082.51752447427;16108;16353.91823040886;16423.83576164238;15243.695126097471;15269.541913742605;15454.07275563467;15457.381704731797;15458.768118478203;988.9703308900745;1015.4871789746168;1017.7557386227289;1021.0963297481745;1021.4688362051729;985.5860538573817;994.627014869424;1010.9789112995891;1011.049613363436;1012.6101450941367;1064.7604289034982;1080.7298175456135;1080.9135269178487;1082.1613249731442;1082.2694681090272;1075.6181555547823;1077.1705786544396;1078.7680648660537;1079.0827796373098;1079.940603266824;802.8394321135796;804.5775967616989;805.7864665863551;809.0332053196432;812.0174588747623;16553.420630278004;16891.40880069204;16979.57551061221;17096.743548846778;17241.482755517354;17441.953482791072;17443.651126977515;17663.293468261254;17934.013629250385;17967.820321796746;13689.97325200604;13980.860191398058;14010.299485025707;14197.574072777834;14234.074785138933;3469.7397695172963;3487.6861082502696;3496.091016335771;3498.895033148984;3502.4571191465366;86957;129380.47168349063;132393;134296;134987;10124.949375253076;11146;11235.662930111946;11488.425578721197;11693.76612467763;25179.748202518105;25269.494610107697;25477.235682929542;25648.743512565008;26014.479710406064;8045.235702608243;8101.675932962733;8109.675612975415;8112.067112282163;8119.3910456715985;3250.512423136501;3947.388154836005;3973.264945985002;4021.879343619696;4025.496812898388;4332.805023773938;4371.322445021026;4404.229259879508;4417.160739459557;4419.779011049499;903.7921278106072;911.6717981526646;925.4447331600985;926.1757036237753;927.096081320707;623.6195920488499;635.6472157952288;643.0739734781899;648.2739331948248;676.972921083161;10909.127269818395;11510.884891151149;11723.179377443497;11854.466549005316;11881.108916831272;626.1203009771278;637.2639601260552;641.6342684669787;650.1093501902329;674.6120980436269;5313.57491400687;5427.294451721251;5472.616916815864;5473.507384335429;5525.143602741501;71995.2800471999;92731;103001;103554;104855.4757226209;84567.5771621138;85292;85731;85967.57016214878;85993;74323.25676743271;78939.60530197312;79791;80792.45735767618;83171;11305.982461578498;11506.712332191762;12023.27860328383;12560.937195313965;12572.811407829073;38816.64141755025;39663.611773587785;39965;40057;42812.57187428086;43465.3480197791;51903.740481297355;52552.16067437622;53203.733981329846;53286;15131.243437828285;15196;15216;15460.226988650298;15703.842961570239;17119.806695755513;17224.224909878834;17302.826971730625;17426;17787.46637600874;13680.31598420095;15905.363785448453;15909.204539772734;16139.273732682292;16184.352625894833;13955.511557095453;15921.124338161513;16274.349026038826;16288.837111628733;16334.264958076677;15401.691966160839;15603.453879114177;15902.60243493922;16077.919610401874;16098.919505402398;11511.769764604493;11521.711957200801;12239.143259971717;12240.632781016584;12287.464066991626;11577.189596728149;11901;11922.344271065174;12465.314407707663;12665.430055647703 15:59:41 INFO - PID 1632 | __end_tp_report 15:59:41 INFO - PID 1632 | __start_cc_report 15:59:41 INFO - PID 1632 | _x_x_mozilla_cycle_collect,1791 15:59:41 INFO - PID 1632 | __end_cc_report 15:59:41 INFO - PID 1632 | __startTimestamp1494025181951__endTimestamp 15:59:41 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | ------- Summary: start ------- 15:59:41 INFO - PID 1632 | Number of tests: 6 15:59:41 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | [#0] dojo.html Cycles:165 Average:6067.80 Median:6356.14 stddev:740.75 (11.7%) stddev-sans-first:711.60 15:59:41 INFO - PID 1632 | Values: 3346.7 3531.6 3546.6 3550.3 3599.1 6014.5 6110.6 6221.2 6261.6 6292.2 6117.3 6199.5 6263.7 6275.9 6297.2 4844.8 5160.7 5274.7 5320.5 5352.1 4504.2 5384.9 5413.2 5433.8 5441.6 5281.6 5322.7 5334.3 5342.8 5425.7 5363.5 5396.1 5407.2 5438.8 5458.8 5336.7 5348.0 5359.1 5371.3 5419.8 6110.1 6148.5 6153.8 6245.5 6249.5 5575.8 6509.3 6596.2 6607.2 6607.5 6678.3 6697.6 6700.2 6708.6 6710.8 6144.9 6228.4 6296.0 6341.1 6351.7 6638.4 6670.4 6681.3 6773.8 6797.5 6452.5 6467.2 6468.5 6477.1 6492.6 6577.6 6581.2 6633.1 6645.1 6648.3 6341.3 6414.7 6423.1 6544.3 6552.0 6384.5 6395.2 6402.8 6415.7 6495.6 4630.4 4814.0 5256.3 5285.7 5293.2 4523.8 4540.0 4574.9 4587.6 4778.4 6307.0 6313.3 6337.6 6358.9 6366.4 6414.4 6494.7 6584.3 6633.5 6699.2 6336.7 6359.6 6381.2 6388.5 6392.0 5778.7 5807.5 5817.9 5820.0 5870.1 6639.5 6871.2 6910.2 6927.9 6928.5 6502.7 6504.8 6507.9 6508.7 6513.0 6710.9 6774.2 6885.7 6907.7 6911.6 6473.2 6529.2 6530.6 6535.7 6554.2 6630.9 6636.9 6639.5 6648.3 6654.2 6290.2 6356.1 6372.2 6429.0 6431.1 5221.7 5278.9 6456.5 6462.7 6475.0 6338.4 6353.0 6362.7 6364.9 6376.4 6257.3 6320.0 6467.4 6494.4 6502.9 6272.1 6297.6 6317.4 6333.1 6373.8 15:59:41 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | [#1] ext.html Cycles:180 Average:41937.14 Median:17486.24 stddev:71372.67 (408.2%) stddev-sans-first:71505.95 15:59:41 INFO - PID 1632 | Values: 765.8 807.1 817.0 1132.3 1147.2 26907.5 27488.5 34490.5 37767.8 37844.1 39139.8 39497.0 39526.6 39569.4 39581.6 8286.3 8464.4 8469.9 8474.4 8485.2 6015.2 6108.1 6116.9 8239.7 8334.4 8433.1 8443.2 8480.2 8481.4 8536.2 8391.7 8456.5 8464.0 8473.7 8475.9 5907.6 5985.9 6008.2 8283.0 8398.1 26651.7 26899.7 36221.3 36432.3 36490.3 3135.3 3612.0 3632.5 3637.8 3640.2 30734.2 30897.5 30905.5 30915.4 30926.0 3105.7 3190.1 3204.0 3209.2 3217.9 294513.0 298955.0 301001.0 301147.0 301149.0 49720.5 49747.8 49792.3 49835.8 49870.3 52102.5 52119.0 52164.7 52178.0 52193.8 14107.5 14229.0 14232.3 14239.9 14252.5 11561.9 11587.4 11593.4 11595.2 11603.8 9900.6 10255.4 10860.9 10864.3 10874.5 4337.6 5118.7 5151.8 5158.4 5186.9 859.7 1038.2 1039.0 1041.8 1077.1 24652.6 24731.8 24765.3 24808.1 24871.9 1137.8 1141.2 1173.7 1174.7 1193.7 10783.2 11108.6 12652.2 12699.0 12716.7 310032.0 310055.0 310395.0 310552.0 310616.0 46879.3 54301.9 54330.9 54418.7 54540.5 1492.6 1519.6 1563.1 1565.9 1595.2 24085.0 24122.2 24340.0 24348.4 24410.5 38020.0 38051.6 38134.6 38203.4 38260.4 187923.0 187994.0 188209.0 188887.0 192194.0 33812.0 34674.7 34701.0 34934.0 34944.7 37499.8 37925.6 38066.6 44336.0 45137.5 28145.3 30959.0 31282.2 31448.4 31459.5 29893.0 29923.1 30724.4 30726.4 31128.8 37699.0 37727.0 37737.0 37772.0 37899.8 29939.8 30827.8 30987.4 31352.2 31782.4 23970.0 24008.4 24088.9 24441.9 24467.0 15:59:41 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | [#2] jquery.html Cycles:180 Average:34770.45 Median:12199.59 stddev:91366.47 (748.9%) stddev-sans-first:91586.28 15:59:41 INFO - PID 1632 | Values: 372.6 417.2 421.3 433.7 436.1 18750.5 20766.0 21038.0 21204.2 21218.7 14003.7 17540.5 21482.1 21901.3 22161.3 5852.5 6156.1 6160.4 6165.6 6174.6 5951.1 6004.7 6013.4 6035.3 6117.1 6085.6 6095.8 6096.5 6104.1 6110.9 5729.7 5925.4 5982.5 5988.0 6052.1 5166.3 5274.7 5303.5 5304.9 5315.4 25772.8 26129.3 26173.0 26525.7 26567.9 2151.6 2174.3 2237.9 2279.7 2281.6 16828.6 16833.6 17333.7 17428.0 17571.6 2069.5 2135.9 2164.5 2175.0 2183.5 148643.0 150252.0 151028.0 151194.5 153662.0 10426.5 11136.7 11175.2 11751.8 11766.6 15522.1 15545.5 15552.0 15570.4 15573.7 7689.5 7929.8 7947.1 8374.9 8500.2 7824.6 8099.2 8207.2 8232.3 8310.5 6589.5 7248.8 7434.9 7441.6 7454.1 2113.1 2116.7 2119.2 2122.3 2158.4 932.6 939.5 950.8 959.2 984.9 18609.3 19501.7 19860.0 19890.4 20148.2 966.0 966.5 999.4 1002.9 1006.8 10081.9 10311.8 10330.3 10439.2 10567.9 526767.0 533940.0 536794.0 538122.0 549758.0 130166.0 131054.0 131914.0 135324.0 137534.3 1131.4 1158.1 1179.1 1221.2 1221.5 20669.6 20758.7 20820.6 21060.2 21071.2 57688.7 57937.0 58110.7 58132.4 58200.0 58476.0 74035.5 79421.6 80852.0 82373.6 8270.3 8408.2 8428.0 8573.4 8581.9 12901.2 13168.9 13264.7 13492.9 13525.5 9913.5 10903.2 11065.0 11457.0 11573.5 10260.2 10314.1 10356.7 10473.5 10763.9 10025.0 11143.7 11304.9 11473.3 11547.1 8209.2 8271.8 8275.7 8534.9 8609.4 9866.5 9870.1 10736.5 11793.6 11937.0 15:59:41 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | [#3] mootools.html Cycles:180 Average:6976.86 Median:4161.95 stddev:4290.14 (103.1%) stddev-sans-first:4280.84 15:59:41 INFO - PID 1632 | Values: 1283.8 1484.1 1486.7 1492.0 1504.0 11026.3 11513.2 11514.3 11523.6 11529.6 9480.4 11160.0 11463.0 11480.5 11484.0 2503.9 2550.1 2550.9 2551.8 2554.4 2456.3 2491.1 2496.8 2498.5 2509.4 2401.0 2482.6 2495.3 2496.2 2499.5 2484.3 2487.8 2504.8 2512.8 2517.6 2475.8 2485.8 2501.1 2505.3 2506.6 9138.4 11481.6 11483.0 11492.5 11509.2 6471.5 6714.9 6717.7 6727.1 6739.4 3906.2 4214.6 4299.3 4313.5 4313.7 1221.4 1268.8 1320.6 1323.3 1327.4 13291.2 14987.7 15383.0 15390.1 16023.8 5763.0 6046.1 6076.2 6100.6 6105.1 13349.5 13538.1 13568.4 13581.6 13587.8 6564.3 6660.8 6667.3 6737.5 6740.9 6074.4 6227.0 6234.4 6244.2 6257.0 4614.4 4627.7 4675.5 4678.9 4711.6 1411.7 1429.7 1438.2 1459.4 1461.6 741.4 804.4 816.3 822.5 825.9 6065.8 6785.5 6824.5 6875.6 7013.3 901.8 920.3 922.8 946.5 967.7 4069.8 4142.7 4181.2 4260.5 4327.7 14181.4 14663.7 14666.0 14677.7 14756.0 13742.9 14690.4 14748.1 14765.6 14775.8 8327.1 8510.9 8541.4 8561.1 8563.7 6110.5 6394.3 6512.2 6532.4 6634.0 10083.1 12976.4 12978.7 12979.2 13102.2 12045.9 12342.4 12373.8 12790.1 12864.5 8710.7 9060.8 9062.6 9068.3 9072.3 10290.2 10316.2 10321.7 10359.7 10399.7 8660.2 8791.0 8840.7 8927.8 8949.5 8593.8 8594.8 8617.3 8647.5 8750.0 7875.8 8045.0 8047.4 8049.8 8056.7 5094.4 5867.4 5963.4 5964.7 6028.4 4521.8 6054.5 6081.0 6146.2 6257.4 15:59:41 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | [#4] prototype.html Cycles:180 Average:5059.83 Median:5800.17 stddev:1624.48 (28.0%) stddev-sans-first:1589.89 15:59:41 INFO - PID 1632 | Values: 337.0 390.3 395.5 402.1 404.3 4873.8 5840.3 5945.9 5973.2 5976.3 4837.0 5685.7 5769.7 5873.1 5879.8 3825.6 4410.5 4422.4 4429.5 4454.2 4383.8 4456.3 4464.8 4470.3 4485.4 4056.1 4147.5 4253.3 4364.9 4414.8 3503.9 3736.5 4246.3 4248.7 4271.8 3048.8 3684.7 3695.9 3696.5 3715.8 4743.8 5614.5 5739.0 5783.8 5816.5 4155.3 4213.8 4506.9 5083.8 5088.1 5531.8 5559.7 6259.0 6292.6 6335.6 4126.4 4202.5 4211.7 4220.7 4264.5 5421.8 6411.6 6560.1 6608.5 6788.6 3578.7 3723.3 4199.2 4224.4 4298.8 3804.1 4013.1 4359.2 4364.9 4371.6 4052.7 4853.2 4954.5 5081.2 5095.8 5717.4 6315.7 6335.7 6397.2 6438.5 1449.3 1452.2 1469.9 1477.3 1485.8 950.6 970.5 980.0 980.1 986.3 5248.2 5690.6 5823.9 5993.5 6065.9 5321.9 5818.7 6353.4 6638.7 6645.2 6068.4 6076.1 6078.8 6081.6 6110.6 3130.9 3131.4 3210.9 3339.4 3339.6 6767.9 6820.6 6840.4 6853.9 6866.9 6332.7 6740.9 6769.6 6774.5 6796.3 6183.9 6438.7 6506.3 6640.6 6728.8 5880.0 6560.4 6566.9 6579.8 6590.4 5315.4 5632.0 5843.6 6318.9 6488.4 4940.8 6427.8 6624.9 6696.0 6765.7 4221.9 4241.3 4244.0 4246.9 4260.9 5248.7 5586.4 6059.2 6244.1 6304.8 6234.9 6406.2 6413.5 6421.6 6425.9 6121.9 6401.5 6426.0 6471.3 6479.7 5198.6 6185.3 6282.4 6288.2 6318.4 6397.0 6412.4 6422.3 6431.5 6434.2 6153.2 6191.0 6221.0 6234.6 6283.0 15:59:41 INFO - PID 1632 | 15:59:41 INFO - PID 1632 | [#5] yui.html Cycles:180 Average:20904.24 Median:17272.15 stddev:29439.37 (170.4%) stddev-sans-first:29483.13 15:59:41 INFO - PID 1632 | Values: 768.7 836.7 856.6 863.9 880.2 15965.8 16082.5 16108.0 16353.9 16423.8 15243.7 15269.5 15454.1 15457.4 15458.8 989.0 1015.5 1017.8 1021.1 1021.5 985.6 994.6 1011.0 1011.0 1012.6 1064.8 1080.7 1080.9 1082.2 1082.3 1075.6 1077.2 1078.8 1079.1 1079.9 802.8 804.6 805.8 809.0 812.0 16553.4 16891.4 16979.6 17096.7 17241.5 17442.0 17443.7 17663.3 17934.0 17967.8 13690.0 13980.9 14010.3 14197.6 14234.1 3469.7 3487.7 3496.1 3498.9 3502.5 86957.0 129380.5 132393.0 134296.0 134987.0 10124.9 11146.0 11235.7 11488.4 11693.8 25179.7 25269.5 25477.2 25648.7 26014.5 8045.2 8101.7 8109.7 8112.1 8119.4 3250.5 3947.4 3973.3 4021.9 4025.5 4332.8 4371.3 4404.2 4417.2 4419.8 903.8 911.7 925.4 926.2 927.1 623.6 635.6 643.1 648.3 677.0 10909.1 11510.9 11723.2 11854.5 11881.1 626.1 637.3 641.6 650.1 674.6 5313.6 5427.3 5472.6 5473.5 5525.1 71995.3 92731.0 103001.0 103554.0 104855.5 84567.6 85292.0 85731.0 85967.6 85993.0 74323.3 78939.6 79791.0 80792.5 83171.0 11306.0 11506.7 12023.3 12560.9 12572.8 38816.6 39663.6 39965.0 40057.0 42812.6 43465.3 51903.7 52552.2 53203.7 53286.0 15131.2 15196.0 15216.0 15460.2 15703.8 17119.8 17224.2 17302.8 17426.0 17787.5 13680.3 15905.4 15909.2 16139.3 16184.4 13955.5 15921.1 16274.3 16288.8 16334.3 15401.7 15603.5 15902.6 16077.9 16098.9 11511.8 11521.7 12239.1 12240.6 12287.5 11577.2 11901.0 11922.3 12465.3 12665.4 15:59:41 INFO - PID 1632 | -------- Summary: end -------- 15:59:41 INFO - PID 1632 | 15:59:42 INFO - TEST-INFO | 1632: exit 0 15:59:42 INFO - TEST-OK | dromaeo_css | took 1119312ms 15:59:42 INFO - TEST-START | kraken 15:59:42 INFO - operating with platform_type : mac_ 15:59:42 INFO - Initialising browser for kraken test... 15:59:42 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/tmp7E0Lil/profile http://localhost:49234/getInfo.html) 15:59:45 INFO - PID 1642 | __metrics Screen width/height:1600/1200 15:59:45 INFO - PID 1642 | colorDepth:24 15:59:45 INFO - PID 1642 | Browser inner width/height: 1024/691 15:59:45 INFO - PID 1642 | __metrics 15:59:45 INFO - PID 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:59:45 INFO - PID 1642 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:59:46 INFO - TEST-INFO | 1642: exit 0 15:59:46 INFO - Browser initialized. 15:59:46 INFO - Running cycle 1/1 for kraken test... 15:59:46 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/tmp7E0Lil/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:59 INFO - PID 1643 | RSS: Main: 250605568 15:59:59 INFO - PID 1643 | 16:00:02 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection) 16:00:03 INFO - PID 1643 | RSS: Main: 320483328 16:00:03 INFO - PID 1643 | 16:00:06 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-dft) 16:00:06 INFO - PID 1643 | RSS: Main: 304553984 16:00:06 INFO - PID 1643 | 16:00:09 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-fft) 16:00:10 INFO - PID 1643 | RSS: Main: 396345344 16:00:10 INFO - PID 1643 | 16:00:12 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator) 16:00:13 INFO - PID 1643 | RSS: Main: 296697856 16:00:13 INFO - PID 1643 | 16:00:15 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:00:16 INFO - PID 1643 | RSS: Main: 300191744 16:00:16 INFO - PID 1643 | 16:00:18 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom) 16:00:19 INFO - PID 1643 | RSS: Main: 363196416 16:00:19 INFO - PID 1643 | 16:00:22 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate) 16:00:23 INFO - PID 1643 | RSS: Main: 381689856 16:00:23 INFO - PID 1643 | 16:00:25 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial) 16:00:26 INFO - PID 1643 | RSS: Main: 371515392 16:00:26 INFO - PID 1643 | 16:00:28 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:00:28 INFO - PID 1643 | RSS: Main: 339734528 16:00:28 INFO - PID 1643 | 16:00:30 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:00:31 INFO - PID 1643 | RSS: Main: 341319680 16:00:31 INFO - PID 1643 | 16:00:33 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:34 INFO - PID 1643 | RSS: Main: 325292032 16:00:34 INFO - PID 1643 | 16:00:36 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:37 INFO - PID 1643 | RSS: Main: 361857024 16:00:37 INFO - PID 1643 | 16:00:40 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:41 INFO - PID 1643 | RSS: Main: 354729984 16:00:41 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49234/tests/kraken/driver.html?testName=ai-astar) 16:00:43 INFO - PID 1643 | RSS: Main: 397717504 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | __start_tp_report 16:00:43 INFO - PID 1643 | _x_x_mozilla_page_load 16:00:43 INFO - PID 1643 | _x_x_mozilla_page_load_details 16:00:43 INFO - PID 1643 | |i|pagename|runs| 16:00:43 INFO - PID 1643 | |0;ai-astar;110;111;111;111;110;112;111;111;109;111 16:00:43 INFO - PID 1643 | |1;audio-beat-detection;145;132;142;183;109;109;110;130;157;142 16:00:43 INFO - PID 1643 | |2;audio-dft;182;188;185;182;183;186;187;184;183;180 16:00:43 INFO - PID 1643 | |3;audio-fft;78;58;57;88;57;58;59;61;81;59 16:00:43 INFO - PID 1643 | |4;audio-oscillator;91;79;89;68;69;70;104;102;103;92 16:00:43 INFO - PID 1643 | |5;imaging-gaussian-blur;90;88;89;88;88;88;88;87;88;89 16:00:43 INFO - PID 1643 | |6;imaging-darkroom;94;95;98;96;95;94;95;95;96;96 16:00:43 INFO - PID 1643 | |7;imaging-desaturate;82;83;84;84;84;85;86;84;93;84 16:00:43 INFO - PID 1643 | |8;json-parse-financial;50;46;47;47;49;49;48;47;48;48 16:00:43 INFO - PID 1643 | |9;json-stringify-tinderbox;54;61;57;60;62;63;58;58;57;57 16:00:43 INFO - PID 1643 | |10;stanford-crypto-aes;82;80;82;83;83;81;81;81;83;81 16:00:43 INFO - PID 1643 | |11;stanford-crypto-ccm;138;136;135;134;134;136;134;146;200;149 16:00:43 INFO - PID 1643 | |12;stanford-crypto-pbkdf2;185;186;185;179;184;184;183;181;191;183 16:00:43 INFO - PID 1643 | |13;stanford-crypto-sha256-iterative;69;66;65;67;68;68;67;67;79;79 16:00:43 INFO - PID 1643 | __end_tp_report 16:00:43 INFO - PID 1643 | __start_cc_report 16:00:43 INFO - PID 1643 | _x_x_mozilla_cycle_collect,2416 16:00:43 INFO - PID 1643 | __end_cc_report 16:00:43 INFO - PID 1643 | __startTimestamp1494025243553__endTimestamp 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | ------- Summary: start ------- 16:00:43 INFO - PID 1643 | Number of tests: 14 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:0.82 (0.7%) stddev-sans-first:0.83 16:00:43 INFO - PID 1643 | Values: 110.0 111.0 111.0 111.0 110.0 112.0 111.0 111.0 109.0 111.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:142.00 stddev:23.54 (16.6%) stddev-sans-first:24.74 16:00:43 INFO - PID 1643 | Values: 145.0 132.0 142.0 183.0 109.0 109.0 110.0 130.0 157.0 142.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:2.49 (1.4%) stddev-sans-first:2.54 16:00:43 INFO - PID 1643 | Values: 182.0 188.0 185.0 182.0 183.0 186.0 187.0 184.0 183.0 180.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#3] audio-fft Cycles:10 Average:65.60 Median:60.00 stddev:11.85 (19.8%) stddev-sans-first:11.69 16:00:43 INFO - PID 1643 | Values: 78.0 58.0 57.0 88.0 57.0 58.0 59.0 61.0 81.0 59.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:74.50 stddev:14.35 (19.3%) stddev-sans-first:15.13 16:00:43 INFO - PID 1643 | Values: 91.0 79.0 89.0 68.0 69.0 70.0 104.0 102.0 103.0 92.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.00 stddev:0.82 (0.9%) stddev-sans-first:0.60 16:00:43 INFO - PID 1643 | Values: 90.0 88.0 89.0 88.0 88.0 88.0 88.0 87.0 88.0 89.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.50 stddev:1.17 (1.2%) stddev-sans-first:1.13 16:00:43 INFO - PID 1643 | Values: 94.0 95.0 98.0 96.0 95.0 94.0 95.0 95.0 96.0 96.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:84.00 stddev:3.03 (3.6%) stddev-sans-first:3.03 16:00:43 INFO - PID 1643 | Values: 82.0 83.0 84.0 84.0 84.0 85.0 86.0 84.0 93.0 84.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#8] json-parse-financial Cycles:10 Average:47.90 Median:48.00 stddev:1.20 (2.5%) stddev-sans-first:1.00 16:00:43 INFO - PID 1643 | Values: 50.0 46.0 47.0 47.0 49.0 49.0 48.0 47.0 48.0 48.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:2.75 (4.7%) stddev-sans-first:2.33 16:00:43 INFO - PID 1643 | Values: 54.0 61.0 57.0 60.0 62.0 63.0 58.0 58.0 57.0 57.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.00 stddev:1.06 (1.3%) stddev-sans-first:1.12 16:00:43 INFO - PID 1643 | Values: 82.0 80.0 82.0 83.0 83.0 81.0 81.0 81.0 83.0 81.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:137.00 stddev:20.30 (14.8%) stddev-sans-first:21.41 16:00:43 INFO - PID 1643 | Values: 138.0 136.0 135.0 134.0 134.0 136.0 134.0 146.0 200.0 149.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:184.50 stddev:3.18 (1.7%) stddev-sans-first:3.35 16:00:43 INFO - PID 1643 | Values: 185.0 186.0 185.0 179.0 184.0 184.0 183.0 181.0 191.0 183.0 16:00:43 INFO - PID 1643 | 16:00:43 INFO - PID 1643 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:68.00 stddev:5.13 (7.5%) stddev-sans-first:5.43 16:00:43 INFO - PID 1643 | Values: 69.0 66.0 65.0 67.0 68.0 68.0 67.0 67.0 79.0 79.0 16:00:43 INFO - PID 1643 | -------- Summary: end -------- 16:00:43 INFO - PID 1643 | 16:00:44 INFO - TEST-INFO | 1643: exit 0 16:00:44 INFO - TEST-OK | kraken | took 62050ms 16:00:44 INFO - SUITE-END | took 1181s 16:00:44 INFO - Completed test suite (00:19:41) 16:00:44 INFO - javascript benchmark 16:00:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7679.458306174248, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.748993825463, 3531.576210854698, 3546.556680414949, 3550.254446566221, 3599.1002249437643, 6014.488768454676, 6110.558759196664, 6221.160143380643, 6261.561690681654, 6292.244930608334, 6117.309816958462, 6199.504039676826, 6263.7494500220055, 6275.905861412071, 6297.18136642237, 4844.806207751703, 5160.716160611165, 5274.709890955995, 5320.4679532046675, 5352.063388906926, 4504.211762941472, 5384.946150538483, 5413.242146099549, 5433.782648694048, 5441.619086663935, 5281.551068159211, 5322.654027488201, 5334.333208348957, 5342.839714808562, 5425.7287135643455, 5363.490468405495, 5396.1366181410785, 5407.161889907071, 5438.809641662562, 5458.808941687062, 5336.706481143536, 5347.973260133675, 5359.08895487768, 5371.301730775013, 5419.783208671648, 6110.144579758838, 6148.493619063313, 6153.753849846045, 6245.531585131088, 6249.468795152417, 5575.832725018216, 6509.251436084864, 6596.208454985384, 6607.174103237096, 6607.504437167184, 6678.332166783332, 6697.631630260334, 6700.195976482804, 6708.563943343667, 6710.765123220664, 6144.863200307957, 6228.37716228378, 6296.0, 6341.143945567289, 6351.650659213695, 6638.369354911276, 6670.39966403026, 6681.265060843303, 6773.762918297836, 6797.456203503708, 6452.451541619015, 6467.223933127961, 6468.482521398277, 6477.125588045647, 6492.64290464029, 6577.6053436793945, 6581.177352830896, 6633.137692100093, 6645.136132302867, 6648.301928297556, 6341.3341599132345, 6414.679266036679, 6423.100765892778, 6544.280129185786, 6552.049952756919, 6384.52116091295, 6395.20059992501, 6402.775902843378, 6415.711292991827, 6495.64273964927, 4630.351750754897, 4814.037192561502, 5256.26412302278, 5285.735713214324, 5293.179557168645, 4523.819047238139, 4540.020323988326, 4574.862754117382, 4587.587117159472, 4778.354922085474, 6307.022411526221, 6313.273973493107, 6337.556371053982, 6358.872822543523, 6366.363363663597, 6414.390632889868, 6494.6752662367635, 6584.30864759203, 6633.4693224541925, 6699.229588597276, 6336.6831658416895, 6359.554831161867, 6381.202349706286, 6388.456981156561, 6391.9680401598625, 5778.653280803165, 5807.5063619591965, 5817.941820581825, 5820.0, 5870.119482077723, 6639.4688424924925, 6871.209810871814, 6910.20532638753, 6927.861442771217, 6928.549644273106, 6502.707378167899, 6504.804855854332, 6507.869842603216, 6508.707108180049, 6512.967435162794, 6710.932890671031, 6774.220964589135, 6885.724571017103, 6907.6891539879825, 6911.619860907698, 6473.158489396348, 6529.151210342625, 6530.640814755234, 6535.738570457129, 6554.213494380705, 6630.900536492053, 6636.9004464932095, 6639.535232533676, 6648.301928297459, 6654.201495820339, 6290.150829637941, 6356.141920840627, 6372.235331760218, 6429.03564465334, 6431.1317972073175, 5221.660592061504, 5278.9472105278455, 6456.451201647913, 6462.741490340334, 6474.967625161844, 6338.366163383625, 6353.0, 6362.6818659067785, 6364.936350636434, 6376.426121649074, 6257.342978987141, 6319.96840015797, 6467.353264673495, 6494.415502604788, 6502.902456463252, 6272.121902933502, 6297.590656607306, 6317.431431171217, 6333.145025421693, 6373.840653983688], "value": 6017.047665342452, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.8162041110131, 807.1242701668689, 817.0236567302072, 1132.2979752553413, 1147.179766466976, 26907.461850762997, 27488.450230995368, 34490.48264276032, 37767.81116094423, 37844.05389865255, 39139.80430097845, 39497.0, 39526.60473395265, 39569.40645890307, 39581.60418395815, 8286.254237118657, 8464.449810762311, 8469.872951905727, 8474.406791524596, 8485.193906578865, 6015.18794962681, 6108.083787431877, 6116.877662446748, 8239.670413183465, 8334.416590838644, 8433.072362040171, 8443.155684431538, 8480.151984801501, 8481.363897707666, 8536.231739143475, 8391.70629027984, 8456.450330728545, 8464.026636936738, 8473.661053557851, 8475.872861907077, 5907.645541267538, 5985.910211346833, 6008.24896887889, 8282.958585207096, 8398.118197589287, 26651.73348266512, 26899.731002689918, 36221.27557448862, 36432.27135457302, 36490.270194596225, 3135.294558724269, 3611.9638803611892, 3632.4732913727626, 3637.836297366625, 3640.2355505343644, 30734.23164420885, 30897.53653695174, 30905.536416953764, 30915.381692366027, 30926.0, 3105.6583775784648, 3190.1067701043744, 3204.006757905057, 3209.1816586770456, 3217.887373941901, 294513.0, 298955.0, 301001.0, 301147.0, 301149.0, 49720.50279497231, 49747.751261243466, 49792.25311620328, 49835.75082124566, 49870.25194622084, 52102.478975209764, 52118.95762084737, 52164.73917630463, 52178.0, 52193.82449404039, 14107.506237281646, 14229.0, 14232.28838558068, 14239.85760142406, 14252.501162459264, 11561.88438115625, 11587.420628968517, 11593.362365069834, 11595.188336816342, 11603.825942610867, 9900.60397584103, 10255.435951022766, 10860.891391085988, 10864.348139111678, 10874.531938188402, 4337.568602361213, 5118.744062796845, 5151.845444636667, 5158.420789605198, 5186.948130518722, 859.7119964811733, 1038.1539045677748, 1038.9740256493496, 1041.8385150301715, 1077.138289368502, 24652.630210546857, 24731.752682472947, 24765.25704228876, 24808.131715389874, 24871.87564062168, 1137.7895089408487, 1141.2068612314363, 1173.6596387047832, 1174.7298121432118, 1193.7373777768878, 10783.245172837984, 11108.555657773599, 12652.240865548096, 12699.04757143218, 12716.682082948, 310032.0, 310055.0, 310395.0, 310552.0, 310616.0, 46879.29681054787, 54301.91396172134, 54330.913381732935, 54418.72790636022, 54540.454595453535, 1492.6492274315526, 1519.574519134644, 1563.1324614838873, 1565.9217039147998, 1595.1784830812142, 24085.0, 24122.155724549557, 24340.0, 24348.391290217176, 24410.51178976375, 38020.0, 38051.61948380592, 38134.61865381311, 38203.42694859524, 38260.42609360806, 187923.0, 187994.0, 188209.0, 188887.0, 192194.0, 33812.0, 34674.65325346714, 34701.0, 34934.0, 34944.65055349516, 37499.81250093841, 37925.620743792206, 38066.619333807415, 44336.0, 45137.548624514646, 28145.296367590974, 30959.0, 31282.21794455157, 31448.37103257968, 31459.52810707887, 29892.953746618765, 29923.10230693083, 30724.385512289184, 30726.38547228998, 31128.844355778077, 37699.0, 37727.0, 37737.0, 37772.0, 37899.81050094732, 29939.80240790344, 30827.845860770554, 30987.380252395276, 31352.21619459532, 31782.364352712353, 23970.0, 24008.39979000539, 24088.879555602813, 24441.900114495245, 24467.0], "value": 16887.624290661744, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.6404020120585, 417.2309739349442, 421.2817146764766, 433.66824379349794, 436.1016306408797, 18750.499800079957, 20765.9617019149, 21038.001389867975, 21204.151833926644, 21218.681719774177, 14003.727674312813, 17540.473785786417, 21482.140714371446, 21901.342959711194, 22161.33515994519, 5852.4732774050435, 6156.076588511737, 6160.353162917896, 6165.568410211287, 6174.629522228658, 5951.107333899928, 6004.699765011754, 6013.428724271187, 6035.3059398169335, 6117.143599896018, 6085.6348619083, 6095.7561697532265, 6096.542759293048, 6104.145419641254, 6110.9388906111035, 5729.742161602738, 5925.377835327272, 5982.491488223506, 5987.970060149672, 6052.092186172065, 5166.276721259027, 5274.736263186864, 5303.4696530346655, 5304.867378315535, 5315.388730296027, 25772.840222190047, 26129.346766330804, 26173.0, 26525.734742652712, 26567.867160664075, 2151.580441813847, 2174.2824867793747, 2237.8545394549465, 2279.7150356205475, 2281.600719874032, 16828.579285517837, 16833.579160521083, 17333.7399939001, 17428.04145771982, 17571.648567028715, 2069.4929742213103, 2135.8718476891436, 2164.5238047629496, 2174.967375489369, 2183.4650510624997, 148643.0, 150252.0, 151028.0, 151194.48805511915, 153661.9999999989, 10426.530806113744, 11136.721581960353, 11175.21773475849, 11751.76496470083, 11766.647000589996, 15522.068675879484, 15545.455909043356, 15552.0, 15570.377184912477, 15573.688526229638, 7689.461737678421, 7929.801754956057, 7947.125816160334, 8374.916250837536, 8500.192481714228, 7824.569648669378, 8099.230573095547, 8207.179282071864, 8232.259096681328, 8310.542920139329, 6589.505787065989, 7248.818779530448, 7434.851302973911, 7441.55350678961, 7454.068241469817, 2113.144176608476, 2116.6824976253683, 2119.173522326294, 2122.310249169026, 2158.449595353175, 932.6409332407072, 939.5020639061308, 950.7944006564006, 959.2278216036158, 984.8916619171886, 18609.348672796386, 19501.707474387895, 19860.0, 19890.403287901387, 20148.201302616882, 965.9806803864024, 966.4877614864133, 999.4303247149055, 1002.8595996560488, 1006.7835415385728, 10081.899181008095, 10311.84532232017, 10330.328528645614, 10439.217058720475, 10567.94716026415, 526767.0, 533940.0, 536794.0, 538122.0, 549758.0, 130166.0, 131054.0, 131914.0, 135324.0, 137534.31232843772, 1131.3551275772732, 1158.1024705852863, 1179.080824088748, 1221.181808188498, 1221.4747658506928, 20669.58660826805, 20758.68861967102, 20820.583588328453, 21060.157593696695, 21071.15715371368, 57688.71155644195, 57937.0, 58110.7094464525, 58132.41867581439, 58200.0, 58476.0, 74035.51928961498, 79421.60289198517, 80852.0, 82373.58813205895, 8270.297024752963, 8408.159184081544, 8427.957860210905, 8573.356998225283, 8581.871271931052, 12901.161424507378, 13168.868311317148, 13264.734705306033, 13492.93253533726, 13525.458981640624, 9913.504324783877, 10903.243205449802, 11065.0, 11456.968872801488, 11573.479193436484, 10260.230482713678, 10314.071733544017, 10356.689299321033, 10473.476326183812, 10763.892361076601, 10025.0, 11143.72140696489, 11304.886951130713, 11473.311601303949, 11547.0762339011, 8209.220124088202, 8271.793205169919, 8275.668973241003, 8534.914650853412, 8609.354298427517, 9866.457344845816, 9870.062344077302, 10736.51685674131, 11793.646190614296, 11936.985356244817], "value": 10070.325385278462, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1283.788174951133, 1484.0724547158027, 1486.6580686442119, 1491.95524134276, 1504.0073551456035, 11026.28329158605, 11513.194076414655, 11514.309141451518, 11523.596674116408, 11529.596464123757, 9480.383775054617, 11160.0, 11463.0, 11480.483378247978, 11484.023857972083, 2503.887325070372, 2550.132954795369, 2550.9489810203786, 2551.795856331489, 2554.361409647588, 2456.349067497115, 2491.1281051631872, 2496.8127390445698, 2498.462830491444, 2509.372656835791, 2401.0, 2482.553140434721, 2495.2763698527406, 2496.2386472125995, 2499.450120973383, 2484.3416494628937, 2487.8134139939575, 2504.8371855829314, 2512.824102312839, 2517.647529345893, 2475.777180053804, 2485.801135909123, 2501.1496091328854, 2505.260948020338, 2506.5738824399714, 9138.406003609743, 11481.598144064918, 11483.023942964857, 11492.482838272297, 11509.194356395055, 6471.482281417475, 6714.932850671479, 6717.731290748364, 6727.091842601235, 6739.359760822714, 3906.160175562239, 4214.641755450791, 4299.333603291495, 4313.503947046099, 4313.741175529478, 1221.4471754034112, 1268.7969924811987, 1320.6236222676587, 1323.2986517145928, 1327.4026687990442, 13291.20252784836, 14987.70024599502, 15383.00010499325, 15390.07659540431, 16023.839761602352, 5763.0, 6046.123312119806, 6076.210092687922, 6100.603460775035, 6105.114758360013, 13349.466021359036, 13538.120022198525, 13568.389422476012, 13581.592552223448, 13587.796183057262, 6564.343565643493, 6660.83347916306, 6667.299933507009, 6737.461003119836, 6740.865182696319, 6074.392560743979, 6226.968865155736, 6234.438900498977, 6244.188255526752, 6257.0, 4614.377059097046, 4627.722336659811, 4675.532446755297, 4678.906421871611, 4711.599514041276, 1411.7105993271355, 1429.7426463236718, 1438.2233593859407, 1459.4016453254114, 1461.5834487171105, 741.4068745003975, 804.3831897885293, 816.2653611749354, 822.5393779483388, 825.9427932246733, 6065.754821170741, 6785.5250132490255, 6824.488163387766, 6875.5530890491955, 7013.333733295329, 901.7520181950016, 920.2913756407662, 922.7554698005034, 946.4699768129869, 967.7193613851904, 4069.8372065117064, 4142.730722503027, 4181.163767246502, 4260.531341552427, 4327.740335579875, 14181.432742690176, 14663.706725865422, 14666.0, 14677.706445871236, 14756.0, 13742.862571374359, 14690.41238350454, 14748.115113093249, 14765.557033288804, 14775.778363324558, 8327.125651806595, 8510.872336914952, 8541.402101852935, 8561.101084386175, 8563.743087707378, 6110.480609148094, 6394.26465956421, 6512.153420055362, 6532.444742196776, 6634.0, 10083.14293285079, 12976.416061277076, 12978.675533111747, 12979.156354836905, 13102.213867168, 12045.939770301093, 12342.444589993293, 12373.75252494963, 12790.104692671424, 12864.485420583445, 8710.738677839676, 9060.81878362416, 9062.592183351633, 9068.274538037072, 9072.319576031692, 10290.228232882713, 10316.226283028953, 10321.690349289533, 10359.741006474898, 10399.740006499898, 8660.228726807361, 8791.032986371622, 8840.690575829814, 8927.776805579653, 8949.507777072324, 8593.785155371166, 8594.828103438022, 8617.267532259828, 8647.481151130953, 8749.99375071875, 7875.842483150191, 8045.034595848536, 8047.396445266745, 8049.83900322002, 8056.677732890619, 5094.439611642791, 5867.354590995073, 5963.373845746134, 5964.671943043, 6028.397160284112, 4521.79651915658, 6054.485368743706, 6081.0, 6146.170267013896, 6257.43683068526], "value": 5358.865725298025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.9814660193691, 390.2955165925504, 395.54117224020126, 402.1354088709277, 404.3389058888718, 4873.8294159704465, 5840.269966254218, 5945.929732648122, 5973.163757074013, 5976.342602313747, 4836.97581512092, 5685.681716806263, 5769.740361683724, 5873.0896711009755, 5879.794207202749, 3825.61743825618, 4410.514843367227, 4422.425084738979, 4429.46846378435, 4454.220511410506, 4383.75889326087, 4456.309272062836, 4464.754438505881, 4470.329450582422, 4485.439320084989, 4056.148208876125, 4147.460830092099, 4253.29820579604, 4364.869053928387, 4414.823407063714, 3503.8773642922374, 3736.532933383327, 4246.341817018367, 4248.745075295461, 4271.78641067947, 3048.756099512034, 3684.6653778330656, 3695.870644527429, 3696.482492451059, 3715.814209289552, 4743.786529606176, 5614.466625670555, 5738.971305143489, 5783.814318064787, 5816.534677225812, 4155.293600087993, 4213.789310534461, 4506.864794056183, 5083.847484575469, 5088.084144853925, 5531.806386776484, 5559.749811258463, 6259.0, 6292.5595208335435, 6335.588186767845, 4126.360414135814, 4202.516710578292, 4211.747295162269, 4220.683448741357, 4264.509581398148, 5421.783128674848, 6411.647359395231, 6560.147180866456, 6608.537402381835, 6788.626625535593, 3578.731595130376, 3723.311187430334, 4199.223143718422, 4224.408582798411, 4298.849540266076, 3804.0680033391995, 4013.090509380734, 4359.215341238544, 4364.865135064842, 4371.584699453547, 4052.675785937118, 4853.247746599282, 4954.47977962316, 5081.187010078444, 5095.770690318945, 5717.371089180187, 6315.747370105144, 6335.68321583919, 6397.168368112116, 6438.5171112165835, 1449.282605110456, 1452.1940323120768, 1469.9416420177456, 1477.3278158437886, 1485.8113509192603, 950.6340059077305, 970.4613939263635, 980.0, 980.0591432225144, 986.31451141456, 5248.189374665734, 5690.601657883908, 5823.912641310384, 5993.520518358521, 6065.878682426327, 5321.920171197357, 5818.679972601548, 6353.428191462791, 6638.701258443285, 6645.169353830771, 6068.393160683896, 6076.149339092531, 6078.848028799315, 6081.604695694766, 6110.633361998294, 3130.89041883533, 3131.389379071105, 3210.9197270067966, 3339.3655205510877, 3339.6159441664036, 6767.898481522781, 6820.624865632438, 6840.41856442208, 6853.931460685429, 6866.931330686729, 6332.651704156316, 6740.932590674129, 6769.627670478072, 6774.458043356618, 6796.320367963164, 6183.938160618516, 6438.678066096676, 6506.31683673217, 6640.634765087966, 6728.831779205559, 5879.970600146972, 6560.442362399059, 6566.9014964776525, 6579.769708060195, 6590.439812615981, 5315.3621565412395, 5632.014397480507, 5843.561732869966, 6318.9052164216655, 6488.383603557654, 4940.827071052496, 6427.839304017437, 6624.900626490703, 6696.0, 6765.665845283369, 4221.8812014815485, 4241.300185469363, 4243.957560424356, 4246.851360202378, 4260.913467065879, 5248.658837175724, 5586.441355864381, 6059.212302400836, 6244.125822384961, 6304.810855674337, 6234.906476402948, 6406.238284471621, 6413.486921046212, 6421.5825971311715, 6425.8714825704155, 6121.908171377344, 6401.486951699134, 6426.0, 6471.255805582418, 6479.708413121515, 5198.636095473281, 6185.257769067561, 6282.371762823681, 6288.182536270256, 6318.399752023551, 6397.008463688136, 6412.39082287182, 6422.325655806073, 6431.453326467303, 6434.163558737334, 6153.169322141453, 6190.969045154746, 6221.0, 6234.625922444668, 6282.968585157045], "value": 4557.316243161577, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.6579472134898, 836.6736972580699, 856.5759948825329, 863.8617821148617, 880.2210044110964, 15965.760513592277, 16082.51752447427, 16108.0, 16353.91823040886, 16423.83576164238, 15243.695126097471, 15269.541913742605, 15454.07275563467, 15457.381704731797, 15458.768118478203, 988.9703308900745, 1015.4871789746168, 1017.7557386227289, 1021.0963297481745, 1021.4688362051729, 985.5860538573817, 994.627014869424, 1010.9789112995891, 1011.049613363436, 1012.6101450941367, 1064.7604289034982, 1080.7298175456135, 1080.9135269178487, 1082.1613249731442, 1082.2694681090272, 1075.6181555547823, 1077.1705786544396, 1078.7680648660537, 1079.0827796373098, 1079.940603266824, 802.8394321135796, 804.5775967616989, 805.7864665863551, 809.0332053196432, 812.0174588747623, 16553.420630278004, 16891.40880069204, 16979.57551061221, 17096.743548846778, 17241.482755517354, 17441.953482791072, 17443.651126977515, 17663.293468261254, 17934.013629250385, 17967.820321796746, 13689.97325200604, 13980.860191398058, 14010.299485025707, 14197.574072777834, 14234.074785138933, 3469.7397695172963, 3487.6861082502696, 3496.091016335771, 3498.895033148984, 3502.4571191465366, 86957.0, 129380.47168349063, 132393.0, 134296.0, 134987.0, 10124.949375253076, 11146.0, 11235.662930111946, 11488.425578721197, 11693.76612467763, 25179.748202518105, 25269.494610107697, 25477.235682929542, 25648.743512565008, 26014.479710406064, 8045.235702608243, 8101.675932962733, 8109.675612975415, 8112.067112282163, 8119.3910456715985, 3250.512423136501, 3947.388154836005, 3973.264945985002, 4021.879343619696, 4025.496812898388, 4332.805023773938, 4371.322445021026, 4404.229259879508, 4417.160739459557, 4419.779011049499, 903.7921278106072, 911.6717981526646, 925.4447331600985, 926.1757036237753, 927.096081320707, 623.6195920488499, 635.6472157952288, 643.0739734781899, 648.2739331948248, 676.972921083161, 10909.127269818395, 11510.884891151149, 11723.179377443497, 11854.466549005316, 11881.108916831272, 626.1203009771278, 637.2639601260552, 641.6342684669787, 650.1093501902329, 674.6120980436269, 5313.57491400687, 5427.294451721251, 5472.616916815864, 5473.507384335429, 5525.143602741501, 71995.2800471999, 92731.0, 103001.0, 103554.0, 104855.4757226209, 84567.5771621138, 85292.0, 85731.0, 85967.57016214878, 85993.0, 74323.25676743271, 78939.60530197312, 79791.0, 80792.45735767618, 83171.0, 11305.982461578498, 11506.712332191762, 12023.27860328383, 12560.937195313965, 12572.811407829073, 38816.64141755025, 39663.611773587785, 39965.0, 40057.0, 42812.57187428086, 43465.3480197791, 51903.740481297355, 52552.16067437622, 53203.733981329846, 53286.0, 15131.243437828285, 15196.0, 15216.0, 15460.226988650298, 15703.842961570239, 17119.806695755513, 17224.224909878834, 17302.826971730625, 17426.0, 17787.46637600874, 13680.31598420095, 15905.363785448453, 15909.204539772734, 16139.273732682292, 16184.352625894833, 13955.511557095453, 15921.124338161513, 16274.349026038826, 16288.837111628733, 16334.264958076677, 15401.691966160839, 15603.453879114177, 15902.60243493922, 16077.919610401874, 16098.919505402398, 11511.769764604493, 11521.711957200801, 12239.143259971717, 12240.632781016584, 12287.464066991626, 11577.189596728149, 11901.0, 11922.344271065174, 12465.314407707663, 12665.430055647703], "value": 8206.821981124485, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1437.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 111.0, 111.0, 110.0, 112.0, 111.0, 111.0, 109.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 132.0, 142.0, 183.0, 109.0, 109.0, 110.0, 130.0, 157.0, 142.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 188.0, 185.0, 182.0, 183.0, 186.0, 187.0, 184.0, 183.0, 180.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 57.0, 88.0, 57.0, 58.0, 59.0, 61.0, 81.0, 59.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 79.0, 89.0, 68.0, 69.0, 70.0, 104.0, 102.0, 103.0, 92.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 88.0, 88.0, 88.0, 88.0, 87.0, 88.0, 89.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 98.0, 96.0, 95.0, 94.0, 95.0, 95.0, 96.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 84.0, 84.0, 84.0, 85.0, 86.0, 84.0, 93.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 46.0, 47.0, 47.0, 49.0, 49.0, 48.0, 47.0, 48.0, 48.0], "value": 47.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 57.0, 60.0, 62.0, 63.0, 58.0, 58.0, 57.0, 57.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 82.0, 83.0, 83.0, 81.0, 81.0, 81.0, 83.0, 81.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 135.0, 134.0, 134.0, 136.0, 134.0, 146.0, 200.0, 149.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 185.0, 179.0, 184.0, 184.0, 183.0, 181.0, 191.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 65.0, 67.0, 68.0, 68.0, 67.0, 67.0, 79.0, 79.0], "value": 69.5, "unit": "score"}]}]} 16:00:44 INFO - Return code: 0 16:00:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:44 INFO - # TBPL SUCCESS # 16:00:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:00:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:00:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:00:44 INFO - 2017-05-05 16:00:44.856 screenresolution[1648:9718] starting screenresolution argv=screenresolution get 16:00:44 INFO - 2017-05-05 16:00:44.859 screenresolution[1648:9718] Display 0: 1600x1200x32@60 16:00:44 INFO - 2017-05-05 16:00:44.865 screenresolution[1649:9721] starting screenresolution argv=screenresolution list 16:00:44 INFO - Available Modes on Display 0 16:00:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:00:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:00:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:00:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:00:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:00:45 INFO - Intel Iris: 16:00:45 INFO - Chipset Model: Intel Iris 16:00:45 INFO - Type: GPU 16:00:45 INFO - Bus: Built-In 16:00:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:00:45 INFO - Vendor: Intel (0x8086) 16:00:45 INFO - Device ID: 0x0a2e 16:00:45 INFO - Revision ID: 0x0009 16:00:45 INFO - Displays: 16:00:45 INFO - Display: 16:00:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:00:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:00:45 INFO - Display Serial Number: 1600x1200 60 16:00:45 INFO - Main Display: Yes 16:00:45 INFO - Mirror: Off 16:00:45 INFO - Online: Yes 16:00:45 INFO - Rotation: Supported 16:00:45 INFO - Return code: 0 16:00:45 INFO - Running post-action listener: _resource_record_post_action 16:00:45 INFO - [mozharness: 2017-05-05 23:00:45.118283Z] Finished run-tests step (success) 16:00:45 INFO - Running post-run listener: _resource_record_post_run 16:00:45 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 36869632; Write bytes: 510062592; Read time: 399; Write time: 2635 16:00:45 INFO - TinderboxPrint: CPU usage
53.2% 16:00:45 INFO - TinderboxPrint: I/O read bytes / time
36,869,632 / 399 16:00:45 INFO - TinderboxPrint: I/O write bytes / time
510,062,592 / 2,635 16:00:45 INFO - TinderboxPrint: CPU idle
2,252.7 (46.8%) 16:00:45 INFO - TinderboxPrint: CPU system
954.5 (19.8%) 16:00:45 INFO - TinderboxPrint: CPU user
1,602.5 (33.3%) 16:00:45 INFO - TinderboxPrint: Swap in / out
244,965,376 / 0 16:00:45 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205879808; Write bytes: 205498368; Read time: 15989; Write time: 490 16:00:45 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 34690560; Write bytes: 297490432; Read time: 368; Write time: 2113 16:00:45 INFO - Running post-run listener: _upload_blobber_files 16:00:45 INFO - Blob upload gear active. 16:00:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:45 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 16:00:45 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:00:45 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:00:46 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:00:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:00:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:47 INFO - (blobuploader) - INFO - Done attempting. 16:00:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:00:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:00:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:48 INFO - (blobuploader) - INFO - Done attempting. 16:00:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:00:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:00:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:48 INFO - (blobuploader) - INFO - Done attempting. 16:00:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:50 INFO - (blobuploader) - INFO - Done attempting. 16:00:50 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:50 INFO - Return code: 0 16:00:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dccada71608c6e798beac853673fa029194ec58e9af58dd115d604ebefbfa883d7d96d784bd3932eed577a3e65f604961bcdef17b046a89b396dac53bf82da7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c86e8ec22687b6755048dc75610b5baeb5699e987ba7289cc60d80f35c8021792656914ff497f6f80c5e5f25d196c5bb3c54628a2af3131aac5368102eb9c83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986eeb72976941647c7ffc6b4b3eccc010b291fe917c3bd321f582323c4d7dd23b3dcd7f8b5582040b0f0fb99f495bf458cd3553b41fa12f548fc6edb10f7dec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fac1739fb8a16d935261c16e5c707df585c733fe1270d2525a284a409fe1d2f21b21ca570bbc8eef983bbf9785ce22cffcc8c7e06c80c776d666d0edec16c02"} 16:00:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:50 INFO - Contents: 16:00:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dccada71608c6e798beac853673fa029194ec58e9af58dd115d604ebefbfa883d7d96d784bd3932eed577a3e65f604961bcdef17b046a89b396dac53bf82da7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c86e8ec22687b6755048dc75610b5baeb5699e987ba7289cc60d80f35c8021792656914ff497f6f80c5e5f25d196c5bb3c54628a2af3131aac5368102eb9c83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986eeb72976941647c7ffc6b4b3eccc010b291fe917c3bd321f582323c4d7dd23b3dcd7f8b5582040b0f0fb99f495bf458cd3553b41fa12f548fc6edb10f7dec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fac1739fb8a16d935261c16e5c707df585c733fe1270d2525a284a409fe1d2f21b21ca570bbc8eef983bbf9785ce22cffcc8c7e06c80c776d666d0edec16c02"} 16:00:50 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:50 INFO - Copying logs to upload dir... 16:00:50 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.559402 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-05-05 16:00:50.801081) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 16:00:50.804265) ========= 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.ik0LzxHPiy/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.S0lBVY7m92/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dccada71608c6e798beac853673fa029194ec58e9af58dd115d604ebefbfa883d7d96d784bd3932eed577a3e65f604961bcdef17b046a89b396dac53bf82da7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c86e8ec22687b6755048dc75610b5baeb5699e987ba7289cc60d80f35c8021792656914ff497f6f80c5e5f25d196c5bb3c54628a2af3131aac5368102eb9c83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986eeb72976941647c7ffc6b4b3eccc010b291fe917c3bd321f582323c4d7dd23b3dcd7f8b5582040b0f0fb99f495bf458cd3553b41fa12f548fc6edb10f7dec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fac1739fb8a16d935261c16e5c707df585c733fe1270d2525a284a409fe1d2f21b21ca570bbc8eef983bbf9785ce22cffcc8c7e06c80c776d666d0edec16c02"} build_url:https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011108 build_url: 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dccada71608c6e798beac853673fa029194ec58e9af58dd115d604ebefbfa883d7d96d784bd3932eed577a3e65f604961bcdef17b046a89b396dac53bf82da7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c86e8ec22687b6755048dc75610b5baeb5699e987ba7289cc60d80f35c8021792656914ff497f6f80c5e5f25d196c5bb3c54628a2af3131aac5368102eb9c83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/986eeb72976941647c7ffc6b4b3eccc010b291fe917c3bd321f582323c4d7dd23b3dcd7f8b5582040b0f0fb99f495bf458cd3553b41fa12f548fc6edb10f7dec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fac1739fb8a16d935261c16e5c707df585c733fe1270d2525a284a409fe1d2f21b21ca570bbc8eef983bbf9785ce22cffcc8c7e06c80c776d666d0edec16c02"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 16:00:50.839560) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:00:50.839939) ========= 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.ik0LzxHPiy/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.S0lBVY7m92/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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.005094 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:00:50.889556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 16:00:50.890045) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 16:00:50.890486) ========= ========= Total master_lag: 0.18 =========