builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0134 starttime: 1488545197.09 results: success (0) buildid: 20170303030202 builduid: d5579e70d8ea435aadfd8b21f4dc581e revision: 9732cd019a8b94c49a275661320c1b742635a3d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:37.086577) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:37.087130) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:37.087540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:37.113560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:37.114004) ========= 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.svQVPCHht4/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.xqmXNFCzbh/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-03 04:46:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 71.3M=0s 2017-03-03 04:46:37 (71.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.315905 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:37.454235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:37.454703) ========= 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.svQVPCHht4/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.xqmXNFCzbh/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.020528 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:37.503530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-03 04:46:37.503891) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9732cd019a8b94c49a275661320c1b742635a3d6 --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 9732cd019a8b94c49a275661320c1b742635a3d6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.svQVPCHht4/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.xqmXNFCzbh/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-03 04:46:37,600 truncating revision to first 12 chars 2017-03-03 04:46:37,600 Setting DEBUG logging. 2017-03-03 04:46:37,600 attempt 1/10 2017-03-03 04:46:37,600 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9732cd019a8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-03 04:46:40,064 unpacking tar archive at: mozilla-central-9732cd019a8b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.714543 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-03 04:46:40.243809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:40.244366) ========= script_repo_revision: 9732cd019a8b94c49a275661320c1b742635a3d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:40.245042) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:40.245326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-03 04:46:40.266163) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 23 mins, 4 secs) (at 2017-03-03 04:46:40.266631) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.svQVPCHht4/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.xqmXNFCzbh/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:46:40 INFO - MultiFileLogger online at 20170303 04:46:40 in /builds/slave/test 04:46:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:46:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:40 INFO - {'append_to_log': False, 04:46:40 INFO - 'base_work_dir': '/builds/slave/test', 04:46:40 INFO - 'blob_upload_branch': 'Firefox', 04:46:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:40 INFO - 'branch': 'Firefox', 04:46:40 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:40 INFO - 'config_files': ('talos/mac_config.py',), 04:46:40 INFO - 'default_actions': ('clobber', 04:46:40 INFO - 'read-buildbot-config', 04:46:40 INFO - 'download-and-extract', 04:46:40 INFO - 'populate-webroot', 04:46:40 INFO - 'create-virtualenv', 04:46:40 INFO - 'install', 04:46:40 INFO - 'run-tests'), 04:46:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:40 INFO - 'download_minidump_stackwalk': True, 04:46:40 INFO - 'download_symbols': 'ondemand', 04:46:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:40 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:40 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:40 INFO - 'gecko_profile': False, 04:46:40 INFO - 'gecko_profile_interval': 0, 04:46:40 INFO - 'installer_path': 'installer.exe', 04:46:40 INFO - 'log_level': 'info', 04:46:40 INFO - 'log_name': 'talos', 04:46:40 INFO - 'log_to_console': True, 04:46:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:46:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:46:40 INFO - 'opt_config_files': (), 04:46:40 INFO - 'pip_index': False, 04:46:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:40 INFO - 'cmd': ('bash', 04:46:40 INFO - '-c', 04:46:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:40 INFO - 'enabled': True, 04:46:40 INFO - 'halt_on_failure': False, 04:46:40 INFO - 'name': 'check_screen_resolution'},), 04:46:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:40 INFO - 'cmd': ('bash', 04:46:40 INFO - '-c', 04:46:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:40 INFO - 'enabled': True, 04:46:40 INFO - 'halt_on_failure': False, 04:46:40 INFO - 'name': 'check_screen_resolution'},), 04:46:40 INFO - 'run_cmd_checks_enabled': True, 04:46:40 INFO - 'suite': 'dromaeojs-e10s', 04:46:40 INFO - 'system_bits': '32', 04:46:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:40 INFO - 'title': 't-yosemite-r7-0134', 04:46:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:40 INFO - 'use_talos_json': True, 04:46:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:40 INFO - 'work_dir': 'build'} 04:46:40 INFO - [mozharness: 2017-03-03 12:46:40.494855Z] Running clobber step. 04:46:40 INFO - Running pre-action listener: _resource_record_pre_action 04:46:40 INFO - Running main action method: clobber 04:46:40 INFO - rmtree: /builds/slave/test/build 04:46:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:42 INFO - Running post-action listener: _resource_record_post_action 04:46:42 INFO - [mozharness: 2017-03-03 12:46:42.382414Z] Finished clobber step (success) 04:46:42 INFO - [mozharness: 2017-03-03 12:46:42.382686Z] Running read-buildbot-config step. 04:46:42 INFO - Running pre-action listener: _resource_record_pre_action 04:46:42 INFO - Running main action method: read_buildbot_config 04:46:42 INFO - Using buildbot properties: 04:46:42 INFO - { 04:46:42 INFO - "project": "", 04:46:42 INFO - "product": "firefox", 04:46:42 INFO - "script_repo_revision": "production", 04:46:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:46:42 INFO - "repository": "", 04:46:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:46:42 INFO - "buildid": "20170303030202", 04:46:42 INFO - "stage_platform": "macosx64", 04:46:42 INFO - "basedir": "/builds/slave/test", 04:46:42 INFO - "nightly_build": "True", 04:46:42 INFO - "buildnumber": 7, 04:46:42 INFO - "slavename": "t-yosemite-r7-0134", 04:46:42 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:46:42 INFO - "platform": "yosemite_r7", 04:46:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:46:42 INFO - "branch": "mozilla-central", 04:46:42 INFO - "repo_path": "mozilla-central", 04:46:42 INFO - "revision": "9732cd019a8b94c49a275661320c1b742635a3d6", 04:46:42 INFO - "pgo_build": "False", 04:46:42 INFO - "builduid": "d5579e70d8ea435aadfd8b21f4dc581e", 04:46:42 INFO - "slavebuilddir": "test" 04:46:42 INFO - } 04:46:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:46:42 INFO - Running post-action listener: _resource_record_post_action 04:46:42 INFO - [mozharness: 2017-03-03 12:46:42.388617Z] Finished read-buildbot-config step (success) 04:46:42 INFO - [mozharness: 2017-03-03 12:46:42.388844Z] Running download-and-extract step. 04:46:42 INFO - Running pre-action listener: _resource_record_pre_action 04:46:42 INFO - Running main action method: download_and_extract 04:46:42 INFO - mkdir: /builds/slave/test/build/tests 04:46:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:42 INFO - trying https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:46:42 INFO - Downloading https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:46:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 04:46:46 INFO - Downloaded 1199 bytes. 04:46:46 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:46:46 INFO - Using the following test package requirements: 04:46:46 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:46:46 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:46:46 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:46:46 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:46:46 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:46:46 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:46:46 INFO - u'jsshell-mac.zip'], 04:46:46 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:46:46 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:46:46 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:46:46 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:46:46 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:46:46 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:46:46 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:46:46 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:46:46 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:46:46 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:46:46 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:46:46 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:46:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:46:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:46:46 INFO - Fetch https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:46:48 INFO - Expected file size: 51772040 04:46:48 INFO - Obtained file size: 51772040 04:47:04 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:47:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:47:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:04 INFO - Fetch https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:47:06 INFO - Expected file size: 51772040 04:47:06 INFO - Obtained file size: 51772040 04:47:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:47:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:47:21 INFO - Fetch https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:47:53 INFO - retry: attempt #1 caught exception: '' 04:47:53 INFO - retry: Failed, sleeping 60 seconds before retrying 04:48:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #2 04:48:53 INFO - Fetch https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:48:54 INFO - Expected file size: 14404424 04:48:54 INFO - Obtained file size: 14404424 04:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:55 INFO - trying https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:48:55 INFO - Downloading https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:48:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 04:49:02 INFO - Downloaded 75100722 bytes. 04:49:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:49:02 INFO - mkdir: /builds/slave/test/properties 04:49:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:49:02 INFO - Writing to file /builds/slave/test/properties/build_url 04:49:02 INFO - Contents: 04:49:02 INFO - build_url:https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:49:03 INFO - Running post-action listener: _resource_record_post_action 04:49:03 INFO - Running post-action listener: set_extra_try_arguments 04:49:03 INFO - [mozharness: 2017-03-03 12:49:03.648259Z] Finished download-and-extract step (success) 04:49:03 INFO - [mozharness: 2017-03-03 12:49:03.648409Z] Running populate-webroot step. 04:49:03 INFO - Running pre-action listener: _resource_record_pre_action 04:49:03 INFO - Running main action method: populate_webroot 04:49:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:49:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:49:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:49:03 INFO - '--tppagecycles', 04:49:03 INFO - '7'], 04:49:03 INFO - 'tests': ['tsvgm']}}, 04:49:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:49:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:49:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:49:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:49:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:49:03 INFO - 'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:49:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:49:03 INFO - 'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['damp', 'tps']}, 04:49:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:49:03 INFO - 'tests': ['damp', 'tps']}, 04:49:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['dromaeo_dom']}, 04:49:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:49:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['basic_compositor_video']}, 04:49:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:49:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['a11yr', 04:49:03 INFO - 'ts_paint', 04:49:03 INFO - 'tpaint', 04:49:03 INFO - 'sessionrestore', 04:49:03 INFO - 'sessionrestore_no_auto_restore', 04:49:03 INFO - 'tabpaint']}, 04:49:03 INFO - 'other-e10s': {'tests': ['a11yr', 04:49:03 INFO - 'ts_paint', 04:49:03 INFO - 'tpaint', 04:49:03 INFO - 'sessionrestore', 04:49:03 INFO - 'sessionrestore_no_auto_restore', 04:49:03 INFO - 'tabpaint']}, 04:49:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:49:03 INFO - 'ts_paint', 04:49:03 INFO - 'tpaint', 04:49:03 INFO - 'sessionrestore', 04:49:03 INFO - 'sessionrestore_no_auto_restore', 04:49:03 INFO - 'tabpaint']}, 04:49:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:49:03 INFO - 'ts_paint', 04:49:03 INFO - 'tpaint', 04:49:03 INFO - 'sessionrestore', 04:49:03 INFO - 'sessionrestore_no_auto_restore', 04:49:03 INFO - 'tabpaint']}, 04:49:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['a11yr', 04:49:03 INFO - 'ts_paint', 04:49:03 INFO - 'tpaint', 04:49:03 INFO - 'sessionrestore', 04:49:03 INFO - 'sessionrestore_no_auto_restore', 04:49:03 INFO - 'tabpaint']}, 04:49:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['a11yr', 04:49:03 INFO - 'ts_paint', 04:49:03 INFO - 'tpaint', 04:49:03 INFO - 'sessionrestore', 04:49:03 INFO - 'sessionrestore_no_auto_restore', 04:49:03 INFO - 'tabpaint']}, 04:49:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['tsvgx', 04:49:03 INFO - 'tsvgr_opacity', 04:49:03 INFO - 'tart', 04:49:03 INFO - 'tscrollx', 04:49:03 INFO - 'cart', 04:49:03 INFO - 'tsvg_static']}, 04:49:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:49:03 INFO - 'tsvgr_opacity', 04:49:03 INFO - 'tart', 04:49:03 INFO - 'tscrollx', 04:49:03 INFO - 'cart', 04:49:03 INFO - 'tsvg_static']}, 04:49:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:49:03 INFO - 'talos_options': ['--disable-e10s'], 04:49:03 INFO - 'tests': ['tp5o']}, 04:49:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:49:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:49:03 INFO - 'talos_options': ['--disable-e10s', 04:49:03 INFO - '--xperf_path', 04:49:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:03 INFO - 'tests': ['tp5n']}, 04:49:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:49:03 INFO - 'talos_options': ['--xperf_path', 04:49:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:03 INFO - 'tests': ['tp5n']}}, 04:49:03 INFO - 'talos.zip': {'path': '', 04:49:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:49:03 INFO - Running post-action listener: _resource_record_post_action 04:49:03 INFO - [mozharness: 2017-03-03 12:49:03.666720Z] Finished populate-webroot step (success) 04:49:03 INFO - [mozharness: 2017-03-03 12:49:03.666849Z] Running create-virtualenv step. 04:49:03 INFO - Running pre-action listener: _resource_record_pre_action 04:49:03 INFO - Running main action method: create_virtualenv 04:49:03 INFO - Creating virtualenv /builds/slave/test/build/venv 04:49:03 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 04:49:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:49:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:49:03 INFO - Using real prefix '/tools/python27' 04:49:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:49:04 INFO - Installing distribute.............................................................................................................................................................................................done. 04:49:07 INFO - Installing pip.................done. 04:49:07 INFO - Return code: 0 04:49:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:49:07 INFO - Reading from file tmpfile_stdout 04:49:07 INFO - Output received: 04:49:07 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) 04:49:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:49:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:07 INFO - 'HOME': '/Users/cltbld', 04:49:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:07 INFO - 'LOGNAME': 'cltbld', 04:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:07 INFO - 'MOZ_NO_REMOTE': '1', 04:49:07 INFO - 'NO_EM_RESTART': '1', 04:49:07 INFO - 'PAGER': '/bin/cat', 04:49:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:07 INFO - 'PWD': '/builds/slave/test', 04:49:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:07 INFO - 'SHELL': '/bin/bash', 04:49:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:07 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:07 INFO - 'USER': 'cltbld', 04:49:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:07 INFO - 'XPC_FLAGS': '0x0', 04:49:07 INFO - 'XPC_SERVICE_NAME': '0', 04:49:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:07 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 04:49:07 INFO - Cleaning up... 04:49:07 INFO - Return code: 0 04:49:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:49:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:07 INFO - 'HOME': '/Users/cltbld', 04:49:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:07 INFO - 'LOGNAME': 'cltbld', 04:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:07 INFO - 'MOZ_NO_REMOTE': '1', 04:49:07 INFO - 'NO_EM_RESTART': '1', 04:49:07 INFO - 'PAGER': '/bin/cat', 04:49:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:07 INFO - 'PWD': '/builds/slave/test', 04:49:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:07 INFO - 'SHELL': '/bin/bash', 04:49:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:07 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:07 INFO - 'USER': 'cltbld', 04:49:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:07 INFO - 'XPC_FLAGS': '0x0', 04:49:07 INFO - 'XPC_SERVICE_NAME': '0', 04:49:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:08 INFO - Downloading/unpacking psutil>=3.1.1 04:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:49:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:12 INFO - Installing collected packages: psutil 04:49:12 INFO - Running setup.py install for psutil 04:49:12 INFO - building 'psutil._psutil_osx' extension 04:49:12 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 04:49:12 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 04:49:12 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 04:49:12 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 04:49:12 INFO - building 'psutil._psutil_posix' extension 04:49:12 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 04:49:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:49:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:49:12 INFO - ^ 04:49:12 INFO - 1 warning generated. 04:49:12 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 04:49:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:12 INFO - Successfully installed psutil 04:49:12 INFO - Cleaning up... 04:49:13 INFO - Return code: 0 04:49:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:13 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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:13 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 04:49:13 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 04:49:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:13 INFO - 'HOME': '/Users/cltbld', 04:49:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:13 INFO - 'LOGNAME': 'cltbld', 04:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:13 INFO - 'MOZ_NO_REMOTE': '1', 04:49:13 INFO - 'NO_EM_RESTART': '1', 04:49:13 INFO - 'PAGER': '/bin/cat', 04:49:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:13 INFO - 'PWD': '/builds/slave/test', 04:49:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:13 INFO - 'SHELL': '/bin/bash', 04:49:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:13 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:13 INFO - 'USER': 'cltbld', 04:49:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:13 INFO - 'XPC_FLAGS': '0x0', 04:49:13 INFO - 'XPC_SERVICE_NAME': '0', 04:49:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:49:13 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 04:49:13 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 04:49:13 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 04:49:13 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 04:49:13 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 04:49:13 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 04:49:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:49:16 INFO - Installing collected packages: mozsystemmonitor 04:49:16 INFO - Running setup.py install for mozsystemmonitor 04:49:16 INFO - Successfully installed mozsystemmonitor 04:49:16 INFO - Cleaning up... 04:49:16 INFO - Return code: 0 04:49:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:49:16 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')]} 04:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:16 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')]} 04:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:16 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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:16 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 04:49:16 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 04:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:16 INFO - 'HOME': '/Users/cltbld', 04:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:16 INFO - 'LOGNAME': 'cltbld', 04:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:16 INFO - 'MOZ_NO_REMOTE': '1', 04:49:16 INFO - 'NO_EM_RESTART': '1', 04:49:16 INFO - 'PAGER': '/bin/cat', 04:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:16 INFO - 'PWD': '/builds/slave/test', 04:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:16 INFO - 'SHELL': '/bin/bash', 04:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:16 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:16 INFO - 'USER': 'cltbld', 04:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:16 INFO - 'XPC_FLAGS': '0x0', 04:49:16 INFO - 'XPC_SERVICE_NAME': '0', 04:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:16 INFO - Downloading/unpacking jsonschema==2.5.1 04:49:16 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 04:49:16 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 04:49:16 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 04:49:16 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 04:49:16 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 04:49:16 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 04:49:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:49:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49:19 INFO - Downloading functools32-3.2.3-2.tar.gz 04:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:49:19 INFO - warning: no files found matching '*.txt' 04:49:19 INFO - no previously-included directories found matching 'build' 04:49:19 INFO - no previously-included directories found matching 'dist' 04:49:19 INFO - no previously-included directories found matching '.git*' 04:49:19 INFO - Installing collected packages: jsonschema, functools32 04:49:20 INFO - Running setup.py install for functools32 04:49:20 INFO - warning: no files found matching '*.txt' 04:49:20 INFO - no previously-included directories found matching 'build' 04:49:20 INFO - no previously-included directories found matching 'dist' 04:49:20 INFO - no previously-included directories found matching '.git*' 04:49:20 INFO - Successfully installed jsonschema functools32 04:49:20 INFO - Cleaning up... 04:49:20 INFO - Return code: 0 04:49:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:20 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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:20 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 04:49:20 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 04:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:20 INFO - 'HOME': '/Users/cltbld', 04:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:20 INFO - 'LOGNAME': 'cltbld', 04:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:20 INFO - 'MOZ_NO_REMOTE': '1', 04:49:20 INFO - 'NO_EM_RESTART': '1', 04:49:20 INFO - 'PAGER': '/bin/cat', 04:49:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:20 INFO - 'PWD': '/builds/slave/test', 04:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:20 INFO - 'SHELL': '/bin/bash', 04:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:20 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:20 INFO - 'USER': 'cltbld', 04:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:20 INFO - 'XPC_FLAGS': '0x0', 04:49:20 INFO - 'XPC_SERVICE_NAME': '0', 04:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:49:20 INFO - Cleaning up... 04:49:20 INFO - Return code: 0 04:49:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:20 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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:20 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 04:49:20 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 04:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:20 INFO - 'HOME': '/Users/cltbld', 04:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:20 INFO - 'LOGNAME': 'cltbld', 04:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:20 INFO - 'MOZ_NO_REMOTE': '1', 04:49:20 INFO - 'NO_EM_RESTART': '1', 04:49:20 INFO - 'PAGER': '/bin/cat', 04:49:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:20 INFO - 'PWD': '/builds/slave/test', 04:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:20 INFO - 'SHELL': '/bin/bash', 04:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:20 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:20 INFO - 'USER': 'cltbld', 04:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:20 INFO - 'XPC_FLAGS': '0x0', 04:49:20 INFO - 'XPC_SERVICE_NAME': '0', 04:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:49:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:49:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:49:23 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 04:49:23 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 04:49:23 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 04:49:23 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 04:49:23 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 04:49:23 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 04:49:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:49:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49:24 INFO - Downloading docopt-0.6.1.tar.gz 04:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:49:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:49:24 INFO - Running setup.py install for blobuploader 04:49:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:49:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:49:24 INFO - Running setup.py install for requests 04:49:25 INFO - Running setup.py install for docopt 04:49:25 INFO - Successfully installed blobuploader requests docopt 04:49:25 INFO - Cleaning up... 04:49:25 INFO - Return code: 0 04:49:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:25 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')]} 04:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:25 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')]} 04:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:25 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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:25 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 04:49:25 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 04:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:25 INFO - 'HOME': '/Users/cltbld', 04:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:25 INFO - 'LOGNAME': 'cltbld', 04:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:25 INFO - 'MOZ_NO_REMOTE': '1', 04:49:25 INFO - 'NO_EM_RESTART': '1', 04:49:25 INFO - 'PAGER': '/bin/cat', 04:49:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:25 INFO - 'PWD': '/builds/slave/test', 04:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:25 INFO - 'SHELL': '/bin/bash', 04:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:25 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:25 INFO - 'USER': 'cltbld', 04:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:25 INFO - 'XPC_FLAGS': '0x0', 04:49:25 INFO - 'XPC_SERVICE_NAME': '0', 04:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:25 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ivQLZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:25 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-UMQ4go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-uQ3rta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-8fK5Dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-dv0wnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-BxZBoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-7MZtMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-9KcGSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-LGjeqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-u69LkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:26 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-x0peRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:27 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-qLWffM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:27 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Bo99X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:27 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-qzMg2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:27 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-86odak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:27 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-wosh9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:27 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-cAj0yM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:49:27 INFO - Running setup.py install for manifestparser 04:49:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:49:27 INFO - Running setup.py install for mozcrash 04:49:27 INFO - Running setup.py install for mozdebug 04:49:28 INFO - Running setup.py install for mozdevice 04:49:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:49:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:49:28 INFO - Running setup.py install for mozfile 04:49:28 INFO - Running setup.py install for mozhttpd 04:49:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:49:28 INFO - Running setup.py install for mozinfo 04:49:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:49:28 INFO - Running setup.py install for mozInstall 04:49:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:49:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:49:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:49:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:49:28 INFO - Running setup.py install for mozleak 04:49:28 INFO - Running setup.py install for mozlog 04:49:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:49:29 INFO - Running setup.py install for moznetwork 04:49:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:49:29 INFO - Running setup.py install for mozprocess 04:49:29 INFO - Running setup.py install for mozprofile 04:49:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:49:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:49:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:49:29 INFO - Running setup.py install for mozrunner 04:49:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:49:29 INFO - Running setup.py install for mozscreenshot 04:49:29 INFO - Running setup.py install for moztest 04:49:30 INFO - Running setup.py install for mozversion 04:49:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:49:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:49:30 INFO - Cleaning up... 04:49:30 INFO - Return code: 0 04:49:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:30 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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:30 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 04:49:30 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 04:49:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:30 INFO - 'HOME': '/Users/cltbld', 04:49:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:30 INFO - 'LOGNAME': 'cltbld', 04:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:30 INFO - 'MOZ_NO_REMOTE': '1', 04:49:30 INFO - 'NO_EM_RESTART': '1', 04:49:30 INFO - 'PAGER': '/bin/cat', 04:49:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:30 INFO - 'PWD': '/builds/slave/test', 04:49:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:30 INFO - 'SHELL': '/bin/bash', 04:49:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:30 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:30 INFO - 'USER': 'cltbld', 04:49:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:30 INFO - 'XPC_FLAGS': '0x0', 04:49:30 INFO - 'XPC_SERVICE_NAME': '0', 04:49:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:30 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-VwkQoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:30 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)) 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:30 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-v89SlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:30 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)) 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:30 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-NwzrtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:30 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)) 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:30 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-sjQx5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:30 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)) 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:30 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-1AKKXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:31 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)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-IaWvOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:31 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)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-yJTtSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:31 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)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Yp1NoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:31 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)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-RGT6aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:31 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)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-22KHup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:31 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)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-3lTOXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:31 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)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-NoIHfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-l6vFiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:31 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)) 04:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:31 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-kOK20Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:32 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)) 04:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:32 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Wlfbia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:32 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)) 04:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:32 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-K_aWKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:32 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-7jXesJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:32 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)) 04:49:32 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)) 04:49:32 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)) 04:49:32 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)) 04:49:32 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)) 04:49:32 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)) 04:49:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49:34 INFO - Downloading blessings-1.6.tar.gz 04:49:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:49:35 INFO - Installing collected packages: blessings 04:49:35 INFO - Running setup.py install for blessings 04:49:35 INFO - Successfully installed blessings 04:49:35 INFO - Cleaning up... 04:49:35 INFO - Return code: 0 04:49:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:49:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:49:35 INFO - Reading from file tmpfile_stdout 04:49:35 INFO - Current package versions: 04:49:35 INFO - blessings == 1.6 04:49:35 INFO - blobuploader == 1.2.4 04:49:35 INFO - docopt == 0.6.1 04:49:35 INFO - functools32 == 3.2.3-2 04:49:35 INFO - jsonschema == 2.5.1 04:49:35 INFO - manifestparser == 1.1 04:49:35 INFO - mozInstall == 1.12 04:49:35 INFO - mozcrash == 1.0 04:49:35 INFO - mozdebug == 0.1 04:49:35 INFO - mozdevice == 0.49 04:49:35 INFO - mozfile == 1.2 04:49:35 INFO - mozhttpd == 0.7 04:49:35 INFO - mozinfo == 0.9 04:49:35 INFO - mozleak == 0.1 04:49:35 INFO - mozlog == 3.4 04:49:35 INFO - moznetwork == 0.27 04:49:35 INFO - mozprocess == 0.25 04:49:35 INFO - mozprofile == 0.28 04:49:35 INFO - mozrunner == 6.13 04:49:35 INFO - mozscreenshot == 0.1 04:49:35 INFO - mozsystemmonitor == 0.3 04:49:35 INFO - moztest == 0.8 04:49:35 INFO - mozversion == 1.4 04:49:35 INFO - psutil == 3.1.1 04:49:35 INFO - requests == 1.2.3 04:49:35 INFO - wsgiref == 0.1.2 04:49:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:35 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')]} 04:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:35 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')]} 04:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:35 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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:35 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 04:49:35 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 04:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:35 INFO - 'HOME': '/Users/cltbld', 04:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:35 INFO - 'LOGNAME': 'cltbld', 04:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:35 INFO - 'MOZ_NO_REMOTE': '1', 04:49:35 INFO - 'NO_EM_RESTART': '1', 04:49:35 INFO - 'PAGER': '/bin/cat', 04:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:35 INFO - 'PWD': '/builds/slave/test', 04:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:35 INFO - 'SHELL': '/bin/bash', 04:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:35 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:35 INFO - 'USER': 'cltbld', 04:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:35 INFO - 'XPC_FLAGS': '0x0', 04:49:35 INFO - 'XPC_SERVICE_NAME': '0', 04:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 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)) 04:49:35 INFO - Cleaning up... 04:49:35 INFO - Return code: 0 04:49:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:49:35 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')]} 04:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:35 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')]} 04:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:35 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 0x108ad4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ad7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8a47a5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, '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.svQVPCHht4/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.xqmXNFCzbh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 04:49:35 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 04:49:35 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 04:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:35 INFO - 'HOME': '/Users/cltbld', 04:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:35 INFO - 'LOGNAME': 'cltbld', 04:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:35 INFO - 'MOZ_NO_REMOTE': '1', 04:49:35 INFO - 'NO_EM_RESTART': '1', 04:49:35 INFO - 'PAGER': '/bin/cat', 04:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:35 INFO - 'PWD': '/builds/slave/test', 04:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:35 INFO - 'SHELL': '/bin/bash', 04:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:35 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:35 INFO - 'USER': 'cltbld', 04:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:35 INFO - 'XPC_FLAGS': '0x0', 04:49:35 INFO - 'XPC_SERVICE_NAME': '0', 04:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:49:36 INFO - Cleaning up... 04:49:36 INFO - Return code: 0 04:49:36 INFO - Running post-action listener: _resource_record_post_action 04:49:36 INFO - Running post-action listener: _start_resource_monitoring 04:49:36 INFO - Starting resource monitoring. 04:49:36 INFO - [mozharness: 2017-03-03 12:49:36.227805Z] Finished create-virtualenv step (success) 04:49:36 INFO - [mozharness: 2017-03-03 12:49:36.228753Z] Running install step. 04:49:36 INFO - Running pre-action listener: _resource_record_pre_action 04:49:36 INFO - Running main action method: install 04:49:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:49:36 INFO - Reading from file tmpfile_stdout 04:49:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:49:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:49:36 INFO - Reading from file tmpfile_stdout 04:49:36 INFO - Output received: 04:49:36 INFO - Usage: mozinstall [options] installer 04:49:36 INFO - Options: 04:49:36 INFO - -h, --help show this help message and exit 04:49:36 INFO - -d DEST, --destination=DEST 04:49:36 INFO - Directory to install application into. [default: 04:49:36 INFO - "/builds/slave/test"] 04:49:36 INFO - --app=APP Application being installed. [default: firefox] 04:49:36 INFO - mkdir: /builds/slave/test/build/application 04:49:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:49:57 INFO - Reading from file tmpfile_stdout 04:49:57 INFO - Output received: 04:49:57 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:49:57 INFO - Running post-action listener: _resource_record_post_action 04:49:57 INFO - [mozharness: 2017-03-03 12:49:57.084318Z] Finished install step (success) 04:49:57 INFO - [mozharness: 2017-03-03 12:49:57.084486Z] Running run-tests step. 04:49:57 INFO - Running pre-action listener: _resource_record_pre_action 04:49:57 INFO - Running main action method: run_tests 04:49:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:57 INFO - 2017-03-03 04:49:57.099 screenresolution[1901:14467] starting screenresolution argv=screenresolution get 04:49:57 INFO - 2017-03-03 04:49:57.102 screenresolution[1901:14467] Display 0: 1600x1200x32@60 04:49:57 INFO - 2017-03-03 04:49:57.108 screenresolution[1902:14469] starting screenresolution argv=screenresolution list 04:49:57 INFO - Available Modes on Display 0 04:49:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:57 INFO - Intel Iris: 04:49:57 INFO - Chipset Model: Intel Iris 04:49:57 INFO - Type: GPU 04:49:57 INFO - Bus: Built-In 04:49:57 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:57 INFO - Vendor: Intel (0x8086) 04:49:57 INFO - Device ID: 0x0a2e 04:49:57 INFO - Revision ID: 0x0009 04:49:57 INFO - Displays: 04:49:57 INFO - Display: 04:49:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:57 INFO - Display Serial Number: 1600x1200 60 04:49:57 INFO - Main Display: Yes 04:49:57 INFO - Mirror: Off 04:49:57 INFO - Online: Yes 04:49:57 INFO - Rotation: Supported 04:49:57 INFO - Return code: 0 04:49:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:49:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:49:57 INFO - Python 2.7.3 04:49:57 INFO - Return code: 0 04:49:57 INFO - grabbing minidump binary from tooltool 04:49:57 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')]} 04:49:57 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 0x1088b2d30>, '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 0x1087d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088a7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:49:57 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 04:49:57 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 04:49:57 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 04:49:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:49:57 INFO - Return code: 0 04:49:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:49:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:49:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:49:57 INFO - ENV: RUST_BACKTRACE is now 1 04:49:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:49:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:49:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:49:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0134 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:49:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.svQVPCHht4/Render', 04:49:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:57 INFO - 'HOME': '/Users/cltbld', 04:49:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:57 INFO - 'LOGNAME': 'cltbld', 04:49:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:49:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:57 INFO - 'MOZ_NO_REMOTE': '1', 04:49:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:49:57 INFO - 'NO_EM_RESTART': '1', 04:49:57 INFO - 'PAGER': '/bin/cat', 04:49:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:57 INFO - 'PWD': '/builds/slave/test', 04:49:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:49:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:57 INFO - 'RUST_BACKTRACE': '1', 04:49:57 INFO - 'SHELL': '/bin/bash', 04:49:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqmXNFCzbh/Listeners', 04:49:57 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 04:49:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:57 INFO - 'USER': 'cltbld', 04:49:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:57 INFO - 'XPC_FLAGS': '0x0', 04:49:57 INFO - 'XPC_SERVICE_NAME': '0', 04:49:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:49:57 INFO - mozversion application_buildid: 20170303030202 04:49:57 INFO - mozversion application_changeset: 9732cd019a8b94c49a275661320c1b742635a3d6 04:49:57 INFO - mozversion application_display_name: Nightly 04:49:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:49:57 INFO - mozversion application_name: Firefox 04:49:57 INFO - mozversion application_remotingname: firefox 04:49:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:49:57 INFO - mozversion application_vendor: Mozilla 04:49:57 INFO - mozversion application_version: 54.0a1 04:49:57 INFO - mozversion platform_buildid: 20170303030202 04:49:57 INFO - mozversion platform_changeset: 9732cd019a8b94c49a275661320c1b742635a3d6 04:49:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:49:57 INFO - mozversion platform_version: 54.0a1 04:49:57 INFO - using testdate: 1488545397 04:49:57 INFO - actual date: 1488545397 04:49:57 INFO - starting webserver on 'localhost:52697' 04:49:57 INFO - SUITE-START | Running 2 tests 04:49:57 INFO - TEST-START | dromaeo_css 04:49:57 INFO - operating with platform_type : mac_ 04:49:57 INFO - Initialising browser for dromaeo_css test... 04:49:57 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmp5CTORt/profile http://localhost:52697/getInfo.html) 04:50:00 INFO - PROCESS | 1912 | 2017-03-03 04:50:00.940 plugin-container[1915:14640] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 04:50:00 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:00 INFO - PROCESS | 1912 | 2017-03-03 04:50:00.941 plugin-container[1915:14640] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 04:50:00 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:00 INFO - PROCESS | 1912 | 2017-03-03 04:50:00.941 plugin-container[1915:14640] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:50:00 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 04:50:00 INFO - PROCESS | 1912 | colorDepth:24 04:50:00 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 04:50:00 INFO - PROCESS | 1912 | __metrics 04:50:01 ERROR - PROCESS | 1912 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 04:50:01 INFO - TEST-INFO | 1912: exit 0 04:50:01 INFO - Browser initialized. 04:50:01 INFO - Running cycle 1/1 for dromaeo_css test... 04:50:01 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmp5CTORt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:04 INFO - PROCESS | 1916 | 2017-03-03 04:50:04.446 plugin-container[1917:14769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 04:50:04 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:04 INFO - PROCESS | 1916 | 2017-03-03 04:50:04.447 plugin-container[1917:14769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 04:50:04 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:04 INFO - PROCESS | 1916 | 2017-03-03 04:50:04.447 plugin-container[1917:14769] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:50:14 INFO - PROCESS | 1916 | RSS: Main: 227598336 04:50:14 INFO - PROCESS | 1916 | 04:53:17 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:52697/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52697/tests/dromaeo/cssquery-ext.html) 04:53:19 INFO - PROCESS | 1916 | RSS: Main: 249696256 04:53:19 INFO - PROCESS | 1916 | 04:55:02 INFO - PROCESS | 1916 | ************************* 04:55:02 INFO - PROCESS | 1916 | A coding exception was thrown and uncaught in a Task. 04:55:02 INFO - PROCESS | 1916 | 04:55:02 ERROR - PROCESS | 1916 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:02 INFO - PROCESS | 1916 | Full stack: 04:55:02 INFO - PROCESS | 1916 | ************************* 04:56:21 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:52697/tests/dromaeo/cssquery-ext.html (next: http://localhost:52697/tests/dromaeo/cssquery-jquery.html) 04:56:22 INFO - PROCESS | 1916 | RSS: Main: 255660032 04:56:22 INFO - PROCESS | 1916 | 04:59:24 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:52697/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52697/tests/dromaeo/cssquery-mootools.html) 04:59:24 INFO - PROCESS | 1916 | RSS: Main: 268632064 04:59:24 INFO - PROCESS | 1916 | 05:02:26 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:52697/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52697/tests/dromaeo/cssquery-prototype.html) 05:02:27 INFO - PROCESS | 1916 | RSS: Main: 261529600 05:02:27 INFO - PROCESS | 1916 | 05:05:30 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:52697/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52697/tests/dromaeo/cssquery-yui.html) 05:05:31 INFO - PROCESS | 1916 | RSS: Main: 271187968 05:05:31 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:52697/tests/dromaeo/cssquery-yui.html (next: http://localhost:52697/tests/dromaeo/cssquery-dojo.html) 05:08:34 INFO - PROCESS | 1916 | RSS: Main: 265003008 05:08:34 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | __start_tp_report 05:08:34 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 05:08:34 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 05:08:34 INFO - PROCESS | 1916 | |i|pagename|runs| 05:08:34 INFO - PROCESS | 1916 | |0;dojo.html;3380.932381352373;3436.209671775492;3447.2243745157357;3447.5690538682666;3464.618891921888;5656.236408084907;5846.093855452406;5864.560157988152;5909.025010873212;5997;4784.90430191396;4894.632902532307;5770.192173095759;5865.973454645431;5883.735231914563;5286.814961476349;5327.254184414166;5387.703676297802;5412.783488660449;5488.945110548903;5339.919901201485;5413.864653383657;5463.180522921573;5481.177823326513;5506.669599824023;5228;5390.757415916293;5432.728363581825;5463.453654634544;5476.4249753775675;4444.422225110715;5275.604329675251;5289.973550132263;5331.147016477384;5422.132458806612;5289.497497737709;5291.3385826771655;5366.060939335601;5373;5422.674639521641;4850.860047888743;5460.426655201187;5977.551683623746;6073.301570319426;6117.694115294262;6213.658248796314;6263.24841019076;6288.654124023177;6320.273168585625;6324;6457.1282876811965;6478.870422591569;6480.6435646039445;6488.643124628144;6492.383223593751;4767.380240568704;6120.856343292863;6148.907766383508;6151.87696246077;6154.415330543681;6281.96859015702;6341.682915854189;6425.839354016187;6520.739170433129;6522.934770652328;5158.484151584887;6235.095911092866;6270.498360131179;6340.524460665469;6359.745610175633;6439.967800160969;6450.290468048511;6452.838679033061;6456.51576131792;6462.5153113515735;4633.49031606523;5513.972430137904;5781.884362312814;6113;6203.379662033851;6374.936250637527;6382.776602818879;6382.840428989312;6388.8083357499345;6395.328490508523;5107.540321371094;5130.8455597490265;5148.871278218075;5161.354830646169;5183.118869792144;4464.441944756905;4477.888052798706;4532.9773351133035;4556.840510582114;4599.149157405891;5998.940010599926;6071.332153463116;6103.6948152592195;6112.572119951561;6140.6929653517145;6374.107624932513;6430.131932189188;6430.421262086435;6439.839004024937;6455.225372955276;5823.505002074787;5992.370801065913;6059.394060593993;6071.453569178795;6089.695515224221;5503.669779813224;5571.554275657938;5584.274044374205;5624.156376543469;5649.689267090267;6609.316927792022;6628.767993120217;6634;6640.236372816995;6659.134312539532;5285.814996475105;6450.387213214737;6452.451541619015;6455.419012288917;6465.3857883501;6257.937420625735;6376.968115159394;6459.095726598186;6486.05952136928;6529;6310.211223597051;6358.77744278948;6368.885445047005;6377.1390862233;6394.392532709385;5007.449180590205;5354.330708661418;6418.422341989243;6448.419642232222;6453.483721302191;6109.8298075392895;6145.863015342176;6180.165677633462;6203;6206.255249370104;6266.717997690206;6278.4035516625845;6283.59156654816;6304.71628776697;6308.968455157695;5988.341282459013;6027.246594175728;6211.068339749074;6218.129461875251;6260.280067792262;6137.7447333336595;6199.659018754011;6242.531810114168;6243.7814676486105;6283.3402492737605;6153.384661533811;6172.537059720628;6180.81457556274;6183.196184495987;6192.102145189013 05:08:34 INFO - PROCESS | 1916 | |1;ext.html;738.7082102569485;784.3685832904512;790.3084800799301;801.6793282686926;940.196132306878;37640.43539346909;38304.233915321674;40003;40072.599274007254;40111.398329025054;38159;38399.42400863989;38459;38462;38501.844944651704;7773.766786996399;7932.722354717586;8983.595738191798;10458.68623941283;10742.30175038624;10552.208584356204;10663.040326370625;10668.519916603771;10668.62659806907;10707.304025238374;10666;10695.572177112907;10697.572097116106;10707.839382409271;10731.463426828666;8918.554072296423;10357.637482688146;10662.040416362526;10695.358278503314;10716.4105974171;8967.237784788302;10466.267361284637;10482.580696772196;10499.475026248734;10500.947495262475;34336.82831585851;34586.308273834635;34757.826210868785;34840.78057268009;34866.65133348685;3670.6696397324367;3671.3208056509366;3675.1730860556427;3713.0902928782352;3850.5379354477363;22174.058185478058;25679.101231456996;25688;26465.073722419624;26588.202353929413;2932.794704370695;3053.40458610571;3076.430860290853;3089.196808829698;3176.1583180457196;325666;326927;328959;334805;352107;31811;34080;41386;43608.90977725519;43675.78162109233;35994;45353.77323113364;45412.545874541494;45492.772536137105;45891.54108458872;10616.575336986589;12616.621501354974;12728.93635531835;12745.745085098246;12755.362231888368;10840.620578279722;10930.836037459445;10935.234533582572;10944.616938407118;10974.506147223394;11780.469878855472;11794.52821887115;11814.93238683227;11818.113641476924;11930.3438310892;5548.500634942875;5576.713547445528;5598.272224610774;5608.298962629671;5641.266635337462;878.3017501086524;912.4707669551644;919.5080631862055;961.8124465729115;968.7529679931572;22994;27183.456330873272;27949.441011180068;28264.858675706902;28322.867085316768;840.306746933782;987.0918754745508;995.158955931521;1017.4658304390107;1024.2113572549094;14216.857831421761;14242;14497;14567.344469498898;14588.270586470633;412191;412205;416248;416301;416814;54776.90446191054;55143.448565514635;55242.44757552454;55254.72372638111;55298.17052744212;1368.5962641020922;1414.7736362182002;1416.8724814766515;1423.5871597236685;1424.3590384326887;24815.13147039845;26281.868590656923;26313.9474421021;26988.920443182054;27297.454050919267;45205.77397112994;45306;48757.5124248753;49809.25286120784;49977.75011124921;473280;473393;474887;475157.6242118767;475889;16934.830651693326;17687.82312176862;19139.330123445612;19708;20389;36961.44557831688;38551.807240963615;38595.614043859205;39073.21853562856;39199.60800392074;31648;32680.67319326776;33498.162545936546;33619.663803361655;34056.65943340535;31629;32154.67845321517;32598.392128630127;33886.83056584701;33945.66054339425;27147;32422.189445264055;32911.67088329086;33388.66611333856;33545.66454335425;21813.345599632037;22740.65889011633;27151.21219695076;27366.726332736416;30056;22957.655635165152;23487.295381138592;23732.169374071826;23818.64272035886;23902.90941976495 05:08:34 INFO - PROCESS | 1916 | |2;jquery.html;395.8001209389258;396.46279291559915;407.11858825642497;415.72146661736633;433.02569219256674;17679;19719;19761.40715778527;20469.403278166916;21115.049822757974;21413.822239776808;21488.462788430297;22402.663960040612;22629.660555091687;22633.886830565825;5694.832559325349;6125.908111378321;6432.42108210261;6498.9350106498805;6548.738050477975;6245;6446.161998940154;6454.806355809334;6457.225132984048;6567.179102612174;5066.41736200338;6465.83835404114;6493.253275873264;6510.08858759774;6518.152640156773;5424.833660762935;5440.727963601804;6296.1500197473215;6339.524535659798;6407.551471397004;6074.726637301332;6093.299270583851;6246.843828904268;6260.436560709512;6268.937310626881;26535.867320663274;26585.867070664717;26665.733342666517;26811.195664130108;26946.05688800902;2901.998810410405;3030.606021217228;3391.8304084795664;3402.863885444604;3426.623071462137;15612.76580851288;15877.285522151531;16230;16325.10211938343;16482.917585411997;1911.665458544749;1960.862739608228;1965.3121407507401;1965.9508512287164;1977.3968939473455;105436.47281763543;109434.90565094327;114349;114872.85127148704;115171;20451;20708.79291207099;20824.58350833005;20832.687509687366;20997.68503472449;33420;33869.66130338714;33924.66075339264;33924.83037584796;33936;10204.744881378025;10236.283460157867;10493.213009024354;10515.316504427188;10618.469076546142;6609.272979972199;8181.731831566117;8469.449485783405;8482.006728418817;8659.134086591417;8449.732540119032;10063;10121.9371965944;10134.54394552247;10323.174146068297;3820.9044773880873;4259.762580438889;4304.7632380218765;4334.718243314174;4336.262835318003;1068.156156636264;1085.316250762015;1097.6871591596357;1102.090775110521;1140.777548378075;20836;21272.78727212739;21371.67942480864;21385.14459421637;21397.893010534848;864.1358641358642;911.5943405184629;931.5156118818312;935.6865450074301;1131.1629394248362;6474.935250647527;6608.041833934052;6655.267920528738;6792.864142717217;6803.149606299213;656045;685533;701738;711258;720033;145658;150760;151717;153292.46707532782;155162.48375162392;913.8081002989313;1049.5014867937668;1126.8647762268417;1126.994365028186;1128.368113856243;17562.560935976704;18544.721829172304;18546.721799173294;19089.618207636046;20312.289069882485;43195;50398.74800625973;55759.72120139373;60156.39843601683;62153.68923155355;60153;81135;86503;87234.9467768625;87386;6401.951375559776;7941.761747147595;10253.282270241012;11371.601993930173;11860.169788114748;12241.20432171906;12525.373731313215;12679.492820287454;12907.225566466042;13110.410031548794;10554.419506926886;10832.458377081271;11032.944835275772;11105.333679979227;11137;10778.02997730208;10956.945215273872;11023.669289921316;11053.668389948314;11368.94315528417;9008.549572521479;11113.277636953571;11147.331160130418;11410.543578256777;11499;11392.601258955896;11467.082633389142;11767.705807354885;11819.881801182222;11861.466234019317;10795.028447439769;10906.945465272624;11165.218434709817;11269.098472122047;11282.097432205568 05:08:34 INFO - PROCESS | 1916 | |3;mootools.html;1496.618362317609;1541.1831729183536;1558.1196623907492;1564.8185619857006;1565.452091767881;9007.189352958232;10371.792564148722;10856.99999999999;11002.889971100287;11042.889571104306;10795.62215322462;10912;10994.835077473845;11004;11037.337759734422;2310.475086442952;2357.917472888449;2358.7287461941924;2363.5745565798147;2382.642603609464;2339.8768591076296;2380.5952987991996;2384.1297926256966;2384.606539920911;2384.642303654457;2313.3406979010983;2315.131825565413;2327.406511339606;2339.9181028664;2341.2156927429246;1854.879432836868;2243.5849367866995;2265.1732117777055;2286.142696488817;2291.633338665814;1954.540682939508;2263.988680056589;2343.425860664131;2347.659589359533;2350.4006478348083;9033.187013168847;10695.893041069567;10795.946020269927;10804.72988175294;10812;5587.972060139714;5748.4538968798315;5805.274340707411;5807.041838096709;5829.154772557998;2622.331305517081;3271.9182020449443;3540.929181416357;3547.6452354764574;3555.1868547041195;1184.8578170619494;1191.910606704492;1767.6553072150937;1793.8923664580168;1866.514706176404;12643.93678031604;12658.430370633343;14701.926490367587;15158.242087895562;15328.693426131415;4579.610733087658;4646.907061858744;4656.95343046572;4759.547842954914;4795.568398844051;11436.599719009795;11619.76760464786;11620.302781832948;11639.534418623329;11786.410679465993;5729.856753581111;5799.710014499258;5800.187973683687;5808.796692115756;5815.79644712433;4383.890402739957;5105.2086926526445;5236.162214045735;5255.343082114736;5279.076161671692;3707.6848467879995;3777.8111094445167;3797.2595343907674;3807.904802379963;3866.6133386661672;1018.3635227982511;1019.9592016319264;1020.1175982774995;1025.4411345816548;1026.9075783179549;856.4433118473042;858.3948316436897;892.4065496444921;922.6770630279483;923.1414784250577;5177.767000484987;6353.491720662336;6353.618782873043;6424.839379015562;6472.543359780612;912.3567884641312;964.4358050540372;996.6412091647002;1005.522376870995;1029.7282855673238;3800.524934383202;4039.8586049488713;4087.173063583264;4231.661467082626;4247.17180149874;13033.348332583333;13430.664233394244;13466.865331346757;13497.865021349857;13509;10193.082622564005;12271.631851044483;13411.12827666218;13456.596302110953;13464.990125740413;8661.480311181349;8962.82074358509;9712.70861874145;10051.59793608262;10115.14021308197;6593.835154120993;6617.66911654425;6845.691943862438;6862.210845752802;6866.793996180123;11821.527138914347;11972.521099155936;12079.214851034654;12090.15368924202;12131.696707582381;12295.815562766744;12405.875941240833;12432.937835310766;12585.993120550516;12642.55751048709;8151.836963260583;8341.082480926972;8418.873716894374;8438.113998030363;8444.535550544779;9566.234701224024;9888.060634239735;9964.850527242243;9983.450910200008;10143.391396516232;8211.260986511004;8840.115988401109;8921.06328835489;9108.407953483002;9163.037881022397;8648.78378040554;8729.214370706666;8777.868331975153;8858.64565417376;8922.420042697204;7543.811404714926;7655.69377224927;7744.806379840549;7746.961265193638;7772.417068719756;5595.580331475075;5679.943200567942;5697.515711164598;5772.94227057724;5839.124131380327;5807.216025836458;5902.586818922634;5948.613340132878;5949.25634295713;6022.969885150546 05:08:34 INFO - PROCESS | 1916 | |4;prototype.html;303.57651076747936;330.6610724007892;373.1771443966055;378.46258313195267;386.86266375436736;4673.626109911207;4836.379812762724;4946.604271658268;4978.67638603491;5255.948810237954;4404.96483326418;4962.602991760659;5266.499682530163;5458;5522.834314970557;4038.1519880825063;4041.1513582147786;4346.630536404402;4494.505604383531;4510.842120525782;4039.53545342285;4448.911021779562;4503.189425903336;4549.976255342538;4556.54434556545;4538.273876179812;4549.886252843688;4557.361969324297;4564.292534657134;4574.61115805157;4200.6219440250525;4431.313146462304;4494.910101797946;4508.301213311942;4575;3767.3972164453558;3953.802309884523;4136.91726165475;4153.335466325373;4174.144300418407;5047.637137972763;5317.73411329432;5321.547668448186;5370.8925821483745;5377.247185394048;4548.636109111296;4991.351124353847;5281.36623605166;5298.814541491068;5338.839834804962;5842.853928651818;5865.736041878083;5882.794102206445;5899.584099816057;5944.138099975479;4093.6111069448357;4094.324436467979;4524.733074739078;4617.607503362218;4656;5557.777688892439;5784.471472401873;5949.345571987078;5985.940140598582;6073.423024812636;4533.886652833639;4645;4773.92586667997;4830.720063916894;4848.927266091011;4737.076270127292;4883.243097319921;4919.926201106986;4928.704277743347;4933.692571468564;4664.440267167894;5077.39071311445;5098.847034588968;5219.973900130475;5266.0257852297445;4902.338184345139;5102.897942041139;5310.9734451327495;5858.882822343529;5881.617694849821;1308.8233088533116;1503.646643038885;1564.0381165583085;1589.6343840916422;1592.562045437514;919.7010971434311;1075.7256899490737;1180.8523934508187;1189.1389974689582;1218.3299185447968;4502.482214545369;5107.565856902205;5150.330457040561;5225.503577160163;5260.238374853233;5052.671576347526;5852.326982397079;5862.515175890448;5865.970670146622;5894.587378883522;4658.743769092733;4768.427788665383;5017.949820501822;5107.642465027412;5444.782208711607;2827.3921106962102;2991.0707593602006;3001.834899080528;3010.4882170031146;3045.9847700761798;5103.979204159109;5259.552938000313;5380.91928621071;5661.433856614388;5780.42195780427;5449.04641687711;5588.580856435784;5722.7424765885635;6103.248873930084;6162.476189523941;5239.633225674166;5354.732263386815;5967.522598192134;6268.4671802896355;6332.651704156316;5710.8001219957105;5986.281646202484;6005.219321488178;6026.608270462406;6076.453119219292;4769.403824521934;5593.300837395325;5762.6830524319885;5976.701164941823;6009.429104235091;5898.498627616529;5978.043513037893;5996.580239383201;6055.515558755201;6264.404881536247;3848.9615103848605;4011.5988401159652;4407.369273368874;4738.175802316202;4788.3056956741775;5478.849441617305;5536.307961504812;5602.075657516464;5767.740451679769;6037.335893051673;5833.67914764692;5847.707614619167;5856.209411729362;5864.501517371071;5961.165436838933;5440.238366628596;5824.417558244142;5849.415058494116;5879.353780941343;5882.49998750111;4633.4208223972;4898.853034408831;5216.713080780593;5302.019126461617;5358.151437753969;5129.615278854027;5798.797042103506;5817.301923769006;5829.387914268941;5839.299284085937;5083.923741143812;5384.300040994645;5727.253187777767;5760.366359700346;5791.449812267828 05:08:34 INFO - PROCESS | 1916 | |5;yui.html;704.7568588836849;733.4645708632695;763.4045444553246;812.4921923797626;826.731312323495;14629.122252664834;15394.538163855103;15572.987755795879;15581.376744930189;15617.453389131382;14133;14736.115833050024;14784.482543110993;14789;14789.482368117118;925.1950802801572;943.1982814607597;943.273679266964;947;948.5636607160715;909.56340956341;936.6955739384694;946.711253067814;947.5262368815593;953.4327075390132;995.7311525888006;997.1225321716879;998.6005597760881;999.8550210219514;1002.1882275356984;976;988.9703308900745;993.5330394714473;993.6522217223986;995.069609914732;731.5500966905315;743.5575832379725;744.1665334824977;744.274332525783;745.5750222373249;15489.92255038729;15860.603484912854;15921.363145474283;16094.436694715745;16178.676426471522;12463.801344770736;13559.728805423836;13690.726185476335;13795;13841.792373114411;11377.94311028448;11573.884261157365;11578.652640420802;11632.243904146204;11916.404179791063;3403.5745531808525;3691.907702307437;3700.9444858327147;3709.536307961505;3830.7893065881367;107723;107799.38300925492;107918;110337;111415;10549.103326217204;10556.736081597868;10658.840117398246;10732.51703673321;11122.110231181487;22255.846354361744;22395.776042239693;22436.775632243796;22463.663045054338;22505.32484025482;7437.516561423598;7455.478116531791;7535.849283014419;7693.115291741409;7774.028246469192;3998.0555362652776;4146.108586653884;4195.811188496525;4246.44796176495;4298.570142985676;3184.9363012739614;3250.7561932854655;3251.7398608111293;3268.542404063433;3316.369889720945;595.3451203675921;596.0612036043249;599.78107990584;614.5421660862596;618.8267285160163;522.5923779452033;584.4447774614133;623.6788054152115;655.5574986883929;663.3399767231635;8973.596188171548;9734.707958761248;9740.805183896424;9774.413535187912;9820.165285950774;638.7508871540103;639.3861892583079;667.2326824152263;689.4002218070211;691.2534462780185;4499.257622492252;4631.513691062457;4641.74470404131;4667.276572131325;4734.905301894012;106218.9378106209;108312.37531437035;108891.91108088977;109162;110827.7006759194;88622.55688721515;90311;91243.54378228067;92946;93407;57535.13697294544;57893.13160302599;70345.24136896516;78574.21425785669;79846.2015379862;8978.820423591362;8979.595918183828;9015.774605634911;9132.406393584395;9164.358494905293;33419;38111;51310.23034654558;52174;52889;61185;64885.67557162184;65222;65411.36471588248;66105.67788644104;10368.901229670826;12468;12561.246325220516;12752.171108877894;13169.364264912627;12975.897048750585;13402.128861623964;14105.083169593943;14498.710025799213;14640.414383424544;13075.803862942254;13320.533781317607;13438.731225375634;13699.657508562366;13786.897048236318;11495.633187772859;13124.343782811013;13210.735785284432;13387.330633468482;13417.731645367234;12916.547920823103;13195.142315749446;13249.271290079138;13468.326583670972;13740.450381984609;8955.686550970946;10637.9468102659;10792.190585705946;10953.068989136013;11486.310821350746;10188.949055254972;10673.626423075155;10682.465876706288;10693.465326733476;10722.410267435365 05:08:34 INFO - PROCESS | 1916 | __end_tp_report 05:08:34 INFO - PROCESS | 1916 | __start_cc_report 05:08:34 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,435 05:08:34 INFO - PROCESS | 1916 | __end_cc_report 05:08:34 INFO - PROCESS | 1916 | __startTimestamp1488546514630__endTimestamp 05:08:34 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | ------- Summary: start ------- 05:08:34 INFO - PROCESS | 1916 | Number of tests: 6 05:08:34 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:5851.69 Median:6137.74 stddev:694.86 (11.3%) stddev-sans-first:669.41 05:08:34 INFO - PROCESS | 1916 | Values: 3380.9 3436.2 3447.2 3447.6 3464.6 5656.2 5846.1 5864.6 5909.0 5997.0 4784.9 4894.6 5770.2 5866.0 5883.7 5286.8 5327.3 5387.7 5412.8 5488.9 5339.9 5413.9 5463.2 5481.2 5506.7 5228.0 5390.8 5432.7 5463.5 5476.4 4444.4 5275.6 5290.0 5331.1 5422.1 5289.5 5291.3 5366.1 5373.0 5422.7 4850.9 5460.4 5977.6 6073.3 6117.7 6213.7 6263.2 6288.7 6320.3 6324.0 6457.1 6478.9 6480.6 6488.6 6492.4 4767.4 6120.9 6148.9 6151.9 6154.4 6282.0 6341.7 6425.8 6520.7 6522.9 5158.5 6235.1 6270.5 6340.5 6359.7 6440.0 6450.3 6452.8 6456.5 6462.5 4633.5 5514.0 5781.9 6113.0 6203.4 6374.9 6382.8 6382.8 6388.8 6395.3 5107.5 5130.8 5148.9 5161.4 5183.1 4464.4 4477.9 4533.0 4556.8 4599.1 5998.9 6071.3 6103.7 6112.6 6140.7 6374.1 6430.1 6430.4 6439.8 6455.2 5823.5 5992.4 6059.4 6071.5 6089.7 5503.7 5571.6 5584.3 5624.2 5649.7 6609.3 6628.8 6634.0 6640.2 6659.1 5285.8 6450.4 6452.5 6455.4 6465.4 6257.9 6377.0 6459.1 6486.1 6529.0 6310.2 6358.8 6368.9 6377.1 6394.4 5007.4 5354.3 6418.4 6448.4 6453.5 6109.8 6145.9 6180.2 6203.0 6206.3 6266.7 6278.4 6283.6 6304.7 6309.0 5988.3 6027.2 6211.1 6218.1 6260.3 6137.7 6199.7 6242.5 6243.8 6283.3 6153.4 6172.5 6180.8 6183.2 6192.1 05:08:34 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:53355.34 Median:179803.84 stddev:109429.31 (60.9%) stddev-sans-first:109664.98 05:08:34 INFO - PROCESS | 1916 | Values: 738.7 784.4 790.3 801.7 940.2 37640.4 38304.2 40003.0 40072.6 40111.4 38159.0 38399.4 38459.0 38462.0 38501.8 7773.8 7932.7 8983.6 10458.7 10742.3 10552.2 10663.0 10668.5 10668.6 10707.3 10666.0 10695.6 10697.6 10707.8 10731.5 8918.6 10357.6 10662.0 10695.4 10716.4 8967.2 10466.3 10482.6 10499.5 10500.9 34336.8 34586.3 34757.8 34840.8 34866.7 3670.7 3671.3 3675.2 3713.1 3850.5 22174.1 25679.1 25688.0 26465.1 26588.2 2932.8 3053.4 3076.4 3089.2 3176.2 325666.0 326927.0 328959.0 334805.0 352107.0 31811.0 34080.0 41386.0 43608.9 43675.8 35994.0 45353.8 45412.5 45492.8 45891.5 10616.6 12616.6 12728.9 12745.7 12755.4 10840.6 10930.8 10935.2 10944.6 10974.5 11780.5 11794.5 11814.9 11818.1 11930.3 5548.5 5576.7 5598.3 5608.3 5641.3 878.3 912.5 919.5 961.8 968.8 22994.0 27183.5 27949.4 28264.9 28322.9 840.3 987.1 995.2 1017.5 1024.2 14216.9 14242.0 14497.0 14567.3 14588.3 412191.0 412205.0 416248.0 416301.0 416814.0 54776.9 55143.4 55242.4 55254.7 55298.2 1368.6 1414.8 1416.9 1423.6 1424.4 24815.1 26281.9 26313.9 26988.9 27297.5 45205.8 45306.0 48757.5 49809.3 49977.8 473280.0 473393.0 474887.0 475157.6 475889.0 16934.8 17687.8 19139.3 19708.0 20389.0 36961.4 38551.8 38595.6 39073.2 39199.6 31648.0 32680.7 33498.2 33619.7 34056.7 31629.0 32154.7 32598.4 33886.8 33945.7 27147.0 32422.2 32911.7 33388.7 33545.7 21813.3 22740.7 27151.2 27366.7 30056.0 22957.7 23487.3 23732.2 23818.6 23902.9 05:08:34 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:39695.00 Median:21405.86 stddev:115474.89 (539.5%) stddev-sans-first:115761.12 05:08:34 INFO - PROCESS | 1916 | Values: 395.8 396.5 407.1 415.7 433.0 17679.0 19719.0 19761.4 20469.4 21115.0 21413.8 21488.5 22402.7 22629.7 22633.9 5694.8 6125.9 6432.4 6498.9 6548.7 6245.0 6446.2 6454.8 6457.2 6567.2 5066.4 6465.8 6493.3 6510.1 6518.2 5424.8 5440.7 6296.2 6339.5 6407.6 6074.7 6093.3 6246.8 6260.4 6268.9 26535.9 26585.9 26665.7 26811.2 26946.1 2902.0 3030.6 3391.8 3402.9 3426.6 15612.8 15877.3 16230.0 16325.1 16482.9 1911.7 1960.9 1965.3 1966.0 1977.4 105436.5 109434.9 114349.0 114872.9 115171.0 20451.0 20708.8 20824.6 20832.7 20997.7 33420.0 33869.7 33924.7 33924.8 33936.0 10204.7 10236.3 10493.2 10515.3 10618.5 6609.3 8181.7 8469.4 8482.0 8659.1 8449.7 10063.0 10121.9 10134.5 10323.2 3820.9 4259.8 4304.8 4334.7 4336.3 1068.2 1085.3 1097.7 1102.1 1140.8 20836.0 21272.8 21371.7 21385.1 21397.9 864.1 911.6 931.5 935.7 1131.2 6474.9 6608.0 6655.3 6792.9 6803.1 656045.0 685533.0 701738.0 711258.0 720033.0 145658.0 150760.0 151717.0 153292.5 155162.5 913.8 1049.5 1126.9 1127.0 1128.4 17562.6 18544.7 18546.7 19089.6 20312.3 43195.0 50398.7 55759.7 60156.4 62153.7 60153.0 81135.0 86503.0 87234.9 87386.0 6402.0 7941.8 10253.3 11371.6 11860.2 12241.2 12525.4 12679.5 12907.2 13110.4 10554.4 10832.5 11032.9 11105.3 11137.0 10778.0 10956.9 11023.7 11053.7 11368.9 9008.5 11113.3 11147.3 11410.5 11499.0 11392.6 11467.1 11767.7 11819.9 11861.5 10795.0 10906.9 11165.2 11269.1 11282.1 05:08:34 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:6544.72 Median:3544.29 stddev:4048.53 (114.2%) stddev-sans-first:4042.11 05:08:34 INFO - PROCESS | 1916 | Values: 1496.6 1541.2 1558.1 1564.8 1565.5 9007.2 10371.8 10857.0 11002.9 11042.9 10795.6 10912.0 10994.8 11004.0 11037.3 2310.5 2357.9 2358.7 2363.6 2382.6 2339.9 2380.6 2384.1 2384.6 2384.6 2313.3 2315.1 2327.4 2339.9 2341.2 1854.9 2243.6 2265.2 2286.1 2291.6 1954.5 2264.0 2343.4 2347.7 2350.4 9033.2 10695.9 10795.9 10804.7 10812.0 5588.0 5748.5 5805.3 5807.0 5829.2 2622.3 3271.9 3540.9 3547.6 3555.2 1184.9 1191.9 1767.7 1793.9 1866.5 12643.9 12658.4 14701.9 15158.2 15328.7 4579.6 4646.9 4657.0 4759.5 4795.6 11436.6 11619.8 11620.3 11639.5 11786.4 5729.9 5799.7 5800.2 5808.8 5815.8 4383.9 5105.2 5236.2 5255.3 5279.1 3707.7 3777.8 3797.3 3807.9 3866.6 1018.4 1020.0 1020.1 1025.4 1026.9 856.4 858.4 892.4 922.7 923.1 5177.8 6353.5 6353.6 6424.8 6472.5 912.4 964.4 996.6 1005.5 1029.7 3800.5 4039.9 4087.2 4231.7 4247.2 13033.3 13430.7 13466.9 13497.9 13509.0 10193.1 12271.6 13411.1 13456.6 13465.0 8661.5 8962.8 9712.7 10051.6 10115.1 6593.8 6617.7 6845.7 6862.2 6866.8 11821.5 11972.5 12079.2 12090.2 12131.7 12295.8 12405.9 12432.9 12586.0 12642.6 8151.8 8341.1 8418.9 8438.1 8444.5 9566.2 9888.1 9964.9 9983.5 10143.4 8211.3 8840.1 8921.1 9108.4 9163.0 8648.8 8729.2 8777.9 8858.6 8922.4 7543.8 7655.7 7744.8 7747.0 7772.4 5595.6 5679.9 5697.5 5772.9 5839.1 5807.2 5902.6 5948.6 5949.3 6023.0 05:08:34 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4800.98 Median:5139.97 stddev:1353.34 (26.3%) stddev-sans-first:1314.37 05:08:34 INFO - PROCESS | 1916 | Values: 303.6 330.7 373.2 378.5 386.9 4673.6 4836.4 4946.6 4978.7 5255.9 4405.0 4962.6 5266.5 5458.0 5522.8 4038.2 4041.2 4346.6 4494.5 4510.8 4039.5 4448.9 4503.2 4550.0 4556.5 4538.3 4549.9 4557.4 4564.3 4574.6 4200.6 4431.3 4494.9 4508.3 4575.0 3767.4 3953.8 4136.9 4153.3 4174.1 5047.6 5317.7 5321.5 5370.9 5377.2 4548.6 4991.4 5281.4 5298.8 5338.8 5842.9 5865.7 5882.8 5899.6 5944.1 4093.6 4094.3 4524.7 4617.6 4656.0 5557.8 5784.5 5949.3 5985.9 6073.4 4533.9 4645.0 4773.9 4830.7 4848.9 4737.1 4883.2 4919.9 4928.7 4933.7 4664.4 5077.4 5098.8 5220.0 5266.0 4902.3 5102.9 5311.0 5858.9 5881.6 1308.8 1503.6 1564.0 1589.6 1592.6 919.7 1075.7 1180.9 1189.1 1218.3 4502.5 5107.6 5150.3 5225.5 5260.2 5052.7 5852.3 5862.5 5866.0 5894.6 4658.7 4768.4 5017.9 5107.6 5444.8 2827.4 2991.1 3001.8 3010.5 3046.0 5104.0 5259.6 5380.9 5661.4 5780.4 5449.0 5588.6 5722.7 6103.2 6162.5 5239.6 5354.7 5967.5 6268.5 6332.7 5710.8 5986.3 6005.2 6026.6 6076.5 4769.4 5593.3 5762.7 5976.7 6009.4 5898.5 5978.0 5996.6 6055.5 6264.4 3849.0 4011.6 4407.4 4738.2 4788.3 5478.8 5536.3 5602.1 5767.7 6037.3 5833.7 5847.7 5856.2 5864.5 5961.2 5440.2 5824.4 5849.4 5879.4 5882.5 4633.4 4898.9 5216.7 5302.0 5358.2 5129.6 5798.8 5817.3 5829.4 5839.3 5083.9 5384.3 5727.3 5760.4 5791.4 05:08:34 INFO - PROCESS | 1916 | 05:08:34 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:20234.16 Median:20970.89 stddev:29657.89 (141.4%) stddev-sans-first:29704.84 05:08:34 INFO - PROCESS | 1916 | Values: 704.8 733.5 763.4 812.5 826.7 14629.1 15394.5 15573.0 15581.4 15617.5 14133.0 14736.1 14784.5 14789.0 14789.5 925.2 943.2 943.3 947.0 948.6 909.6 936.7 946.7 947.5 953.4 995.7 997.1 998.6 999.9 1002.2 976.0 989.0 993.5 993.7 995.1 731.6 743.6 744.2 744.3 745.6 15489.9 15860.6 15921.4 16094.4 16178.7 12463.8 13559.7 13690.7 13795.0 13841.8 11377.9 11573.9 11578.7 11632.2 11916.4 3403.6 3691.9 3700.9 3709.5 3830.8 107723.0 107799.4 107918.0 110337.0 111415.0 10549.1 10556.7 10658.8 10732.5 11122.1 22255.8 22395.8 22436.8 22463.7 22505.3 7437.5 7455.5 7535.8 7693.1 7774.0 3998.1 4146.1 4195.8 4246.4 4298.6 3184.9 3250.8 3251.7 3268.5 3316.4 595.3 596.1 599.8 614.5 618.8 522.6 584.4 623.7 655.6 663.3 8973.6 9734.7 9740.8 9774.4 9820.2 638.8 639.4 667.2 689.4 691.3 4499.3 4631.5 4641.7 4667.3 4734.9 106218.9 108312.4 108891.9 109162.0 110827.7 88622.6 90311.0 91243.5 92946.0 93407.0 57535.1 57893.1 70345.2 78574.2 79846.2 8978.8 8979.6 9015.8 9132.4 9164.4 33419.0 38111.0 51310.2 52174.0 52889.0 61185.0 64885.7 65222.0 65411.4 66105.7 10368.9 12468.0 12561.2 12752.2 13169.4 12975.9 13402.1 14105.1 14498.7 14640.4 13075.8 13320.5 13438.7 13699.7 13786.9 11495.6 13124.3 13210.7 13387.3 13417.7 12916.5 13195.1 13249.3 13468.3 13740.5 8955.7 10637.9 10792.2 10953.1 11486.3 10188.9 10673.6 10682.5 10693.5 10722.4 05:08:34 INFO - PROCESS | 1916 | -------- Summary: end -------- 05:08:34 INFO - PROCESS | 1916 | 05:08:35 INFO - TEST-INFO | 1916: exit 0 05:08:35 INFO - TEST-OK | dromaeo_css | took 1117602ms 05:08:35 INFO - TEST-START | kraken 05:08:35 INFO - operating with platform_type : mac_ 05:08:35 INFO - Initialising browser for kraken test... 05:08:35 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpnOWTws/profile http://localhost:52697/getInfo.html) 05:08:38 INFO - PROCESS | 1922 | 2017-03-03 05:08:38.104 plugin-container[1924:16999] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 05:08:38 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:08:38 INFO - PROCESS | 1922 | 2017-03-03 05:08:38.105 plugin-container[1924:16999] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 05:08:38 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:08:38 INFO - PROCESS | 1922 | 2017-03-03 05:08:38.105 plugin-container[1924:16999] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:08:38 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 05:08:38 INFO - PROCESS | 1922 | colorDepth:24 05:08:38 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 05:08:38 INFO - PROCESS | 1922 | __metrics 05:08:38 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 05:08:39 INFO - TEST-INFO | 1922: exit 0 05:08:39 INFO - Browser initialized. 05:08:39 INFO - Running cycle 1/1 for kraken test... 05:08:39 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpnOWTws/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:41 INFO - PROCESS | 1925 | 2017-03-03 05:08:41.505 plugin-container[1926:17120] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 05:08:41 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:08:41 INFO - PROCESS | 1925 | 2017-03-03 05:08:41.505 plugin-container[1926:17120] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 05:08:41 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:08:41 INFO - PROCESS | 1925 | 2017-03-03 05:08:41.505 plugin-container[1926:17120] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:08:51 INFO - PROCESS | 1925 | RSS: Main: 226865152 05:08:51 INFO - PROCESS | 1925 | 05:08:54 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52697/tests/kraken/driver.html?testName=audio-beat-detection) 05:08:55 INFO - PROCESS | 1925 | RSS: Main: 241512448 05:08:55 INFO - PROCESS | 1925 | 05:08:57 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52697/tests/kraken/driver.html?testName=audio-dft) 05:08:58 INFO - PROCESS | 1925 | RSS: Main: 249524224 05:08:58 INFO - PROCESS | 1925 | 05:09:01 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52697/tests/kraken/driver.html?testName=audio-fft) 05:09:02 INFO - PROCESS | 1925 | RSS: Main: 252116992 05:09:02 INFO - PROCESS | 1925 | 05:09:04 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52697/tests/kraken/driver.html?testName=audio-oscillator) 05:09:05 INFO - PROCESS | 1925 | RSS: Main: 253456384 05:09:05 INFO - PROCESS | 1925 | 05:09:07 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52697/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:09:07 INFO - PROCESS | 1925 | RSS: Main: 255123456 05:09:07 INFO - PROCESS | 1925 | 05:09:10 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52697/tests/kraken/driver.html?testName=imaging-darkroom) 05:09:11 INFO - PROCESS | 1925 | RSS: Main: 260313088 05:09:11 INFO - PROCESS | 1925 | 05:09:14 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52697/tests/kraken/driver.html?testName=imaging-desaturate) 05:09:15 INFO - PROCESS | 1925 | RSS: Main: 260255744 05:09:15 INFO - PROCESS | 1925 | 05:09:19 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52697/tests/kraken/driver.html?testName=json-parse-financial) 05:09:19 INFO - PROCESS | 1925 | RSS: Main: 260399104 05:09:19 INFO - PROCESS | 1925 | 05:09:21 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52697/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:09:22 INFO - PROCESS | 1925 | RSS: Main: 259915776 05:09:22 INFO - PROCESS | 1925 | 05:09:24 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52697/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:09:24 INFO - PROCESS | 1925 | RSS: Main: 259833856 05:09:24 INFO - PROCESS | 1925 | 05:09:27 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52697/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:09:27 INFO - PROCESS | 1925 | RSS: Main: 260210688 05:09:27 INFO - PROCESS | 1925 | 05:09:30 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52697/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:09:31 INFO - PROCESS | 1925 | RSS: Main: 260812800 05:09:31 INFO - PROCESS | 1925 | 05:09:34 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52697/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:09:34 INFO - PROCESS | 1925 | RSS: Main: 260370432 05:09:34 INFO - PROCESS | 1925 | 05:09:36 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52697/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52697/tests/kraken/driver.html?testName=ai-astar) 05:09:37 INFO - PROCESS | 1925 | RSS: Main: 263983104 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | __start_tp_report 05:09:37 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 05:09:37 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 05:09:37 INFO - PROCESS | 1925 | |i|pagename|runs| 05:09:37 INFO - PROCESS | 1925 | |0;ai-astar;108;118;118;119;119;119;118;118;121;120 05:09:37 INFO - PROCESS | 1925 | |1;audio-beat-detection;145;115;131;174;113;115;108;114;132;114 05:09:37 INFO - PROCESS | 1925 | |2;audio-dft;198;203;212;214;218;193;196;196;196;191 05:09:37 INFO - PROCESS | 1925 | |3;audio-fft;89;64;79;112;56;71;86;86;67;68 05:09:37 INFO - PROCESS | 1925 | |4;audio-oscillator;96;70;85;74;74;72;70;95;103;117 05:09:37 INFO - PROCESS | 1925 | |5;imaging-gaussian-blur;102;89;88;89;87;83;77;76;84;136 05:09:37 INFO - PROCESS | 1925 | |6;imaging-darkroom;110;155;97;98;98;98;98;97;96;137 05:09:37 INFO - PROCESS | 1925 | |7;imaging-desaturate;90;94;134;144;93;90;78;112;145;120 05:09:37 INFO - PROCESS | 1925 | |8;json-parse-financial;67;66;68;67;68;67;66;70;79;106 05:09:37 INFO - PROCESS | 1925 | |9;json-stringify-tinderbox;59;76;48;80;89;87;88;51;75;89 05:09:37 INFO - PROCESS | 1925 | |10;stanford-crypto-aes;86;85;90;102;107;86;91;100;99;107 05:09:37 INFO - PROCESS | 1925 | |11;stanford-crypto-ccm;139;152;163;145;145;143;143;147;188;143 05:09:37 INFO - PROCESS | 1925 | |12;stanford-crypto-pbkdf2;177;183;176;174;175;174;176;176;175;173 05:09:37 INFO - PROCESS | 1925 | |13;stanford-crypto-sha256-iterative;69;69;68;70;68;74;86;92;65;84 05:09:37 INFO - PROCESS | 1925 | __end_tp_report 05:09:37 INFO - PROCESS | 1925 | __start_cc_report 05:09:37 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,1008 05:09:37 INFO - PROCESS | 1925 | __end_cc_report 05:09:37 INFO - PROCESS | 1925 | __startTimestamp1488546577031__endTimestamp 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | ------- Summary: start ------- 05:09:37 INFO - PROCESS | 1925 | Number of tests: 14 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#0] ai-astar Cycles:10 Average:117.80 Median:119.00 stddev:3.58 (3.0%) stddev-sans-first:1.05 05:09:37 INFO - PROCESS | 1925 | Values: 108.0 118.0 118.0 119.0 119.0 119.0 118.0 118.0 121.0 120.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:123.00 stddev:20.41 (16.6%) stddev-sans-first:20.47 05:09:37 INFO - PROCESS | 1925 | Values: 145.0 115.0 131.0 174.0 113.0 115.0 108.0 114.0 132.0 114.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#2] audio-dft Cycles:10 Average:201.70 Median:200.50 stddev:9.58 (4.8%) stddev-sans-first:10.07 05:09:37 INFO - PROCESS | 1925 | Values: 198.0 203.0 212.0 214.0 218.0 193.0 196.0 196.0 196.0 191.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#3] audio-fft Cycles:10 Average:77.80 Median:75.00 stddev:16.18 (21.6%) stddev-sans-first:16.64 05:09:37 INFO - PROCESS | 1925 | Values: 89.0 64.0 79.0 112.0 56.0 71.0 86.0 86.0 67.0 68.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#4] audio-oscillator Cycles:10 Average:85.60 Median:74.00 stddev:16.42 (22.2%) stddev-sans-first:16.98 05:09:37 INFO - PROCESS | 1925 | Values: 96.0 70.0 85.0 74.0 74.0 72.0 70.0 95.0 103.0 117.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:85.50 stddev:17.36 (20.3%) stddev-sans-first:17.96 05:09:37 INFO - PROCESS | 1925 | Values: 102.0 89.0 88.0 89.0 87.0 83.0 77.0 76.0 84.0 136.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#6] imaging-darkroom Cycles:10 Average:108.40 Median:97.50 stddev:20.65 (21.2%) stddev-sans-first:21.90 05:09:37 INFO - PROCESS | 1925 | Values: 110.0 155.0 97.0 98.0 98.0 98.0 98.0 97.0 96.0 137.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#7] imaging-desaturate Cycles:10 Average:110.00 Median:84.00 stddev:24.56 (29.2%) stddev-sans-first:24.96 05:09:37 INFO - PROCESS | 1925 | Values: 90.0 94.0 134.0 144.0 93.0 90.0 78.0 112.0 145.0 120.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#8] json-parse-financial Cycles:10 Average:72.40 Median:67.50 stddev:12.41 (18.4%) stddev-sans-first:13.01 05:09:37 INFO - PROCESS | 1925 | Values: 67.0 66.0 68.0 67.0 68.0 67.0 66.0 70.0 79.0 106.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#9] json-stringify-tinderbox Cycles:10 Average:74.20 Median:83.50 stddev:15.94 (19.1%) stddev-sans-first:15.93 05:09:37 INFO - PROCESS | 1925 | Values: 59.0 76.0 48.0 80.0 89.0 87.0 88.0 51.0 75.0 89.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#10] stanford-crypto-aes Cycles:10 Average:95.30 Median:86.00 stddev:8.69 (10.1%) stddev-sans-first:8.54 05:09:37 INFO - PROCESS | 1925 | Values: 86.0 85.0 90.0 102.0 107.0 86.0 91.0 100.0 99.0 107.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#11] stanford-crypto-ccm Cycles:10 Average:150.80 Median:146.00 stddev:14.67 (10.0%) stddev-sans-first:14.93 05:09:37 INFO - PROCESS | 1925 | Values: 139.0 152.0 163.0 145.0 145.0 143.0 143.0 147.0 188.0 143.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:176.00 stddev:2.77 (1.6%) stddev-sans-first:2.91 05:09:37 INFO - PROCESS | 1925 | Values: 177.0 183.0 176.0 174.0 175.0 174.0 176.0 176.0 175.0 173.0 05:09:37 INFO - PROCESS | 1925 | 05:09:37 INFO - PROCESS | 1925 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:72.00 stddev:9.34 (13.0%) stddev-sans-first:9.69 05:09:37 INFO - PROCESS | 1925 | Values: 69.0 69.0 68.0 70.0 68.0 74.0 86.0 92.0 65.0 84.0 05:09:37 INFO - PROCESS | 1925 | -------- Summary: end -------- 05:09:37 INFO - PROCESS | 1925 | 05:09:38 INFO - TEST-INFO | 1925: exit 0 05:09:38 INFO - TEST-OK | kraken | took 62843ms 05:09:38 INFO - SUITE-END | took 1180s 05:09:38 INFO - Completed test suite (00:19:40) 05:09:38 INFO - javascript benchmark 05:09:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7552.353195428793, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3380.932381352373, 3436.209671775492, 3447.2243745157357, 3447.5690538682666, 3464.618891921888, 5656.236408084907, 5846.093855452406, 5864.560157988152, 5909.025010873212, 5997.0, 4784.90430191396, 4894.632902532307, 5770.192173095759, 5865.973454645431, 5883.735231914563, 5286.814961476349, 5327.254184414166, 5387.703676297802, 5412.783488660449, 5488.945110548903, 5339.919901201485, 5413.864653383657, 5463.180522921573, 5481.177823326513, 5506.669599824023, 5228.0, 5390.757415916293, 5432.728363581825, 5463.453654634544, 5476.4249753775675, 4444.422225110715, 5275.604329675251, 5289.973550132263, 5331.147016477384, 5422.132458806612, 5289.497497737709, 5291.3385826771655, 5366.060939335601, 5373.0, 5422.674639521641, 4850.860047888743, 5460.426655201187, 5977.551683623746, 6073.301570319426, 6117.694115294262, 6213.658248796314, 6263.24841019076, 6288.654124023177, 6320.273168585625, 6324.0, 6457.1282876811965, 6478.870422591569, 6480.6435646039445, 6488.643124628144, 6492.383223593751, 4767.380240568704, 6120.856343292863, 6148.907766383508, 6151.87696246077, 6154.415330543681, 6281.96859015702, 6341.682915854189, 6425.839354016187, 6520.739170433129, 6522.934770652328, 5158.484151584887, 6235.095911092866, 6270.498360131179, 6340.524460665469, 6359.745610175633, 6439.967800160969, 6450.290468048511, 6452.838679033061, 6456.51576131792, 6462.5153113515735, 4633.49031606523, 5513.972430137904, 5781.884362312814, 6113.0, 6203.379662033851, 6374.936250637527, 6382.776602818879, 6382.840428989312, 6388.8083357499345, 6395.328490508523, 5107.540321371094, 5130.8455597490265, 5148.871278218075, 5161.354830646169, 5183.118869792144, 4464.441944756905, 4477.888052798706, 4532.9773351133035, 4556.840510582114, 4599.149157405891, 5998.940010599926, 6071.332153463116, 6103.6948152592195, 6112.572119951561, 6140.6929653517145, 6374.107624932513, 6430.131932189188, 6430.421262086435, 6439.839004024937, 6455.225372955276, 5823.505002074787, 5992.370801065913, 6059.394060593993, 6071.453569178795, 6089.695515224221, 5503.669779813224, 5571.554275657938, 5584.274044374205, 5624.156376543469, 5649.689267090267, 6609.316927792022, 6628.767993120217, 6634.0, 6640.236372816995, 6659.134312539532, 5285.814996475105, 6450.387213214737, 6452.451541619015, 6455.419012288917, 6465.3857883501, 6257.937420625735, 6376.968115159394, 6459.095726598186, 6486.05952136928, 6529.0, 6310.211223597051, 6358.77744278948, 6368.885445047005, 6377.1390862233, 6394.392532709385, 5007.449180590205, 5354.330708661418, 6418.422341989243, 6448.419642232222, 6453.483721302191, 6109.8298075392895, 6145.863015342176, 6180.165677633462, 6203.0, 6206.255249370104, 6266.717997690206, 6278.4035516625845, 6283.59156654816, 6304.71628776697, 6308.968455157695, 5988.341282459013, 6027.246594175728, 6211.068339749074, 6218.129461875251, 6260.280067792262, 6137.7447333336595, 6199.659018754011, 6242.531810114168, 6243.7814676486105, 6283.3402492737605, 6153.384661533811, 6172.537059720628, 6180.81457556274, 6183.196184495987, 6192.102145189013], "value": 5809.6564018817135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.7082102569485, 784.3685832904512, 790.3084800799301, 801.6793282686926, 940.196132306878, 37640.43539346909, 38304.233915321674, 40003.0, 40072.599274007254, 40111.398329025054, 38159.0, 38399.42400863989, 38459.0, 38462.0, 38501.844944651704, 7773.766786996399, 7932.722354717586, 8983.595738191798, 10458.68623941283, 10742.30175038624, 10552.208584356204, 10663.040326370625, 10668.519916603771, 10668.62659806907, 10707.304025238374, 10666.0, 10695.572177112907, 10697.572097116106, 10707.839382409271, 10731.463426828666, 8918.554072296423, 10357.637482688146, 10662.040416362526, 10695.358278503314, 10716.4105974171, 8967.237784788302, 10466.267361284637, 10482.580696772196, 10499.475026248734, 10500.947495262475, 34336.82831585851, 34586.308273834635, 34757.826210868785, 34840.78057268009, 34866.65133348685, 3670.6696397324367, 3671.3208056509366, 3675.1730860556427, 3713.0902928782352, 3850.5379354477363, 22174.058185478058, 25679.101231456996, 25688.0, 26465.073722419624, 26588.202353929413, 2932.794704370695, 3053.40458610571, 3076.430860290853, 3089.196808829698, 3176.1583180457196, 325666.0, 326927.0, 328959.0, 334805.0, 352107.0, 31811.0, 34080.0, 41386.0, 43608.90977725519, 43675.78162109233, 35994.0, 45353.77323113364, 45412.545874541494, 45492.772536137105, 45891.54108458872, 10616.575336986589, 12616.621501354974, 12728.93635531835, 12745.745085098246, 12755.362231888368, 10840.620578279722, 10930.836037459445, 10935.234533582572, 10944.616938407118, 10974.506147223394, 11780.469878855472, 11794.52821887115, 11814.93238683227, 11818.113641476924, 11930.3438310892, 5548.500634942875, 5576.713547445528, 5598.272224610774, 5608.298962629671, 5641.266635337462, 878.3017501086524, 912.4707669551644, 919.5080631862055, 961.8124465729115, 968.7529679931572, 22994.0, 27183.456330873272, 27949.441011180068, 28264.858675706902, 28322.867085316768, 840.306746933782, 987.0918754745508, 995.158955931521, 1017.4658304390107, 1024.2113572549094, 14216.857831421761, 14242.0, 14497.0, 14567.344469498898, 14588.270586470633, 412191.0, 412205.0, 416248.0, 416301.0, 416814.0, 54776.90446191054, 55143.448565514635, 55242.44757552454, 55254.72372638111, 55298.17052744212, 1368.5962641020922, 1414.7736362182002, 1416.8724814766515, 1423.5871597236685, 1424.3590384326887, 24815.13147039845, 26281.868590656923, 26313.9474421021, 26988.920443182054, 27297.454050919267, 45205.77397112994, 45306.0, 48757.5124248753, 49809.25286120784, 49977.75011124921, 473280.0, 473393.0, 474887.0, 475157.6242118767, 475889.0, 16934.830651693326, 17687.82312176862, 19139.330123445612, 19708.0, 20389.0, 36961.44557831688, 38551.807240963615, 38595.614043859205, 39073.21853562856, 39199.60800392074, 31648.0, 32680.67319326776, 33498.162545936546, 33619.663803361655, 34056.65943340535, 31629.0, 32154.67845321517, 32598.392128630127, 33886.83056584701, 33945.66054339425, 27147.0, 32422.189445264055, 32911.67088329086, 33388.66611333856, 33545.66454335425, 21813.345599632037, 22740.65889011633, 27151.21219695076, 27366.726332736416, 30056.0, 22957.655635165152, 23487.295381138592, 23732.169374071826, 23818.64272035886, 23902.90941976495], "value": 17604.93710970158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.8001209389258, 396.46279291559915, 407.11858825642497, 415.72146661736633, 433.02569219256674, 17679.0, 19719.0, 19761.40715778527, 20469.403278166916, 21115.049822757974, 21413.822239776808, 21488.462788430297, 22402.663960040612, 22629.660555091687, 22633.886830565825, 5694.832559325349, 6125.908111378321, 6432.42108210261, 6498.9350106498805, 6548.738050477975, 6245.0, 6446.161998940154, 6454.806355809334, 6457.225132984048, 6567.179102612174, 5066.41736200338, 6465.83835404114, 6493.253275873264, 6510.08858759774, 6518.152640156773, 5424.833660762935, 5440.727963601804, 6296.1500197473215, 6339.524535659798, 6407.551471397004, 6074.726637301332, 6093.299270583851, 6246.843828904268, 6260.436560709512, 6268.937310626881, 26535.867320663274, 26585.867070664717, 26665.733342666517, 26811.195664130108, 26946.05688800902, 2901.998810410405, 3030.606021217228, 3391.8304084795664, 3402.863885444604, 3426.623071462137, 15612.76580851288, 15877.285522151531, 16230.0, 16325.10211938343, 16482.917585411997, 1911.665458544749, 1960.862739608228, 1965.3121407507401, 1965.9508512287164, 1977.3968939473455, 105436.47281763543, 109434.90565094327, 114349.0, 114872.85127148704, 115171.0, 20451.0, 20708.79291207099, 20824.58350833005, 20832.687509687366, 20997.68503472449, 33420.0, 33869.66130338714, 33924.66075339264, 33924.83037584796, 33936.0, 10204.744881378025, 10236.283460157867, 10493.213009024354, 10515.316504427188, 10618.469076546142, 6609.272979972199, 8181.731831566117, 8469.449485783405, 8482.006728418817, 8659.134086591417, 8449.732540119032, 10063.0, 10121.9371965944, 10134.54394552247, 10323.174146068297, 3820.9044773880873, 4259.762580438889, 4304.7632380218765, 4334.718243314174, 4336.262835318003, 1068.156156636264, 1085.316250762015, 1097.6871591596357, 1102.090775110521, 1140.777548378075, 20836.0, 21272.78727212739, 21371.67942480864, 21385.14459421637, 21397.893010534848, 864.1358641358642, 911.5943405184629, 931.5156118818312, 935.6865450074301, 1131.1629394248362, 6474.935250647527, 6608.041833934052, 6655.267920528738, 6792.864142717217, 6803.149606299213, 656045.0, 685533.0, 701738.0, 711258.0, 720033.0, 145658.0, 150760.0, 151717.0, 153292.46707532782, 155162.48375162392, 913.8081002989313, 1049.5014867937668, 1126.8647762268417, 1126.994365028186, 1128.368113856243, 17562.560935976704, 18544.721829172304, 18546.721799173294, 19089.618207636046, 20312.289069882485, 43195.0, 50398.74800625973, 55759.72120139373, 60156.39843601683, 62153.68923155355, 60153.0, 81135.0, 86503.0, 87234.9467768625, 87386.0, 6401.951375559776, 7941.761747147595, 10253.282270241012, 11371.601993930173, 11860.169788114748, 12241.20432171906, 12525.373731313215, 12679.492820287454, 12907.225566466042, 13110.410031548794, 10554.419506926886, 10832.458377081271, 11032.944835275772, 11105.333679979227, 11137.0, 10778.02997730208, 10956.945215273872, 11023.669289921316, 11053.668389948314, 11368.94315528417, 9008.549572521479, 11113.277636953571, 11147.331160130418, 11410.543578256777, 11499.0, 11392.601258955896, 11467.082633389142, 11767.705807354885, 11819.881801182222, 11861.466234019317, 10795.028447439769, 10906.945465272624, 11165.218434709817, 11269.098472122047, 11282.097432205568], "value": 11037.137951282197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.618362317609, 1541.1831729183536, 1558.1196623907492, 1564.8185619857006, 1565.452091767881, 9007.189352958232, 10371.792564148722, 10856.99999999999, 11002.889971100287, 11042.889571104306, 10795.62215322462, 10912.0, 10994.835077473845, 11004.0, 11037.337759734422, 2310.475086442952, 2357.917472888449, 2358.7287461941924, 2363.5745565798147, 2382.642603609464, 2339.8768591076296, 2380.5952987991996, 2384.1297926256966, 2384.606539920911, 2384.642303654457, 2313.3406979010983, 2315.131825565413, 2327.406511339606, 2339.9181028664, 2341.2156927429246, 1854.879432836868, 2243.5849367866995, 2265.1732117777055, 2286.142696488817, 2291.633338665814, 1954.540682939508, 2263.988680056589, 2343.425860664131, 2347.659589359533, 2350.4006478348083, 9033.187013168847, 10695.893041069567, 10795.946020269927, 10804.72988175294, 10812.0, 5587.972060139714, 5748.4538968798315, 5805.274340707411, 5807.041838096709, 5829.154772557998, 2622.331305517081, 3271.9182020449443, 3540.929181416357, 3547.6452354764574, 3555.1868547041195, 1184.8578170619494, 1191.910606704492, 1767.6553072150937, 1793.8923664580168, 1866.514706176404, 12643.93678031604, 12658.430370633343, 14701.926490367587, 15158.242087895562, 15328.693426131415, 4579.610733087658, 4646.907061858744, 4656.95343046572, 4759.547842954914, 4795.568398844051, 11436.599719009795, 11619.76760464786, 11620.302781832948, 11639.534418623329, 11786.410679465993, 5729.856753581111, 5799.710014499258, 5800.187973683687, 5808.796692115756, 5815.79644712433, 4383.890402739957, 5105.2086926526445, 5236.162214045735, 5255.343082114736, 5279.076161671692, 3707.6848467879995, 3777.8111094445167, 3797.2595343907674, 3807.904802379963, 3866.6133386661672, 1018.3635227982511, 1019.9592016319264, 1020.1175982774995, 1025.4411345816548, 1026.9075783179549, 856.4433118473042, 858.3948316436897, 892.4065496444921, 922.6770630279483, 923.1414784250577, 5177.767000484987, 6353.491720662336, 6353.618782873043, 6424.839379015562, 6472.543359780612, 912.3567884641312, 964.4358050540372, 996.6412091647002, 1005.522376870995, 1029.7282855673238, 3800.524934383202, 4039.8586049488713, 4087.173063583264, 4231.661467082626, 4247.17180149874, 13033.348332583333, 13430.664233394244, 13466.865331346757, 13497.865021349857, 13509.0, 10193.082622564005, 12271.631851044483, 13411.12827666218, 13456.596302110953, 13464.990125740413, 8661.480311181349, 8962.82074358509, 9712.70861874145, 10051.59793608262, 10115.14021308197, 6593.835154120993, 6617.66911654425, 6845.691943862438, 6862.210845752802, 6866.793996180123, 11821.527138914347, 11972.521099155936, 12079.214851034654, 12090.15368924202, 12131.696707582381, 12295.815562766744, 12405.875941240833, 12432.937835310766, 12585.993120550516, 12642.55751048709, 8151.836963260583, 8341.082480926972, 8418.873716894374, 8438.113998030363, 8444.535550544779, 9566.234701224024, 9888.060634239735, 9964.850527242243, 9983.450910200008, 10143.391396516232, 8211.260986511004, 8840.115988401109, 8921.06328835489, 9108.407953483002, 9163.037881022397, 8648.78378040554, 8729.214370706666, 8777.868331975153, 8858.64565417376, 8922.420042697204, 7543.811404714926, 7655.69377224927, 7744.806379840549, 7746.961265193638, 7772.417068719756, 5595.580331475075, 5679.943200567942, 5697.515711164598, 5772.94227057724, 5839.124131380327, 5807.216025836458, 5902.586818922634, 5948.613340132878, 5949.25634295713, 6022.969885150546], "value": 5032.12694300283, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.57651076747936, 330.6610724007892, 373.1771443966055, 378.46258313195267, 386.86266375436736, 4673.626109911207, 4836.379812762724, 4946.604271658268, 4978.67638603491, 5255.948810237954, 4404.96483326418, 4962.602991760659, 5266.499682530163, 5458.0, 5522.834314970557, 4038.1519880825063, 4041.1513582147786, 4346.630536404402, 4494.505604383531, 4510.842120525782, 4039.53545342285, 4448.911021779562, 4503.189425903336, 4549.976255342538, 4556.54434556545, 4538.273876179812, 4549.886252843688, 4557.361969324297, 4564.292534657134, 4574.61115805157, 4200.6219440250525, 4431.313146462304, 4494.910101797946, 4508.301213311942, 4575.0, 3767.3972164453558, 3953.802309884523, 4136.91726165475, 4153.335466325373, 4174.144300418407, 5047.637137972763, 5317.73411329432, 5321.547668448186, 5370.8925821483745, 5377.247185394048, 4548.636109111296, 4991.351124353847, 5281.36623605166, 5298.814541491068, 5338.839834804962, 5842.853928651818, 5865.736041878083, 5882.794102206445, 5899.584099816057, 5944.138099975479, 4093.6111069448357, 4094.324436467979, 4524.733074739078, 4617.607503362218, 4656.0, 5557.777688892439, 5784.471472401873, 5949.345571987078, 5985.940140598582, 6073.423024812636, 4533.886652833639, 4645.0, 4773.92586667997, 4830.720063916894, 4848.927266091011, 4737.076270127292, 4883.243097319921, 4919.926201106986, 4928.704277743347, 4933.692571468564, 4664.440267167894, 5077.39071311445, 5098.847034588968, 5219.973900130475, 5266.0257852297445, 4902.338184345139, 5102.897942041139, 5310.9734451327495, 5858.882822343529, 5881.617694849821, 1308.8233088533116, 1503.646643038885, 1564.0381165583085, 1589.6343840916422, 1592.562045437514, 919.7010971434311, 1075.7256899490737, 1180.8523934508187, 1189.1389974689582, 1218.3299185447968, 4502.482214545369, 5107.565856902205, 5150.330457040561, 5225.503577160163, 5260.238374853233, 5052.671576347526, 5852.326982397079, 5862.515175890448, 5865.970670146622, 5894.587378883522, 4658.743769092733, 4768.427788665383, 5017.949820501822, 5107.642465027412, 5444.782208711607, 2827.3921106962102, 2991.0707593602006, 3001.834899080528, 3010.4882170031146, 3045.9847700761798, 5103.979204159109, 5259.552938000313, 5380.91928621071, 5661.433856614388, 5780.42195780427, 5449.04641687711, 5588.580856435784, 5722.7424765885635, 6103.248873930084, 6162.476189523941, 5239.633225674166, 5354.732263386815, 5967.522598192134, 6268.4671802896355, 6332.651704156316, 5710.8001219957105, 5986.281646202484, 6005.219321488178, 6026.608270462406, 6076.453119219292, 4769.403824521934, 5593.300837395325, 5762.6830524319885, 5976.701164941823, 6009.429104235091, 5898.498627616529, 5978.043513037893, 5996.580239383201, 6055.515558755201, 6264.404881536247, 3848.9615103848605, 4011.5988401159652, 4407.369273368874, 4738.175802316202, 4788.3056956741775, 5478.849441617305, 5536.307961504812, 5602.075657516464, 5767.740451679769, 6037.335893051673, 5833.67914764692, 5847.707614619167, 5856.209411729362, 5864.501517371071, 5961.165436838933, 5440.238366628596, 5824.417558244142, 5849.415058494116, 5879.353780941343, 5882.49998750111, 4633.4208223972, 4898.853034408831, 5216.713080780593, 5302.019126461617, 5358.151437753969, 5129.615278854027, 5798.797042103506, 5817.301923769006, 5829.387914268941, 5839.299284085937, 5083.923741143812, 5384.300040994645, 5727.253187777767, 5760.366359700346, 5791.449812267828], "value": 4386.55066011455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.7568588836849, 733.4645708632695, 763.4045444553246, 812.4921923797626, 826.731312323495, 14629.122252664834, 15394.538163855103, 15572.987755795879, 15581.376744930189, 15617.453389131382, 14133.0, 14736.115833050024, 14784.482543110993, 14789.0, 14789.482368117118, 925.1950802801572, 943.1982814607597, 943.273679266964, 947.0, 948.5636607160715, 909.56340956341, 936.6955739384694, 946.711253067814, 947.5262368815593, 953.4327075390132, 995.7311525888006, 997.1225321716879, 998.6005597760881, 999.8550210219514, 1002.1882275356984, 976.0, 988.9703308900745, 993.5330394714473, 993.6522217223986, 995.069609914732, 731.5500966905315, 743.5575832379725, 744.1665334824977, 744.274332525783, 745.5750222373249, 15489.92255038729, 15860.603484912854, 15921.363145474283, 16094.436694715745, 16178.676426471522, 12463.801344770736, 13559.728805423836, 13690.726185476335, 13795.0, 13841.792373114411, 11377.94311028448, 11573.884261157365, 11578.652640420802, 11632.243904146204, 11916.404179791063, 3403.5745531808525, 3691.907702307437, 3700.9444858327147, 3709.536307961505, 3830.7893065881367, 107723.0, 107799.38300925492, 107918.0, 110337.0, 111415.0, 10549.103326217204, 10556.736081597868, 10658.840117398246, 10732.51703673321, 11122.110231181487, 22255.846354361744, 22395.776042239693, 22436.775632243796, 22463.663045054338, 22505.32484025482, 7437.516561423598, 7455.478116531791, 7535.849283014419, 7693.115291741409, 7774.028246469192, 3998.0555362652776, 4146.108586653884, 4195.811188496525, 4246.44796176495, 4298.570142985676, 3184.9363012739614, 3250.7561932854655, 3251.7398608111293, 3268.542404063433, 3316.369889720945, 595.3451203675921, 596.0612036043249, 599.78107990584, 614.5421660862596, 618.8267285160163, 522.5923779452033, 584.4447774614133, 623.6788054152115, 655.5574986883929, 663.3399767231635, 8973.596188171548, 9734.707958761248, 9740.805183896424, 9774.413535187912, 9820.165285950774, 638.7508871540103, 639.3861892583079, 667.2326824152263, 689.4002218070211, 691.2534462780185, 4499.257622492252, 4631.513691062457, 4641.74470404131, 4667.276572131325, 4734.905301894012, 106218.9378106209, 108312.37531437035, 108891.91108088977, 109162.0, 110827.7006759194, 88622.55688721515, 90311.0, 91243.54378228067, 92946.0, 93407.0, 57535.13697294544, 57893.13160302599, 70345.24136896516, 78574.21425785669, 79846.2015379862, 8978.820423591362, 8979.595918183828, 9015.774605634911, 9132.406393584395, 9164.358494905293, 33419.0, 38111.0, 51310.23034654558, 52174.0, 52889.0, 61185.0, 64885.67557162184, 65222.0, 65411.36471588248, 66105.67788644104, 10368.901229670826, 12468.0, 12561.246325220516, 12752.171108877894, 13169.364264912627, 12975.897048750585, 13402.128861623964, 14105.083169593943, 14498.710025799213, 14640.414383424544, 13075.803862942254, 13320.533781317607, 13438.731225375634, 13699.657508562366, 13786.897048236318, 11495.633187772859, 13124.343782811013, 13210.735785284432, 13387.330633468482, 13417.731645367234, 12916.547920823103, 13195.142315749446, 13249.271290079138, 13468.326583670972, 13740.450381984609, 8955.686550970946, 10637.9468102659, 10792.190585705946, 10953.068989136013, 11486.310821350746, 10188.949055254972, 10673.626423075155, 10682.465876706288, 10693.465326733476, 10722.410267435365], "value": 7446.295215076218, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 118.0, 119.0, 119.0, 119.0, 118.0, 118.0, 121.0, 120.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 131.0, 174.0, 113.0, 115.0, 108.0, 114.0, 132.0, 114.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 203.0, 212.0, 214.0, 218.0, 193.0, 196.0, 196.0, 196.0, 191.0], "value": 201.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 64.0, 79.0, 112.0, 56.0, 71.0, 86.0, 86.0, 67.0, 68.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 70.0, 85.0, 74.0, 74.0, 72.0, 70.0, 95.0, 103.0, 117.0], "value": 85.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 89.0, 88.0, 89.0, 87.0, 83.0, 77.0, 76.0, 84.0, 136.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 155.0, 97.0, 98.0, 98.0, 98.0, 98.0, 97.0, 96.0, 137.0], "value": 108.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 134.0, 144.0, 93.0, 90.0, 78.0, 112.0, 145.0, 120.0], "value": 110.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 67.0, 68.0, 67.0, 66.0, 70.0, 79.0, 106.0], "value": 72.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 76.0, 48.0, 80.0, 89.0, 87.0, 88.0, 51.0, 75.0, 89.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 90.0, 102.0, 107.0, 86.0, 91.0, 100.0, 99.0, 107.0], "value": 95.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 163.0, 145.0, 145.0, 143.0, 143.0, 147.0, 188.0, 143.0], "value": 150.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 183.0, 176.0, 174.0, 175.0, 174.0, 176.0, 176.0, 175.0, 173.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 70.0, 68.0, 74.0, 86.0, 92.0, 65.0, 84.0], "value": 74.5, "unit": "score"}]}]} 05:09:38 INFO - Return code: 0 05:09:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:09:38 ERROR - # TBPL SUCCESS # 05:09:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:38 INFO - 2017-03-03 05:09:38.318 screenresolution[1930:17535] starting screenresolution argv=screenresolution get 05:09:38 INFO - 2017-03-03 05:09:38.321 screenresolution[1930:17535] Display 0: 1600x1200x32@60 05:09:38 INFO - 2017-03-03 05:09:38.327 screenresolution[1931:17538] starting screenresolution argv=screenresolution list 05:09:38 INFO - Available Modes on Display 0 05:09:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:38 INFO - Intel Iris: 05:09:38 INFO - Chipset Model: Intel Iris 05:09:38 INFO - Type: GPU 05:09:38 INFO - Bus: Built-In 05:09:38 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:38 INFO - Vendor: Intel (0x8086) 05:09:38 INFO - Device ID: 0x0a2e 05:09:38 INFO - Revision ID: 0x0009 05:09:38 INFO - Displays: 05:09:38 INFO - Display: 05:09:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:38 INFO - Display Serial Number: 1600x1200 60 05:09:38 INFO - Main Display: Yes 05:09:38 INFO - Mirror: Off 05:09:38 INFO - Online: Yes 05:09:38 INFO - Rotation: Supported 05:09:38 INFO - Return code: 0 05:09:38 INFO - Running post-action listener: _resource_record_post_action 05:09:38 INFO - [mozharness: 2017-03-03 13:09:38.580219Z] Finished run-tests step (success) 05:09:38 INFO - Running post-run listener: _resource_record_post_run 05:09:38 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 1224704; Write bytes: 515942400; Read time: 15; Write time: 2155 05:09:38 INFO - TinderboxPrint: CPU usage
54.3% 05:09:38 INFO - TinderboxPrint: I/O read bytes / time
1,224,704 / 15 05:09:38 INFO - TinderboxPrint: I/O write bytes / time
515,942,400 / 2,155 05:09:38 INFO - TinderboxPrint: CPU idle
2,194.3 (45.7%) 05:09:38 INFO - TinderboxPrint: CPU system
964.3 (20.1%) 05:09:38 INFO - TinderboxPrint: CPU user
1,647.1 (34.3%) 05:09:38 INFO - TinderboxPrint: Swap in / out
212,004,864 / 0 05:09:38 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 201906688; Write bytes: 203220992; Read time: 15443; Write time: 760 05:09:38 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1224704; Write bytes: 301703168; Read time: 15; Write time: 1357 05:09:39 INFO - Running post-run listener: _upload_blobber_files 05:09:39 INFO - Blob upload gear active. 05:09:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:09:39 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 05:09:39 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'] 05:09:39 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 05:09:39 INFO - (blobuploader) - INFO - Open directory for files ... 05:09:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:09:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:09:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:40 INFO - (blobuploader) - INFO - Done attempting. 05:09:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:09:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:09:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:41 INFO - (blobuploader) - INFO - Done attempting. 05:09:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:09:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:09:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:42 INFO - (blobuploader) - INFO - Done attempting. 05:09:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:09:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:09:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:44 INFO - (blobuploader) - INFO - Done attempting. 05:09:44 INFO - (blobuploader) - INFO - Iteration through files over. 05:09:44 INFO - Return code: 0 05:09:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:09:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:09:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f520eb3a3f6c4a56a0ebf0034e02299f5dc191c03dfdaabcea4a2457596c53f61d569562a7b7e9bfe7dd74f73d99ba014f5777b415d81cdc6aff43515279258", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dd32f55b9e67d73be7c74dff64604cc7135a418241a1193e541a97c88e9687cd5f49ca20182a018058772a7daf1c5455ca82462e1222e082a59aa845a8c4b2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef3456a87362218393877d8621d2ce6cd947b0ced898f13fa4a2db6e68ba42b00c7a831523a4c05b391bb763a528b689b6202edb7e7c81c53941dd0f194c33d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3ac28d3c0b1cc0bcf4869d35cf230952afcbc5a53714922c0f8a562a1701ef7fffe0dbd37d6f594fb10e4be3fa5f066520200ec78c2d8cab0b6c1d0828b767d"} 05:09:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:09:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:09:44 INFO - Contents: 05:09:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f520eb3a3f6c4a56a0ebf0034e02299f5dc191c03dfdaabcea4a2457596c53f61d569562a7b7e9bfe7dd74f73d99ba014f5777b415d81cdc6aff43515279258", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dd32f55b9e67d73be7c74dff64604cc7135a418241a1193e541a97c88e9687cd5f49ca20182a018058772a7daf1c5455ca82462e1222e082a59aa845a8c4b2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef3456a87362218393877d8621d2ce6cd947b0ced898f13fa4a2db6e68ba42b00c7a831523a4c05b391bb763a528b689b6202edb7e7c81c53941dd0f194c33d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3ac28d3c0b1cc0bcf4869d35cf230952afcbc5a53714922c0f8a562a1701ef7fffe0dbd37d6f594fb10e4be3fa5f066520200ec78c2d8cab0b6c1d0828b767d"} 05:09:44 INFO - Running post-run listener: copy_logs_to_upload_dir 05:09:44 INFO - Copying logs to upload dir... 05:09:44 INFO - mkdir: /builds/slave/test/build/upload/logs 05:09:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1384.334396 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 23 mins, 4 secs) (at 2017-03-03 05:09:44.648210) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-03 05:09:44.652197) ========= 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.svQVPCHht4/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.xqmXNFCzbh/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f520eb3a3f6c4a56a0ebf0034e02299f5dc191c03dfdaabcea4a2457596c53f61d569562a7b7e9bfe7dd74f73d99ba014f5777b415d81cdc6aff43515279258", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dd32f55b9e67d73be7c74dff64604cc7135a418241a1193e541a97c88e9687cd5f49ca20182a018058772a7daf1c5455ca82462e1222e082a59aa845a8c4b2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef3456a87362218393877d8621d2ce6cd947b0ced898f13fa4a2db6e68ba42b00c7a831523a4c05b391bb763a528b689b6202edb7e7c81c53941dd0f194c33d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3ac28d3c0b1cc0bcf4869d35cf230952afcbc5a53714922c0f8a562a1701ef7fffe0dbd37d6f594fb10e4be3fa5f066520200ec78c2d8cab0b6c1d0828b767d"} build_url:https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/djQsOxbgQ-GUcpfSVR06KQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f520eb3a3f6c4a56a0ebf0034e02299f5dc191c03dfdaabcea4a2457596c53f61d569562a7b7e9bfe7dd74f73d99ba014f5777b415d81cdc6aff43515279258", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dd32f55b9e67d73be7c74dff64604cc7135a418241a1193e541a97c88e9687cd5f49ca20182a018058772a7daf1c5455ca82462e1222e082a59aa845a8c4b2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef3456a87362218393877d8621d2ce6cd947b0ced898f13fa4a2db6e68ba42b00c7a831523a4c05b391bb763a528b689b6202edb7e7c81c53941dd0f194c33d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3ac28d3c0b1cc0bcf4869d35cf230952afcbc5a53714922c0f8a562a1701ef7fffe0dbd37d6f594fb10e4be3fa5f066520200ec78c2d8cab0b6c1d0828b767d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-03 05:09:44.687190) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 05:09:44.687521) ========= 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.svQVPCHht4/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.xqmXNFCzbh/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.005284 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 05:09:44.736503) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-03 05:09:44.736842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-03 05:09:44.737173) ========= ========= Total master_lag: 0.19 =========