builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0174 starttime: 1486476092.53 results: success (0) buildid: 20170207050756 builduid: 2b23a2c91c404b0d8540aaad0a17f928 revision: b57c0a563ecd514d6ec5acc255a9435b6128650b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:32.534235) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:32.534689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:32.534974) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:32.561437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:32.561854) ========= 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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-02-07 06:01:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-02-07 06:01:32 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.416987 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:33.003318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:33.003654) ========= 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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.027478 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:33.052732) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 06:01:33.053109) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b57c0a563ecd514d6ec5acc255a9435b6128650b --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 b57c0a563ecd514d6ec5acc255a9435b6128650b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xB6rGGMEWi/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.aXJpU3cqj9/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-02-07 06:01:33,155 truncating revision to first 12 chars 2017-02-07 06:01:33,156 Setting DEBUG logging. 2017-02-07 06:01:33,156 attempt 1/10 2017-02-07 06:01:33,156 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b57c0a563ecd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-07 06:01:34,325 unpacking tar archive at: mozilla-central-b57c0a563ecd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.442988 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 06:01:34.519464) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:34.519830) ========= script_repo_revision: b57c0a563ecd514d6ec5acc255a9435b6128650b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:34.520292) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:34.520596) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 06:01:34.540430) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-07 06:01:34.540859) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xB6rGGMEWi/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.aXJpU3cqj9/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:01:34 INFO - MultiFileLogger online at 20170207 06:01:34 in /builds/slave/test 06:01:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:01:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:01:34 INFO - {'append_to_log': False, 06:01:34 INFO - 'base_work_dir': '/builds/slave/test', 06:01:34 INFO - 'blob_upload_branch': 'Firefox', 06:01:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:01:34 INFO - 'branch': 'Firefox', 06:01:34 INFO - 'buildbot_json_path': 'buildprops.json', 06:01:34 INFO - 'config_files': ('talos/mac_config.py',), 06:01:34 INFO - 'default_actions': ('clobber', 06:01:34 INFO - 'read-buildbot-config', 06:01:34 INFO - 'download-and-extract', 06:01:34 INFO - 'populate-webroot', 06:01:34 INFO - 'create-virtualenv', 06:01:34 INFO - 'install', 06:01:34 INFO - 'run-tests'), 06:01:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:01:34 INFO - 'download_minidump_stackwalk': True, 06:01:34 INFO - 'download_symbols': 'ondemand', 06:01:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:01:34 INFO - 'tooltool.py': '/tools/tooltool.py', 06:01:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:01:34 INFO - '/tools/misc-python/virtualenv.py')}, 06:01:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:01:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:01:34 INFO - 'installer_path': 'installer.exe', 06:01:34 INFO - 'log_level': 'info', 06:01:34 INFO - 'log_name': 'talos', 06:01:34 INFO - 'log_to_console': True, 06:01:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:01:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:01:34 INFO - 'opt_config_files': (), 06:01:34 INFO - 'pip_index': False, 06:01:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:01:34 INFO - 'cmd': ('bash', 06:01:34 INFO - '-c', 06:01:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:01:34 INFO - 'enabled': True, 06:01:34 INFO - 'halt_on_failure': False, 06:01:34 INFO - 'name': 'check_screen_resolution'},), 06:01:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:01:34 INFO - 'cmd': ('bash', 06:01:34 INFO - '-c', 06:01:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:01:34 INFO - 'enabled': True, 06:01:34 INFO - 'halt_on_failure': False, 06:01:34 INFO - 'name': 'check_screen_resolution'},), 06:01:34 INFO - 'run_cmd_checks_enabled': True, 06:01:34 INFO - 'sps_profile': False, 06:01:34 INFO - 'sps_profile_interval': 0, 06:01:34 INFO - 'suite': 'dromaeojs', 06:01:34 INFO - 'system_bits': '32', 06:01:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:01:34 INFO - 'title': 't-yosemite-r7-0174', 06:01:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:01:34 INFO - 'use_talos_json': True, 06:01:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:01:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:01:34 INFO - 'work_dir': 'build'} 06:01:34 INFO - [mozharness: 2017-02-07 14:01:34.753535Z] Running clobber step. 06:01:34 INFO - Running pre-action listener: _resource_record_pre_action 06:01:34 INFO - Running main action method: clobber 06:01:34 INFO - rmtree: /builds/slave/test/build 06:01:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:01:37 INFO - Running post-action listener: _resource_record_post_action 06:01:37 INFO - [mozharness: 2017-02-07 14:01:37.368062Z] Finished clobber step (success) 06:01:37 INFO - [mozharness: 2017-02-07 14:01:37.368266Z] Running read-buildbot-config step. 06:01:37 INFO - Running pre-action listener: _resource_record_pre_action 06:01:37 INFO - Running main action method: read_buildbot_config 06:01:37 INFO - Using buildbot properties: 06:01:37 INFO - { 06:01:37 INFO - "project": "", 06:01:37 INFO - "product": "firefox", 06:01:37 INFO - "script_repo_revision": "production", 06:01:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:01:37 INFO - "repository": "", 06:01:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:01:37 INFO - "buildid": "20170207050756", 06:01:37 INFO - "pgo_build": "False", 06:01:37 INFO - "basedir": "/builds/slave/test", 06:01:37 INFO - "buildnumber": 81, 06:01:37 INFO - "slavename": "t-yosemite-r7-0174", 06:01:37 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:01:37 INFO - "platform": "yosemite_r7", 06:01:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:01:37 INFO - "branch": "mozilla-central", 06:01:37 INFO - "repo_path": "mozilla-central", 06:01:37 INFO - "revision": "b57c0a563ecd514d6ec5acc255a9435b6128650b", 06:01:37 INFO - "stage_platform": "macosx64", 06:01:37 INFO - "builduid": "2b23a2c91c404b0d8540aaad0a17f928", 06:01:37 INFO - "slavebuilddir": "test" 06:01:37 INFO - } 06:01:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 06:01:37 INFO - Running post-action listener: _resource_record_post_action 06:01:37 INFO - [mozharness: 2017-02-07 14:01:37.372373Z] Finished read-buildbot-config step (success) 06:01:37 INFO - [mozharness: 2017-02-07 14:01:37.372494Z] Running download-and-extract step. 06:01:37 INFO - Running pre-action listener: _resource_record_pre_action 06:01:37 INFO - Running main action method: download_and_extract 06:01:37 INFO - mkdir: /builds/slave/test/build/tests 06:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:01:37 INFO - trying https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 06:01:37 INFO - Downloading https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/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 06:01:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/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 06:01:38 INFO - Downloaded 1199 bytes. 06:01:38 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:01:38 INFO - Using the following test package requirements: 06:01:38 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:01:38 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:01:38 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 06:01:38 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:01:38 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 06:01:38 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:01:38 INFO - u'jsshell-mac.zip'], 06:01:38 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:01:38 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 06:01:38 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:01:38 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:01:38 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 06:01:38 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:01:38 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 06:01:38 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:01:38 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 06:01:38 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:01:38 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 06:01:38 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:01:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:01:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:01:38 INFO - Fetch https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:01:40 INFO - Expected file size: 34854180 06:01:40 INFO - Obtained file size: 34854180 06:01:46 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 06:01:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:01:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:01:46 INFO - Fetch https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:01:48 INFO - Expected file size: 34854180 06:01:48 INFO - Obtained file size: 34854180 06:01:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 06:01:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:01:54 INFO - Fetch https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 06:01:55 INFO - Expected file size: 14403454 06:01:55 INFO - Obtained file size: 14403454 06:01: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')]} 06:01:55 INFO - trying https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:01:55 INFO - Downloading https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 06:01:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/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 06:01:57 INFO - Downloaded 70271631 bytes. 06:01:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:01:57 INFO - mkdir: /builds/slave/test/properties 06:01:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:01:57 INFO - Writing to file /builds/slave/test/properties/build_url 06:01:57 INFO - Contents: 06:01:57 INFO - build_url:https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:01:58 INFO - Running post-action listener: _resource_record_post_action 06:01:58 INFO - Running post-action listener: set_extra_try_arguments 06:01:58 INFO - [mozharness: 2017-02-07 14:01:58.907605Z] Finished download-and-extract step (success) 06:01:58 INFO - [mozharness: 2017-02-07 14:01:58.907754Z] Running populate-webroot step. 06:01:58 INFO - Running pre-action listener: _resource_record_pre_action 06:01:58 INFO - Running main action method: populate_webroot 06:01:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:01:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:01:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:01:58 INFO - '--tppagecycles', 06:01:58 INFO - '7'], 06:01:58 INFO - 'tests': ['tsvgm']}}, 06:01:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:01:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:01:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:01:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:01:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:01:58 INFO - 'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:01:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:01:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:01:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:01:58 INFO - 'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['damp', 'tps']}, 06:01:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:01:58 INFO - 'tests': ['damp', 'tps']}, 06:01:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['dromaeo_dom']}, 06:01:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:01:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['basic_compositor_video']}, 06:01:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:01:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['a11yr', 06:01:58 INFO - 'ts_paint', 06:01:58 INFO - 'tpaint', 06:01:58 INFO - 'sessionrestore', 06:01:58 INFO - 'sessionrestore_no_auto_restore', 06:01:58 INFO - 'tabpaint']}, 06:01:58 INFO - 'other-e10s': {'tests': ['a11yr', 06:01:58 INFO - 'ts_paint', 06:01:58 INFO - 'tpaint', 06:01:58 INFO - 'sessionrestore', 06:01:58 INFO - 'sessionrestore_no_auto_restore', 06:01:58 INFO - 'tabpaint']}, 06:01:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:01:58 INFO - 'ts_paint', 06:01:58 INFO - 'tpaint', 06:01:58 INFO - 'sessionrestore', 06:01:58 INFO - 'sessionrestore_no_auto_restore', 06:01:58 INFO - 'tabpaint']}, 06:01:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:01:58 INFO - 'ts_paint', 06:01:58 INFO - 'tpaint', 06:01:58 INFO - 'sessionrestore', 06:01:58 INFO - 'sessionrestore_no_auto_restore', 06:01:58 INFO - 'tabpaint']}, 06:01:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['a11yr', 06:01:58 INFO - 'ts_paint', 06:01:58 INFO - 'tpaint', 06:01:58 INFO - 'sessionrestore', 06:01:58 INFO - 'sessionrestore_no_auto_restore', 06:01:58 INFO - 'tabpaint']}, 06:01:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['a11yr', 06:01:58 INFO - 'ts_paint', 06:01:58 INFO - 'tpaint', 06:01:58 INFO - 'sessionrestore', 06:01:58 INFO - 'sessionrestore_no_auto_restore', 06:01:58 INFO - 'tabpaint']}, 06:01:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['tsvgx', 06:01:58 INFO - 'tsvgr_opacity', 06:01:58 INFO - 'tart', 06:01:58 INFO - 'tscrollx', 06:01:58 INFO - 'cart', 06:01:58 INFO - 'tsvg_static']}, 06:01:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:01:58 INFO - 'tsvgr_opacity', 06:01:58 INFO - 'tart', 06:01:58 INFO - 'tscrollx', 06:01:58 INFO - 'cart', 06:01:58 INFO - 'tsvg_static']}, 06:01:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:01:58 INFO - 'talos_options': ['--disable-e10s'], 06:01:58 INFO - 'tests': ['tp5o']}, 06:01:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:01:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:01:58 INFO - 'talos_options': ['--disable-e10s', 06:01:58 INFO - '--xperf_path', 06:01:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:01:58 INFO - 'tests': ['tp5n']}, 06:01:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:01:58 INFO - 'talos_options': ['--xperf_path', 06:01:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:01:58 INFO - 'tests': ['tp5n']}}, 06:01:58 INFO - 'talos.zip': {'path': '', 06:01:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:01:58 INFO - Running post-action listener: _resource_record_post_action 06:01:58 INFO - [mozharness: 2017-02-07 14:01:58.926490Z] Finished populate-webroot step (success) 06:01:58 INFO - [mozharness: 2017-02-07 14:01:58.926617Z] Running create-virtualenv step. 06:01:58 INFO - Running pre-action listener: _resource_record_pre_action 06:01:58 INFO - Running main action method: create_virtualenv 06:01:58 INFO - Creating virtualenv /builds/slave/test/build/venv 06:01:58 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 06:01:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:01:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:01:59 INFO - Using real prefix '/tools/python27' 06:01:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:01:59 INFO - Installing distribute.............................................................................................................................................................................................done. 06:02:02 INFO - Installing pip.................done. 06:02:02 INFO - Return code: 0 06:02:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:02:02 INFO - Reading from file tmpfile_stdout 06:02:02 INFO - Output received: 06:02:02 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) 06:02:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:02 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:02 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 06:02:02 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 06:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:02 INFO - 'HOME': '/Users/cltbld', 06:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:02 INFO - 'LOGNAME': 'cltbld', 06:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:02 INFO - 'MOZ_NO_REMOTE': '1', 06:02:02 INFO - 'NO_EM_RESTART': '1', 06:02:02 INFO - 'PAGER': '/bin/cat', 06:02:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:02 INFO - 'PWD': '/builds/slave/test', 06:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:02 INFO - 'SHELL': '/bin/bash', 06:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:02 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:02 INFO - 'USER': 'cltbld', 06:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:02 INFO - 'XPC_FLAGS': '0x0', 06:02:02 INFO - 'XPC_SERVICE_NAME': '0', 06:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:03 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 06:02:03 INFO - Cleaning up... 06:02:03 INFO - Return code: 0 06:02:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:02:03 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')]} 06:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:03 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')]} 06:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:03 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:03 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 06:02:03 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 06:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:03 INFO - 'HOME': '/Users/cltbld', 06:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:03 INFO - 'LOGNAME': 'cltbld', 06:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:03 INFO - 'MOZ_NO_REMOTE': '1', 06:02:03 INFO - 'NO_EM_RESTART': '1', 06:02:03 INFO - 'PAGER': '/bin/cat', 06:02:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:03 INFO - 'PWD': '/builds/slave/test', 06:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:03 INFO - 'SHELL': '/bin/bash', 06:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:03 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:03 INFO - 'USER': 'cltbld', 06:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:03 INFO - 'XPC_FLAGS': '0x0', 06:02:03 INFO - 'XPC_SERVICE_NAME': '0', 06:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:03 INFO - Downloading/unpacking psutil>=3.1.1 06:02:03 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 06:02:03 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 06:02:03 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 06:02:03 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 06:02:03 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 06:02:03 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 06:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:02:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:02:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:02:06 INFO - Installing collected packages: psutil 06:02:06 INFO - Running setup.py install for psutil 06:02:07 INFO - building 'psutil._psutil_osx' extension 06:02:07 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 06:02:07 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 06:02:07 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 06:02:07 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 06:02:07 INFO - building 'psutil._psutil_posix' extension 06:02:07 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 06:02:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:02:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:02:07 INFO - ^ 06:02:07 INFO - 1 warning generated. 06:02:07 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 06:02:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:02:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:02:08 INFO - Successfully installed psutil 06:02:08 INFO - Cleaning up... 06:02:08 INFO - Return code: 0 06:02:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:02:08 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')]} 06:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:08 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')]} 06:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:08 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:08 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 06:02:08 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 06:02:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:08 INFO - 'HOME': '/Users/cltbld', 06:02:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:08 INFO - 'LOGNAME': 'cltbld', 06:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:08 INFO - 'MOZ_NO_REMOTE': '1', 06:02:08 INFO - 'NO_EM_RESTART': '1', 06:02:08 INFO - 'PAGER': '/bin/cat', 06:02:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:08 INFO - 'PWD': '/builds/slave/test', 06:02:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:08 INFO - 'SHELL': '/bin/bash', 06:02:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:08 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:08 INFO - 'USER': 'cltbld', 06:02:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:08 INFO - 'XPC_FLAGS': '0x0', 06:02:08 INFO - 'XPC_SERVICE_NAME': '0', 06:02:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:02: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 06:02: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 06:02: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 06:02: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 06:02: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 06:02: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 06:02:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:02:11 INFO - Installing collected packages: mozsystemmonitor 06:02:11 INFO - Running setup.py install for mozsystemmonitor 06:02:11 INFO - Successfully installed mozsystemmonitor 06:02:11 INFO - Cleaning up... 06:02:11 INFO - Return code: 0 06:02:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:11 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:11 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 06:02:11 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 06:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:11 INFO - 'HOME': '/Users/cltbld', 06:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:11 INFO - 'LOGNAME': 'cltbld', 06:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:11 INFO - 'MOZ_NO_REMOTE': '1', 06:02:11 INFO - 'NO_EM_RESTART': '1', 06:02:11 INFO - 'PAGER': '/bin/cat', 06:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:11 INFO - 'PWD': '/builds/slave/test', 06:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:11 INFO - 'SHELL': '/bin/bash', 06:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:11 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:11 INFO - 'USER': 'cltbld', 06:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:11 INFO - 'XPC_FLAGS': '0x0', 06:02:11 INFO - 'XPC_SERVICE_NAME': '0', 06:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:11 INFO - Downloading/unpacking jsonschema==2.5.1 06:02:11 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 06:02:11 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 06:02:11 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 06:02:11 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 06:02:11 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 06:02:11 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 06:02:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:02:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:02:14 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 06:02:14 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 06:02:14 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 06:02:14 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 06:02:14 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 06:02:14 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 06:02:14 INFO - Downloading functools32-3.2.3-2.tar.gz 06:02:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:02:14 INFO - warning: no files found matching '*.txt' 06:02:14 INFO - no previously-included directories found matching 'build' 06:02:14 INFO - no previously-included directories found matching 'dist' 06:02:14 INFO - no previously-included directories found matching '.git*' 06:02:14 INFO - Installing collected packages: jsonschema, functools32 06:02:14 INFO - Running setup.py install for functools32 06:02:14 INFO - warning: no files found matching '*.txt' 06:02:14 INFO - no previously-included directories found matching 'build' 06:02:14 INFO - no previously-included directories found matching 'dist' 06:02:14 INFO - no previously-included directories found matching '.git*' 06:02:14 INFO - Successfully installed jsonschema functools32 06:02:14 INFO - Cleaning up... 06:02:15 INFO - Return code: 0 06:02:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:02:15 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')]} 06:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:15 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')]} 06:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:15 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:15 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 06:02:15 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 06:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:15 INFO - 'HOME': '/Users/cltbld', 06:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:15 INFO - 'LOGNAME': 'cltbld', 06:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:15 INFO - 'MOZ_NO_REMOTE': '1', 06:02:15 INFO - 'NO_EM_RESTART': '1', 06:02:15 INFO - 'PAGER': '/bin/cat', 06:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:15 INFO - 'PWD': '/builds/slave/test', 06:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:15 INFO - 'SHELL': '/bin/bash', 06:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:15 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:15 INFO - 'USER': 'cltbld', 06:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:15 INFO - 'XPC_FLAGS': '0x0', 06:02:15 INFO - 'XPC_SERVICE_NAME': '0', 06:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:02:15 INFO - Cleaning up... 06:02:15 INFO - Return code: 0 06:02:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:02:15 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')]} 06:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:15 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')]} 06:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:15 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:15 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 06:02:15 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 06:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:15 INFO - 'HOME': '/Users/cltbld', 06:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:15 INFO - 'LOGNAME': 'cltbld', 06:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:15 INFO - 'MOZ_NO_REMOTE': '1', 06:02:15 INFO - 'NO_EM_RESTART': '1', 06:02:15 INFO - 'PAGER': '/bin/cat', 06:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:15 INFO - 'PWD': '/builds/slave/test', 06:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:15 INFO - 'SHELL': '/bin/bash', 06:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:15 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:15 INFO - 'USER': 'cltbld', 06:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:15 INFO - 'XPC_FLAGS': '0x0', 06:02:15 INFO - 'XPC_SERVICE_NAME': '0', 06:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:15 INFO - Downloading/unpacking blobuploader==1.2.4 06:02:15 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 06:02:15 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 06:02:15 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 06:02:15 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 06:02:15 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 06:02:15 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 06:02:18 INFO - Downloading blobuploader-1.2.4.tar.gz 06:02:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:02:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:02:18 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 06:02:18 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 06:02:18 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 06:02:18 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 06:02:18 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 06:02:18 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 06:02:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:02:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:02:18 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 06:02:18 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 06:02:18 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 06:02:18 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 06:02:18 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 06:02:18 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 06:02:19 INFO - Downloading docopt-0.6.1.tar.gz 06:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:02:19 INFO - Installing collected packages: blobuploader, requests, docopt 06:02:19 INFO - Running setup.py install for blobuploader 06:02:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:02:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:02:19 INFO - Running setup.py install for requests 06:02:20 INFO - Running setup.py install for docopt 06:02:20 INFO - Successfully installed blobuploader requests docopt 06:02:20 INFO - Cleaning up... 06:02:20 INFO - Return code: 0 06:02:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02: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')]} 06:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:20 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:20 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 06:02:20 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 06:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:20 INFO - 'HOME': '/Users/cltbld', 06:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:20 INFO - 'LOGNAME': 'cltbld', 06:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:20 INFO - 'MOZ_NO_REMOTE': '1', 06:02:20 INFO - 'NO_EM_RESTART': '1', 06:02:20 INFO - 'PAGER': '/bin/cat', 06:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:20 INFO - 'PWD': '/builds/slave/test', 06:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:20 INFO - 'SHELL': '/bin/bash', 06:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:20 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:20 INFO - 'USER': 'cltbld', 06:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:20 INFO - 'XPC_FLAGS': '0x0', 06:02:20 INFO - 'XPC_SERVICE_NAME': '0', 06:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:02:20 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ggzjHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:02:20 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-lFqBh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:02:20 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-MdVCXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-nMxzGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-7e3dq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-wxeBdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-GAcQtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-7wT3Dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-khGDMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-fTA9lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-4jhDvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-QcaDWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:02:21 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-xsFGS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:02:22 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-BMFSf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:02:22 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-bLU_6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:02:22 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-flVvQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:02:22 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-PtQG9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:02:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:02:22 INFO - Running setup.py install for manifestparser 06:02:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:02:22 INFO - Running setup.py install for mozcrash 06:02:22 INFO - Running setup.py install for mozdebug 06:02:22 INFO - Running setup.py install for mozdevice 06:02:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Running setup.py install for mozfile 06:02:23 INFO - Running setup.py install for mozhttpd 06:02:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Running setup.py install for mozinfo 06:02:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Running setup.py install for mozInstall 06:02:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Running setup.py install for mozleak 06:02:23 INFO - Running setup.py install for mozlog 06:02:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:02:23 INFO - Running setup.py install for moznetwork 06:02:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:02:24 INFO - Running setup.py install for mozprocess 06:02:24 INFO - Running setup.py install for mozprofile 06:02:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:02:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:02:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:02:24 INFO - Running setup.py install for mozrunner 06:02:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:02:24 INFO - Running setup.py install for mozscreenshot 06:02:24 INFO - Running setup.py install for moztest 06:02:24 INFO - Running setup.py install for mozversion 06:02:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:02:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:02:25 INFO - Cleaning up... 06:02:25 INFO - Return code: 0 06:02:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02: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')]} 06:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:25 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:25 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 06:02:25 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 06:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:25 INFO - 'HOME': '/Users/cltbld', 06:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:25 INFO - 'LOGNAME': 'cltbld', 06:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:25 INFO - 'MOZ_NO_REMOTE': '1', 06:02:25 INFO - 'NO_EM_RESTART': '1', 06:02:25 INFO - 'PAGER': '/bin/cat', 06:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:25 INFO - 'PWD': '/builds/slave/test', 06:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:25 INFO - 'SHELL': '/bin/bash', 06:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:25 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:25 INFO - 'USER': 'cltbld', 06:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:25 INFO - 'XPC_FLAGS': '0x0', 06:02:25 INFO - 'XPC_SERVICE_NAME': '0', 06:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:02:25 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-UqhnPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:02:25 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)) 06:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:02:25 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-DnONl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:02:25 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)) 06:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:02:25 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-M8wyln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:02:25 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)) 06:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:02:25 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-A7MnlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:02:25 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)) 06:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:02:25 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-7Bx35D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:02:25 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)) 06:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:02:25 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-CNBSeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:02:25 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)) 06:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:02:25 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-keVeRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:02:26 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-lan30t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:02:26 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-x5dEiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:02:26 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-mPuQm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:02:26 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-4xldkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:02:26 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-M0iaBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-bY_tPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:02:26 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-n0028S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:02:26 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-SCGZcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:02:26 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)) 06:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:02:26 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Y1PItk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:02:27 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)) 06:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:02:27 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-UQjk5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:02:27 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)) 06:02:27 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)) 06:02:27 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)) 06:02:27 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)) 06:02:27 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)) 06:02:27 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)) 06:02:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:02:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:02:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:02:29 INFO - Downloading blessings-1.6.tar.gz 06:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:02:29 INFO - Installing collected packages: blessings 06:02:29 INFO - Running setup.py install for blessings 06:02:30 INFO - Successfully installed blessings 06:02:30 INFO - Cleaning up... 06:02:30 INFO - Return code: 0 06:02:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:02:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:02:30 INFO - Reading from file tmpfile_stdout 06:02:30 INFO - Current package versions: 06:02:30 INFO - blessings == 1.6 06:02:30 INFO - blobuploader == 1.2.4 06:02:30 INFO - docopt == 0.6.1 06:02:30 INFO - functools32 == 3.2.3-2 06:02:30 INFO - jsonschema == 2.5.1 06:02:30 INFO - manifestparser == 1.1 06:02:30 INFO - mozInstall == 1.12 06:02:30 INFO - mozcrash == 1.0 06:02:30 INFO - mozdebug == 0.1 06:02:30 INFO - mozdevice == 0.49 06:02:30 INFO - mozfile == 1.2 06:02:30 INFO - mozhttpd == 0.7 06:02:30 INFO - mozinfo == 0.9 06:02:30 INFO - mozleak == 0.1 06:02:30 INFO - mozlog == 3.4 06:02:30 INFO - moznetwork == 0.27 06:02:30 INFO - mozprocess == 0.24 06:02:30 INFO - mozprofile == 0.28 06:02:30 INFO - mozrunner == 6.13 06:02:30 INFO - mozscreenshot == 0.1 06:02:30 INFO - mozsystemmonitor == 0.3 06:02:30 INFO - moztest == 0.8 06:02:30 INFO - mozversion == 1.4 06:02:30 INFO - psutil == 3.1.1 06:02:30 INFO - requests == 1.2.3 06:02:30 INFO - wsgiref == 0.1.2 06:02:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02: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')]} 06:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:31 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 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:31 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 06:02:31 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 06:02:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:31 INFO - 'HOME': '/Users/cltbld', 06:02:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:31 INFO - 'LOGNAME': 'cltbld', 06:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:31 INFO - 'MOZ_NO_REMOTE': '1', 06:02:31 INFO - 'NO_EM_RESTART': '1', 06:02:31 INFO - 'PAGER': '/bin/cat', 06:02:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:31 INFO - 'PWD': '/builds/slave/test', 06:02:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:31 INFO - 'SHELL': '/bin/bash', 06:02:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:31 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:31 INFO - 'USER': 'cltbld', 06:02:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:31 INFO - 'XPC_FLAGS': '0x0', 06:02:31 INFO - 'XPC_SERVICE_NAME': '0', 06:02:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 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)) 06:02:31 INFO - Cleaning up... 06:02:31 INFO - Return code: 0 06:02:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:02:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d22768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a324a6530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, '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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:02:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:31 INFO - 'HOME': '/Users/cltbld', 06:02:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:31 INFO - 'LOGNAME': 'cltbld', 06:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:31 INFO - 'MOZ_NO_REMOTE': '1', 06:02:31 INFO - 'NO_EM_RESTART': '1', 06:02:31 INFO - 'PAGER': '/bin/cat', 06:02:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:31 INFO - 'PWD': '/builds/slave/test', 06:02:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:31 INFO - 'SHELL': '/bin/bash', 06:02:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:31 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:31 INFO - 'USER': 'cltbld', 06:02:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:31 INFO - 'XPC_FLAGS': '0x0', 06:02:31 INFO - 'XPC_SERVICE_NAME': '0', 06:02:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:02:32 INFO - Cleaning up... 06:02:32 INFO - Return code: 0 06:02:32 INFO - Running post-action listener: _resource_record_post_action 06:02:32 INFO - Running post-action listener: _start_resource_monitoring 06:02:32 INFO - Starting resource monitoring. 06:02:32 INFO - [mozharness: 2017-02-07 14:02:32.188682Z] Finished create-virtualenv step (success) 06:02:32 INFO - [mozharness: 2017-02-07 14:02:32.189551Z] Running install step. 06:02:32 INFO - Running pre-action listener: _resource_record_pre_action 06:02:32 INFO - Running main action method: install 06:02:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:02:32 INFO - Reading from file tmpfile_stdout 06:02:32 INFO - Detecting whether we're running mozinstall >=1.0... 06:02:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:02:32 INFO - Reading from file tmpfile_stdout 06:02:32 INFO - Output received: 06:02:32 INFO - Usage: mozinstall [options] installer 06:02:32 INFO - Options: 06:02:32 INFO - -h, --help show this help message and exit 06:02:32 INFO - -d DEST, --destination=DEST 06:02:32 INFO - Directory to install application into. [default: 06:02:32 INFO - "/builds/slave/test"] 06:02:32 INFO - --app=APP Application being installed. [default: firefox] 06:02:32 INFO - mkdir: /builds/slave/test/build/application 06:02:32 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'] 06:02:32 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 06:02:54 INFO - Reading from file tmpfile_stdout 06:02:54 INFO - Output received: 06:02:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:02:54 INFO - Running post-action listener: _resource_record_post_action 06:02:54 INFO - [mozharness: 2017-02-07 14:02:54.331380Z] Finished install step (success) 06:02:54 INFO - [mozharness: 2017-02-07 14:02:54.331545Z] Running run-tests step. 06:02:54 INFO - Running pre-action listener: _resource_record_pre_action 06:02:54 INFO - Running main action method: run_tests 06:02:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:02:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:02:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:02:54 INFO - 2017-02-07 06:02:54.346 screenresolution[1624:7353] starting screenresolution argv=screenresolution get 06:02:54 INFO - 2017-02-07 06:02:54.348 screenresolution[1624:7353] Display 0: 1600x1200x32@60 06:02:54 INFO - 2017-02-07 06:02:54.354 screenresolution[1625:7356] starting screenresolution argv=screenresolution list 06:02:54 INFO - Available Modes on Display 0 06:02:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:02:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:02:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:02:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:02:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:02:54 INFO - Intel Iris: 06:02:54 INFO - Chipset Model: Intel Iris 06:02:54 INFO - Type: GPU 06:02:54 INFO - Bus: Built-In 06:02:54 INFO - VRAM (Dynamic, Max): 1536 MB 06:02:54 INFO - Vendor: Intel (0x8086) 06:02:54 INFO - Device ID: 0x0a2e 06:02:54 INFO - Revision ID: 0x0009 06:02:54 INFO - Displays: 06:02:54 INFO - Display: 06:02:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:02:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:02:54 INFO - Display Serial Number: 1600x1200 60 06:02:54 INFO - Main Display: Yes 06:02:54 INFO - Mirror: Off 06:02:54 INFO - Online: Yes 06:02:54 INFO - Rotation: Supported 06:02:54 INFO - Return code: 0 06:02:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:02:54 INFO - Python 2.7.3 06:02:54 INFO - Return code: 0 06:02:54 INFO - grabbing minidump binary from tooltool 06:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:54 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 0x107afdd30>, '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 0x107a24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:02:54 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 06:02:54 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 06:02:54 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 06:02:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:02:54 INFO - Return code: 0 06:02:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:02:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:02:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:02:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:02:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:02:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0174 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:02:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xB6rGGMEWi/Render', 06:02:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:02:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:02:54 INFO - 'HOME': '/Users/cltbld', 06:02:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:02:54 INFO - 'LOGNAME': 'cltbld', 06:02:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:02:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:54 INFO - 'MOZ_NO_REMOTE': '1', 06:02:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:02:54 INFO - 'NO_EM_RESTART': '1', 06:02:54 INFO - 'PAGER': '/bin/cat', 06:02:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:54 INFO - 'PWD': '/builds/slave/test', 06:02:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:02:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:02:54 INFO - 'SHELL': '/bin/bash', 06:02:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXJpU3cqj9/Listeners', 06:02:54 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:02:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:02:54 INFO - 'USER': 'cltbld', 06:02:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:02:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:54 INFO - 'XPC_FLAGS': '0x0', 06:02:54 INFO - 'XPC_SERVICE_NAME': '0', 06:02:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:02:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:02:54 INFO - mozversion application_buildid: 20170207050756 06:02:54 INFO - mozversion application_changeset: b57c0a563ecd514d6ec5acc255a9435b6128650b 06:02:54 INFO - mozversion application_display_name: Nightly 06:02:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:02:54 INFO - mozversion application_name: Firefox 06:02:54 INFO - mozversion application_remotingname: firefox 06:02:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:02:54 INFO - mozversion application_vendor: Mozilla 06:02:54 INFO - mozversion application_version: 54.0a1 06:02:54 INFO - mozversion platform_buildid: 20170207050756 06:02:54 INFO - mozversion platform_changeset: b57c0a563ecd514d6ec5acc255a9435b6128650b 06:02:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:02:54 INFO - mozversion platform_version: 54.0a1 06:02:54 INFO - using testdate: 1486476174 06:02:54 INFO - actual date: 1486476174 06:02:54 INFO - starting webserver on 'localhost:49248' 06:02:54 INFO - SUITE-START | Running 2 tests 06:02:54 INFO - TEST-START | dromaeo_css 06:02:54 INFO - operating with platform_type : mac_ 06:02:54 INFO - Initialising browser for dromaeo_css test... 06:02:54 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmp6K6guH/profile http://localhost:49248/getInfo.html) 06:02:57 INFO - PROCESS | 1635 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:02:57 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 06:02:57 INFO - PROCESS | 1635 | colorDepth:24 06:02:57 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 06:02:57 INFO - PROCESS | 1635 | __metrics 06:02:57 INFO - PROCESS | 1635 | 1486476177400 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 06:02:57 INFO - PROCESS | 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:02:57 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:02:58 INFO - TEST-INFO | 1635: exit 0 06:02:58 INFO - Browser initialized. 06:02:58 INFO - Running cycle 1/1 for dromaeo_css test... 06:02:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmp6K6guH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:03:10 INFO - PROCESS | 1637 | RSS: Main: 236716032 06:03:10 INFO - PROCESS | 1637 | 06:06:15 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 06:06:16 INFO - PROCESS | 1637 | RSS: Main: 356245504 06:06:16 INFO - PROCESS | 1637 | 06:07:59 INFO - PROCESS | 1637 | ************************* 06:07:59 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 06:07:59 INFO - PROCESS | 1637 | 06:07:59 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:07:59 INFO - PROCESS | 1637 | Full stack: 06:07:59 INFO - PROCESS | 1637 | ************************* 06:09:19 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 06:09:20 INFO - PROCESS | 1637 | RSS: Main: 377344000 06:09:20 INFO - PROCESS | 1637 | 06:12:22 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 06:12:23 INFO - PROCESS | 1637 | RSS: Main: 338747392 06:12:23 INFO - PROCESS | 1637 | 06:15:25 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 06:15:26 INFO - PROCESS | 1637 | RSS: Main: 344154112 06:15:26 INFO - PROCESS | 1637 | 06:18:29 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 06:18:30 INFO - PROCESS | 1637 | RSS: Main: 343150592 06:18:30 INFO - PROCESS | 1637 | 06:21:33 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 06:21:34 INFO - PROCESS | 1637 | RSS: Main: 546533376 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - PROCESS | 1637 | __start_tp_report 06:21:34 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 06:21:34 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 06:21:34 INFO - PROCESS | 1637 | |i|pagename|runs| 06:21:34 INFO - PROCESS | 1637 | |0;dojo.html;2946.7937244392874;3245.9612923864343;3265.2979609383983;3272.4764037753957;3357.513160591714;5361.651492652974;5430.294061771964;5497.752601132948;5581.665100093992;5593.608447408683;5158.664686795365;5435.456454354563;5497.312835895513;5516.668999860001;5570.6657600543995;4950.87622809429;4955.578775804061;5034.974825125869;5147.639665223436;5150.510701483353;4248.893777655568;5101.081805275049;5132.076226279269;5169.224616307565;5170.457102004292;4964.652474326798;4985.127602669519;5005.499450054966;5026.949730502684;5070.985802839428;4966.354373931402;5005.974970125162;5048.545630893201;5100.872478188038;5110.872228194287;4972.328735620717;4987.700737955698;5008.198688209869;5051.318072060299;5077.974610126963;5304.363476382859;5362.383325917531;5454.509094181503;5455.781768729246;5467.863303417406;5842.415758424166;5862.237909071836;5864.794732184396;5873.383294754075;5885.382034886318;5844.766209351621;5970.283565972111;5985.940140598582;5987.790427335066;6030.969845150747;5570.359408667974;5572.637778544382;5587.944120558824;5591.329040515164;5602.971985140048;6114.541409394314;6148.6925653717135;6205.193324867829;6244.344343843922;6328.6835658216905;5712.914306285409;5759.568032397587;5767.307923049177;5907.793227237026;6062.878742425215;6100.054491553817;6176.7838125665385;6177.752889884354;6181.196444462191;6182.783602573888;6077.24034495688;6111.113888486234;6114.484818317571;6124.724387402578;6130.938690613037;5815;5846.064629659234;5849.415058494202;6094.177286066413;6100.694965251809;4927.876803079952;4929.186684197139;4932.284818701268;4935.925961110586;4965.0566392385335;4364.541723119027;4366.170427618742;4366.3013917773005;4376.737395756265;4401.207782599099;5352.839414817562;5442.673439593558;5464.972675136599;5624.971875140598;5652.830415087554;5101.489851014869;6096.359882212392;6104.0347610180015;6120.9347721082095;6208.782692605737;5559.277293951841;5568.359638641608;5574.08027675429;5584.050711379075;5605.103183490622;5296.417394086648;5340.786368545215;5343.412224655285;5348.3849357323625;5382.811601593926;5722.484976352148;6141.977005061692;6149.354317796746;6177.980633195472;6198.256209254918;5994.55040871945;5996.070609055603;6014.789482368096;6015.87968240624;6030.668313242813;6194.783182588768;6244.125822384779;6255.437010669061;6269.96865015672;6279.96860015697;5789.623674461147;5791.362950075404;5817.563682723728;5915.201447804491;5948.405159484017;6147.354527774519;6207.81376558704;6211;6213.565050446426;6227.097070924781;5704.515116215168;5755.827325180251;5774.42255774436;5795.913061304;5833.066709326487;5588.133839254922;5627.268455100811;5882.735276912464;5893.116032595145;5913.113033045078;5548.029094908456;5585.888282234414;5593.328800543798;5726.685032323096;5740.454656807597;5782.653040817564;5796;5841.32824725162;5879.911801323069;5950;5748.166515855094;5752.424757524214;5757;5759.798407055733;5760 06:21:34 INFO - PROCESS | 1637 | |1;ext.html;449.8245684183168;471.89146496305847;502.4247236913732;508.4356364435477;509.0302972836747;38156.42765358518;40503.39244911325;40505.392419113734;40520.59479405205;40561.594384056225;41447.58552414475;41839;41964;42026;42046.36930446038;7953.164917683618;8167.918320816805;9232.769180770467;9459.243260539175;9493.772117480055;11024.338539687604;11340.432978351091;11344.262622929484;11454.71363215922;11456.140789440784;11335.546578136864;11386.146039047064;11393.202475826696;11412;11433.485493152786;8011.319037881729;8249.958750206271;11073.446327683583;11248.918949994277;11328.49021794017;11058.723531911686;11287.097032237401;11288.887111128866;11301.49143288554;11308.434578271053;39815;40059.998500037735;40091.19817603631;40144;40227.79316620506;3968.285708572456;3968.3452230381954;3974.82113304902;4005.339119045354;4121.505419349697;30488;30623;30657.846710766527;30658.38683226345;30743.231419214473;3052.343746094578;3230.127865476332;3290.5393244945726;3292.3250733599552;3293.884714034997;325042;328942;333482;414610;435748.82125589484;50514.737131571266;52274.738626307386;52392;58783.82432351329;59385.70307148523;63235.735285294955;64365;64378.356216437234;68066;68068.31931680719;15178;15288.770668439982;15300.387984480718;15485.535433937;15485.767713484307;11912.761744765055;11934.641960741192;11986.940065299617;11990.280583164864;12012.63962081139;16784.58038549046;16818.411355602497;16852.49442516702;16857.83142168587;16884;6776.661166941732;6793.660316984131;6831.69257383419;6840.794776156723;6870.106886104874;829.4815740162398;831.6444195960546;848.3976376772438;850.6044689219542;857.3655494933829;29142.23129387191;29375.706242937726;29395;29801;30121;1022.1056575496441;1035.471909326234;1054.177741361739;1067.8344856547249;1150.0339714639742;13180.670483237804;15278.15970121654;15356.616084597974;15663.138527380876;15758;506961;510770;511627;513123;514973;57187.28438146862;57672;57821;57833.132503012486;57916.999999999156;1093.906093906094;1272.7009152849073;1389.756792561318;1400.621832105326;1401.5865319730501;27883.44233115286;28647.57028644614;28955.276118097216;29432.11703648894;29551;49762.75118624384;50218.99562008813;50268;50343.74828125836;50535.49464505308;376455;376940;377173;377402;378278.258261263;32849.67150328466;33594.992150235536;33740.66259337375;38334.876404942166;38869;41271.76184714343;41546.79226603848;44959.32561011653;46081;47853.76073119612;38089.61910380972;38605.613943860204;38888.36137750418;39318.6408815472;39689.60310396975;37399;37435.62564374322;37736.81131594325;38636.61363386443;38889.416658750706;35131.64868351386;36233.91298261056;36538.63461365352;36797;37322.813385932895;28309.575356370082;33587.328253435284;33841.32317353688;34228.82885585556;34287.48568771421;19387.90306048461;23871.40321491907;24004;24537.631935520625;24613.945248155036 06:21:34 INFO - PROCESS | 1637 | |2;jquery.html;386.1716617854701;387.80609695152424;391.91419141914196;397.96020397960206;418.29517263411157;13920.721585568283;17936.461906142835;19327.420177394666;20558.486037849063;20589.38231853047;15658.295376708047;17937.551561210978;20683.689744653842;20777.376678699664;20998.160073597075;5734.168545560901;5950.821475355746;6087.147799308101;6095.268567771874;6096.268447786272;5998.790042348519;6005.369436209201;6054.757809687607;7403.62981850908;7737.845243095135;7785.948896898931;7787.454878158531;7905.565193914361;7914.3668506519625;7944.046714394281;7712.1902200269005;7836.373090152802;7888.881666775003;7922.36621070313;7941.880871786928;7450.441216908754;7564.546127232384;7635.083789945242;7670.04413874464;7680.577568233745;27125.457490850073;27472.17583472479;27497.17508474729;28445.586529616372;28456.715432845613;2819.4361127774528;2858.1425572328217;2877.424515096968;2878.553824157259;2894.6526416830116;12359.62921112368;15546.89062187567;16423;17193.875771631843;17227.569310767205;1613.8547530722174;1899.221319259111;1915.2530513099903;1918.7217853411178;1969.9310524131586;143347.28326358303;156375;160250;162361;165562;16384.344626215057;21341;21530.56938861214;21752.89123554372;21773.23793667214;39097.609023909965;39480.80259598684;39534.209315813525;39738.801305993285;39865.601343986185;8949.418287811272;8997.05894042365;10100.040496152853;10363.689089327332;10485.423301718478;7501.212372700897;7950.3242224410415;8549.829003419896;8580.742577722678;8600.655973761104;9586.568604412818;9862.65480708186;10655.84016239757;10732.678019659423;10900.203910279452;3500.842462089212;3982.844974957288;4001.359782434855;4075.816588253536;4093.7543747375253;992.9573947355248;996.4320337407609;996.8504724291414;1003.6587560229557;1029.8249297619273;16864.915675421544;19968.60062798765;19988.20047198125;20627.58744825095;20683.379498615064;1021.8722659667542;1024.3853687787416;1044.1333693034762;1048.1981284317503;1055.208593554834;9931.553080111413;10490.527926243338;10634.20243481742;10755.247132700635;10778.73053173677;343081;354682;693840;707225;709714;109343.45328273308;123218.76781232252;123304.38347808203;126096;131025;1019.393460890754;1024.9743756406149;1044.9791004180026;1045.5974449836867;1051.7212938571286;17498.73751893748;17726.02506862135;17787.644247114727;17868.55328616795;18186.45440636783;51100.488995109576;51727.48272517227;51744.741276293375;52414.47585524096;52566;70185.29814701992;71981;73166;73366;73643.79068627949;8104.635291411782;8168.529664660418;8217.917820821714;8274.958625206835;8288.295494883003;10833.566657333933;12389.132760706663;12445.079799863015;12607.558735444216;12674.239545626922;10583.735406614896;10592.311499752492;10964.122870170208;11021.173411993972;11044.33733975964;10646.787064258826;10757.569697212337;10802.621908233174;11006.944965275123;11013.944930275618;10439.478026098514;11687.240329378903;11848.052155827685;11886.227395219283;11916.702082448008;7917.722879699183;8021.759347219593;8259.917400825914;8283.37874659391;8433.156684331518;10148.340357876714;10785.62250321235;11029.117670586173;11056;11103.056240219672 06:21:34 INFO - PROCESS | 1637 | |3;mootools.html;1227.7114878003674;1377.5729523847608;1410.5486244401789;1436.3321055709096;1440.4238304678133;8172.9182708172975;8394.832103357929;8576.871346929802;8577.699780507683;8584.8712269316;8237.835243295145;8392.454490458116;8531.872021919677;8542.23119919207;8629.91370086299;2255.4361409647545;2295.8392912496047;2299.2067736630834;2299.402155439581;2303.6199027160496;2171.869687818728;2260.4687898343877;2261.7738226177335;2264.230161745015;2268.6370180771078;1950.151684017457;2023.3929821053698;2026.898655067248;2185.344396680989;2279.1567120165564;1924.9326273580427;2222.4666080140732;2263.8302127340517;2274.374546999572;2275.2377953385635;2222.3999520129637;2284.9885750571307;2288.7482376938524;2291.6104262275285;2294.5640328337563;8394.748157555283;8462.957685211535;8471.06818249992;8474.279686226679;8512.616932238065;4452.443444569429;5081.923771143436;5192.584593232532;5225.790968361299;5248.107821670325;3277.2298509850166;3386.6444023377435;3432.3993301172445;3438.346714124308;3440.17435815405;1162.4594563528;1192.8508936382952;1205.2286536616573;1253.154120968352;1256.1018871506826;10704.678859634223;10847.969442902911;10941.617043403523;10949.178811589163;10969.50637221335;4427.158839820424;4612.446506419184;4698.130845793539;4699.671023028423;4703.129920964632;9628.277879159092;9640.42157470554;9648.324617276863;9661.661841835643;9679.516024198761;5261.105612045962;5267.130923397596;5269;5276.683398996072;5291.814786482455;3784.413415920537;4478.417805685305;4598.563136502027;4669.4396672399525;4773.880652983634;3796.867109651149;3818.9427158592644;3858.459815625815;3915.8433662653742;3972.245273398045;1253.9623811285676;1274.4456161569562;1277.5656276865725;1290.0969321475004;1297.7534268489146;734.4565021883767;740.6185814305637;764.636797521173;769.1231995525106;802.9759107226793;5363.678179309254;5418.431064738224;5558;5602.97198514013;5605.635633683797;555.1506195520859;558.0847410247197;579.7999690106907;587.5006244692063;588.2499812738793;3534.293141371736;3826.8086595670106;3911.6675082617726;4055.8174882130374;4061.3095773718537;9939.55272012761;9945.651902183388;10139.239557033252;10155.187584993182;10170.186385089175;10001.699949001542;10015.799684006279;10022.098011179029;10029.247806414549;10081.344712593658;6230;7905.486143400545;7908.960455197687;7910.248526389983;7911.564863932424;4649;5458.61789674719;5647.181158731879;5660.547156227574;5774.971125144515;8769.736907892773;8989.280857531512;9040.502772347583;9108.544572771203;9238.445693258158;9285.767855803659;9303.116203960613;9341.719748407559;9373.1564159226;9480.957094719437;7183.066201393785;7202.855942881218;7323.890141647986;7327.413806895543;7336.119665640157;7846.882296765439;7861.764147075597;7910.802229944297;7941.602919853869;8012.439129260896;7040.859182816417;7103.9289607103265;7132.072830531998;7164.605946672983;7253.673584688596;7031.156261248683;7048.647567621701;7055.541389809646;7106.289371062852;7113.893291600527;6299;6323.272823625158;6349.936500634934;6420;6447.387498187664;4092;4179.2059508693255;4912.484189160086;5050.3182070420025;5078.162103252922;4915.582175515121;4990.4011518618;5051.419086805065;5115.1559992600805;5242.921356179737 06:21:34 INFO - PROCESS | 1637 | |4;prototype.html;353.9981851360649;366.3533862732276;369.81139618794424;376.64783427495274;377.78277490442986;4730.114630075651;4763.9519305752765;5056.544910958013;5140.845774626762;5339.973300133499;4503.4463110227;4815.566599006089;5181.911798522306;5322.2282768998475;5341.172118321657;3528.541289632344;3575.982120089409;3734.8319325630277;3734.9626503734885;4056.9391459128137;3366.9831650841834;3992.800359982004;3998.720729184366;4007.2185923744883;4025.657819085381;3364.814935178564;3609.8736544220956;3711.517502724642;4009.3985902114623;4021.256067627484;3742.5883152853435;3822.0444888777806;4021.336479480882;4034.979825100885;4058.289799285128;3437.673421024999;3440.4323286657673;3449.2411669432686;3483.425234836249;3493.615702272748;4212.831486740527;4466.731996080245;5084.9745751271375;5092.719900405476;5107.463716309771;3837.1198112924467;4072.6334629883454;4512.390827238347;4522.909541809146;4577.885552861205;4922.26166075088;5267.604929630293;5554.333699890017;5596.692181929992;5665.3768085510565;3397.405454045532;3827.3876179811373;3853.8651147209994;3857.242071918564;3857.633524815138;4808.845876989532;5750.3099628044065;5771.826845194651;5899.675517846516;5967;3532.8586856525453;3720.4419337099653;3738.7009039277336;3872.9225415491533;3894.4937158169255;3905.6094390560716;3921.3531964622016;3939.1924655445564;3942.8225729842225;3951.150502641946;4559.635229181657;4578;4595.1728688835665;4603.562661547147;4608.331791890158;4130.847931621916;4873;4922.163232250526;5694.1279929648235;5745.33385318253;1016.3952448293254;1098.81869491533;1233.3031837012059;1271.968200794969;1279.5777393460137;723.583939234942;740.8592367450167;771.9807004824925;825.9922894069226;829.2205326992728;4243.554426785205;4370.956290437119;4514.616257618073;4981.825636102719;5039.52272931676;4712.151812673752;5118.8976220476125;5498.958265674726;5511.3937466878615;5521.337439507285;4518.502964673883;4602.05657840142;4973.32860063894;5027.924581131286;5030.220315851049;2635.3806855388966;2682.8792704328353;2778.7769784172583;2895.478813813492;2898.3623602807347;5159.355080614923;5625.38120806711;5704.800331988444;5706.686132262766;5771.394003629643;4632.62938964882;4691.906161876744;5490.396056433789;5556.888862222733;5907.970460147758;5419.051665958442;5490.972545137329;5765.365809760839;5927.733252003584;6024.800947654075;5073.575336647674;5776.508996735324;5793.742757821627;5797.333306669619;5821.27234095738;5460.426655201306;5692.669788173799;5724.341700704473;5734.2258795062135;5760;4864.562189402971;5856.765729370777;5943.286805583185;5962.522998160223;5980.551458640706;3281.4249160402665;3383.225754880974;3863.594322596087;3879.903002424962;3888.3778595424596;4941.9243917553995;5322.9467705324;5567.025770490229;5635.436456354331;5641.717914104361;5542.916856247079;5557.41647127062;5560.527355174855;5569.916451253315;5584.581156413204;4849.733264670477;5547.583931205257;5555.388907220284;5570.718734690962;5593.664380137205;4919.7615085136895;5193.636445448782;5244.711540865136;5470.425605311385;5476.220228425857;4850.902981940412;5521.696306703009;5527.032769265443;5567.10926251814;5595.804146855004;4657.627389808875;5386.9226154769985;5436.320459942507;5440.863978400572;5456.754446050017 06:21:34 INFO - PROCESS | 1637 | |5;yui.html;692.9757458488948;696.4393663101204;717.1143637607557;770.6801677303919;827.5117680568466;13918.234497102669;14686;14700.338484768212;14701.04443211192;14701.970862039649;13357.666058348523;13448.327583620805;13466.394012269448;13502.459901603948;13540.796888046663;828.2835347424465;847.8473874702582;851.5231470376609;852.3096292003466;864.5763575847852;853.5988085599789;855.9400841941067;856.7344123321759;857.100044952797;858.4806192253659;910.3036177324319;910.8451563234233;912.5072460871122;916.6333466613341;917.6421195733669;902.5306840442933;903.2773780975226;903.7243640689587;903.8870141232313;904.8506996345595;671.8018184635545;673.2627772589008;674.5446823394238;675.5271310082912;677.806825054862;14300.141991480545;14441;14454.421823127062;14459;14691.191984440851;13655.112417692819;13889.861101389059;13904.652383690489;13907;14001.5099471518;10079.89920100797;11381.260218085798;11544.53821847125;11673.299602023906;11996.880031199664;3595.496630471736;3618.8371523281253;3633.9273214535824;3710.5547334319785;3759.548854137521;78883;87065;102228;102247.46628800574;103736;10479.423631700178;10560.366378017343;10850.45747712627;10966.506507207196;11490.023348015513;21666.24168154107;21776;21978;22042.88978555097;22208.222712204995;7464.029676142067;7549.773506794805;7557.659905304275;7614.733484328022;7661.693532258647;4167.4790651168605;4184.769837658897;4233.618974292329;4275.3800698898485;4368.672349573794;3594.8202589870402;3625.510556074949;3684.3184010958057;3707.332680117551;3708;673.1253459116435;679.4258851270669;679.4598294355999;683.4122654517112;685.9828504287433;650.3983814971133;652.2727159217438;674.308833445722;677.7051982387677;719.4711886763225;9609.615615375447;9869.104611912331;10001.899981000242;10080.596776128872;10240.64157754475;696.1611258433576;700.5201437015559;705.8976448415053;706.2055187913597;723.5188599581325;3929.2534418460973;4636.976815115903;4724.787384567634;4753.33453316536;4843.903121937541;78662.6066869662;87552;96039;98220;100316.99683003222;73670;83907.74138387929;85036;86462.13537864666;89683;73741.52516949738;74603;75848;76966.84549731646;77081;10037.5483103262;10412.895871041193;10474.738131546772;10523.894761052292;10711.839322410015;49768.502314977835;50206.7489662564;50631;51160.74419627878;52421.7378913103;64816.35183648103;66280.6685966567;68086;68413.3158668407;68567.65716171387;12001.81997270059;12048;12062.45718942632;12103.818442723543;12756.107072504836;14178.929105354408;14335;14499.130052196902;14972.47596334123;15051.698966020836;12847.486100556247;12850.228986260481;13136.671583210496;13243.139195952232;13561.864381356454;13005.349732513525;13028.804567931678;13049.869501304865;13336;13366.866331336561;12395.380230988234;12974.740505189655;13063.281519516519;13247.801282980956;13266.403011864297;9926.602935882484;10360.430176340373;10800.513976870903;10834.262179548043;11168.106551476025;8669.349798764992;8708.259797917248;10371.792564148825;10389.220808439246;10651.147908167173 06:21:34 INFO - PROCESS | 1637 | __end_tp_report 06:21:34 INFO - PROCESS | 1637 | __start_cc_report 06:21:34 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1509 06:21:34 INFO - PROCESS | 1637 | __end_cc_report 06:21:34 INFO - PROCESS | 1637 | __startTimestamp1486477294136__endTimestamp 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - PROCESS | 1637 | ------- Summary: start ------- 06:21:34 INFO - PROCESS | 1637 | Number of tests: 6 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5550.98 Median:5712.91 stddev:622.47 (10.9%) stddev-sans-first:589.91 06:21:34 INFO - PROCESS | 1637 | Values: 2946.8 3246.0 3265.3 3272.5 3357.5 5361.7 5430.3 5497.8 5581.7 5593.6 5158.7 5435.5 5497.3 5516.7 5570.7 4950.9 4955.6 5035.0 5147.6 5150.5 4248.9 5101.1 5132.1 5169.2 5170.5 4964.7 4985.1 5005.5 5026.9 5071.0 4966.4 5006.0 5048.5 5100.9 5110.9 4972.3 4987.7 5008.2 5051.3 5078.0 5304.4 5362.4 5454.5 5455.8 5467.9 5842.4 5862.2 5864.8 5873.4 5885.4 5844.8 5970.3 5985.9 5987.8 6031.0 5570.4 5572.6 5587.9 5591.3 5603.0 6114.5 6148.7 6205.2 6244.3 6328.7 5712.9 5759.6 5767.3 5907.8 6062.9 6100.1 6176.8 6177.8 6181.2 6182.8 6077.2 6111.1 6114.5 6124.7 6130.9 5815.0 5846.1 5849.4 6094.2 6100.7 4927.9 4929.2 4932.3 4935.9 4965.1 4364.5 4366.2 4366.3 4376.7 4401.2 5352.8 5442.7 5465.0 5625.0 5652.8 5101.5 6096.4 6104.0 6120.9 6208.8 5559.3 5568.4 5574.1 5584.1 5605.1 5296.4 5340.8 5343.4 5348.4 5382.8 5722.5 6142.0 6149.4 6178.0 6198.3 5994.6 5996.1 6014.8 6015.9 6030.7 6194.8 6244.1 6255.4 6270.0 6280.0 5789.6 5791.4 5817.6 5915.2 5948.4 6147.4 6207.8 6211.0 6213.6 6227.1 5704.5 5755.8 5774.4 5795.9 5833.1 5588.1 5627.3 5882.7 5893.1 5913.1 5548.0 5585.9 5593.3 5726.7 5740.5 5782.7 5796.0 5841.3 5879.9 5950.0 5748.2 5752.4 5757.0 5759.8 5760.0 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:57629.61 Median:206945.31 stddev:112515.14 (54.4%) stddev-sans-first:112748.87 06:21:34 INFO - PROCESS | 1637 | Values: 449.8 471.9 502.4 508.4 509.0 38156.4 40503.4 40505.4 40520.6 40561.6 41447.6 41839.0 41964.0 42026.0 42046.4 7953.2 8167.9 9232.8 9459.2 9493.8 11024.3 11340.4 11344.3 11454.7 11456.1 11335.5 11386.1 11393.2 11412.0 11433.5 8011.3 8250.0 11073.4 11248.9 11328.5 11058.7 11287.1 11288.9 11301.5 11308.4 39815.0 40060.0 40091.2 40144.0 40227.8 3968.3 3968.3 3974.8 4005.3 4121.5 30488.0 30623.0 30657.8 30658.4 30743.2 3052.3 3230.1 3290.5 3292.3 3293.9 325042.0 328942.0 333482.0 414610.0 435748.8 50514.7 52274.7 52392.0 58783.8 59385.7 63235.7 64365.0 64378.4 68066.0 68068.3 15178.0 15288.8 15300.4 15485.5 15485.8 11912.8 11934.6 11986.9 11990.3 12012.6 16784.6 16818.4 16852.5 16857.8 16884.0 6776.7 6793.7 6831.7 6840.8 6870.1 829.5 831.6 848.4 850.6 857.4 29142.2 29375.7 29395.0 29801.0 30121.0 1022.1 1035.5 1054.2 1067.8 1150.0 13180.7 15278.2 15356.6 15663.1 15758.0 506961.0 510770.0 511627.0 513123.0 514973.0 57187.3 57672.0 57821.0 57833.1 57917.0 1093.9 1272.7 1389.8 1400.6 1401.6 27883.4 28647.6 28955.3 29432.1 29551.0 49762.8 50219.0 50268.0 50343.7 50535.5 376455.0 376940.0 377173.0 377402.0 378278.3 32849.7 33595.0 33740.7 38334.9 38869.0 41271.8 41546.8 44959.3 46081.0 47853.8 38089.6 38605.6 38888.4 39318.6 39689.6 37399.0 37435.6 37736.8 38636.6 38889.4 35131.6 36233.9 36538.6 36797.0 37322.8 28309.6 33587.3 33841.3 34228.8 34287.5 19387.9 23871.4 24004.0 24537.6 24613.9 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:36240.56 Median:21763.06 stddev:99197.93 (455.8%) stddev-sans-first:99439.68 06:21:34 INFO - PROCESS | 1637 | Values: 386.2 387.8 391.9 398.0 418.3 13920.7 17936.5 19327.4 20558.5 20589.4 15658.3 17937.6 20683.7 20777.4 20998.2 5734.2 5950.8 6087.1 6095.3 6096.3 5998.8 6005.4 6054.8 7403.6 7737.8 7785.9 7787.5 7905.6 7914.4 7944.0 7712.2 7836.4 7888.9 7922.4 7941.9 7450.4 7564.5 7635.1 7670.0 7680.6 27125.5 27472.2 27497.2 28445.6 28456.7 2819.4 2858.1 2877.4 2878.6 2894.7 12359.6 15546.9 16423.0 17193.9 17227.6 1613.9 1899.2 1915.3 1918.7 1969.9 143347.3 156375.0 160250.0 162361.0 165562.0 16384.3 21341.0 21530.6 21752.9 21773.2 39097.6 39480.8 39534.2 39738.8 39865.6 8949.4 8997.1 10100.0 10363.7 10485.4 7501.2 7950.3 8549.8 8580.7 8600.7 9586.6 9862.7 10655.8 10732.7 10900.2 3500.8 3982.8 4001.4 4075.8 4093.8 993.0 996.4 996.9 1003.7 1029.8 16864.9 19968.6 19988.2 20627.6 20683.4 1021.9 1024.4 1044.1 1048.2 1055.2 9931.6 10490.5 10634.2 10755.2 10778.7 343081.0 354682.0 693840.0 707225.0 709714.0 109343.5 123218.8 123304.4 126096.0 131025.0 1019.4 1025.0 1045.0 1045.6 1051.7 17498.7 17726.0 17787.6 17868.6 18186.5 51100.5 51727.5 51744.7 52414.5 52566.0 70185.3 71981.0 73166.0 73366.0 73643.8 8104.6 8168.5 8217.9 8275.0 8288.3 10833.6 12389.1 12445.1 12607.6 12674.2 10583.7 10592.3 10964.1 11021.2 11044.3 10646.8 10757.6 10802.6 11006.9 11013.9 10439.5 11687.2 11848.1 11886.2 11916.7 7917.7 8021.8 8259.9 8283.4 8433.2 10148.3 10785.6 11029.1 11056.0 11103.1 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5424.41 Median:5209.19 stddev:3026.73 (58.1%) stddev-sans-first:3018.78 06:21:34 INFO - PROCESS | 1637 | Values: 1227.7 1377.6 1410.5 1436.3 1440.4 8172.9 8394.8 8576.9 8577.7 8584.9 8237.8 8392.5 8531.9 8542.2 8629.9 2255.4 2295.8 2299.2 2299.4 2303.6 2171.9 2260.5 2261.8 2264.2 2268.6 1950.2 2023.4 2026.9 2185.3 2279.2 1924.9 2222.5 2263.8 2274.4 2275.2 2222.4 2285.0 2288.7 2291.6 2294.6 8394.7 8463.0 8471.1 8474.3 8512.6 4452.4 5081.9 5192.6 5225.8 5248.1 3277.2 3386.6 3432.4 3438.3 3440.2 1162.5 1192.9 1205.2 1253.2 1256.1 10704.7 10848.0 10941.6 10949.2 10969.5 4427.2 4612.4 4698.1 4699.7 4703.1 9628.3 9640.4 9648.3 9661.7 9679.5 5261.1 5267.1 5269.0 5276.7 5291.8 3784.4 4478.4 4598.6 4669.4 4773.9 3796.9 3818.9 3858.5 3915.8 3972.2 1254.0 1274.4 1277.6 1290.1 1297.8 734.5 740.6 764.6 769.1 803.0 5363.7 5418.4 5558.0 5603.0 5605.6 555.2 558.1 579.8 587.5 588.2 3534.3 3826.8 3911.7 4055.8 4061.3 9939.6 9945.7 10139.2 10155.2 10170.2 10001.7 10015.8 10022.1 10029.2 10081.3 6230.0 7905.5 7909.0 7910.2 7911.6 4649.0 5458.6 5647.2 5660.5 5775.0 8769.7 8989.3 9040.5 9108.5 9238.4 9285.8 9303.1 9341.7 9373.2 9481.0 7183.1 7202.9 7323.9 7327.4 7336.1 7846.9 7861.8 7910.8 7941.6 8012.4 7040.9 7103.9 7132.1 7164.6 7253.7 7031.2 7048.6 7055.5 7106.3 7113.9 6299.0 6323.3 6349.9 6420.0 6447.4 4092.0 4179.2 4912.5 5050.3 5078.2 4915.6 4990.4 5051.4 5115.2 5242.9 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4430.71 Median:4896.38 stddev:1383.98 (28.3%) stddev-sans-first:1353.62 06:21:34 INFO - PROCESS | 1637 | Values: 354.0 366.4 369.8 376.6 377.8 4730.1 4764.0 5056.5 5140.8 5340.0 4503.4 4815.6 5181.9 5322.2 5341.2 3528.5 3576.0 3734.8 3735.0 4056.9 3367.0 3992.8 3998.7 4007.2 4025.7 3364.8 3609.9 3711.5 4009.4 4021.3 3742.6 3822.0 4021.3 4035.0 4058.3 3437.7 3440.4 3449.2 3483.4 3493.6 4212.8 4466.7 5085.0 5092.7 5107.5 3837.1 4072.6 4512.4 4522.9 4577.9 4922.3 5267.6 5554.3 5596.7 5665.4 3397.4 3827.4 3853.9 3857.2 3857.6 4808.8 5750.3 5771.8 5899.7 5967.0 3532.9 3720.4 3738.7 3872.9 3894.5 3905.6 3921.4 3939.2 3942.8 3951.2 4559.6 4578.0 4595.2 4603.6 4608.3 4130.8 4873.0 4922.2 5694.1 5745.3 1016.4 1098.8 1233.3 1272.0 1279.6 723.6 740.9 772.0 826.0 829.2 4243.6 4371.0 4514.6 4981.8 5039.5 4712.2 5118.9 5499.0 5511.4 5521.3 4518.5 4602.1 4973.3 5027.9 5030.2 2635.4 2682.9 2778.8 2895.5 2898.4 5159.4 5625.4 5704.8 5706.7 5771.4 4632.6 4691.9 5490.4 5556.9 5908.0 5419.1 5491.0 5765.4 5927.7 6024.8 5073.6 5776.5 5793.7 5797.3 5821.3 5460.4 5692.7 5724.3 5734.2 5760.0 4864.6 5856.8 5943.3 5962.5 5980.6 3281.4 3383.2 3863.6 3879.9 3888.4 4941.9 5322.9 5567.0 5635.4 5641.7 5542.9 5557.4 5560.5 5569.9 5584.6 4849.7 5547.6 5555.4 5570.7 5593.7 4919.8 5193.6 5244.7 5470.4 5476.2 4850.9 5521.7 5527.0 5567.1 5595.8 4657.6 5386.9 5436.3 5440.9 5456.8 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19514.18 Median:4176.12 stddev:27341.93 (654.7%) stddev-sans-first:27382.11 06:21:34 INFO - PROCESS | 1637 | Values: 693.0 696.4 717.1 770.7 827.5 13918.2 14686.0 14700.3 14701.0 14702.0 13357.7 13448.3 13466.4 13502.5 13540.8 828.3 847.8 851.5 852.3 864.6 853.6 855.9 856.7 857.1 858.5 910.3 910.8 912.5 916.6 917.6 902.5 903.3 903.7 903.9 904.9 671.8 673.3 674.5 675.5 677.8 14300.1 14441.0 14454.4 14459.0 14691.2 13655.1 13889.9 13904.7 13907.0 14001.5 10079.9 11381.3 11544.5 11673.3 11996.9 3595.5 3618.8 3633.9 3710.6 3759.5 78883.0 87065.0 102228.0 102247.5 103736.0 10479.4 10560.4 10850.5 10966.5 11490.0 21666.2 21776.0 21978.0 22042.9 22208.2 7464.0 7549.8 7557.7 7614.7 7661.7 4167.5 4184.8 4233.6 4275.4 4368.7 3594.8 3625.5 3684.3 3707.3 3708.0 673.1 679.4 679.5 683.4 686.0 650.4 652.3 674.3 677.7 719.5 9609.6 9869.1 10001.9 10080.6 10240.6 696.2 700.5 705.9 706.2 723.5 3929.3 4637.0 4724.8 4753.3 4843.9 78662.6 87552.0 96039.0 98220.0 100317.0 73670.0 83907.7 85036.0 86462.1 89683.0 73741.5 74603.0 75848.0 76966.8 77081.0 10037.5 10412.9 10474.7 10523.9 10711.8 49768.5 50206.7 50631.0 51160.7 52421.7 64816.4 66280.7 68086.0 68413.3 68567.7 12001.8 12048.0 12062.5 12103.8 12756.1 14178.9 14335.0 14499.1 14972.5 15051.7 12847.5 12850.2 13136.7 13243.1 13561.9 13005.3 13028.8 13049.9 13336.0 13366.9 12395.4 12974.7 13063.3 13247.8 13266.4 9926.6 10360.4 10800.5 10834.3 11168.1 8669.3 8708.3 10371.8 10389.2 10651.1 06:21:34 INFO - PROCESS | 1637 | -------- Summary: end -------- 06:21:34 INFO - PROCESS | 1637 | 06:21:34 INFO - TEST-INFO | 1637: exit 0 06:21:34 INFO - TEST-OK | dromaeo_css | took 1119842ms 06:21:34 INFO - TEST-START | kraken 06:21:34 INFO - operating with platform_type : mac_ 06:21:34 INFO - Initialising browser for kraken test... 06:21:34 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpA8aPjo/profile http://localhost:49248/getInfo.html) 06:21:36 INFO - PROCESS | 1647 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:21:37 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 06:21:37 INFO - PROCESS | 1647 | colorDepth:24 06:21:37 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 06:21:37 INFO - PROCESS | 1647 | __metrics 06:21:37 INFO - PROCESS | 1647 | 1486477297296 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 06:21:37 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:21:37 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:21:38 INFO - TEST-INFO | 1647: exit 0 06:21:38 INFO - Browser initialized. 06:21:38 INFO - Running cycle 1/1 for kraken test... 06:21:38 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpA8aPjo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:21:50 INFO - PROCESS | 1648 | RSS: Main: 237359104 06:21:50 INFO - PROCESS | 1648 | 06:21:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 06:21:54 INFO - PROCESS | 1648 | RSS: Main: 288829440 06:21:54 INFO - PROCESS | 1648 | 06:21:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 06:21:57 INFO - PROCESS | 1648 | RSS: Main: 290590720 06:21:57 INFO - PROCESS | 1648 | 06:22:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 06:22:01 INFO - PROCESS | 1648 | RSS: Main: 382062592 06:22:01 INFO - PROCESS | 1648 | 06:22:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 06:22:03 INFO - PROCESS | 1648 | RSS: Main: 285790208 06:22:03 INFO - PROCESS | 1648 | 06:22:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:22:06 INFO - PROCESS | 1648 | RSS: Main: 288944128 06:22:06 INFO - PROCESS | 1648 | 06:22:09 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 06:22:09 INFO - PROCESS | 1648 | RSS: Main: 329965568 06:22:09 INFO - PROCESS | 1648 | 06:22:13 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 06:22:13 INFO - PROCESS | 1648 | RSS: Main: 332718080 06:22:13 INFO - PROCESS | 1648 | 06:22:16 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 06:22:17 INFO - PROCESS | 1648 | RSS: Main: 330932224 06:22:17 INFO - PROCESS | 1648 | 06:22:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:22:19 INFO - PROCESS | 1648 | RSS: Main: 306667520 06:22:19 INFO - PROCESS | 1648 | 06:22:21 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:22:21 INFO - PROCESS | 1648 | RSS: Main: 310140928 06:22:21 INFO - PROCESS | 1648 | 06:22:24 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:22:24 INFO - PROCESS | 1648 | RSS: Main: 311447552 06:22:24 INFO - PROCESS | 1648 | 06:22:27 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:22:28 INFO - PROCESS | 1648 | RSS: Main: 330723328 06:22:28 INFO - PROCESS | 1648 | 06:22:31 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:22:32 INFO - PROCESS | 1648 | RSS: Main: 324399104 06:22:32 INFO - PROCESS | 1648 | 06:22:33 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 06:22:34 INFO - PROCESS | 1648 | RSS: Main: 369410048 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | __start_tp_report 06:22:34 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 06:22:34 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 06:22:34 INFO - PROCESS | 1648 | |i|pagename|runs| 06:22:34 INFO - PROCESS | 1648 | |0;ai-astar;115;114;115;113;115;115;114;113;113;114 06:22:34 INFO - PROCESS | 1648 | |1;audio-beat-detection;140;109;110;143;142;136;113;110;131;111 06:22:34 INFO - PROCESS | 1648 | |2;audio-dft;184;183;183;183;183;178;184;200;259;184 06:22:34 INFO - PROCESS | 1648 | |3;audio-fft;79;58;59;87;61;59;58;60;74;72 06:22:34 INFO - PROCESS | 1648 | |4;audio-oscillator;89;70;84;70;68;69;85;68;67;101 06:22:34 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;90;86;88;85;87;84;90;86;88;85 06:22:34 INFO - PROCESS | 1648 | |6;imaging-darkroom;93;94;103;153;124;94;122;101;93;93 06:22:34 INFO - PROCESS | 1648 | |7;imaging-desaturate;85;86;88;84;87;86;84;86;85;86 06:22:34 INFO - PROCESS | 1648 | |8;json-parse-financial;58;56;57;57;58;60;58;59;58;58 06:22:34 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;64;73;71;67;76;71;69;70;71;70 06:22:34 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;90;88;86;96;90;86;89;91;106;108 06:22:34 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;139;139;135;163;147;125;160;153;179;146 06:22:34 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;172;173;175;150;177;241;236;172;171;173 06:22:34 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;63;79;88;88;70;67;65;65;63 06:22:34 INFO - PROCESS | 1648 | __end_tp_report 06:22:34 INFO - PROCESS | 1648 | __start_cc_report 06:22:34 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2057 06:22:34 INFO - PROCESS | 1648 | __end_cc_report 06:22:34 INFO - PROCESS | 1648 | __startTimestamp1486477354215__endTimestamp 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | ------- Summary: start ------- 06:22:34 INFO - PROCESS | 1648 | Number of tests: 14 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.50 stddev:0.88 (0.8%) stddev-sans-first:0.87 06:22:34 INFO - PROCESS | 1648 | Values: 115.0 114.0 115.0 113.0 115.0 115.0 114.0 113.0 113.0 114.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:133.50 stddev:15.05 (11.3%) stddev-sans-first:14.88 06:22:34 INFO - PROCESS | 1648 | Values: 140.0 109.0 110.0 143.0 142.0 136.0 113.0 110.0 131.0 111.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:192.10 Median:184.00 stddev:24.19 (13.1%) stddev-sans-first:25.48 06:22:34 INFO - PROCESS | 1648 | Values: 184.0 183.0 183.0 183.0 183.0 178.0 184.0 200.0 259.0 184.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:66.70 Median:66.50 stddev:10.50 (15.8%) stddev-sans-first:10.15 06:22:34 INFO - PROCESS | 1648 | Values: 79.0 58.0 59.0 87.0 61.0 59.0 58.0 60.0 74.0 72.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:70.00 stddev:11.82 (16.9%) stddev-sans-first:11.72 06:22:34 INFO - PROCESS | 1648 | Values: 89.0 70.0 84.0 70.0 68.0 69.0 85.0 68.0 67.0 101.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.50 stddev:2.08 (2.4%) stddev-sans-first:1.88 06:22:34 INFO - PROCESS | 1648 | Values: 90.0 86.0 88.0 85.0 87.0 84.0 90.0 86.0 88.0 85.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:107.00 Median:93.00 stddev:20.02 (21.5%) stddev-sans-first:20.59 06:22:34 INFO - PROCESS | 1648 | Values: 93.0 94.0 103.0 153.0 124.0 94.0 122.0 101.0 93.0 93.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.25 (1.5%) stddev-sans-first:1.30 06:22:34 INFO - PROCESS | 1648 | Values: 85.0 86.0 88.0 84.0 87.0 86.0 84.0 86.0 85.0 86.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:1.10 (1.9%) stddev-sans-first:1.17 06:22:34 INFO - PROCESS | 1648 | Values: 58.0 56.0 57.0 57.0 58.0 60.0 58.0 59.0 58.0 58.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.00 stddev:3.22 (4.5%) stddev-sans-first:2.52 06:22:34 INFO - PROCESS | 1648 | Values: 64.0 73.0 71.0 67.0 76.0 71.0 69.0 70.0 71.0 70.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:89.50 stddev:7.92 (8.8%) stddev-sans-first:8.32 06:22:34 INFO - PROCESS | 1648 | Values: 90.0 88.0 86.0 96.0 90.0 86.0 89.0 91.0 106.0 108.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:150.00 stddev:15.69 (10.5%) stddev-sans-first:16.26 06:22:34 INFO - PROCESS | 1648 | Values: 139.0 139.0 135.0 163.0 147.0 125.0 160.0 153.0 179.0 146.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.00 Median:174.00 stddev:29.70 (17.1%) stddev-sans-first:31.18 06:22:34 INFO - PROCESS | 1648 | Values: 172.0 173.0 175.0 150.0 177.0 241.0 236.0 172.0 171.0 173.0 06:22:34 INFO - PROCESS | 1648 | 06:22:34 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:68.50 stddev:9.85 (14.4%) stddev-sans-first:10.31 06:22:34 INFO - PROCESS | 1648 | Values: 67.0 63.0 79.0 88.0 88.0 70.0 67.0 65.0 65.0 63.0 06:22:34 INFO - PROCESS | 1648 | -------- Summary: end -------- 06:22:34 INFO - PROCESS | 1648 | 06:22:35 INFO - TEST-INFO | 1648: exit 0 06:22:35 INFO - TEST-OK | kraken | took 60379ms 06:22:35 INFO - SUITE-END | took 1180s 06:22:35 INFO - Completed test suite (00:19:40) 06:22:35 INFO - javascript benchmark 06:22:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7292.258692599299, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2946.7937244392874, 3245.9612923864343, 3265.2979609383983, 3272.4764037753957, 3357.513160591714, 5361.651492652974, 5430.294061771964, 5497.752601132948, 5581.665100093992, 5593.608447408683, 5158.664686795365, 5435.456454354563, 5497.312835895513, 5516.668999860001, 5570.6657600543995, 4950.87622809429, 4955.578775804061, 5034.974825125869, 5147.639665223436, 5150.510701483353, 4248.893777655568, 5101.081805275049, 5132.076226279269, 5169.224616307565, 5170.457102004292, 4964.652474326798, 4985.127602669519, 5005.499450054966, 5026.949730502684, 5070.985802839428, 4966.354373931402, 5005.974970125162, 5048.545630893201, 5100.872478188038, 5110.872228194287, 4972.328735620717, 4987.700737955698, 5008.198688209869, 5051.318072060299, 5077.974610126963, 5304.363476382859, 5362.383325917531, 5454.509094181503, 5455.781768729246, 5467.863303417406, 5842.415758424166, 5862.237909071836, 5864.794732184396, 5873.383294754075, 5885.382034886318, 5844.766209351621, 5970.283565972111, 5985.940140598582, 5987.790427335066, 6030.969845150747, 5570.359408667974, 5572.637778544382, 5587.944120558824, 5591.329040515164, 5602.971985140048, 6114.541409394314, 6148.6925653717135, 6205.193324867829, 6244.344343843922, 6328.6835658216905, 5712.914306285409, 5759.568032397587, 5767.307923049177, 5907.793227237026, 6062.878742425215, 6100.054491553817, 6176.7838125665385, 6177.752889884354, 6181.196444462191, 6182.783602573888, 6077.24034495688, 6111.113888486234, 6114.484818317571, 6124.724387402578, 6130.938690613037, 5815.0, 5846.064629659234, 5849.415058494202, 6094.177286066413, 6100.694965251809, 4927.876803079952, 4929.186684197139, 4932.284818701268, 4935.925961110586, 4965.0566392385335, 4364.541723119027, 4366.170427618742, 4366.3013917773005, 4376.737395756265, 4401.207782599099, 5352.839414817562, 5442.673439593558, 5464.972675136599, 5624.971875140598, 5652.830415087554, 5101.489851014869, 6096.359882212392, 6104.0347610180015, 6120.9347721082095, 6208.782692605737, 5559.277293951841, 5568.359638641608, 5574.08027675429, 5584.050711379075, 5605.103183490622, 5296.417394086648, 5340.786368545215, 5343.412224655285, 5348.3849357323625, 5382.811601593926, 5722.484976352148, 6141.977005061692, 6149.354317796746, 6177.980633195472, 6198.256209254918, 5994.55040871945, 5996.070609055603, 6014.789482368096, 6015.87968240624, 6030.668313242813, 6194.783182588768, 6244.125822384779, 6255.437010669061, 6269.96865015672, 6279.96860015697, 5789.623674461147, 5791.362950075404, 5817.563682723728, 5915.201447804491, 5948.405159484017, 6147.354527774519, 6207.81376558704, 6211.0, 6213.565050446426, 6227.097070924781, 5704.515116215168, 5755.827325180251, 5774.42255774436, 5795.913061304, 5833.066709326487, 5588.133839254922, 5627.268455100811, 5882.735276912464, 5893.116032595145, 5913.113033045078, 5548.029094908456, 5585.888282234414, 5593.328800543798, 5726.685032323096, 5740.454656807597, 5782.653040817564, 5796.0, 5841.32824725162, 5879.911801323069, 5950.0, 5748.166515855094, 5752.424757524214, 5757.0, 5759.798407055733, 5760.0], "value": 5511.211006908919, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.8245684183168, 471.89146496305847, 502.4247236913732, 508.4356364435477, 509.0302972836747, 38156.42765358518, 40503.39244911325, 40505.392419113734, 40520.59479405205, 40561.594384056225, 41447.58552414475, 41839.0, 41964.0, 42026.0, 42046.36930446038, 7953.164917683618, 8167.918320816805, 9232.769180770467, 9459.243260539175, 9493.772117480055, 11024.338539687604, 11340.432978351091, 11344.262622929484, 11454.71363215922, 11456.140789440784, 11335.546578136864, 11386.146039047064, 11393.202475826696, 11412.0, 11433.485493152786, 8011.319037881729, 8249.958750206271, 11073.446327683583, 11248.918949994277, 11328.49021794017, 11058.723531911686, 11287.097032237401, 11288.887111128866, 11301.49143288554, 11308.434578271053, 39815.0, 40059.998500037735, 40091.19817603631, 40144.0, 40227.79316620506, 3968.285708572456, 3968.3452230381954, 3974.82113304902, 4005.339119045354, 4121.505419349697, 30488.0, 30623.0, 30657.846710766527, 30658.38683226345, 30743.231419214473, 3052.343746094578, 3230.127865476332, 3290.5393244945726, 3292.3250733599552, 3293.884714034997, 325042.0, 328942.0, 333482.0, 414610.0, 435748.82125589484, 50514.737131571266, 52274.738626307386, 52392.0, 58783.82432351329, 59385.70307148523, 63235.735285294955, 64365.0, 64378.356216437234, 68066.0, 68068.31931680719, 15178.0, 15288.770668439982, 15300.387984480718, 15485.535433937, 15485.767713484307, 11912.761744765055, 11934.641960741192, 11986.940065299617, 11990.280583164864, 12012.63962081139, 16784.58038549046, 16818.411355602497, 16852.49442516702, 16857.83142168587, 16884.0, 6776.661166941732, 6793.660316984131, 6831.69257383419, 6840.794776156723, 6870.106886104874, 829.4815740162398, 831.6444195960546, 848.3976376772438, 850.6044689219542, 857.3655494933829, 29142.23129387191, 29375.706242937726, 29395.0, 29801.0, 30121.0, 1022.1056575496441, 1035.471909326234, 1054.177741361739, 1067.8344856547249, 1150.0339714639742, 13180.670483237804, 15278.15970121654, 15356.616084597974, 15663.138527380876, 15758.0, 506961.0, 510770.0, 511627.0, 513123.0, 514973.0, 57187.28438146862, 57672.0, 57821.0, 57833.132503012486, 57916.999999999156, 1093.906093906094, 1272.7009152849073, 1389.756792561318, 1400.621832105326, 1401.5865319730501, 27883.44233115286, 28647.57028644614, 28955.276118097216, 29432.11703648894, 29551.0, 49762.75118624384, 50218.99562008813, 50268.0, 50343.74828125836, 50535.49464505308, 376455.0, 376940.0, 377173.0, 377402.0, 378278.258261263, 32849.67150328466, 33594.992150235536, 33740.66259337375, 38334.876404942166, 38869.0, 41271.76184714343, 41546.79226603848, 44959.32561011653, 46081.0, 47853.76073119612, 38089.61910380972, 38605.613943860204, 38888.36137750418, 39318.6408815472, 39689.60310396975, 37399.0, 37435.62564374322, 37736.81131594325, 38636.61363386443, 38889.416658750706, 35131.64868351386, 36233.91298261056, 36538.63461365352, 36797.0, 37322.813385932895, 28309.575356370082, 33587.328253435284, 33841.32317353688, 34228.82885585556, 34287.48568771421, 19387.90306048461, 23871.40321491907, 24004.0, 24537.631935520625, 24613.945248155036], "value": 19460.369674169237, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.1716617854701, 387.80609695152424, 391.91419141914196, 397.96020397960206, 418.29517263411157, 13920.721585568283, 17936.461906142835, 19327.420177394666, 20558.486037849063, 20589.38231853047, 15658.295376708047, 17937.551561210978, 20683.689744653842, 20777.376678699664, 20998.160073597075, 5734.168545560901, 5950.821475355746, 6087.147799308101, 6095.268567771874, 6096.268447786272, 5998.790042348519, 6005.369436209201, 6054.757809687607, 7403.62981850908, 7737.845243095135, 7785.948896898931, 7787.454878158531, 7905.565193914361, 7914.3668506519625, 7944.046714394281, 7712.1902200269005, 7836.373090152802, 7888.881666775003, 7922.36621070313, 7941.880871786928, 7450.441216908754, 7564.546127232384, 7635.083789945242, 7670.04413874464, 7680.577568233745, 27125.457490850073, 27472.17583472479, 27497.17508474729, 28445.586529616372, 28456.715432845613, 2819.4361127774528, 2858.1425572328217, 2877.424515096968, 2878.553824157259, 2894.6526416830116, 12359.62921112368, 15546.89062187567, 16423.0, 17193.875771631843, 17227.569310767205, 1613.8547530722174, 1899.221319259111, 1915.2530513099903, 1918.7217853411178, 1969.9310524131586, 143347.28326358303, 156375.0, 160250.0, 162361.0, 165562.0, 16384.344626215057, 21341.0, 21530.56938861214, 21752.89123554372, 21773.23793667214, 39097.609023909965, 39480.80259598684, 39534.209315813525, 39738.801305993285, 39865.601343986185, 8949.418287811272, 8997.05894042365, 10100.040496152853, 10363.689089327332, 10485.423301718478, 7501.212372700897, 7950.3242224410415, 8549.829003419896, 8580.742577722678, 8600.655973761104, 9586.568604412818, 9862.65480708186, 10655.84016239757, 10732.678019659423, 10900.203910279452, 3500.842462089212, 3982.844974957288, 4001.359782434855, 4075.816588253536, 4093.7543747375253, 992.9573947355248, 996.4320337407609, 996.8504724291414, 1003.6587560229557, 1029.8249297619273, 16864.915675421544, 19968.60062798765, 19988.20047198125, 20627.58744825095, 20683.379498615064, 1021.8722659667542, 1024.3853687787416, 1044.1333693034762, 1048.1981284317503, 1055.208593554834, 9931.553080111413, 10490.527926243338, 10634.20243481742, 10755.247132700635, 10778.73053173677, 343081.0, 354682.0, 693840.0, 707225.0, 709714.0, 109343.45328273308, 123218.76781232252, 123304.38347808203, 126096.0, 131025.0, 1019.393460890754, 1024.9743756406149, 1044.9791004180026, 1045.5974449836867, 1051.7212938571286, 17498.73751893748, 17726.02506862135, 17787.644247114727, 17868.55328616795, 18186.45440636783, 51100.488995109576, 51727.48272517227, 51744.741276293375, 52414.47585524096, 52566.0, 70185.29814701992, 71981.0, 73166.0, 73366.0, 73643.79068627949, 8104.635291411782, 8168.529664660418, 8217.917820821714, 8274.958625206835, 8288.295494883003, 10833.566657333933, 12389.132760706663, 12445.079799863015, 12607.558735444216, 12674.239545626922, 10583.735406614896, 10592.311499752492, 10964.122870170208, 11021.173411993972, 11044.33733975964, 10646.787064258826, 10757.569697212337, 10802.621908233174, 11006.944965275123, 11013.944930275618, 10439.478026098514, 11687.240329378903, 11848.052155827685, 11886.227395219283, 11916.702082448008, 7917.722879699183, 8021.759347219593, 8259.917400825914, 8283.37874659391, 8433.156684331518, 10148.340357876714, 10785.62250321235, 11029.117670586173, 11056.0, 11103.056240219672], "value": 11012.618424349263, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.7114878003674, 1377.5729523847608, 1410.5486244401789, 1436.3321055709096, 1440.4238304678133, 8172.9182708172975, 8394.832103357929, 8576.871346929802, 8577.699780507683, 8584.8712269316, 8237.835243295145, 8392.454490458116, 8531.872021919677, 8542.23119919207, 8629.91370086299, 2255.4361409647545, 2295.8392912496047, 2299.2067736630834, 2299.402155439581, 2303.6199027160496, 2171.869687818728, 2260.4687898343877, 2261.7738226177335, 2264.230161745015, 2268.6370180771078, 1950.151684017457, 2023.3929821053698, 2026.898655067248, 2185.344396680989, 2279.1567120165564, 1924.9326273580427, 2222.4666080140732, 2263.8302127340517, 2274.374546999572, 2275.2377953385635, 2222.3999520129637, 2284.9885750571307, 2288.7482376938524, 2291.6104262275285, 2294.5640328337563, 8394.748157555283, 8462.957685211535, 8471.06818249992, 8474.279686226679, 8512.616932238065, 4452.443444569429, 5081.923771143436, 5192.584593232532, 5225.790968361299, 5248.107821670325, 3277.2298509850166, 3386.6444023377435, 3432.3993301172445, 3438.346714124308, 3440.17435815405, 1162.4594563528, 1192.8508936382952, 1205.2286536616573, 1253.154120968352, 1256.1018871506826, 10704.678859634223, 10847.969442902911, 10941.617043403523, 10949.178811589163, 10969.50637221335, 4427.158839820424, 4612.446506419184, 4698.130845793539, 4699.671023028423, 4703.129920964632, 9628.277879159092, 9640.42157470554, 9648.324617276863, 9661.661841835643, 9679.516024198761, 5261.105612045962, 5267.130923397596, 5269.0, 5276.683398996072, 5291.814786482455, 3784.413415920537, 4478.417805685305, 4598.563136502027, 4669.4396672399525, 4773.880652983634, 3796.867109651149, 3818.9427158592644, 3858.459815625815, 3915.8433662653742, 3972.245273398045, 1253.9623811285676, 1274.4456161569562, 1277.5656276865725, 1290.0969321475004, 1297.7534268489146, 734.4565021883767, 740.6185814305637, 764.636797521173, 769.1231995525106, 802.9759107226793, 5363.678179309254, 5418.431064738224, 5558.0, 5602.97198514013, 5605.635633683797, 555.1506195520859, 558.0847410247197, 579.7999690106907, 587.5006244692063, 588.2499812738793, 3534.293141371736, 3826.8086595670106, 3911.6675082617726, 4055.8174882130374, 4061.3095773718537, 9939.55272012761, 9945.651902183388, 10139.239557033252, 10155.187584993182, 10170.186385089175, 10001.699949001542, 10015.799684006279, 10022.098011179029, 10029.247806414549, 10081.344712593658, 6230.0, 7905.486143400545, 7908.960455197687, 7910.248526389983, 7911.564863932424, 4649.0, 5458.61789674719, 5647.181158731879, 5660.547156227574, 5774.971125144515, 8769.736907892773, 8989.280857531512, 9040.502772347583, 9108.544572771203, 9238.445693258158, 9285.767855803659, 9303.116203960613, 9341.719748407559, 9373.1564159226, 9480.957094719437, 7183.066201393785, 7202.855942881218, 7323.890141647986, 7327.413806895543, 7336.119665640157, 7846.882296765439, 7861.764147075597, 7910.802229944297, 7941.602919853869, 8012.439129260896, 7040.859182816417, 7103.9289607103265, 7132.072830531998, 7164.605946672983, 7253.673584688596, 7031.156261248683, 7048.647567621701, 7055.541389809646, 7106.289371062852, 7113.893291600527, 6299.0, 6323.272823625158, 6349.936500634934, 6420.0, 6447.387498187664, 4092.0, 4179.2059508693255, 4912.484189160086, 5050.3182070420025, 5078.162103252922, 4915.582175515121, 4990.4011518618, 5051.419086805065, 5115.1559992600805, 5242.921356179737], "value": 4321.038063334154, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.9981851360649, 366.3533862732276, 369.81139618794424, 376.64783427495274, 377.78277490442986, 4730.114630075651, 4763.9519305752765, 5056.544910958013, 5140.845774626762, 5339.973300133499, 4503.4463110227, 4815.566599006089, 5181.911798522306, 5322.2282768998475, 5341.172118321657, 3528.541289632344, 3575.982120089409, 3734.8319325630277, 3734.9626503734885, 4056.9391459128137, 3366.9831650841834, 3992.800359982004, 3998.720729184366, 4007.2185923744883, 4025.657819085381, 3364.814935178564, 3609.8736544220956, 3711.517502724642, 4009.3985902114623, 4021.256067627484, 3742.5883152853435, 3822.0444888777806, 4021.336479480882, 4034.979825100885, 4058.289799285128, 3437.673421024999, 3440.4323286657673, 3449.2411669432686, 3483.425234836249, 3493.615702272748, 4212.831486740527, 4466.731996080245, 5084.9745751271375, 5092.719900405476, 5107.463716309771, 3837.1198112924467, 4072.6334629883454, 4512.390827238347, 4522.909541809146, 4577.885552861205, 4922.26166075088, 5267.604929630293, 5554.333699890017, 5596.692181929992, 5665.3768085510565, 3397.405454045532, 3827.3876179811373, 3853.8651147209994, 3857.242071918564, 3857.633524815138, 4808.845876989532, 5750.3099628044065, 5771.826845194651, 5899.675517846516, 5967.0, 3532.8586856525453, 3720.4419337099653, 3738.7009039277336, 3872.9225415491533, 3894.4937158169255, 3905.6094390560716, 3921.3531964622016, 3939.1924655445564, 3942.8225729842225, 3951.150502641946, 4559.635229181657, 4578.0, 4595.1728688835665, 4603.562661547147, 4608.331791890158, 4130.847931621916, 4873.0, 4922.163232250526, 5694.1279929648235, 5745.33385318253, 1016.3952448293254, 1098.81869491533, 1233.3031837012059, 1271.968200794969, 1279.5777393460137, 723.583939234942, 740.8592367450167, 771.9807004824925, 825.9922894069226, 829.2205326992728, 4243.554426785205, 4370.956290437119, 4514.616257618073, 4981.825636102719, 5039.52272931676, 4712.151812673752, 5118.8976220476125, 5498.958265674726, 5511.3937466878615, 5521.337439507285, 4518.502964673883, 4602.05657840142, 4973.32860063894, 5027.924581131286, 5030.220315851049, 2635.3806855388966, 2682.8792704328353, 2778.7769784172583, 2895.478813813492, 2898.3623602807347, 5159.355080614923, 5625.38120806711, 5704.800331988444, 5706.686132262766, 5771.394003629643, 4632.62938964882, 4691.906161876744, 5490.396056433789, 5556.888862222733, 5907.970460147758, 5419.051665958442, 5490.972545137329, 5765.365809760839, 5927.733252003584, 6024.800947654075, 5073.575336647674, 5776.508996735324, 5793.742757821627, 5797.333306669619, 5821.27234095738, 5460.426655201306, 5692.669788173799, 5724.341700704473, 5734.2258795062135, 5760.0, 4864.562189402971, 5856.765729370777, 5943.286805583185, 5962.522998160223, 5980.551458640706, 3281.4249160402665, 3383.225754880974, 3863.594322596087, 3879.903002424962, 3888.3778595424596, 4941.9243917553995, 5322.9467705324, 5567.025770490229, 5635.436456354331, 5641.717914104361, 5542.916856247079, 5557.41647127062, 5560.527355174855, 5569.916451253315, 5584.581156413204, 4849.733264670477, 5547.583931205257, 5555.388907220284, 5570.718734690962, 5593.664380137205, 4919.7615085136895, 5193.636445448782, 5244.711540865136, 5470.425605311385, 5476.220228425857, 4850.902981940412, 5521.696306703009, 5527.032769265443, 5567.10926251814, 5595.804146855004, 4657.627389808875, 5386.9226154769985, 5436.320459942507, 5440.863978400572, 5456.754446050017], "value": 3998.4824576285264, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.9757458488948, 696.4393663101204, 717.1143637607557, 770.6801677303919, 827.5117680568466, 13918.234497102669, 14686.0, 14700.338484768212, 14701.04443211192, 14701.970862039649, 13357.666058348523, 13448.327583620805, 13466.394012269448, 13502.459901603948, 13540.796888046663, 828.2835347424465, 847.8473874702582, 851.5231470376609, 852.3096292003466, 864.5763575847852, 853.5988085599789, 855.9400841941067, 856.7344123321759, 857.100044952797, 858.4806192253659, 910.3036177324319, 910.8451563234233, 912.5072460871122, 916.6333466613341, 917.6421195733669, 902.5306840442933, 903.2773780975226, 903.7243640689587, 903.8870141232313, 904.8506996345595, 671.8018184635545, 673.2627772589008, 674.5446823394238, 675.5271310082912, 677.806825054862, 14300.141991480545, 14441.0, 14454.421823127062, 14459.0, 14691.191984440851, 13655.112417692819, 13889.861101389059, 13904.652383690489, 13907.0, 14001.5099471518, 10079.89920100797, 11381.260218085798, 11544.53821847125, 11673.299602023906, 11996.880031199664, 3595.496630471736, 3618.8371523281253, 3633.9273214535824, 3710.5547334319785, 3759.548854137521, 78883.0, 87065.0, 102228.0, 102247.46628800574, 103736.0, 10479.423631700178, 10560.366378017343, 10850.45747712627, 10966.506507207196, 11490.023348015513, 21666.24168154107, 21776.0, 21978.0, 22042.88978555097, 22208.222712204995, 7464.029676142067, 7549.773506794805, 7557.659905304275, 7614.733484328022, 7661.693532258647, 4167.4790651168605, 4184.769837658897, 4233.618974292329, 4275.3800698898485, 4368.672349573794, 3594.8202589870402, 3625.510556074949, 3684.3184010958057, 3707.332680117551, 3708.0, 673.1253459116435, 679.4258851270669, 679.4598294355999, 683.4122654517112, 685.9828504287433, 650.3983814971133, 652.2727159217438, 674.308833445722, 677.7051982387677, 719.4711886763225, 9609.615615375447, 9869.104611912331, 10001.899981000242, 10080.596776128872, 10240.64157754475, 696.1611258433576, 700.5201437015559, 705.8976448415053, 706.2055187913597, 723.5188599581325, 3929.2534418460973, 4636.976815115903, 4724.787384567634, 4753.33453316536, 4843.903121937541, 78662.6066869662, 87552.0, 96039.0, 98220.0, 100316.99683003222, 73670.0, 83907.74138387929, 85036.0, 86462.13537864666, 89683.0, 73741.52516949738, 74603.0, 75848.0, 76966.84549731646, 77081.0, 10037.5483103262, 10412.895871041193, 10474.738131546772, 10523.894761052292, 10711.839322410015, 49768.502314977835, 50206.7489662564, 50631.0, 51160.74419627878, 52421.7378913103, 64816.35183648103, 66280.6685966567, 68086.0, 68413.3158668407, 68567.65716171387, 12001.81997270059, 12048.0, 12062.45718942632, 12103.818442723543, 12756.107072504836, 14178.929105354408, 14335.0, 14499.130052196902, 14972.47596334123, 15051.698966020836, 12847.486100556247, 12850.228986260481, 13136.671583210496, 13243.139195952232, 13561.864381356454, 13005.349732513525, 13028.804567931678, 13049.869501304865, 13336.0, 13366.866331336561, 12395.380230988234, 12974.740505189655, 13063.281519516519, 13247.801282980956, 13266.403011864297, 9926.602935882484, 10360.430176340373, 10800.513976870903, 10834.262179548043, 11168.106551476025, 8669.349798764992, 8708.259797917248, 10371.792564148825, 10389.220808439246, 10651.147908167173], "value": 7367.991644752353, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1479.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 113.0, 115.0, 115.0, 114.0, 113.0, 113.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 110.0, 143.0, 142.0, 136.0, 113.0, 110.0, 131.0, 111.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 183.0, 183.0, 178.0, 184.0, 200.0, 259.0, 184.0], "value": 192.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 87.0, 61.0, 59.0, 58.0, 60.0, 74.0, 72.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 84.0, 70.0, 68.0, 69.0, 85.0, 68.0, 67.0, 101.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 88.0, 85.0, 87.0, 84.0, 90.0, 86.0, 88.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 103.0, 153.0, 124.0, 94.0, 122.0, 101.0, 93.0, 93.0], "value": 107.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 88.0, 84.0, 87.0, 86.0, 84.0, 86.0, 85.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 57.0, 57.0, 58.0, 60.0, 58.0, 59.0, 58.0, 58.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 71.0, 67.0, 76.0, 71.0, 69.0, 70.0, 71.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 86.0, 96.0, 90.0, 86.0, 89.0, 91.0, 106.0, 108.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 135.0, 163.0, 147.0, 125.0, 160.0, 153.0, 179.0, 146.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 175.0, 150.0, 177.0, 241.0, 236.0, 172.0, 171.0, 173.0], "value": 184.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 79.0, 88.0, 88.0, 70.0, 67.0, 65.0, 65.0, 63.0], "value": 71.5, "unit": "score"}]}]} 06:22:35 INFO - Return code: 0 06:22:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:22:35 ERROR - # TBPL SUCCESS # 06:22:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:22:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:22:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:22:35 INFO - 2017-02-07 06:22:35.539 screenresolution[1652:9714] starting screenresolution argv=screenresolution get 06:22:35 INFO - 2017-02-07 06:22:35.543 screenresolution[1652:9714] Display 0: 1600x1200x32@60 06:22:35 INFO - 2017-02-07 06:22:35.550 screenresolution[1653:9717] starting screenresolution argv=screenresolution list 06:22:35 INFO - Available Modes on Display 0 06:22:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:22:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:22:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:22:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:22:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:22:35 INFO - Intel Iris: 06:22:35 INFO - Chipset Model: Intel Iris 06:22:35 INFO - Type: GPU 06:22:35 INFO - Bus: Built-In 06:22:35 INFO - VRAM (Dynamic, Max): 1536 MB 06:22:35 INFO - Vendor: Intel (0x8086) 06:22:35 INFO - Device ID: 0x0a2e 06:22:35 INFO - Revision ID: 0x0009 06:22:35 INFO - Displays: 06:22:35 INFO - Display: 06:22:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:22:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:22:35 INFO - Display Serial Number: 1600x1200 60 06:22:35 INFO - Main Display: Yes 06:22:35 INFO - Mirror: Off 06:22:35 INFO - Online: Yes 06:22:35 INFO - Rotation: Supported 06:22:35 INFO - Return code: 0 06:22:35 INFO - Running post-action listener: _resource_record_post_action 06:22:35 INFO - [mozharness: 2017-02-07 14:22:35.789636Z] Finished run-tests step (success) 06:22:35 INFO - Running post-run listener: _resource_record_post_run 06:22:35 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 38802432; Write bytes: 554873344; Read time: 379; Write time: 1739 06:22:35 INFO - TinderboxPrint: CPU usage
53.6% 06:22:35 INFO - TinderboxPrint: I/O read bytes / time
38,802,432 / 379 06:22:35 INFO - TinderboxPrint: I/O write bytes / time
554,873,344 / 1,739 06:22:35 INFO - TinderboxPrint: CPU idle
2,232.5 (46.4%) 06:22:35 INFO - TinderboxPrint: CPU system
961.6 (20.0%) 06:22:35 INFO - TinderboxPrint: CPU user
1,619.6 (33.6%) 06:22:35 INFO - TinderboxPrint: Swap in / out
290,533,376 / 0 06:22:35 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 253106688; Write bytes: 246042624; Read time: 18195; Write time: 429 06:22:35 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 34468864; Write bytes: 300585472; Read time: 346; Write time: 1276 06:22:36 INFO - Running post-run listener: _upload_blobber_files 06:22:36 INFO - Blob upload gear active. 06:22:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:22:36 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 06:22:36 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'] 06:22:36 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 06:22:36 INFO - (blobuploader) - INFO - Open directory for files ... 06:22:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:22:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:22:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:22:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:22:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:22:37 INFO - (blobuploader) - INFO - Done attempting. 06:22:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:22:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:22:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:22:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:22:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:22:38 INFO - (blobuploader) - INFO - Done attempting. 06:22:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:22:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:22:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:22:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:22:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:22:39 INFO - (blobuploader) - INFO - Done attempting. 06:22:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:22:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:22:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:22:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:22:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:22:41 INFO - (blobuploader) - INFO - Done attempting. 06:22:41 INFO - (blobuploader) - INFO - Iteration through files over. 06:22:41 INFO - Return code: 0 06:22:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:22:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:22:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c58fcbb7cb1d348a596533defd40d024d6d83e0e27d73c0cd78c07326a9072c5324a14ce8521c08445aecd6be3d3dad90f4df94ac2ea6c5d585c259b14617ee9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bfd2ddd551585d3d419463bfa65468c313b463645de3ac75d532d92231671170e971cff0d4598fac3ac8f973ef94eb44dd3b5f4ca427d7a48663ae8084c3105", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9c9f48551ae50d214335ea33b9bfbc668a78d17e0cc6c468b62a6a3d06e158270003362eba3d9013a0e9e58f4a8587a3e9b810811191eb1b2445efbab5a8491", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caea4470ea9fa22f99aeccf48bb86ea753cd5cf4591cf2953062ee787f8b499b341264751e5b1c9f90707aa2f5699c875c4ee7d849d073a306c252fb59adf3d3"} 06:22:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:22:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:22:41 INFO - Contents: 06:22:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c58fcbb7cb1d348a596533defd40d024d6d83e0e27d73c0cd78c07326a9072c5324a14ce8521c08445aecd6be3d3dad90f4df94ac2ea6c5d585c259b14617ee9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bfd2ddd551585d3d419463bfa65468c313b463645de3ac75d532d92231671170e971cff0d4598fac3ac8f973ef94eb44dd3b5f4ca427d7a48663ae8084c3105", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9c9f48551ae50d214335ea33b9bfbc668a78d17e0cc6c468b62a6a3d06e158270003362eba3d9013a0e9e58f4a8587a3e9b810811191eb1b2445efbab5a8491", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caea4470ea9fa22f99aeccf48bb86ea753cd5cf4591cf2953062ee787f8b499b341264751e5b1c9f90707aa2f5699c875c4ee7d849d073a306c252fb59adf3d3"} 06:22:41 INFO - Running post-run listener: copy_logs_to_upload_dir 06:22:41 INFO - Copying logs to upload dir... 06:22:41 INFO - mkdir: /builds/slave/test/build/upload/logs 06:22:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.176660 ========= master_lag: 1.51 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-07 06:22:43.232114) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 06:22:43.235571) ========= 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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c58fcbb7cb1d348a596533defd40d024d6d83e0e27d73c0cd78c07326a9072c5324a14ce8521c08445aecd6be3d3dad90f4df94ac2ea6c5d585c259b14617ee9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bfd2ddd551585d3d419463bfa65468c313b463645de3ac75d532d92231671170e971cff0d4598fac3ac8f973ef94eb44dd3b5f4ca427d7a48663ae8084c3105", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9c9f48551ae50d214335ea33b9bfbc668a78d17e0cc6c468b62a6a3d06e158270003362eba3d9013a0e9e58f4a8587a3e9b810811191eb1b2445efbab5a8491", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caea4470ea9fa22f99aeccf48bb86ea753cd5cf4591cf2953062ee787f8b499b341264751e5b1c9f90707aa2f5699c875c4ee7d849d073a306c252fb59adf3d3"} build_url:https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011023 build_url: 'https://queue.taskcluster.net/v1/task/JrwmJmdrQ8mZAEYf66t2_Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c58fcbb7cb1d348a596533defd40d024d6d83e0e27d73c0cd78c07326a9072c5324a14ce8521c08445aecd6be3d3dad90f4df94ac2ea6c5d585c259b14617ee9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bfd2ddd551585d3d419463bfa65468c313b463645de3ac75d532d92231671170e971cff0d4598fac3ac8f973ef94eb44dd3b5f4ca427d7a48663ae8084c3105", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9c9f48551ae50d214335ea33b9bfbc668a78d17e0cc6c468b62a6a3d06e158270003362eba3d9013a0e9e58f4a8587a3e9b810811191eb1b2445efbab5a8491", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caea4470ea9fa22f99aeccf48bb86ea753cd5cf4591cf2953062ee787f8b499b341264751e5b1c9f90707aa2f5699c875c4ee7d849d073a306c252fb59adf3d3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 06:22:43.275167) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:22:43.275480) ========= 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.xB6rGGMEWi/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.aXJpU3cqj9/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.005069 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 06:22:43.324836) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 06:22:43.325160) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 06:22:43.325487) ========= ========= Total master_lag: 1.66 =========