builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0283 starttime: 1482767630.09 results: success (0) buildid: 20161226064736 builduid: 5cbeebde563446c784c3a4c58c2aa6d3 revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:50.094640) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:50.095357) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:50.095635) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:50.252321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:50.252703) ========= 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.WjeR1l3AMX/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.8rcmXOesva/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-26 07:53:50-- 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% 18.7M=0.001s 2016-12-26 07:53:50 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.720373 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:50.998258) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:50.998606) ========= 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.WjeR1l3AMX/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.8rcmXOesva/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.025119 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:51.046870) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:51.047235) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 --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 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WjeR1l3AMX/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.8rcmXOesva/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-26 07:53:51,146 truncating revision to first 12 chars 2016-12-26 07:53:51,146 Setting DEBUG logging. 2016-12-26 07:53:51,146 attempt 1/10 2016-12-26 07:53:51,146 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1747e26ee37b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 07:53:51,810 unpacking tar archive at: mozilla-central-1747e26ee37b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.947991 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:52.019419) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:52.019737) ========= script_repo_revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:52.020176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:52.020462) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 07:53:52.040539) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-26 07:53:52.041039) ========= /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.WjeR1l3AMX/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.8rcmXOesva/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:53:52 INFO - MultiFileLogger online at 20161226 07:53:52 in /builds/slave/test 07:53:52 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 07:53:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:53:52 INFO - {'append_to_log': False, 07:53:52 INFO - 'base_work_dir': '/builds/slave/test', 07:53:52 INFO - 'blob_upload_branch': 'Firefox', 07:53:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:53:52 INFO - 'branch': 'Firefox', 07:53:52 INFO - 'buildbot_json_path': 'buildprops.json', 07:53:52 INFO - 'config_files': ('talos/mac_config.py',), 07:53:52 INFO - 'default_actions': ('clobber', 07:53:52 INFO - 'read-buildbot-config', 07:53:52 INFO - 'download-and-extract', 07:53:52 INFO - 'populate-webroot', 07:53:52 INFO - 'create-virtualenv', 07:53:52 INFO - 'install', 07:53:52 INFO - 'run-tests'), 07:53:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:53:52 INFO - 'download_minidump_stackwalk': True, 07:53:52 INFO - 'download_symbols': 'ondemand', 07:53:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:53:52 INFO - 'tooltool.py': '/tools/tooltool.py', 07:53:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:53:52 INFO - '/tools/misc-python/virtualenv.py')}, 07:53:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:53:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:53:52 INFO - 'installer_path': 'installer.exe', 07:53:52 INFO - 'log_level': 'info', 07:53:52 INFO - 'log_name': 'talos', 07:53:52 INFO - 'log_to_console': True, 07:53:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:53:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:53:52 INFO - 'opt_config_files': (), 07:53:52 INFO - 'pip_index': False, 07:53:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:53:52 INFO - 'cmd': ('bash', 07:53:52 INFO - '-c', 07:53:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:53:52 INFO - 'enabled': True, 07:53:52 INFO - 'halt_on_failure': False, 07:53:52 INFO - 'name': 'check_screen_resolution'},), 07:53:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:53:52 INFO - 'cmd': ('bash', 07:53:52 INFO - '-c', 07:53:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:53:52 INFO - 'enabled': True, 07:53:52 INFO - 'halt_on_failure': False, 07:53:52 INFO - 'name': 'check_screen_resolution'},), 07:53:52 INFO - 'run_cmd_checks_enabled': True, 07:53:52 INFO - 'sps_profile': False, 07:53:52 INFO - 'sps_profile_interval': 0, 07:53:52 INFO - 'suite': 'dromaeojs', 07:53:52 INFO - 'system_bits': '32', 07:53:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:53:52 INFO - 'title': 't-yosemite-r7-0283', 07:53:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:53:52 INFO - 'use_talos_json': True, 07:53:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:53:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:53:52 INFO - 'work_dir': 'build'} 07:53:52 INFO - [mozharness: 2016-12-26 15:53:52.248936Z] Running clobber step. 07:53:52 INFO - Running pre-action listener: _resource_record_pre_action 07:53:52 INFO - Running main action method: clobber 07:53:52 INFO - rmtree: /builds/slave/test/build 07:53:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:53:54 INFO - Running post-action listener: _resource_record_post_action 07:53:54 INFO - [mozharness: 2016-12-26 15:53:54.657625Z] Finished clobber step (success) 07:53:54 INFO - [mozharness: 2016-12-26 15:53:54.657761Z] Running read-buildbot-config step. 07:53:54 INFO - Running pre-action listener: _resource_record_pre_action 07:53:54 INFO - Running main action method: read_buildbot_config 07:53:54 INFO - Using buildbot properties: 07:53:54 INFO - { 07:53:54 INFO - "project": "", 07:53:54 INFO - "product": "firefox", 07:53:54 INFO - "script_repo_revision": "production", 07:53:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:53:54 INFO - "repository": "", 07:53:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:53:54 INFO - "buildid": "20161226064736", 07:53:54 INFO - "pgo_build": "False", 07:53:54 INFO - "basedir": "/builds/slave/test", 07:53:54 INFO - "buildnumber": 161, 07:53:54 INFO - "slavename": "t-yosemite-r7-0283", 07:53:54 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:53:54 INFO - "platform": "yosemite_r7", 07:53:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:53:54 INFO - "branch": "mozilla-central", 07:53:54 INFO - "repo_path": "mozilla-central", 07:53:54 INFO - "revision": "1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9", 07:53:54 INFO - "stage_platform": "macosx64", 07:53:54 INFO - "builduid": "5cbeebde563446c784c3a4c58c2aa6d3", 07:53:54 INFO - "slavebuilddir": "test" 07:53:54 INFO - } 07:53:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:53:54 INFO - Running post-action listener: _resource_record_post_action 07:53:54 INFO - [mozharness: 2016-12-26 15:53:54.661174Z] Finished read-buildbot-config step (success) 07:53:54 INFO - [mozharness: 2016-12-26 15:53:54.661287Z] Running download-and-extract step. 07:53:54 INFO - Running pre-action listener: _resource_record_pre_action 07:53:54 INFO - Running main action method: download_and_extract 07:53:54 INFO - mkdir: /builds/slave/test/build/tests 07:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:54 INFO - trying https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:53:54 INFO - Downloading https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:53:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:53:56 INFO - Downloaded 1199 bytes. 07:53:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:53:56 INFO - Using the following test package requirements: 07:53:56 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:53:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:53:56 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:53:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:53:56 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:53:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:53:56 INFO - u'jsshell-mac.zip'], 07:53:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:53:56 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:53:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:53:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:53:56 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:53:56 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:53:56 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:53:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:53:56 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:53:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:53:56 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:53:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:53:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:53:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:53:56 INFO - Fetch https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:53:56 INFO - Expected file size: 16294310 07:53:56 INFO - Obtained file size: 16294310 07:54:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:54:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:54:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:54:03 INFO - Fetch https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:54:04 INFO - Expected file size: 16294310 07:54:04 INFO - Obtained file size: 16294310 07:54:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:54:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:54:10 INFO - Fetch https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:54:11 INFO - Expected file size: 14409776 07:54:11 INFO - Obtained file size: 14409776 07:54:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:11 INFO - trying https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:54:11 INFO - Downloading https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:54:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:54:14 INFO - Downloaded 69696432 bytes. 07:54:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:54:14 INFO - mkdir: /builds/slave/test/properties 07:54:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:54:14 INFO - Writing to file /builds/slave/test/properties/build_url 07:54:14 INFO - Contents: 07:54:14 INFO - build_url:https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:54:14 INFO - Running post-action listener: _resource_record_post_action 07:54:14 INFO - Running post-action listener: set_extra_try_arguments 07:54:14 INFO - [mozharness: 2016-12-26 15:54:14.932963Z] Finished download-and-extract step (success) 07:54:14 INFO - [mozharness: 2016-12-26 15:54:14.933096Z] Running populate-webroot step. 07:54:14 INFO - Running pre-action listener: _resource_record_pre_action 07:54:14 INFO - Running main action method: populate_webroot 07:54:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:54:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:54:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:54:14 INFO - '--tppagecycles', 07:54:14 INFO - '7'], 07:54:14 INFO - 'tests': ['tsvgm']}}, 07:54:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:54:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:54:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:54:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:54:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:54:14 INFO - 'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:54:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:54:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:54:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:54:14 INFO - 'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['damp', 'tps']}, 07:54:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:54:14 INFO - 'tests': ['damp', 'tps']}, 07:54:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['dromaeo_dom']}, 07:54:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:54:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['basic_compositor_video']}, 07:54:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:54:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['a11yr', 07:54:14 INFO - 'ts_paint', 07:54:14 INFO - 'tpaint', 07:54:14 INFO - 'sessionrestore', 07:54:14 INFO - 'sessionrestore_no_auto_restore', 07:54:14 INFO - 'tabpaint']}, 07:54:14 INFO - 'other-e10s': {'tests': ['a11yr', 07:54:14 INFO - 'ts_paint', 07:54:14 INFO - 'tpaint', 07:54:14 INFO - 'sessionrestore', 07:54:14 INFO - 'sessionrestore_no_auto_restore', 07:54:14 INFO - 'tabpaint']}, 07:54:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:54:14 INFO - 'ts_paint', 07:54:14 INFO - 'tpaint', 07:54:14 INFO - 'sessionrestore', 07:54:14 INFO - 'sessionrestore_no_auto_restore', 07:54:14 INFO - 'tabpaint']}, 07:54:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:54:14 INFO - 'ts_paint', 07:54:14 INFO - 'tpaint', 07:54:14 INFO - 'sessionrestore', 07:54:14 INFO - 'sessionrestore_no_auto_restore', 07:54:14 INFO - 'tabpaint']}, 07:54:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['a11yr', 07:54:14 INFO - 'ts_paint', 07:54:14 INFO - 'tpaint', 07:54:14 INFO - 'sessionrestore', 07:54:14 INFO - 'sessionrestore_no_auto_restore', 07:54:14 INFO - 'tabpaint']}, 07:54:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['a11yr', 07:54:14 INFO - 'ts_paint', 07:54:14 INFO - 'tpaint', 07:54:14 INFO - 'sessionrestore', 07:54:14 INFO - 'sessionrestore_no_auto_restore', 07:54:14 INFO - 'tabpaint']}, 07:54:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['tsvgx', 07:54:14 INFO - 'tsvgr_opacity', 07:54:14 INFO - 'tart', 07:54:14 INFO - 'tscrollx', 07:54:14 INFO - 'cart', 07:54:14 INFO - 'tsvg_static']}, 07:54:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:54:14 INFO - 'tsvgr_opacity', 07:54:14 INFO - 'tart', 07:54:14 INFO - 'tscrollx', 07:54:14 INFO - 'cart', 07:54:14 INFO - 'tsvg_static']}, 07:54:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:54:14 INFO - 'talos_options': ['--disable-e10s'], 07:54:14 INFO - 'tests': ['tp5o']}, 07:54:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:54:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:54:14 INFO - 'talos_options': ['--disable-e10s', 07:54:14 INFO - '--xperf_path', 07:54:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:54:14 INFO - 'tests': ['tp5n']}, 07:54:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:54:14 INFO - 'talos_options': ['--xperf_path', 07:54:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:54:14 INFO - 'tests': ['tp5n']}}, 07:54:14 INFO - 'talos.zip': {'path': '', 07:54:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:54:14 INFO - Running post-action listener: _resource_record_post_action 07:54:14 INFO - [mozharness: 2016-12-26 15:54:14.951372Z] Finished populate-webroot step (success) 07:54:14 INFO - [mozharness: 2016-12-26 15:54:14.951485Z] Running create-virtualenv step. 07:54:14 INFO - Running pre-action listener: _resource_record_pre_action 07:54:14 INFO - Running main action method: create_virtualenv 07:54:14 INFO - Creating virtualenv /builds/slave/test/build/venv 07:54:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:54:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:54:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:54:15 INFO - Using real prefix '/tools/python27' 07:54:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:54:15 INFO - Installing distribute.............................................................................................................................................................................................done. 07:54:18 INFO - Installing pip.................done. 07:54:18 INFO - Return code: 0 07:54:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:54:18 INFO - Reading from file tmpfile_stdout 07:54:18 INFO - Output received: 07:54:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:54:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:18 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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:54:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:18 INFO - 'HOME': '/Users/cltbld', 07:54:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:18 INFO - 'LOGNAME': 'cltbld', 07:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:18 INFO - 'MOZ_NO_REMOTE': '1', 07:54:18 INFO - 'NO_EM_RESTART': '1', 07:54:18 INFO - 'PAGER': '/bin/cat', 07:54:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:18 INFO - 'PWD': '/builds/slave/test', 07:54:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:18 INFO - 'SHELL': '/bin/bash', 07:54:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:18 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:18 INFO - 'USER': 'cltbld', 07:54:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:18 INFO - 'XPC_FLAGS': '0x0', 07:54:18 INFO - 'XPC_SERVICE_NAME': '0', 07:54:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54: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 07:54:19 INFO - Cleaning up... 07:54:19 INFO - Return code: 0 07:54:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:54: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')]} 07:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54: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')]} 07:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54: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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54: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 07:54: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 07:54:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:19 INFO - 'HOME': '/Users/cltbld', 07:54:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:19 INFO - 'LOGNAME': 'cltbld', 07:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:19 INFO - 'MOZ_NO_REMOTE': '1', 07:54:19 INFO - 'NO_EM_RESTART': '1', 07:54:19 INFO - 'PAGER': '/bin/cat', 07:54:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:19 INFO - 'PWD': '/builds/slave/test', 07:54:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:19 INFO - 'SHELL': '/bin/bash', 07:54:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:19 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:19 INFO - 'USER': 'cltbld', 07:54:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:19 INFO - 'XPC_FLAGS': '0x0', 07:54:19 INFO - 'XPC_SERVICE_NAME': '0', 07:54:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:19 INFO - Downloading/unpacking psutil>=3.1.1 07:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:54:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:54:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:54:23 INFO - Installing collected packages: psutil 07:54:23 INFO - Running setup.py install for psutil 07:54:23 INFO - building 'psutil._psutil_osx' extension 07:54: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:54:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:54:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:54:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:54:24 INFO - building 'psutil._psutil_posix' extension 07:54:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:54:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:54:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:54:24 INFO - ^ 07:54:24 INFO - 1 warning generated. 07:54:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:54:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:54:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:54:24 INFO - Successfully installed psutil 07:54:24 INFO - Cleaning up... 07:54:24 INFO - Return code: 0 07:54:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:24 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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:54:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:24 INFO - 'HOME': '/Users/cltbld', 07:54:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:24 INFO - 'LOGNAME': 'cltbld', 07:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:24 INFO - 'MOZ_NO_REMOTE': '1', 07:54:24 INFO - 'NO_EM_RESTART': '1', 07:54:24 INFO - 'PAGER': '/bin/cat', 07:54:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:24 INFO - 'PWD': '/builds/slave/test', 07:54:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:24 INFO - 'SHELL': '/bin/bash', 07:54:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:24 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:24 INFO - 'USER': 'cltbld', 07:54:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:24 INFO - 'XPC_FLAGS': '0x0', 07:54:24 INFO - 'XPC_SERVICE_NAME': '0', 07:54:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:54: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 07:54: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 07:54: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 07:54: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 07:54: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 07:54: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 07:54:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:54:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:54:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:54:27 INFO - Installing collected packages: mozsystemmonitor 07:54:27 INFO - Running setup.py install for mozsystemmonitor 07:54:27 INFO - Successfully installed mozsystemmonitor 07:54:27 INFO - Cleaning up... 07:54:27 INFO - Return code: 0 07:54:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54: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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54: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 07:54: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 07:54:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:27 INFO - 'HOME': '/Users/cltbld', 07:54:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:27 INFO - 'LOGNAME': 'cltbld', 07:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:27 INFO - 'MOZ_NO_REMOTE': '1', 07:54:27 INFO - 'NO_EM_RESTART': '1', 07:54:27 INFO - 'PAGER': '/bin/cat', 07:54:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:27 INFO - 'PWD': '/builds/slave/test', 07:54:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:27 INFO - 'SHELL': '/bin/bash', 07:54:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:27 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:27 INFO - 'USER': 'cltbld', 07:54:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:27 INFO - 'XPC_FLAGS': '0x0', 07:54:27 INFO - 'XPC_SERVICE_NAME': '0', 07:54:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:28 INFO - Downloading/unpacking jsonschema==2.5.1 07:54: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 07:54: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 07:54: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 07:54: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 07:54: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 07:54: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 07:54:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:54:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:54: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 07:54: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 07:54: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 07:54: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 07:54: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 07:54: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 07:54:31 INFO - Downloading functools32-3.2.3-2.tar.gz 07:54:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:54:31 INFO - warning: no files found matching '*.txt' 07:54:31 INFO - no previously-included directories found matching 'build' 07:54:31 INFO - no previously-included directories found matching 'dist' 07:54:31 INFO - no previously-included directories found matching '.git*' 07:54:31 INFO - Installing collected packages: jsonschema, functools32 07:54:31 INFO - Running setup.py install for functools32 07:54:31 INFO - warning: no files found matching '*.txt' 07:54:31 INFO - no previously-included directories found matching 'build' 07:54:31 INFO - no previously-included directories found matching 'dist' 07:54:31 INFO - no previously-included directories found matching '.git*' 07:54:31 INFO - Successfully installed jsonschema functools32 07:54:31 INFO - Cleaning up... 07:54:31 INFO - Return code: 0 07:54:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:54:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:31 INFO - 'HOME': '/Users/cltbld', 07:54:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:31 INFO - 'LOGNAME': 'cltbld', 07:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:31 INFO - 'MOZ_NO_REMOTE': '1', 07:54:31 INFO - 'NO_EM_RESTART': '1', 07:54:31 INFO - 'PAGER': '/bin/cat', 07:54:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:31 INFO - 'PWD': '/builds/slave/test', 07:54:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:31 INFO - 'SHELL': '/bin/bash', 07:54:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:31 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:31 INFO - 'USER': 'cltbld', 07:54:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:31 INFO - 'XPC_FLAGS': '0x0', 07:54:31 INFO - 'XPC_SERVICE_NAME': '0', 07:54:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:54:31 INFO - Cleaning up... 07:54:31 INFO - Return code: 0 07:54:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:54:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:31 INFO - 'HOME': '/Users/cltbld', 07:54:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:31 INFO - 'LOGNAME': 'cltbld', 07:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:31 INFO - 'MOZ_NO_REMOTE': '1', 07:54:31 INFO - 'NO_EM_RESTART': '1', 07:54:31 INFO - 'PAGER': '/bin/cat', 07:54:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:31 INFO - 'PWD': '/builds/slave/test', 07:54:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:31 INFO - 'SHELL': '/bin/bash', 07:54:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:31 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:31 INFO - 'USER': 'cltbld', 07:54:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:31 INFO - 'XPC_FLAGS': '0x0', 07:54:31 INFO - 'XPC_SERVICE_NAME': '0', 07:54:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:32 INFO - Downloading/unpacking blobuploader==1.2.4 07:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:34 INFO - Downloading blobuploader-1.2.4.tar.gz 07:54:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:54:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:54:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:35 INFO - Downloading docopt-0.6.1.tar.gz 07:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:54:36 INFO - Installing collected packages: blobuploader, requests, docopt 07:54:36 INFO - Running setup.py install for blobuploader 07:54:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:54:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:54:36 INFO - Running setup.py install for requests 07:54:36 INFO - Running setup.py install for docopt 07:54:36 INFO - Successfully installed blobuploader requests docopt 07:54:36 INFO - Cleaning up... 07:54:36 INFO - Return code: 0 07:54:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:36 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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:54:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:36 INFO - 'HOME': '/Users/cltbld', 07:54:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:36 INFO - 'LOGNAME': 'cltbld', 07:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:36 INFO - 'MOZ_NO_REMOTE': '1', 07:54:36 INFO - 'NO_EM_RESTART': '1', 07:54:36 INFO - 'PAGER': '/bin/cat', 07:54:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:36 INFO - 'PWD': '/builds/slave/test', 07:54:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:36 INFO - 'SHELL': '/bin/bash', 07:54:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:36 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:36 INFO - 'USER': 'cltbld', 07:54:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:36 INFO - 'XPC_FLAGS': '0x0', 07:54:36 INFO - 'XPC_SERVICE_NAME': '0', 07:54:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:54:37 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Lnsjy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:54:37 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-RFy7Ry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:54:37 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-yx8ruW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:54:37 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Z1SUBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:54:37 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-_M5nnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:54:37 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-WtPGd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:54:37 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-TqoyuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:54:37 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-NjEp41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-WxkS3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-ea9ixY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-0_NdnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-BUbV4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-WdYkP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-4Q79IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-_5buDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-qg4mD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:54:38 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-3YaWnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:54:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:54:38 INFO - Running setup.py install for manifestparser 07:54:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:54:39 INFO - Running setup.py install for mozcrash 07:54:39 INFO - Running setup.py install for mozdebug 07:54:39 INFO - Running setup.py install for mozdevice 07:54:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:54:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:54:39 INFO - Running setup.py install for mozfile 07:54:39 INFO - Running setup.py install for mozhttpd 07:54:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:54:39 INFO - Running setup.py install for mozinfo 07:54:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Running setup.py install for mozInstall 07:54:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Running setup.py install for mozleak 07:54:40 INFO - Running setup.py install for mozlog 07:54:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Running setup.py install for moznetwork 07:54:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Running setup.py install for mozprocess 07:54:40 INFO - Running setup.py install for mozprofile 07:54:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:54:40 INFO - Running setup.py install for mozrunner 07:54:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:54:41 INFO - Running setup.py install for mozscreenshot 07:54:41 INFO - Running setup.py install for moztest 07:54:41 INFO - Running setup.py install for mozversion 07:54:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:54:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:54:41 INFO - Cleaning up... 07:54:41 INFO - Return code: 0 07:54:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54: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')]} 07:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54: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')]} 07:54:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:41 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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:41 INFO - 'HOME': '/Users/cltbld', 07:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:41 INFO - 'LOGNAME': 'cltbld', 07:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:41 INFO - 'MOZ_NO_REMOTE': '1', 07:54:41 INFO - 'NO_EM_RESTART': '1', 07:54:41 INFO - 'PAGER': '/bin/cat', 07:54:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:41 INFO - 'PWD': '/builds/slave/test', 07:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:41 INFO - 'SHELL': '/bin/bash', 07:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:41 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:41 INFO - 'USER': 'cltbld', 07:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:41 INFO - 'XPC_FLAGS': '0x0', 07:54:41 INFO - 'XPC_SERVICE_NAME': '0', 07:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:54:41 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-fNfXEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:54:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:54:41 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-GzsCiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:54:42 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)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-dAsClY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-k8G5OP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-I_7269-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-xpCLRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-cmqenL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-KR2YWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Au6k5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Omq3Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:54:42 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-ZPiA9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:54:43 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-o1MCg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:54:43 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-NrkAC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:54:43 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-SFGMMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:54:43 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)) 07:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:43 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-8pPpLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:54:43 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-gAF08h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:54:43 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-DGZ4E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:54:43 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)) 07:54:43 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)) 07:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:54:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:46 INFO - Downloading blessings-1.6.tar.gz 07:54:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:54:46 INFO - Installing collected packages: blessings 07:54:46 INFO - Running setup.py install for blessings 07:54:46 INFO - Successfully installed blessings 07:54:46 INFO - Cleaning up... 07:54:46 INFO - Return code: 0 07:54:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:54:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:54:46 INFO - Reading from file tmpfile_stdout 07:54:46 INFO - Current package versions: 07:54:46 INFO - blessings == 1.6 07:54:46 INFO - blobuploader == 1.2.4 07:54:46 INFO - docopt == 0.6.1 07:54:46 INFO - functools32 == 3.2.3-2 07:54:46 INFO - jsonschema == 2.5.1 07:54:46 INFO - manifestparser == 1.1 07:54:46 INFO - mozInstall == 1.12 07:54:46 INFO - mozcrash == 1.0 07:54:46 INFO - mozdebug == 0.1 07:54:46 INFO - mozdevice == 0.49 07:54:46 INFO - mozfile == 1.2 07:54:46 INFO - mozhttpd == 0.7 07:54:46 INFO - mozinfo == 0.9 07:54:46 INFO - mozleak == 0.1 07:54:46 INFO - mozlog == 3.4 07:54:46 INFO - moznetwork == 0.27 07:54:46 INFO - mozprocess == 0.23 07:54:46 INFO - mozprofile == 0.28 07:54:46 INFO - mozrunner == 6.13 07:54:46 INFO - mozscreenshot == 0.1 07:54:46 INFO - mozsystemmonitor == 0.3 07:54:46 INFO - moztest == 0.7 07:54:46 INFO - mozversion == 1.4 07:54:46 INFO - psutil == 3.1.1 07:54:46 INFO - requests == 1.2.3 07:54:46 INFO - wsgiref == 0.1.2 07:54:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:46 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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:46 INFO - 'HOME': '/Users/cltbld', 07:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:46 INFO - 'LOGNAME': 'cltbld', 07:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:46 INFO - 'MOZ_NO_REMOTE': '1', 07:54:46 INFO - 'NO_EM_RESTART': '1', 07:54:46 INFO - 'PAGER': '/bin/cat', 07:54:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:46 INFO - 'PWD': '/builds/slave/test', 07:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:46 INFO - 'SHELL': '/bin/bash', 07:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:46 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:46 INFO - 'USER': 'cltbld', 07:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:46 INFO - 'XPC_FLAGS': '0x0', 07:54:46 INFO - 'XPC_SERVICE_NAME': '0', 07:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:54:47 INFO - Cleaning up... 07:54:47 INFO - Return code: 0 07:54:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:47 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 0x10aca5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0c34e6d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, '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.WjeR1l3AMX/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.8rcmXOesva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:47 INFO - 'HOME': '/Users/cltbld', 07:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:47 INFO - 'LOGNAME': 'cltbld', 07:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:47 INFO - 'MOZ_NO_REMOTE': '1', 07:54:47 INFO - 'NO_EM_RESTART': '1', 07:54:47 INFO - 'PAGER': '/bin/cat', 07:54:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:47 INFO - 'PWD': '/builds/slave/test', 07:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:47 INFO - 'SHELL': '/bin/bash', 07:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:54:47 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:47 INFO - 'USER': 'cltbld', 07:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:47 INFO - 'XPC_FLAGS': '0x0', 07:54:47 INFO - 'XPC_SERVICE_NAME': '0', 07:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:54:47 INFO - Cleaning up... 07:54:47 INFO - Return code: 0 07:54:47 INFO - Running post-action listener: _resource_record_post_action 07:54:47 INFO - Running post-action listener: _start_resource_monitoring 07:54:47 INFO - Starting resource monitoring. 07:54:47 INFO - [mozharness: 2016-12-26 15:54:47.468068Z] Finished create-virtualenv step (success) 07:54:47 INFO - [mozharness: 2016-12-26 15:54:47.469089Z] Running install step. 07:54:47 INFO - Running pre-action listener: _resource_record_pre_action 07:54:47 INFO - Running main action method: install 07:54:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:54:47 INFO - Reading from file tmpfile_stdout 07:54:47 INFO - Detecting whether we're running mozinstall >=1.0... 07:54:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:54:47 INFO - Reading from file tmpfile_stdout 07:54:47 INFO - Output received: 07:54:47 INFO - Usage: mozinstall [options] installer 07:54:47 INFO - Options: 07:54:47 INFO - -h, --help show this help message and exit 07:54:47 INFO - -d DEST, --destination=DEST 07:54:47 INFO - Directory to install application into. [default: 07:54:47 INFO - "/builds/slave/test"] 07:54:47 INFO - --app=APP Application being installed. [default: firefox] 07:54:47 INFO - mkdir: /builds/slave/test/build/application 07:54:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:55:10 INFO - Reading from file tmpfile_stdout 07:55:10 INFO - Output received: 07:55:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:55:10 INFO - Running post-action listener: _resource_record_post_action 07:55:10 INFO - [mozharness: 2016-12-26 15:55:10.448772Z] Finished install step (success) 07:55:10 INFO - [mozharness: 2016-12-26 15:55:10.448937Z] Running run-tests step. 07:55:10 INFO - Running pre-action listener: _resource_record_pre_action 07:55:10 INFO - Running main action method: run_tests 07:55:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:55:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:55:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:55:10 INFO - 2016-12-26 07:55:10.463 screenresolution[1636:9186] starting screenresolution argv=screenresolution get 07:55:10 INFO - 2016-12-26 07:55:10.466 screenresolution[1636:9186] Display 0: 1600x1200x32@60 07:55:10 INFO - 2016-12-26 07:55:10.471 screenresolution[1637:9189] starting screenresolution argv=screenresolution list 07:55:10 INFO - Available Modes on Display 0 07:55:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:55:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:55:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:55:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:55:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:55:10 INFO - Intel Iris: 07:55:10 INFO - Chipset Model: Intel Iris 07:55:10 INFO - Type: GPU 07:55:10 INFO - Bus: Built-In 07:55:10 INFO - VRAM (Dynamic, Max): 1536 MB 07:55:10 INFO - Vendor: Intel (0x8086) 07:55:10 INFO - Device ID: 0x0a2e 07:55:10 INFO - Revision ID: 0x0009 07:55:10 INFO - Displays: 07:55:10 INFO - Display: 07:55:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:55:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:55:10 INFO - Display Serial Number: 1600x1200 60 07:55:10 INFO - Main Display: Yes 07:55:10 INFO - Mirror: Off 07:55:10 INFO - Online: Yes 07:55:10 INFO - Rotation: Supported 07:55:10 INFO - Return code: 0 07:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:55:10 INFO - Python 2.7.3 07:55:10 INFO - Return code: 0 07:55:10 INFO - grabbing minidump binary from tooltool 07:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:10 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 0x10aa83d30>, '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 0x10a9aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa78be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:55:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:55:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:55:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:55:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:55:10 INFO - Return code: 0 07:55:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:55:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:55:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:55:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:55:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:55:10 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/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--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 07:55:10 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/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0283 --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 07:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WjeR1l3AMX/Render', 07:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:10 INFO - 'HOME': '/Users/cltbld', 07:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:10 INFO - 'LOGNAME': 'cltbld', 07:55:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:55:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:10 INFO - 'MOZ_NO_REMOTE': '1', 07:55:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:55:10 INFO - 'NO_EM_RESTART': '1', 07:55:10 INFO - 'PAGER': '/bin/cat', 07:55:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:10 INFO - 'PWD': '/builds/slave/test', 07:55:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:10 INFO - 'SHELL': '/bin/bash', 07:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8rcmXOesva/Listeners', 07:55:10 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 07:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:10 INFO - 'USER': 'cltbld', 07:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:10 INFO - 'XPC_FLAGS': '0x0', 07:55:10 INFO - 'XPC_SERVICE_NAME': '0', 07:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:10 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/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--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 07:55:11 INFO - mozversion application_buildid: 20161226064736 07:55:11 INFO - mozversion application_changeset: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 07:55:11 INFO - mozversion application_display_name: Nightly 07:55:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:55:11 INFO - mozversion application_name: Firefox 07:55:11 INFO - mozversion application_remotingname: firefox 07:55:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:55:11 INFO - mozversion application_vendor: Mozilla 07:55:11 INFO - mozversion application_version: 53.0a1 07:55:11 INFO - mozversion platform_buildid: 20161226064736 07:55:11 INFO - mozversion platform_changeset: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 07:55:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:55:11 INFO - mozversion platform_version: 53.0a1 07:55:11 INFO - using testdate: 1482767711 07:55:11 INFO - actual date: 1482767711 07:55:11 INFO - starting webserver on 'localhost:49243' 07:55:11 INFO - SUITE-START | Running 2 tests 07:55:11 INFO - TEST-START | dromaeo_css 07:55:11 INFO - operating with platform_type : mac_ 07:55:11 INFO - Initialising browser for dromaeo_css test... 07:55:11 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpe0liQS/profile http://localhost:49243/getInfo.html) 07:55:11 INFO - PROCESS | 1647 | 1482767711612 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:55:13 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 07:55:13 INFO - PROCESS | 1647 | colorDepth:24 07:55:13 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 07:55:13 INFO - PROCESS | 1647 | __metrics 07:55:13 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:55:13 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:55:14 INFO - TEST-INFO | 1647: exit 0 07:55:14 INFO - Browser initialized. 07:55:14 INFO - Running cycle 1/1 for dromaeo_css test... 07:55:14 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpe0liQS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:55:26 INFO - PROCESS | 1650 | RSS: Main: 234156032 07:55:26 INFO - PROCESS | 1650 | 07:58:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 07:58:32 INFO - PROCESS | 1650 | RSS: Main: 388993024 07:58:32 INFO - PROCESS | 1650 | 08:00:14 INFO - PROCESS | 1650 | ************************* 08:00:14 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 08:00:14 INFO - PROCESS | 1650 | 08:00:14 INFO - PROCESS | 1650 | Full message: ReferenceError: fetch is not defined 08:00:14 INFO - PROCESS | 1650 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:00:14 INFO - PROCESS | 1650 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:00:14 INFO - PROCESS | 1650 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:00:14 INFO - PROCESS | 1650 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:00:14 INFO - PROCESS | 1650 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:00:14 INFO - PROCESS | 1650 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:00:14 INFO - PROCESS | 1650 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:00:14 INFO - PROCESS | 1650 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:00:14 INFO - PROCESS | 1650 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:00:14 INFO - PROCESS | 1650 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:00:14 INFO - PROCESS | 1650 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:00:14 INFO - PROCESS | 1650 | 08:00:14 INFO - PROCESS | 1650 | ************************* 08:01:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:01:36 INFO - PROCESS | 1650 | RSS: Main: 364371968 08:01:36 INFO - PROCESS | 1650 | 08:04:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:04:39 INFO - PROCESS | 1650 | RSS: Main: 345337856 08:04:39 INFO - PROCESS | 1650 | 08:07:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 08:07:42 INFO - PROCESS | 1650 | RSS: Main: 339959808 08:07:42 INFO - PROCESS | 1650 | 08:10:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 08:10:46 INFO - PROCESS | 1650 | RSS: Main: 336728064 08:10:46 INFO - PROCESS | 1650 | 08:13:49 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 08:13:50 INFO - PROCESS | 1650 | RSS: Main: 586985472 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - PROCESS | 1650 | __start_tp_report 08:13:50 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 08:13:50 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 08:13:50 INFO - PROCESS | 1650 | |i|pagename|runs| 08:13:50 INFO - PROCESS | 1650 | |0;dojo.html;3320.1699575106218;3321.05349975257;3335.1161942085337;3366.696997270248;3384.7292216622673;5446.863828404292;5500.42245564216;5503.559715222783;5538.806141785039;5558.610897237195;5502.2296878436955;5541.224228607998;5613.186088017245;5626.268585083943;5639.238702775114;5044.066847633199;5049.419316778581;5056.823011194609;5102.591792656579;5118.718470484122;5105.693658380491;5197.558207552363;5206.791728330882;5208.921866172011;5258.395284542269;5003.374578177728;5147.588192944556;5154.407243167027;5176.870578235536;5186.429492755794;5035.3454050973505;5036.043151801146;5076.898462030738;5104.412992505872;5104.770285337168;5030.496950304941;5083.694978301313;5086.516780905845;5087.694738315713;5135.281060651474;5506.311711036121;5509;5518.2826232589505;5525.723713814293;5550.056490396642;5869.060950247983;5880.8235752927485;5892.499137573312;5949.286085669704;6034.0948857671265;4998.7250701211415;5558.777069044803;6080.14877917092;6085.36103709113;6100.72546735398;5169.922451163236;5507.091329930597;5580.35825880029;5584.190292407578;5589.692566908859;6107.947487155049;6138.232720909887;6142.621558143003;6147.293061297919;6157.322694503601;5875.970620146872;5901.852453688606;5904.350521442638;5951.940480595225;5955.731992060368;6057.878842423127;6070.726817293233;6071.635701857903;6072.119542666289;6087.786927457518;5862.677552734555;5913.113033045078;5920.940790592039;5986.431289027535;6010.308814486302;6051.425114614105;6068.605540639845;6075.878482430327;6079.726412311368;6111.113888486234;4787.593054590398;4796.2805579162705;4798.7840547175465;4801.855944321676;4833.709977401367;4234.428352172479;4259.510156332061;4275.294576394922;4284.67864910133;4299.828006879752;5690.9430905690415;5698.202251684767;5705.657660540381;5712.11462223356;5727.341355744059;6077;6084.239470066242;6094.542909281821;6102;6107.96946015276;5685.232493613392;5699.344575373802;5699.430056994267;5729.4843464088435;5796.188533605298;5255.921161182585;5257.6056795739705;5261.657992230493;5279.8152064678325;5306.495882891119;6260.655663938436;6261.186045814015;6267.686615669107;6274.2784579773;6287.497000239879;5913.704314784158;5940.7920722776225;5961.016432288798;6125.295591007091;6137.7851775187655;5250.264962905273;6163.599366041193;6189.96905015472;6191.876162476815;6210.254769427697;5996.790112346047;6004;6010.459058684739;6016.759329626766;6074.210352654127;5918.467337939606;6112.694365281629;6113.602615829956;6128.019516877278;6142.201513803356;5720.74256658443;5783.19035335045;5860.472557469678;5922.674252916131;5932.436418540232;5829.387914268941;5918.40815918422;5945.34601193877;5976.850578735392;5981.192539007352;5750.6837123958585;5791.131330300489;5812.564057695774;5825.271841019872;5827.650340979555;5649.915251271152;5703.657780533182;5934.881302373842;5950.2264705587995;5959.582829202088;5707.571932105026;5716.914246286392;5742.138679198154;5781.913271301018;5826.504747096375 08:13:50 INFO - PROCESS | 1650 | |1;ext.html;455.34430420194917;476.53537800644364;479.58995059224384;483.9370881785367;494.9158643030684;27545;39363.80318098409;41257.7937110314;41439.79280103603;41516.58483415165;41947.161056778845;42427.15145697092;42430.36354454685;42462;42502.574974250245;9378.906210937907;9499.145076943074;9503.429794212334;9503.71488855332;9536.380135291183;8488.575571221445;8749.781255468632;11150.49822757978;11411.486483108238;11448.656540303762;11359.488823002985;11405.942970285138;11423.143264255172;11425.942870285637;11451.370174640391;11414.258073225255;11479.713007174803;11484.71288217793;11486.712832179179;11516.424178791069;8460.407771456;8690.304775617935;10987.505562249638;11219.38293393863;11271.549138034468;38721;39106;39115;39178;39265;4034.8184331705143;4044.6123331771637;4082.2243773682903;4082.857100001486;4096.815643296059;30325.696743032728;30395.544066839015;31033.37933241345;31039;31339.9031033915;2759.8758055887533;3217.3726123405945;3229.5155726640955;3289.342131573671;3326.783759055678;468991;479029.9999999965;479847;480000;481615;61138.77722445526;61670.69164654148;61780.38219617836;61788;61802.0729689055;63257.62310203592;63301.36698633047;63337;63339.68330158412;63358;15346.462873799363;15807.68384632324;15823.129727865078;15837.049777013186;15849.603759906095;10864.511097000495;12172;12225.572104976283;12251.32617706017;12295.508179672714;15797.9731317464;17898.821011789714;18329.175187116613;18350.81649183518;18412.81587184111;6080.817575472743;6984.895226571605;7016;7022.296415879923;7119.220194951262;820.2494717333692;837.476577139288;861.9482831030158;868.0841711993907;885.3315746611268;26519.204423867315;28186.577201341996;29070.709292907224;29966.55050174249;30200.84899575488;1021.2953062386929;1027.5992362978445;1056.4612047855649;1063.9680809575725;1071.1163290285544;15126.092434453967;15173.696526069416;15311.923440382727;15550.922245388701;15757.606059848595;567109;628412;639004;640611;835061;52163;52880.73559632177;52906.470935290155;52922.47077529175;53027.73486132544;1337.0840973932693;1358.6331690443526;1380.2615600653803;1380.2753554384028;1384.702289007868;25958;28201.153965381072;28452.85773571119;28845.711542884303;29179.41641167207;45614;46019;46049.53950460452;46099.3085103717;46205.306920395546;541908;543767;545637;545940;548649;21947.476040254936;24433.900474479047;24708.13521526738;24746.010159593414;24825;37312.253754925296;42275;42345;43699.78150109229;44168.55831441644;25576.872115639304;34788;35160.94517164489;35974.28051439009;37084.62915370812;28011.879524818778;28711.995080172095;34587.78942736992;35648.4652730204;35908;32483.35033299368;34819.82590087033;34864;35128.82435587806;35143.94568162959;30145.849270754385;31047.322120063218;31316.217094571908;31466.842665786524;31726.524102138024;23528.882355588114;23568.646470302618;24042.75957240405;24095.277141685776;24156.39609009789 08:13:50 INFO - PROCESS | 1650 | |2;jquery.html;348.21303853291556;362.33692343012285;368.3222869919348;373.39323599151385;397.7454429165333;14393.208373539452;14636.268186590682;15535.922320388396;19534.51163720904;20052.398428047145;18730.34443794464;19153.04234788262;19688.606227875433;19901.900490497526;20400.795992040075;5600.887982240352;5925.525957923356;5927.377625349341;5928.466438020578;5936.762529498815;4702.083093796711;5884.293884733838;5913.083472061838;5915.5859089863725;5916.112583112546;5601.3558440779425;5864.706764661772;5872.031114866042;5875.6474611523445;5885.588008839383;5732.971335143318;5758.942410575883;5804.622699524517;5822.534197264209;5823;4816.397950256218;4859.757012149378;4983.825566105169;5075.086484432801;5099.821506247264;24284.757152428425;24686.382840428952;24698;24859.276737419834;25095.62356564653;3029.6515900671266;3041.148478426044;3057.4343746406985;3070.401271752009;3164.5727826743323;14398.424063037466;14456.71086578273;15037.699246015129;15045.141423129571;15237.6190595236;1752.1502071495315;1806.2323512507132;1833.7799464064399;1836.6510363030982;1882.576420305434;94417.99999999932;104642.47678761634;108245.45877270642;108260;108573.45713271383;11159.386233757059;11328.03711684516;11550.249233799945;11697.064234861191;11697.707557310965;14475.92762036183;14502.274886255645;14510.419583216764;14522.636934076732;14595.489157879423;6754.324567543206;7942.523448592988;8197.385196110316;8323.209295116954;8330.333573314121;6795.966020169867;7926.643301051467;8098.825670277777;8105.797355066171;8112.878306825402;3594.478800573902;4219.915601687949;4488.281874900001;4506;4599.839005634787;1708.146660872942;1919.1076149590508;1922.3656193456127;1926.6917293233016;1938.9127489263017;962.5091203486272;965.4207475514635;969.2633598465217;985.3841349156777;990.3513198854617;17445.651086978443;17916;19537;19571.706424403645;19618.607627847363;879.8680197970356;944.4333399960107;989.3420875117966;993.3295025857517;1043.5564884923876;10068.496575171212;10638.840417393745;10690.51892664832;10700.464976751131;10700.94649526763;545016;548781.9789549039;552633;565466;566953;62076.37923620796;87486.56256718676;101975;103127.48436257771;116961;1017.0795430135739;1030.4899074957943;1048.1981284317503;1052.7999680060764;1054;18811.341603043827;18896;18931;19118.617627647647;19146.61706765829;46082.539174607824;48428.515714842404;52438.737806310724;53590;55266;73216.63391683008;73942.78171654859;74462.62768686122;74857.6257118711;75061;7518.924810751822;7882.763517094496;7944.761657150295;8045.758627241192;8056.516609003478;11152.88847111551;12014.579489717818;12019.21875078117;12394.442250098948;12438.315892625631;10408.687739367831;10857.077148442155;10873.565057397933;10910.345379277269;11015.83476247841;9213.078692130734;9677.177439917687;10287.742806429898;10422.895771042193;10539.367637941441;10919.890801091888;10957.232993690364;10996.835047474135;11069.833952490559;11190.216684831983;6557.704903279459;7678.769636910902;7735.458517903918;7843.333316668147;8029.357651387992;10376.584936602452;10410;10762.515686793957;10819.72950676208;10836.945815270874 08:13:50 INFO - PROCESS | 1650 | |3;mootools.html;1330.7272009238106;1398.8461269260379;1422.971540569188;1423.7294913966346;1428.9499867504626;7775.688972441103;8171.468854524452;8172.795680108;8175.427720059598;8193.426460147792;7344.669489872956;7642.656080476378;7650.808729781758;7988.480748751327;8209.58952052398;2270.909163633453;2314.375118717945;2317.8261630377706;2318.038014224095;2318.5594737;2295.2310975823034;2337.2170322941834;2338.251759436981;2341.239097293378;2348.518553696488;2014.5265862522297;2088.164734106362;2197.571473562656;2261.118163916074;2268.1381075191402;2263.513344630904;2268.274152271274;2268.2854900706225;2273.533925545259;2276.1236923784295;2240.2943072931976;2255.9323220303418;2259.378670865509;2265.399669087693;2273.30664147435;8114.6754129834735;8246;8284.627191776384;8319.500829950222;8335.04147023094;4994.87512812179;5170.689758614495;5172.663776854492;5179.585633149339;5180.533751962342;3290.7038366547126;3489.755717099804;3540.9114772130647;3548.556430446194;3554.1470047188745;1158.339746344584;1220.450797141281;1231.6736064942797;1240.9131360804747;1261.6593519749708;10318;10674.466276686135;10697.786044279072;10883.78232435347;10911.399873006982;4220.767857767853;4282.100758840616;4444.533324000998;4478.686491945533;4479.507254202035;8509.489430634057;8513.10612385703;8513.872291915626;8523.318134549221;8529.019162796234;5011.5489605935645;5014.52362025607;5021.748912554431;5022.42242142151;5036.219385995177;4224.324108142682;4259.446271984622;4390.407295015132;4687.859364219079;4694.389729335165;3582.964170358263;3608.9819550902075;3691.593924668284;3720.08857829828;3725.6646901778972;961.2214106573698;977.8093271812071;1135.6195674448986;1138.317009794117;1145.6448500964727;845.4377838737294;848.719922425598;857.6740838481462;859.1194026123148;889.8665200220019;5066.36670416198;5316.46835316473;5690.459406356389;5823.679697616587;5870.647761134345;871.8430682477089;884.9469031858109;919.4299534288783;925.3799954030816;970.6990832841842;3768.2736315676934;3774.6036666149657;3893.858377892148;3950.4271880577157;3954.3277642800795;9157.404768690147;9854.408735475894;9893.505324733735;10022.098011179029;10044.849327260097;9659.661911833053;9835.16401105914;9966.950165249127;10000;10010.899891001143;7557.130929943017;7799.45403821727;7813.843723125619;7825.0218722659665;7829.217078292125;5420.241166236809;5432.972835135957;5532.889342213214;5551.694656793915;5601.579881508985;9037.638494460149;9063.773405664911;9108.590113444778;9258.907410925805;9264.629414823332;8917.019127896056;9124.133207345292;9236;9351.205147562534;9381.108794664497;6629.966850165912;6820.215675197416;7006.003289062593;7096.361327480551;7263;6642.867142657216;7977.6011199438635;8002.879956800536;8015.398845086525;8023.157568455228;7095.9645201773665;7156.5706057634625;7178.174509931425;7239.276072392719;7357.926420735724;6969.837171469845;7012.123484564429;7023.508354415142;7186.317299856505;7196.244394338519;6242.469390101892;6354.1104245406605;6393.1369265148605;6408.9745640697265;6410.294867564658;4783.1390349737385;4829.323894654681;4855.878603034952;4871.5385384383835;4971.975140124277;4791.113643975876;4873.293372461044;4892.094962431962;4941.209406494943;4959.454460009469 08:13:50 INFO - PROCESS | 1650 | |4;prototype.html;340.67635746041265;347.23088359284196;351.63956944132264;360.5565154859521;363.3532312483778;4513.7517436540975;4758.357621721067;5090.363704536938;5094.516020978006;5103.591712662987;4144.502659680835;4290.54949230331;4630.421197350332;4686.695364801294;4864.318995340646;3522.876699315531;3592.8203589820537;3979.82090805913;3999.0202400411795;4000.7399519031314;3246.3669584431045;3862.7296089273764;4001.2197621463824;4008.9599104009026;4030.45588845505;3342.2145795737983;3346.732261419093;3742.8315725792268;3979.482667253253;4007.811836630728;3569.446735755962;3706.518152640153;3863.690904727615;3959.802009899522;3983.183447393277;3115.797473164237;3501.7023552998025;3513.4351923096283;3520.929581408383;3530.6645868642695;4331.4049339048825;4476.9552304476865;4845.3216549683075;4881.34101896243;4916.311716359713;4337.9611582556145;4401.498171814798;4436.268015777393;4494.235979883395;4516.277395616718;4463.575960283768;5244.659097158673;5251.212318152269;5276.92084618731;5315.521603055705;3582.3193593217206;3852.364359880616;3858.7684738915755;3864.2851072551666;3887.33915234408;4651.348811166439;5161.974190129063;5520.530754885839;5533.474319939599;5542.7782888684005;3125.781195316306;3181.5704879841387;3638.654327838904;3663.5970043295215;3704.259148170323;3328.2345060635707;3732.9734323061375;3760.7179461540495;3777.1690228149764;3786.715996300233;3863.918102931184;4018.799060046986;4348.847790327387;4376.474823021258;4394.682015078595;4494.685372023992;4538.455385353779;5192.039472697563;5330.360356757214;5399.433059528771;1073.9087177589993;1116.1740312168936;1160.4545863444168;1175.5944199251244;1208.546794951893;717.587387252332;748.9962550187323;775.166695802015;797.8112612207768;799.8800179972934;4192.61021217909;4281.892952676208;4640.95359046412;4826.58973987208;4904.5477261369315;4680.555347242007;4768.356271903249;4905.950940490621;5171.259232606724;5209.322788037531;4092.9739778809085;4152.294110001307;4234.534201237861;4766.308885211625;4813;2378.9643155352683;2600.7230889079897;2674.61218123371;2682.463507298548;2702.500037493031;4846.975765121152;4998.350214472168;5036.546710796046;5314.234056049153;5353.304070470814;4213.873583792491;4219.514755803122;4714.95285047152;4779.235322348408;4782.854032568133;4802.51974802517;4809.975950120227;5121.667091639031;5366.7584958677735;5413.593980451482;4190;5024.271480635361;5079.5428411443145;5095.337606111182;5097.61767867404;4249.300279887946;4499.572540608637;4697.718136911796;4906.779194936165;5402.891942161214;5754.913676294943;5772.826815195383;5791.971040144772;5800.187973683604;5809.833640665932;3271.4275001875053;3598.6581274778855;3675.3016926783826;3692.2830883639235;3716.925661486701;5102.821401251087;5236.319278493771;5406.324209473816;5449.22900057475;5480.479354461319;4330.956690433181;5044.1424957758;5268;5290.259363689163;5309.256704061511;4420.823167073281;4984.102861484967;5190.2214667800135;5248;5273.892482578702;4456.041950980555;4612.792424340845;4930.309756634146;5008.073506401327;5193.610479213999;4374.890627734205;5184.326037615086;5185.040767458032;5194.870128246824;5257.395399529103;4545.704529205591;4972;5202.921956170736;5219.8434046978655;5219.894162973025 08:13:50 INFO - PROCESS | 1650 | |5;yui.html;714.9571025738456;722.7940037089425;754.5510421299325;768.7117331000875;802.9558374289415;14633.414663413463;15427.46003889864;15635.29641166146;15638.452654157107;15658.686826263482;11806.58676946307;14183.290835458212;14185.290735463237;14188.574342769707;14189.645258868508;671.3018460800762;695.1241435790914;695.8434352270724;827.7020272701823;844.552387234765;858.3262139220712;860.862262038074;875.9912400876005;880.1506546182932;881.1761003461752;927.420362273579;927.9814403711922;929.0106037070499;929.1405449958795;929.5212965322864;714.5212707485997;736.2416710787844;917.3624331089928;919.3610440743686;923.7921467669788;655.6852710698892;674.8582797612507;677.2245778583494;683.781190019194;684.4524380495584;14848.2575871206;14858.2570871456;14889.106653600711;14892.70214595702;14897.106173629509;13162.407691653803;13298.734025319536;13342.399592018286;13351.933240333736;13501.59495215145;11821.586244481401;11931.891430894799;12012.759744805142;12028.63914082579;12187.51249950001;4427.6015158635555;4495.325701144821;4523.457185137771;4534.206513860095;4655.324977878188;107121.92878071197;107846.46076769567;108646;109484;110275;10382.480875956171;10650.786984260427;10826.67519974402;10842.512086956105;11378.374189419661;21345.679814802792;21470.46323841885;21725.78274217269;21744.34766956994;21812;7636.325701312924;7677.9634749308125;7702.691892324357;7892.171322011106;7947.284744373034;4099.036726369301;4116.75299482032;4119.217348703737;4136.4415803866095;4244.236037513278;3386.6274709781906;3449.034270404291;3479.5824501060033;3482.5995010574106;3490.2321489272317;652.5464801962685;664.5049438168595;675.6756756756785;713.3793599568305;718.7219636475384;620.5283984171972;657.6087228099274;665.9933400666029;667.3805314141973;672.7578071894114;9179.495127767903;9254.259659227246;9375.390599611004;9661.855072173923;9677.225821934227;623.3703959001441;650.1580453312971;670.4200866250658;680.6426626021379;680.993190068093;4333.674974376934;4344.56554344563;4636.721796692209;4695.171646092992;4772.164871147535;84209.57895210484;85941;86135.13864861272;92036;97460.51269743606;71941.6402917982;73422.26577734154;74577.25422745812;77801.61099194581;84071.5796421014;76232.61883690656;78062.60968695233;78367;78924.60537697274;80332.79500807493;8305.775054826412;9044.050374710561;9508.572114254737;9973.900260997298;10091.24315676342;32755.672443275264;43987;47056.29415558838;51009.744951275;51693.48306516887;50225;57433.71283143557;67340.98988515078;69743.30256697368;70290.94563581653;12759.983413021106;12765.170263932814;12931.612051638089;13214.339283036003;13593.252371119683;14501.709965800836;14576.635584110481;14613.85386146125;14671.779923300946;14844.480443184437;13458.327083645974;13663;13751.862481375458;13784.379702913593;14303.21332326732;13463.798043029166;13543.322833858465;13700.040997130105;13895.58313250604;14156.079854809404;13040.739185216433;13392.665183370495;13450.394732236877;13455.932720336335;13879.42090895923;9697.660581879883;10955.397453140155;11155.326700994916;11406.201565890307;11846.052315814542;7806.531608103532;8807.119288071142;10376.948115259376;10745.1275550628;10998.285111467729 08:13:50 INFO - PROCESS | 1650 | __end_tp_report 08:13:50 INFO - PROCESS | 1650 | __start_cc_report 08:13:50 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1695 08:13:50 INFO - PROCESS | 1650 | __end_cc_report 08:13:50 INFO - PROCESS | 1650 | __startTimestamp1482768830021__endTimestamp 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - PROCESS | 1650 | ------- Summary: start ------- 08:13:50 INFO - PROCESS | 1650 | Number of tests: 6 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5583.99 Median:5742.14 stddev:606.99 (10.6%) stddev-sans-first:582.29 08:13:50 INFO - PROCESS | 1650 | Values: 3320.2 3321.1 3335.1 3366.7 3384.7 5446.9 5500.4 5503.6 5538.8 5558.6 5502.2 5541.2 5613.2 5626.3 5639.2 5044.1 5049.4 5056.8 5102.6 5118.7 5105.7 5197.6 5206.8 5208.9 5258.4 5003.4 5147.6 5154.4 5176.9 5186.4 5035.3 5036.0 5076.9 5104.4 5104.8 5030.5 5083.7 5086.5 5087.7 5135.3 5506.3 5509.0 5518.3 5525.7 5550.1 5869.1 5880.8 5892.5 5949.3 6034.1 4998.7 5558.8 6080.1 6085.4 6100.7 5169.9 5507.1 5580.4 5584.2 5589.7 6107.9 6138.2 6142.6 6147.3 6157.3 5876.0 5901.9 5904.4 5951.9 5955.7 6057.9 6070.7 6071.6 6072.1 6087.8 5862.7 5913.1 5920.9 5986.4 6010.3 6051.4 6068.6 6075.9 6079.7 6111.1 4787.6 4796.3 4798.8 4801.9 4833.7 4234.4 4259.5 4275.3 4284.7 4299.8 5690.9 5698.2 5705.7 5712.1 5727.3 6077.0 6084.2 6094.5 6102.0 6108.0 5685.2 5699.3 5699.4 5729.5 5796.2 5255.9 5257.6 5261.7 5279.8 5306.5 6260.7 6261.2 6267.7 6274.3 6287.5 5913.7 5940.8 5961.0 6125.3 6137.8 5250.3 6163.6 6190.0 6191.9 6210.3 5996.8 6004.0 6010.5 6016.8 6074.2 5918.5 6112.7 6113.6 6128.0 6142.2 5720.7 5783.2 5860.5 5922.7 5932.4 5829.4 5918.4 5945.3 5976.9 5981.2 5750.7 5791.1 5812.6 5825.3 5827.7 5649.9 5703.7 5934.9 5950.2 5959.6 5707.6 5716.9 5742.1 5781.9 5826.5 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:68598.58 Median:35136.39 stddev:152421.95 (433.8%) stddev-sans-first:152763.67 08:13:50 INFO - PROCESS | 1650 | Values: 455.3 476.5 479.6 483.9 494.9 27545.0 39363.8 41257.8 41439.8 41516.6 41947.2 42427.2 42430.4 42462.0 42502.6 9378.9 9499.1 9503.4 9503.7 9536.4 8488.6 8749.8 11150.5 11411.5 11448.7 11359.5 11405.9 11423.1 11425.9 11451.4 11414.3 11479.7 11484.7 11486.7 11516.4 8460.4 8690.3 10987.5 11219.4 11271.5 38721.0 39106.0 39115.0 39178.0 39265.0 4034.8 4044.6 4082.2 4082.9 4096.8 30325.7 30395.5 31033.4 31039.0 31339.9 2759.9 3217.4 3229.5 3289.3 3326.8 468991.0 479030.0 479847.0 480000.0 481615.0 61138.8 61670.7 61780.4 61788.0 61802.1 63257.6 63301.4 63337.0 63339.7 63358.0 15346.5 15807.7 15823.1 15837.0 15849.6 10864.5 12172.0 12225.6 12251.3 12295.5 15798.0 17898.8 18329.2 18350.8 18412.8 6080.8 6984.9 7016.0 7022.3 7119.2 820.2 837.5 861.9 868.1 885.3 26519.2 28186.6 29070.7 29966.6 30200.8 1021.3 1027.6 1056.5 1064.0 1071.1 15126.1 15173.7 15311.9 15550.9 15757.6 567109.0 628412.0 639004.0 640611.0 835061.0 52163.0 52880.7 52906.5 52922.5 53027.7 1337.1 1358.6 1380.3 1380.3 1384.7 25958.0 28201.2 28452.9 28845.7 29179.4 45614.0 46019.0 46049.5 46099.3 46205.3 541908.0 543767.0 545637.0 545940.0 548649.0 21947.5 24433.9 24708.1 24746.0 24825.0 37312.3 42275.0 42345.0 43699.8 44168.6 25576.9 34788.0 35160.9 35974.3 37084.6 28011.9 28712.0 34587.8 35648.5 35908.0 32483.4 34819.8 34864.0 35128.8 35143.9 30145.8 31047.3 31316.2 31466.8 31726.5 23528.9 23568.6 24042.8 24095.3 24156.4 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:32192.59 Median:14062.64 stddev:92115.90 (655.0%) stddev-sans-first:92343.28 08:13:50 INFO - PROCESS | 1650 | Values: 348.2 362.3 368.3 373.4 397.7 14393.2 14636.3 15535.9 19534.5 20052.4 18730.3 19153.0 19688.6 19901.9 20400.8 5600.9 5925.5 5927.4 5928.5 5936.8 4702.1 5884.3 5913.1 5915.6 5916.1 5601.4 5864.7 5872.0 5875.6 5885.6 5733.0 5758.9 5804.6 5822.5 5823.0 4816.4 4859.8 4983.8 5075.1 5099.8 24284.8 24686.4 24698.0 24859.3 25095.6 3029.7 3041.1 3057.4 3070.4 3164.6 14398.4 14456.7 15037.7 15045.1 15237.6 1752.2 1806.2 1833.8 1836.7 1882.6 94418.0 104642.5 108245.5 108260.0 108573.5 11159.4 11328.0 11550.2 11697.1 11697.7 14475.9 14502.3 14510.4 14522.6 14595.5 6754.3 7942.5 8197.4 8323.2 8330.3 6796.0 7926.6 8098.8 8105.8 8112.9 3594.5 4219.9 4488.3 4506.0 4599.8 1708.1 1919.1 1922.4 1926.7 1938.9 962.5 965.4 969.3 985.4 990.4 17445.7 17916.0 19537.0 19571.7 19618.6 879.9 944.4 989.3 993.3 1043.6 10068.5 10638.8 10690.5 10700.5 10700.9 545016.0 548782.0 552633.0 565466.0 566953.0 62076.4 87486.6 101975.0 103127.5 116961.0 1017.1 1030.5 1048.2 1052.8 1054.0 18811.3 18896.0 18931.0 19118.6 19146.6 46082.5 48428.5 52438.7 53590.0 55266.0 73216.6 73942.8 74462.6 74857.6 75061.0 7518.9 7882.8 7944.8 8045.8 8056.5 11152.9 12014.6 12019.2 12394.4 12438.3 10408.7 10857.1 10873.6 10910.3 11015.8 9213.1 9677.2 10287.7 10422.9 10539.4 10919.9 10957.2 10996.8 11069.8 11190.2 6557.7 7678.8 7735.5 7843.3 8029.4 10376.6 10410.0 10762.5 10819.7 10836.9 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5336.53 Median:5176.12 stddev:2903.71 (56.1%) stddev-sans-first:2896.25 08:13:50 INFO - PROCESS | 1650 | Values: 1330.7 1398.8 1423.0 1423.7 1428.9 7775.7 8171.5 8172.8 8175.4 8193.4 7344.7 7642.7 7650.8 7988.5 8209.6 2270.9 2314.4 2317.8 2318.0 2318.6 2295.2 2337.2 2338.3 2341.2 2348.5 2014.5 2088.2 2197.6 2261.1 2268.1 2263.5 2268.3 2268.3 2273.5 2276.1 2240.3 2255.9 2259.4 2265.4 2273.3 8114.7 8246.0 8284.6 8319.5 8335.0 4994.9 5170.7 5172.7 5179.6 5180.5 3290.7 3489.8 3540.9 3548.6 3554.1 1158.3 1220.5 1231.7 1240.9 1261.7 10318.0 10674.5 10697.8 10883.8 10911.4 4220.8 4282.1 4444.5 4478.7 4479.5 8509.5 8513.1 8513.9 8523.3 8529.0 5011.5 5014.5 5021.7 5022.4 5036.2 4224.3 4259.4 4390.4 4687.9 4694.4 3583.0 3609.0 3691.6 3720.1 3725.7 961.2 977.8 1135.6 1138.3 1145.6 845.4 848.7 857.7 859.1 889.9 5066.4 5316.5 5690.5 5823.7 5870.6 871.8 884.9 919.4 925.4 970.7 3768.3 3774.6 3893.9 3950.4 3954.3 9157.4 9854.4 9893.5 10022.1 10044.8 9659.7 9835.2 9967.0 10000.0 10010.9 7557.1 7799.5 7813.8 7825.0 7829.2 5420.2 5433.0 5532.9 5551.7 5601.6 9037.6 9063.8 9108.6 9258.9 9264.6 8917.0 9124.1 9236.0 9351.2 9381.1 6630.0 6820.2 7006.0 7096.4 7263.0 6642.9 7977.6 8002.9 8015.4 8023.2 7096.0 7156.6 7178.2 7239.3 7357.9 6969.8 7012.1 7023.5 7186.3 7196.2 6242.5 6354.1 6393.1 6409.0 6410.3 4783.1 4829.3 4855.9 4871.5 4972.0 4791.1 4873.3 4892.1 4941.2 4959.5 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4182.89 Median:4527.37 stddev:1263.01 (27.9%) stddev-sans-first:1233.19 08:13:50 INFO - PROCESS | 1650 | Values: 340.7 347.2 351.6 360.6 363.4 4513.8 4758.4 5090.4 5094.5 5103.6 4144.5 4290.5 4630.4 4686.7 4864.3 3522.9 3592.8 3979.8 3999.0 4000.7 3246.4 3862.7 4001.2 4009.0 4030.5 3342.2 3346.7 3742.8 3979.5 4007.8 3569.4 3706.5 3863.7 3959.8 3983.2 3115.8 3501.7 3513.4 3520.9 3530.7 4331.4 4477.0 4845.3 4881.3 4916.3 4338.0 4401.5 4436.3 4494.2 4516.3 4463.6 5244.7 5251.2 5276.9 5315.5 3582.3 3852.4 3858.8 3864.3 3887.3 4651.3 5162.0 5520.5 5533.5 5542.8 3125.8 3181.6 3638.7 3663.6 3704.3 3328.2 3733.0 3760.7 3777.2 3786.7 3863.9 4018.8 4348.8 4376.5 4394.7 4494.7 4538.5 5192.0 5330.4 5399.4 1073.9 1116.2 1160.5 1175.6 1208.5 717.6 749.0 775.2 797.8 799.9 4192.6 4281.9 4641.0 4826.6 4904.5 4680.6 4768.4 4906.0 5171.3 5209.3 4093.0 4152.3 4234.5 4766.3 4813.0 2379.0 2600.7 2674.6 2682.5 2702.5 4847.0 4998.4 5036.5 5314.2 5353.3 4213.9 4219.5 4715.0 4779.2 4782.9 4802.5 4810.0 5121.7 5366.8 5413.6 4190.0 5024.3 5079.5 5095.3 5097.6 4249.3 4499.6 4697.7 4906.8 5402.9 5754.9 5772.8 5792.0 5800.2 5809.8 3271.4 3598.7 3675.3 3692.3 3716.9 5102.8 5236.3 5406.3 5449.2 5480.5 4331.0 5044.1 5268.0 5290.3 5309.3 4420.8 4984.1 5190.2 5248.0 5273.9 4456.0 4612.8 4930.3 5008.1 5193.6 4374.9 5184.3 5185.0 5194.9 5257.4 4545.7 4972.0 5202.9 5219.8 5219.9 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:19504.63 Median:4585.46 stddev:27587.79 (601.6%) stddev-sans-first:27629.11 08:13:50 INFO - PROCESS | 1650 | Values: 715.0 722.8 754.6 768.7 803.0 14633.4 15427.5 15635.3 15638.5 15658.7 11806.6 14183.3 14185.3 14188.6 14189.6 671.3 695.1 695.8 827.7 844.6 858.3 860.9 876.0 880.2 881.2 927.4 928.0 929.0 929.1 929.5 714.5 736.2 917.4 919.4 923.8 655.7 674.9 677.2 683.8 684.5 14848.3 14858.3 14889.1 14892.7 14897.1 13162.4 13298.7 13342.4 13351.9 13501.6 11821.6 11931.9 12012.8 12028.6 12187.5 4427.6 4495.3 4523.5 4534.2 4655.3 107121.9 107846.5 108646.0 109484.0 110275.0 10382.5 10650.8 10826.7 10842.5 11378.4 21345.7 21470.5 21725.8 21744.3 21812.0 7636.3 7678.0 7702.7 7892.2 7947.3 4099.0 4116.8 4119.2 4136.4 4244.2 3386.6 3449.0 3479.6 3482.6 3490.2 652.5 664.5 675.7 713.4 718.7 620.5 657.6 666.0 667.4 672.8 9179.5 9254.3 9375.4 9661.9 9677.2 623.4 650.2 670.4 680.6 681.0 4333.7 4344.6 4636.7 4695.2 4772.2 84209.6 85941.0 86135.1 92036.0 97460.5 71941.6 73422.3 74577.3 77801.6 84071.6 76232.6 78062.6 78367.0 78924.6 80332.8 8305.8 9044.1 9508.6 9973.9 10091.2 32755.7 43987.0 47056.3 51009.7 51693.5 50225.0 57433.7 67341.0 69743.3 70290.9 12760.0 12765.2 12931.6 13214.3 13593.3 14501.7 14576.6 14613.9 14671.8 14844.5 13458.3 13663.0 13751.9 13784.4 14303.2 13463.8 13543.3 13700.0 13895.6 14156.1 13040.7 13392.7 13450.4 13455.9 13879.4 9697.7 10955.4 11155.3 11406.2 11846.1 7806.5 8807.1 10376.9 10745.1 10998.3 08:13:50 INFO - PROCESS | 1650 | -------- Summary: end -------- 08:13:50 INFO - PROCESS | 1650 | 08:13:50 INFO - TEST-INFO | 1650: exit 0 08:13:50 INFO - TEST-OK | dromaeo_css | took 1119595ms 08:13:50 INFO - TEST-START | kraken 08:13:50 INFO - operating with platform_type : mac_ 08:13:50 INFO - Initialising browser for kraken test... 08:13:50 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpCtHrAi/profile http://localhost:49243/getInfo.html) 08:13:51 INFO - PROCESS | 1656 | 1482768831162 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:13:53 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 08:13:53 INFO - PROCESS | 1656 | colorDepth:24 08:13:53 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 08:13:53 INFO - PROCESS | 1656 | __metrics 08:13:53 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:13:53 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:13:53 INFO - TEST-INFO | 1656: exit 0 08:13:53 INFO - Browser initialized. 08:13:53 INFO - Running cycle 1/1 for kraken test... 08:13:53 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpCtHrAi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:14:05 INFO - PROCESS | 1657 | RSS: Main: 225472512 08:14:05 INFO - PROCESS | 1657 | 08:14:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 08:14:09 INFO - PROCESS | 1657 | RSS: Main: 286216192 08:14:09 INFO - PROCESS | 1657 | 08:14:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 08:14:12 INFO - PROCESS | 1657 | RSS: Main: 287477760 08:14:12 INFO - PROCESS | 1657 | 08:14:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 08:14:16 INFO - PROCESS | 1657 | RSS: Main: 377323520 08:14:16 INFO - PROCESS | 1657 | 08:14:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 08:14:19 INFO - PROCESS | 1657 | RSS: Main: 280059904 08:14:19 INFO - PROCESS | 1657 | 08:14:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:14:21 INFO - PROCESS | 1657 | RSS: Main: 283254784 08:14:21 INFO - PROCESS | 1657 | 08:14:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 08:14:25 INFO - PROCESS | 1657 | RSS: Main: 325251072 08:14:25 INFO - PROCESS | 1657 | 08:14:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 08:14:28 INFO - PROCESS | 1657 | RSS: Main: 326373376 08:14:28 INFO - PROCESS | 1657 | 08:14:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 08:14:32 INFO - PROCESS | 1657 | RSS: Main: 324386816 08:14:32 INFO - PROCESS | 1657 | 08:14:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:14:34 INFO - PROCESS | 1657 | RSS: Main: 303538176 08:14:34 INFO - PROCESS | 1657 | 08:14:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:14:37 INFO - PROCESS | 1657 | RSS: Main: 306675712 08:14:37 INFO - PROCESS | 1657 | 08:14:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:14:40 INFO - PROCESS | 1657 | RSS: Main: 308887552 08:14:40 INFO - PROCESS | 1657 | 08:14:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:14:43 INFO - PROCESS | 1657 | RSS: Main: 323764224 08:14:43 INFO - PROCESS | 1657 | 08:14:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:14:47 INFO - PROCESS | 1657 | RSS: Main: 317612032 08:14:47 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 08:14:49 INFO - PROCESS | 1657 | RSS: Main: 361254912 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | __start_tp_report 08:14:49 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 08:14:49 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 08:14:49 INFO - PROCESS | 1657 | |i|pagename|runs| 08:14:49 INFO - PROCESS | 1657 | |0;ai-astar;124;170;142;115;114;115;113;114;114;115 08:14:49 INFO - PROCESS | 1657 | |1;audio-beat-detection;143;109;110;145;111;109;110;109;126;109 08:14:49 INFO - PROCESS | 1657 | |2;audio-dft;183;186;183;186;185;181;184;184;184;184 08:14:49 INFO - PROCESS | 1657 | |3;audio-fft;80;59;58;88;61;59;59;59;74;58 08:14:49 INFO - PROCESS | 1657 | |4;audio-oscillator;89;70;86;69;69;69;83;69;69;84 08:14:49 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;92;88;91;89;91;88;92;88;91;88 08:14:49 INFO - PROCESS | 1657 | |6;imaging-darkroom;95;96;97;97;96;97;95;96;95;97 08:14:49 INFO - PROCESS | 1657 | |7;imaging-desaturate;102;129;84;87;83;86;85;86;84;83 08:14:49 INFO - PROCESS | 1657 | |8;json-parse-financial;56;57;55;55;56;56;57;56;56;57 08:14:49 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;65;74;67;77;75;72;69;71;72;71 08:14:49 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;85;98;105;108;107;90;87;87;87;88 08:14:49 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;141;148;145;146;141;140;139;140;180;155 08:14:49 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;170;175;177;171;174;172;169;217;173;170 08:14:49 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;65;68;68;67;67;67;68;67;66;65 08:14:49 INFO - PROCESS | 1657 | __end_tp_report 08:14:49 INFO - PROCESS | 1657 | __start_cc_report 08:14:49 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1966 08:14:49 INFO - PROCESS | 1657 | __end_cc_report 08:14:49 INFO - PROCESS | 1657 | __startTimestamp1482768889260__endTimestamp 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | ------- Summary: start ------- 08:14:49 INFO - PROCESS | 1657 | Number of tests: 14 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:123.60 Median:115.00 stddev:18.57 (16.1%) stddev-sans-first:19.69 08:14:49 INFO - PROCESS | 1657 | Values: 124.0 170.0 142.0 115.0 114.0 115.0 113.0 114.0 114.0 115.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:118.10 Median:110.50 stddev:14.60 (13.2%) stddev-sans-first:12.40 08:14:49 INFO - PROCESS | 1657 | Values: 143.0 109.0 110.0 145.0 111.0 109.0 110.0 109.0 126.0 109.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:1.49 (0.8%) stddev-sans-first:1.54 08:14:49 INFO - PROCESS | 1657 | Values: 183.0 186.0 183.0 186.0 185.0 181.0 184.0 184.0 184.0 184.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:65.50 Median:60.00 stddev:11.01 (18.3%) stddev-sans-first:10.35 08:14:49 INFO - PROCESS | 1657 | Values: 80.0 59.0 58.0 88.0 61.0 59.0 59.0 59.0 74.0 58.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:8.58 (11.2%) stddev-sans-first:7.63 08:14:49 INFO - PROCESS | 1657 | Values: 89.0 70.0 86.0 69.0 69.0 69.0 83.0 69.0 69.0 84.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:91.00 stddev:1.75 (1.9%) stddev-sans-first:1.67 08:14:49 INFO - PROCESS | 1657 | Values: 92.0 88.0 91.0 89.0 91.0 88.0 92.0 88.0 91.0 88.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.50 stddev:0.88 (0.9%) stddev-sans-first:0.83 08:14:49 INFO - PROCESS | 1657 | Values: 95.0 96.0 97.0 97.0 96.0 97.0 95.0 96.0 95.0 97.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:90.90 Median:84.50 stddev:14.50 (17.2%) stddev-sans-first:14.82 08:14:49 INFO - PROCESS | 1657 | Values: 102.0 129.0 84.0 87.0 83.0 86.0 85.0 86.0 84.0 83.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 08:14:49 INFO - PROCESS | 1657 | Values: 56.0 57.0 55.0 55.0 56.0 56.0 57.0 56.0 56.0 57.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:3.62 (5.0%) stddev-sans-first:3.04 08:14:49 INFO - PROCESS | 1657 | Values: 65.0 74.0 67.0 77.0 75.0 72.0 69.0 71.0 72.0 71.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:87.00 stddev:9.32 (10.7%) stddev-sans-first:9.27 08:14:49 INFO - PROCESS | 1657 | Values: 85.0 98.0 105.0 108.0 107.0 90.0 87.0 87.0 87.0 88.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:145.50 stddev:12.43 (8.5%) stddev-sans-first:12.96 08:14:49 INFO - PROCESS | 1657 | Values: 141.0 148.0 145.0 146.0 141.0 140.0 139.0 140.0 180.0 155.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:173.50 stddev:14.34 (8.3%) stddev-sans-first:15.00 08:14:49 INFO - PROCESS | 1657 | Values: 170.0 175.0 177.0 171.0 174.0 172.0 169.0 217.0 173.0 170.0 08:14:49 INFO - PROCESS | 1657 | 08:14:49 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.00 08:14:49 INFO - PROCESS | 1657 | Values: 65.0 68.0 68.0 67.0 67.0 67.0 68.0 67.0 66.0 65.0 08:14:49 INFO - PROCESS | 1657 | -------- Summary: end -------- 08:14:49 INFO - PROCESS | 1657 | 08:14:50 INFO - TEST-INFO | 1657: exit 0 08:14:50 INFO - TEST-OK | kraken | took 59654ms 08:14:50 INFO - SUITE-END | took 1179s 08:14:50 INFO - Completed test suite (00:19:39) 08:14:50 INFO - javascript benchmark 08:14:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.1699575106218, 3321.05349975257, 3335.1161942085337, 3366.696997270248, 3384.7292216622673, 5446.863828404292, 5500.42245564216, 5503.559715222783, 5538.806141785039, 5558.610897237195, 5502.2296878436955, 5541.224228607998, 5613.186088017245, 5626.268585083943, 5639.238702775114, 5044.066847633199, 5049.419316778581, 5056.823011194609, 5102.591792656579, 5118.718470484122, 5105.693658380491, 5197.558207552363, 5206.791728330882, 5208.921866172011, 5258.395284542269, 5003.374578177728, 5147.588192944556, 5154.407243167027, 5176.870578235536, 5186.429492755794, 5035.3454050973505, 5036.043151801146, 5076.898462030738, 5104.412992505872, 5104.770285337168, 5030.496950304941, 5083.694978301313, 5086.516780905845, 5087.694738315713, 5135.281060651474, 5506.311711036121, 5509.0, 5518.2826232589505, 5525.723713814293, 5550.056490396642, 5869.060950247983, 5880.8235752927485, 5892.499137573312, 5949.286085669704, 6034.0948857671265, 4998.7250701211415, 5558.777069044803, 6080.14877917092, 6085.36103709113, 6100.72546735398, 5169.922451163236, 5507.091329930597, 5580.35825880029, 5584.190292407578, 5589.692566908859, 6107.947487155049, 6138.232720909887, 6142.621558143003, 6147.293061297919, 6157.322694503601, 5875.970620146872, 5901.852453688606, 5904.350521442638, 5951.940480595225, 5955.731992060368, 6057.878842423127, 6070.726817293233, 6071.635701857903, 6072.119542666289, 6087.786927457518, 5862.677552734555, 5913.113033045078, 5920.940790592039, 5986.431289027535, 6010.308814486302, 6051.425114614105, 6068.605540639845, 6075.878482430327, 6079.726412311368, 6111.113888486234, 4787.593054590398, 4796.2805579162705, 4798.7840547175465, 4801.855944321676, 4833.709977401367, 4234.428352172479, 4259.510156332061, 4275.294576394922, 4284.67864910133, 4299.828006879752, 5690.9430905690415, 5698.202251684767, 5705.657660540381, 5712.11462223356, 5727.341355744059, 6077.0, 6084.239470066242, 6094.542909281821, 6102.0, 6107.96946015276, 5685.232493613392, 5699.344575373802, 5699.430056994267, 5729.4843464088435, 5796.188533605298, 5255.921161182585, 5257.6056795739705, 5261.657992230493, 5279.8152064678325, 5306.495882891119, 6260.655663938436, 6261.186045814015, 6267.686615669107, 6274.2784579773, 6287.497000239879, 5913.704314784158, 5940.7920722776225, 5961.016432288798, 6125.295591007091, 6137.7851775187655, 5250.264962905273, 6163.599366041193, 6189.96905015472, 6191.876162476815, 6210.254769427697, 5996.790112346047, 6004.0, 6010.459058684739, 6016.759329626766, 6074.210352654127, 5918.467337939606, 6112.694365281629, 6113.602615829956, 6128.019516877278, 6142.201513803356, 5720.74256658443, 5783.19035335045, 5860.472557469678, 5922.674252916131, 5932.436418540232, 5829.387914268941, 5918.40815918422, 5945.34601193877, 5976.850578735392, 5981.192539007352, 5750.6837123958585, 5791.131330300489, 5812.564057695774, 5825.271841019872, 5827.650340979555, 5649.915251271152, 5703.657780533182, 5934.881302373842, 5950.2264705587995, 5959.582829202088, 5707.571932105026, 5716.914246286392, 5742.138679198154, 5781.913271301018, 5826.504747096375], "value": 5546.42002247041, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.34430420194917, 476.53537800644364, 479.58995059224384, 483.9370881785367, 494.9158643030684, 27545.0, 39363.80318098409, 41257.7937110314, 41439.79280103603, 41516.58483415165, 41947.161056778845, 42427.15145697092, 42430.36354454685, 42462.0, 42502.574974250245, 9378.906210937907, 9499.145076943074, 9503.429794212334, 9503.71488855332, 9536.380135291183, 8488.575571221445, 8749.781255468632, 11150.49822757978, 11411.486483108238, 11448.656540303762, 11359.488823002985, 11405.942970285138, 11423.143264255172, 11425.942870285637, 11451.370174640391, 11414.258073225255, 11479.713007174803, 11484.71288217793, 11486.712832179179, 11516.424178791069, 8460.407771456, 8690.304775617935, 10987.505562249638, 11219.38293393863, 11271.549138034468, 38721.0, 39106.0, 39115.0, 39178.0, 39265.0, 4034.8184331705143, 4044.6123331771637, 4082.2243773682903, 4082.857100001486, 4096.815643296059, 30325.696743032728, 30395.544066839015, 31033.37933241345, 31039.0, 31339.9031033915, 2759.8758055887533, 3217.3726123405945, 3229.5155726640955, 3289.342131573671, 3326.783759055678, 468991.0, 479029.9999999965, 479847.0, 480000.0, 481615.0, 61138.77722445526, 61670.69164654148, 61780.38219617836, 61788.0, 61802.0729689055, 63257.62310203592, 63301.36698633047, 63337.0, 63339.68330158412, 63358.0, 15346.462873799363, 15807.68384632324, 15823.129727865078, 15837.049777013186, 15849.603759906095, 10864.511097000495, 12172.0, 12225.572104976283, 12251.32617706017, 12295.508179672714, 15797.9731317464, 17898.821011789714, 18329.175187116613, 18350.81649183518, 18412.81587184111, 6080.817575472743, 6984.895226571605, 7016.0, 7022.296415879923, 7119.220194951262, 820.2494717333692, 837.476577139288, 861.9482831030158, 868.0841711993907, 885.3315746611268, 26519.204423867315, 28186.577201341996, 29070.709292907224, 29966.55050174249, 30200.84899575488, 1021.2953062386929, 1027.5992362978445, 1056.4612047855649, 1063.9680809575725, 1071.1163290285544, 15126.092434453967, 15173.696526069416, 15311.923440382727, 15550.922245388701, 15757.606059848595, 567109.0, 628412.0, 639004.0, 640611.0, 835061.0, 52163.0, 52880.73559632177, 52906.470935290155, 52922.47077529175, 53027.73486132544, 1337.0840973932693, 1358.6331690443526, 1380.2615600653803, 1380.2753554384028, 1384.702289007868, 25958.0, 28201.153965381072, 28452.85773571119, 28845.711542884303, 29179.41641167207, 45614.0, 46019.0, 46049.53950460452, 46099.3085103717, 46205.306920395546, 541908.0, 543767.0, 545637.0, 545940.0, 548649.0, 21947.476040254936, 24433.900474479047, 24708.13521526738, 24746.010159593414, 24825.0, 37312.253754925296, 42275.0, 42345.0, 43699.78150109229, 44168.55831441644, 25576.872115639304, 34788.0, 35160.94517164489, 35974.28051439009, 37084.62915370812, 28011.879524818778, 28711.995080172095, 34587.78942736992, 35648.4652730204, 35908.0, 32483.35033299368, 34819.82590087033, 34864.0, 35128.82435587806, 35143.94568162959, 30145.849270754385, 31047.322120063218, 31316.217094571908, 31466.842665786524, 31726.524102138024, 23528.882355588114, 23568.646470302618, 24042.75957240405, 24095.277141685776, 24156.39609009789], "value": 19452.856702700803, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.21303853291556, 362.33692343012285, 368.3222869919348, 373.39323599151385, 397.7454429165333, 14393.208373539452, 14636.268186590682, 15535.922320388396, 19534.51163720904, 20052.398428047145, 18730.34443794464, 19153.04234788262, 19688.606227875433, 19901.900490497526, 20400.795992040075, 5600.887982240352, 5925.525957923356, 5927.377625349341, 5928.466438020578, 5936.762529498815, 4702.083093796711, 5884.293884733838, 5913.083472061838, 5915.5859089863725, 5916.112583112546, 5601.3558440779425, 5864.706764661772, 5872.031114866042, 5875.6474611523445, 5885.588008839383, 5732.971335143318, 5758.942410575883, 5804.622699524517, 5822.534197264209, 5823.0, 4816.397950256218, 4859.757012149378, 4983.825566105169, 5075.086484432801, 5099.821506247264, 24284.757152428425, 24686.382840428952, 24698.0, 24859.276737419834, 25095.62356564653, 3029.6515900671266, 3041.148478426044, 3057.4343746406985, 3070.401271752009, 3164.5727826743323, 14398.424063037466, 14456.71086578273, 15037.699246015129, 15045.141423129571, 15237.6190595236, 1752.1502071495315, 1806.2323512507132, 1833.7799464064399, 1836.6510363030982, 1882.576420305434, 94417.99999999932, 104642.47678761634, 108245.45877270642, 108260.0, 108573.45713271383, 11159.386233757059, 11328.03711684516, 11550.249233799945, 11697.064234861191, 11697.707557310965, 14475.92762036183, 14502.274886255645, 14510.419583216764, 14522.636934076732, 14595.489157879423, 6754.324567543206, 7942.523448592988, 8197.385196110316, 8323.209295116954, 8330.333573314121, 6795.966020169867, 7926.643301051467, 8098.825670277777, 8105.797355066171, 8112.878306825402, 3594.478800573902, 4219.915601687949, 4488.281874900001, 4506.0, 4599.839005634787, 1708.146660872942, 1919.1076149590508, 1922.3656193456127, 1926.6917293233016, 1938.9127489263017, 962.5091203486272, 965.4207475514635, 969.2633598465217, 985.3841349156777, 990.3513198854617, 17445.651086978443, 17916.0, 19537.0, 19571.706424403645, 19618.607627847363, 879.8680197970356, 944.4333399960107, 989.3420875117966, 993.3295025857517, 1043.5564884923876, 10068.496575171212, 10638.840417393745, 10690.51892664832, 10700.464976751131, 10700.94649526763, 545016.0, 548781.9789549039, 552633.0, 565466.0, 566953.0, 62076.37923620796, 87486.56256718676, 101975.0, 103127.48436257771, 116961.0, 1017.0795430135739, 1030.4899074957943, 1048.1981284317503, 1052.7999680060764, 1054.0, 18811.341603043827, 18896.0, 18931.0, 19118.617627647647, 19146.61706765829, 46082.539174607824, 48428.515714842404, 52438.737806310724, 53590.0, 55266.0, 73216.63391683008, 73942.78171654859, 74462.62768686122, 74857.6257118711, 75061.0, 7518.924810751822, 7882.763517094496, 7944.761657150295, 8045.758627241192, 8056.516609003478, 11152.88847111551, 12014.579489717818, 12019.21875078117, 12394.442250098948, 12438.315892625631, 10408.687739367831, 10857.077148442155, 10873.565057397933, 10910.345379277269, 11015.83476247841, 9213.078692130734, 9677.177439917687, 10287.742806429898, 10422.895771042193, 10539.367637941441, 10919.890801091888, 10957.232993690364, 10996.835047474135, 11069.833952490559, 11190.216684831983, 6557.704903279459, 7678.769636910902, 7735.458517903918, 7843.333316668147, 8029.357651387992, 10376.584936602452, 10410.0, 10762.515686793957, 10819.72950676208, 10836.945815270874], "value": 9329.54914906731, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.7272009238106, 1398.8461269260379, 1422.971540569188, 1423.7294913966346, 1428.9499867504626, 7775.688972441103, 8171.468854524452, 8172.795680108, 8175.427720059598, 8193.426460147792, 7344.669489872956, 7642.656080476378, 7650.808729781758, 7988.480748751327, 8209.58952052398, 2270.909163633453, 2314.375118717945, 2317.8261630377706, 2318.038014224095, 2318.5594737, 2295.2310975823034, 2337.2170322941834, 2338.251759436981, 2341.239097293378, 2348.518553696488, 2014.5265862522297, 2088.164734106362, 2197.571473562656, 2261.118163916074, 2268.1381075191402, 2263.513344630904, 2268.274152271274, 2268.2854900706225, 2273.533925545259, 2276.1236923784295, 2240.2943072931976, 2255.9323220303418, 2259.378670865509, 2265.399669087693, 2273.30664147435, 8114.6754129834735, 8246.0, 8284.627191776384, 8319.500829950222, 8335.04147023094, 4994.87512812179, 5170.689758614495, 5172.663776854492, 5179.585633149339, 5180.533751962342, 3290.7038366547126, 3489.755717099804, 3540.9114772130647, 3548.556430446194, 3554.1470047188745, 1158.339746344584, 1220.450797141281, 1231.6736064942797, 1240.9131360804747, 1261.6593519749708, 10318.0, 10674.466276686135, 10697.786044279072, 10883.78232435347, 10911.399873006982, 4220.767857767853, 4282.100758840616, 4444.533324000998, 4478.686491945533, 4479.507254202035, 8509.489430634057, 8513.10612385703, 8513.872291915626, 8523.318134549221, 8529.019162796234, 5011.5489605935645, 5014.52362025607, 5021.748912554431, 5022.42242142151, 5036.219385995177, 4224.324108142682, 4259.446271984622, 4390.407295015132, 4687.859364219079, 4694.389729335165, 3582.964170358263, 3608.9819550902075, 3691.593924668284, 3720.08857829828, 3725.6646901778972, 961.2214106573698, 977.8093271812071, 1135.6195674448986, 1138.317009794117, 1145.6448500964727, 845.4377838737294, 848.719922425598, 857.6740838481462, 859.1194026123148, 889.8665200220019, 5066.36670416198, 5316.46835316473, 5690.459406356389, 5823.679697616587, 5870.647761134345, 871.8430682477089, 884.9469031858109, 919.4299534288783, 925.3799954030816, 970.6990832841842, 3768.2736315676934, 3774.6036666149657, 3893.858377892148, 3950.4271880577157, 3954.3277642800795, 9157.404768690147, 9854.408735475894, 9893.505324733735, 10022.098011179029, 10044.849327260097, 9659.661911833053, 9835.16401105914, 9966.950165249127, 10000.0, 10010.899891001143, 7557.130929943017, 7799.45403821727, 7813.843723125619, 7825.0218722659665, 7829.217078292125, 5420.241166236809, 5432.972835135957, 5532.889342213214, 5551.694656793915, 5601.579881508985, 9037.638494460149, 9063.773405664911, 9108.590113444778, 9258.907410925805, 9264.629414823332, 8917.019127896056, 9124.133207345292, 9236.0, 9351.205147562534, 9381.108794664497, 6629.966850165912, 6820.215675197416, 7006.003289062593, 7096.361327480551, 7263.0, 6642.867142657216, 7977.6011199438635, 8002.879956800536, 8015.398845086525, 8023.157568455228, 7095.9645201773665, 7156.5706057634625, 7178.174509931425, 7239.276072392719, 7357.926420735724, 6969.837171469845, 7012.123484564429, 7023.508354415142, 7186.317299856505, 7196.244394338519, 6242.469390101892, 6354.1104245406605, 6393.1369265148605, 6408.9745640697265, 6410.294867564658, 4783.1390349737385, 4829.323894654681, 4855.878603034952, 4871.5385384383835, 4971.975140124277, 4791.113643975876, 4873.293372461044, 4892.094962431962, 4941.209406494943, 4959.454460009469], "value": 4330.115158116094, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.67635746041265, 347.23088359284196, 351.63956944132264, 360.5565154859521, 363.3532312483778, 4513.7517436540975, 4758.357621721067, 5090.363704536938, 5094.516020978006, 5103.591712662987, 4144.502659680835, 4290.54949230331, 4630.421197350332, 4686.695364801294, 4864.318995340646, 3522.876699315531, 3592.8203589820537, 3979.82090805913, 3999.0202400411795, 4000.7399519031314, 3246.3669584431045, 3862.7296089273764, 4001.2197621463824, 4008.9599104009026, 4030.45588845505, 3342.2145795737983, 3346.732261419093, 3742.8315725792268, 3979.482667253253, 4007.811836630728, 3569.446735755962, 3706.518152640153, 3863.690904727615, 3959.802009899522, 3983.183447393277, 3115.797473164237, 3501.7023552998025, 3513.4351923096283, 3520.929581408383, 3530.6645868642695, 4331.4049339048825, 4476.9552304476865, 4845.3216549683075, 4881.34101896243, 4916.311716359713, 4337.9611582556145, 4401.498171814798, 4436.268015777393, 4494.235979883395, 4516.277395616718, 4463.575960283768, 5244.659097158673, 5251.212318152269, 5276.92084618731, 5315.521603055705, 3582.3193593217206, 3852.364359880616, 3858.7684738915755, 3864.2851072551666, 3887.33915234408, 4651.348811166439, 5161.974190129063, 5520.530754885839, 5533.474319939599, 5542.7782888684005, 3125.781195316306, 3181.5704879841387, 3638.654327838904, 3663.5970043295215, 3704.259148170323, 3328.2345060635707, 3732.9734323061375, 3760.7179461540495, 3777.1690228149764, 3786.715996300233, 3863.918102931184, 4018.799060046986, 4348.847790327387, 4376.474823021258, 4394.682015078595, 4494.685372023992, 4538.455385353779, 5192.039472697563, 5330.360356757214, 5399.433059528771, 1073.9087177589993, 1116.1740312168936, 1160.4545863444168, 1175.5944199251244, 1208.546794951893, 717.587387252332, 748.9962550187323, 775.166695802015, 797.8112612207768, 799.8800179972934, 4192.61021217909, 4281.892952676208, 4640.95359046412, 4826.58973987208, 4904.5477261369315, 4680.555347242007, 4768.356271903249, 4905.950940490621, 5171.259232606724, 5209.322788037531, 4092.9739778809085, 4152.294110001307, 4234.534201237861, 4766.308885211625, 4813.0, 2378.9643155352683, 2600.7230889079897, 2674.61218123371, 2682.463507298548, 2702.500037493031, 4846.975765121152, 4998.350214472168, 5036.546710796046, 5314.234056049153, 5353.304070470814, 4213.873583792491, 4219.514755803122, 4714.95285047152, 4779.235322348408, 4782.854032568133, 4802.51974802517, 4809.975950120227, 5121.667091639031, 5366.7584958677735, 5413.593980451482, 4190.0, 5024.271480635361, 5079.5428411443145, 5095.337606111182, 5097.61767867404, 4249.300279887946, 4499.572540608637, 4697.718136911796, 4906.779194936165, 5402.891942161214, 5754.913676294943, 5772.826815195383, 5791.971040144772, 5800.187973683604, 5809.833640665932, 3271.4275001875053, 3598.6581274778855, 3675.3016926783826, 3692.2830883639235, 3716.925661486701, 5102.821401251087, 5236.319278493771, 5406.324209473816, 5449.22900057475, 5480.479354461319, 4330.956690433181, 5044.1424957758, 5268.0, 5290.259363689163, 5309.256704061511, 4420.823167073281, 4984.102861484967, 5190.2214667800135, 5248.0, 5273.892482578702, 4456.041950980555, 4612.792424340845, 4930.309756634146, 5008.073506401327, 5193.610479213999, 4374.890627734205, 5184.326037615086, 5185.040767458032, 5194.870128246824, 5257.395399529103, 4545.704529205591, 4972.0, 5202.921956170736, 5219.8434046978655, 5219.894162973025], "value": 3792.067696770817, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.9571025738456, 722.7940037089425, 754.5510421299325, 768.7117331000875, 802.9558374289415, 14633.414663413463, 15427.46003889864, 15635.29641166146, 15638.452654157107, 15658.686826263482, 11806.58676946307, 14183.290835458212, 14185.290735463237, 14188.574342769707, 14189.645258868508, 671.3018460800762, 695.1241435790914, 695.8434352270724, 827.7020272701823, 844.552387234765, 858.3262139220712, 860.862262038074, 875.9912400876005, 880.1506546182932, 881.1761003461752, 927.420362273579, 927.9814403711922, 929.0106037070499, 929.1405449958795, 929.5212965322864, 714.5212707485997, 736.2416710787844, 917.3624331089928, 919.3610440743686, 923.7921467669788, 655.6852710698892, 674.8582797612507, 677.2245778583494, 683.781190019194, 684.4524380495584, 14848.2575871206, 14858.2570871456, 14889.106653600711, 14892.70214595702, 14897.106173629509, 13162.407691653803, 13298.734025319536, 13342.399592018286, 13351.933240333736, 13501.59495215145, 11821.586244481401, 11931.891430894799, 12012.759744805142, 12028.63914082579, 12187.51249950001, 4427.6015158635555, 4495.325701144821, 4523.457185137771, 4534.206513860095, 4655.324977878188, 107121.92878071197, 107846.46076769567, 108646.0, 109484.0, 110275.0, 10382.480875956171, 10650.786984260427, 10826.67519974402, 10842.512086956105, 11378.374189419661, 21345.679814802792, 21470.46323841885, 21725.78274217269, 21744.34766956994, 21812.0, 7636.325701312924, 7677.9634749308125, 7702.691892324357, 7892.171322011106, 7947.284744373034, 4099.036726369301, 4116.75299482032, 4119.217348703737, 4136.4415803866095, 4244.236037513278, 3386.6274709781906, 3449.034270404291, 3479.5824501060033, 3482.5995010574106, 3490.2321489272317, 652.5464801962685, 664.5049438168595, 675.6756756756785, 713.3793599568305, 718.7219636475384, 620.5283984171972, 657.6087228099274, 665.9933400666029, 667.3805314141973, 672.7578071894114, 9179.495127767903, 9254.259659227246, 9375.390599611004, 9661.855072173923, 9677.225821934227, 623.3703959001441, 650.1580453312971, 670.4200866250658, 680.6426626021379, 680.993190068093, 4333.674974376934, 4344.56554344563, 4636.721796692209, 4695.171646092992, 4772.164871147535, 84209.57895210484, 85941.0, 86135.13864861272, 92036.0, 97460.51269743606, 71941.6402917982, 73422.26577734154, 74577.25422745812, 77801.61099194581, 84071.5796421014, 76232.61883690656, 78062.60968695233, 78367.0, 78924.60537697274, 80332.79500807493, 8305.775054826412, 9044.050374710561, 9508.572114254737, 9973.900260997298, 10091.24315676342, 32755.672443275264, 43987.0, 47056.29415558838, 51009.744951275, 51693.48306516887, 50225.0, 57433.71283143557, 67340.98988515078, 69743.30256697368, 70290.94563581653, 12759.983413021106, 12765.170263932814, 12931.612051638089, 13214.339283036003, 13593.252371119683, 14501.709965800836, 14576.635584110481, 14613.85386146125, 14671.779923300946, 14844.480443184437, 13458.327083645974, 13663.0, 13751.862481375458, 13784.379702913593, 14303.21332326732, 13463.798043029166, 13543.322833858465, 13700.040997130105, 13895.58313250604, 14156.079854809404, 13040.739185216433, 13392.665183370495, 13450.394732236877, 13455.932720336335, 13879.42090895923, 9697.660581879883, 10955.397453140155, 11155.326700994916, 11406.201565890307, 11846.052315814542, 7806.531608103532, 8807.119288071142, 10376.948115259376, 10745.1275550628, 10998.285111467729], "value": 7359.113952674939, "unit": "score"}], "name": "dromaeo_css", "value": 7039.115837918052, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 170.0, 142.0, 115.0, 114.0, 115.0, 113.0, 114.0, 114.0, 115.0], "value": 123.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 110.0, 145.0, 111.0, 109.0, 110.0, 109.0, 126.0, 109.0], "value": 118.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 183.0, 186.0, 185.0, 181.0, 184.0, 184.0, 184.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 88.0, 61.0, 59.0, 59.0, 59.0, 74.0, 58.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 86.0, 69.0, 69.0, 69.0, 83.0, 69.0, 69.0, 84.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 91.0, 89.0, 91.0, 88.0, 92.0, 88.0, 91.0, 88.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 97.0, 96.0, 97.0, 95.0, 96.0, 95.0, 97.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 129.0, 84.0, 87.0, 83.0, 86.0, 85.0, 86.0, 84.0, 83.0], "value": 90.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 55.0, 55.0, 56.0, 56.0, 57.0, 56.0, 56.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 67.0, 77.0, 75.0, 72.0, 69.0, 71.0, 72.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 98.0, 105.0, 108.0, 107.0, 90.0, 87.0, 87.0, 87.0, 88.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 148.0, 145.0, 146.0, 141.0, 140.0, 139.0, 140.0, 180.0, 155.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 175.0, 177.0, 171.0, 174.0, 172.0, 169.0, 217.0, 173.0, 170.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 68.0, 67.0, 67.0, 67.0, 68.0, 67.0, 66.0, 65.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1456.3999999999999, "alertThreshold": 2.0}]} 08:14:50 INFO - Return code: 0 08:14:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:14:50 INFO - # TBPL SUCCESS # 08:14:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:14:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:14:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:14:50 INFO - 2016-12-26 08:14:50.549 screenresolution[1661:11489] starting screenresolution argv=screenresolution get 08:14:50 INFO - 2016-12-26 08:14:50.552 screenresolution[1661:11489] Display 0: 1600x1200x32@60 08:14:50 INFO - 2016-12-26 08:14:50.558 screenresolution[1662:11492] starting screenresolution argv=screenresolution list 08:14:50 INFO - Available Modes on Display 0 08:14:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:14:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:14:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:14:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:14:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:14:50 INFO - Intel Iris: 08:14:50 INFO - Chipset Model: Intel Iris 08:14:50 INFO - Type: GPU 08:14:50 INFO - Bus: Built-In 08:14:50 INFO - VRAM (Dynamic, Max): 1536 MB 08:14:50 INFO - Vendor: Intel (0x8086) 08:14:50 INFO - Device ID: 0x0a2e 08:14:50 INFO - Revision ID: 0x0009 08:14:50 INFO - Displays: 08:14:50 INFO - Display: 08:14:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:14:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:14:50 INFO - Display Serial Number: 1600x1200 60 08:14:50 INFO - Main Display: Yes 08:14:50 INFO - Mirror: Off 08:14:50 INFO - Online: Yes 08:14:50 INFO - Rotation: Supported 08:14:50 INFO - Return code: 0 08:14:50 INFO - Running post-action listener: _resource_record_post_action 08:14:50 INFO - [mozharness: 2016-12-26 16:14:50.800120Z] Finished run-tests step (success) 08:14:50 INFO - Running post-run listener: _resource_record_post_run 08:14:50 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32168960; Write bytes: 550953472; Read time: 195; Write time: 1666 08:14:50 INFO - TinderboxPrint: CPU usage
53.7% 08:14:50 INFO - TinderboxPrint: I/O read bytes / time
32,168,960 / 195 08:14:50 INFO - TinderboxPrint: I/O write bytes / time
550,953,472 / 1,666 08:14:50 INFO - TinderboxPrint: CPU idle
2,228.3 (46.3%) 08:14:50 INFO - TinderboxPrint: CPU system
961.8 (20.0%) 08:14:50 INFO - TinderboxPrint: CPU user
1,619.7 (33.7%) 08:14:50 INFO - TinderboxPrint: Swap in / out
277,835,776 / 0 08:14:50 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 228866560; Write bytes: 224512000; Read time: 17427; Write time: 498 08:14:50 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31247360; Write bytes: 297372160; Read time: 183; Write time: 1101 08:14:51 INFO - Running post-run listener: _upload_blobber_files 08:14:51 INFO - Blob upload gear active. 08:14:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:14:51 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 08:14:51 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'] 08:14:51 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 08:14:51 INFO - (blobuploader) - INFO - Open directory for files ... 08:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:14:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:14:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:14:52 INFO - (blobuploader) - INFO - Done attempting. 08:14:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:14:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:14:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:14:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:14:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:14:53 INFO - (blobuploader) - INFO - Done attempting. 08:14:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:14:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:14:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:14:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:14:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:14:54 INFO - (blobuploader) - INFO - Done attempting. 08:14:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:14:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:14:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:14:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:14:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:14:56 INFO - (blobuploader) - INFO - Done attempting. 08:14:56 INFO - (blobuploader) - INFO - Iteration through files over. 08:14:56 INFO - Return code: 0 08:14:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:14:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:14:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e57d8a45129fc0b377098637c96d2d986d8428b56a5d45eb42922eb1fd5774098ea5fbc3289cdbdfc68bfce885f094d48ad2316c02814a7bc316b1b3857df73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f173a750125c9f13581d14f4f1164614c51b1615cf2491a42cf105faa2622c291e0103d2133d73db81c394dd437ebeace7936f1add156ae0cb2243dc56bbcf55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d186f4b9719484d3bd23fbb2f3b15e420cf1f4a2aee64d20b64ed14bc63eda51e17169bd394d8c5f84f83d74097eaa1e5a839706f45e3c1db456aa3958e46d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39a54d40bad187cbebab5e60567033a494cd89bb10a69ce6def848206df6a1a1aae1100e122ef023cab14caa1c08850681d09c0a1e797b12eb87de2d8eee3920"} 08:14:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:14:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:14:56 INFO - Contents: 08:14:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e57d8a45129fc0b377098637c96d2d986d8428b56a5d45eb42922eb1fd5774098ea5fbc3289cdbdfc68bfce885f094d48ad2316c02814a7bc316b1b3857df73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f173a750125c9f13581d14f4f1164614c51b1615cf2491a42cf105faa2622c291e0103d2133d73db81c394dd437ebeace7936f1add156ae0cb2243dc56bbcf55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d186f4b9719484d3bd23fbb2f3b15e420cf1f4a2aee64d20b64ed14bc63eda51e17169bd394d8c5f84f83d74097eaa1e5a839706f45e3c1db456aa3958e46d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39a54d40bad187cbebab5e60567033a494cd89bb10a69ce6def848206df6a1a1aae1100e122ef023cab14caa1c08850681d09c0a1e797b12eb87de2d8eee3920"} 08:14:56 INFO - Running post-run listener: copy_logs_to_upload_dir 08:14:56 INFO - Copying logs to upload dir... 08:14:56 INFO - mkdir: /builds/slave/test/build/upload/logs 08:14:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.941614 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-26 08:14:57.022616) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 08:14:57.026469) ========= 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.WjeR1l3AMX/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.8rcmXOesva/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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/4e57d8a45129fc0b377098637c96d2d986d8428b56a5d45eb42922eb1fd5774098ea5fbc3289cdbdfc68bfce885f094d48ad2316c02814a7bc316b1b3857df73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f173a750125c9f13581d14f4f1164614c51b1615cf2491a42cf105faa2622c291e0103d2133d73db81c394dd437ebeace7936f1add156ae0cb2243dc56bbcf55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d186f4b9719484d3bd23fbb2f3b15e420cf1f4a2aee64d20b64ed14bc63eda51e17169bd394d8c5f84f83d74097eaa1e5a839706f45e3c1db456aa3958e46d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39a54d40bad187cbebab5e60567033a494cd89bb10a69ce6def848206df6a1a1aae1100e122ef023cab14caa1c08850681d09c0a1e797b12eb87de2d8eee3920"} build_url:https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011111 build_url: 'https://queue.taskcluster.net/v1/task/NthQX7UwQYC212KpZrEUkA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e57d8a45129fc0b377098637c96d2d986d8428b56a5d45eb42922eb1fd5774098ea5fbc3289cdbdfc68bfce885f094d48ad2316c02814a7bc316b1b3857df73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f173a750125c9f13581d14f4f1164614c51b1615cf2491a42cf105faa2622c291e0103d2133d73db81c394dd437ebeace7936f1add156ae0cb2243dc56bbcf55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d186f4b9719484d3bd23fbb2f3b15e420cf1f4a2aee64d20b64ed14bc63eda51e17169bd394d8c5f84f83d74097eaa1e5a839706f45e3c1db456aa3958e46d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39a54d40bad187cbebab5e60567033a494cd89bb10a69ce6def848206df6a1a1aae1100e122ef023cab14caa1c08850681d09c0a1e797b12eb87de2d8eee3920"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 08:14:57.061125) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:14:57.061448) ========= 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.WjeR1l3AMX/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.8rcmXOesva/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.005108 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:14:57.110111) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 08:14:57.110423) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 08:14:57.110792) ========= ========= Total master_lag: 0.18 =========