builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0002 starttime: 1487156522.96 results: success (0) buildid: 20170210030206 builduid: 1bfbb0aef7d5463999819ddc6fd6d7ad revision: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:02.963003) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:02.963440) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:02.963720) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:02.987686) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:02.987965) ========= 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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-15 03:02:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.40M=0.001s 2017-02-15 03:02:03 (8.40 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126949 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:03.138046) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:03.138404) ========= 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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.021663 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:03.187238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-15 03:02:03.187623) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 25a94c1047e793ef096d8556fa3c26dd72bd37d7 --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 25a94c1047e793ef096d8556fa3c26dd72bd37d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Wk54jZfbCL/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.oIRt9CNhh8/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-15 03:02:03,262 truncating revision to first 12 chars 2017-02-15 03:02:03,262 Setting DEBUG logging. 2017-02-15 03:02:03,263 attempt 1/10 2017-02-15 03:02:03,263 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/25a94c1047e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-15 03:02:05,307 unpacking tar archive at: mozilla-central-25a94c1047e7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.284479 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-15 03:02:05.498136) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.498720) ========= script_repo_revision: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.499369) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.499859) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.527414) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-15 03:02:05.527928) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Wk54jZfbCL/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.oIRt9CNhh8/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:05 INFO - MultiFileLogger online at 20170215 03:02:05 in /builds/slave/test 03:02:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:02:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:02:05 INFO - {'append_to_log': False, 03:02:05 INFO - 'base_work_dir': '/builds/slave/test', 03:02:05 INFO - 'blob_upload_branch': 'Firefox', 03:02:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:02:05 INFO - 'branch': 'Firefox', 03:02:05 INFO - 'buildbot_json_path': 'buildprops.json', 03:02:05 INFO - 'config_files': ('talos/mac_config.py',), 03:02:05 INFO - 'default_actions': ('clobber', 03:02:05 INFO - 'read-buildbot-config', 03:02:05 INFO - 'download-and-extract', 03:02:05 INFO - 'populate-webroot', 03:02:05 INFO - 'create-virtualenv', 03:02:05 INFO - 'install', 03:02:05 INFO - 'run-tests'), 03:02:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:02:05 INFO - 'download_minidump_stackwalk': True, 03:02:05 INFO - 'download_symbols': 'ondemand', 03:02:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:02:05 INFO - 'tooltool.py': '/tools/tooltool.py', 03:02:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:02:05 INFO - '/tools/misc-python/virtualenv.py')}, 03:02:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:02:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:02:05 INFO - 'installer_path': 'installer.exe', 03:02:05 INFO - 'log_level': 'info', 03:02:05 INFO - 'log_name': 'talos', 03:02:05 INFO - 'log_to_console': True, 03:02:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:02:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:02:05 INFO - 'opt_config_files': (), 03:02:05 INFO - 'pip_index': False, 03:02:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:02:05 INFO - 'cmd': ('bash', 03:02:05 INFO - '-c', 03:02:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:02:05 INFO - 'enabled': True, 03:02:05 INFO - 'halt_on_failure': False, 03:02:05 INFO - 'name': 'check_screen_resolution'},), 03:02:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:02:05 INFO - 'cmd': ('bash', 03:02:05 INFO - '-c', 03:02:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:02:05 INFO - 'enabled': True, 03:02:05 INFO - 'halt_on_failure': False, 03:02:05 INFO - 'name': 'check_screen_resolution'},), 03:02:05 INFO - 'run_cmd_checks_enabled': True, 03:02:05 INFO - 'sps_profile': False, 03:02:05 INFO - 'sps_profile_interval': 0, 03:02:05 INFO - 'suite': 'dromaeojs-e10s', 03:02:05 INFO - 'system_bits': '32', 03:02:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:02:05 INFO - 'title': 't-yosemite-r7-0002', 03:02:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:02:05 INFO - 'use_talos_json': True, 03:02:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:02:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:02:05 INFO - 'work_dir': 'build'} 03:02:05 INFO - [mozharness: 2017-02-15 11:02:05.730703Z] Running clobber step. 03:02:05 INFO - Running pre-action listener: _resource_record_pre_action 03:02:05 INFO - Running main action method: clobber 03:02:05 INFO - rmtree: /builds/slave/test/build 03:02:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:02:06 INFO - Running post-action listener: _resource_record_post_action 03:02:06 INFO - [mozharness: 2017-02-15 11:02:06.649142Z] Finished clobber step (success) 03:02:06 INFO - [mozharness: 2017-02-15 11:02:06.649270Z] Running read-buildbot-config step. 03:02:06 INFO - Running pre-action listener: _resource_record_pre_action 03:02:06 INFO - Running main action method: read_buildbot_config 03:02:06 INFO - Using buildbot properties: 03:02:06 INFO - { 03:02:06 INFO - "project": "", 03:02:06 INFO - "product": "firefox", 03:02:06 INFO - "script_repo_revision": "production", 03:02:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:02:06 INFO - "repository": "", 03:02:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:02:06 INFO - "buildid": "20170210030206", 03:02:06 INFO - "stage_platform": "macosx64", 03:02:06 INFO - "basedir": "/builds/slave/test", 03:02:06 INFO - "nightly_build": "True", 03:02:06 INFO - "buildnumber": 52, 03:02:06 INFO - "slavename": "t-yosemite-r7-0002", 03:02:06 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 03:02:06 INFO - "platform": "yosemite_r7", 03:02:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:02:06 INFO - "branch": "mozilla-central", 03:02:06 INFO - "repo_path": "mozilla-central", 03:02:06 INFO - "revision": "25a94c1047e793ef096d8556fa3c26dd72bd37d7", 03:02:06 INFO - "pgo_build": "False", 03:02:06 INFO - "builduid": "1bfbb0aef7d5463999819ddc6fd6d7ad", 03:02:06 INFO - "slavebuilddir": "test" 03:02:06 INFO - } 03:02:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 03:02:06 INFO - Running post-action listener: _resource_record_post_action 03:02:06 INFO - [mozharness: 2017-02-15 11:02:06.652775Z] Finished read-buildbot-config step (success) 03:02:06 INFO - [mozharness: 2017-02-15 11:02:06.652883Z] Running download-and-extract step. 03:02:06 INFO - Running pre-action listener: _resource_record_pre_action 03:02:06 INFO - Running main action method: download_and_extract 03:02:06 INFO - mkdir: /builds/slave/test/build/tests 03:02:06 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')]} 03:02:06 INFO - trying https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 03:02:06 INFO - Downloading https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/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 03:02:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/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 03:02:08 INFO - Downloaded 1199 bytes. 03:02:08 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:02:08 INFO - Using the following test package requirements: 03:02:08 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:02:08 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:08 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 03:02:08 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:08 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 03:02:08 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:08 INFO - u'jsshell-mac.zip'], 03:02:08 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:08 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 03:02:08 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:02:08 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:08 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 03:02:08 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:08 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 03:02:08 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:08 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 03:02:08 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:08 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 03:02:08 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:02:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:02:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:02:08 INFO - Fetch https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:02:09 INFO - Expected file size: 34945452 03:02:09 INFO - Obtained file size: 34945452 03:02:16 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 03:02:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:02:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:02:16 INFO - Fetch https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:02:17 INFO - Expected file size: 34945452 03:02:17 INFO - Obtained file size: 34945452 03:02:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 03:02:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:02:24 INFO - Fetch https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 03:02:25 INFO - Expected file size: 14403454 03:02:25 INFO - Obtained file size: 14403454 03: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')]} 03:02:25 INFO - trying https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:02:25 INFO - Downloading https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 03:02:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/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 03:02:27 INFO - Downloaded 70413587 bytes. 03:02:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:02:27 INFO - mkdir: /builds/slave/test/properties 03:02:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:02:27 INFO - Writing to file /builds/slave/test/properties/build_url 03:02:27 INFO - Contents: 03:02:27 INFO - build_url:https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:02:28 INFO - Running post-action listener: _resource_record_post_action 03:02:28 INFO - Running post-action listener: set_extra_try_arguments 03:02:28 INFO - [mozharness: 2017-02-15 11:02:28.304283Z] Finished download-and-extract step (success) 03:02:28 INFO - [mozharness: 2017-02-15 11:02:28.304412Z] Running populate-webroot step. 03:02:28 INFO - Running pre-action listener: _resource_record_pre_action 03:02:28 INFO - Running main action method: populate_webroot 03:02:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:02:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:02:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:02:28 INFO - '--tppagecycles', 03:02:28 INFO - '7'], 03:02:28 INFO - 'tests': ['tsvgm']}}, 03:02:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:02:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:02:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:02:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:02:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:02:28 INFO - 'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:02:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:02:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:02:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:02:28 INFO - 'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['damp', 'tps']}, 03:02:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:02:28 INFO - 'tests': ['damp', 'tps']}, 03:02:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['dromaeo_dom']}, 03:02:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:02:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['basic_compositor_video']}, 03:02:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:02:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['a11yr', 03:02:28 INFO - 'ts_paint', 03:02:28 INFO - 'tpaint', 03:02:28 INFO - 'sessionrestore', 03:02:28 INFO - 'sessionrestore_no_auto_restore', 03:02:28 INFO - 'tabpaint']}, 03:02:28 INFO - 'other-e10s': {'tests': ['a11yr', 03:02:28 INFO - 'ts_paint', 03:02:28 INFO - 'tpaint', 03:02:28 INFO - 'sessionrestore', 03:02:28 INFO - 'sessionrestore_no_auto_restore', 03:02:28 INFO - 'tabpaint']}, 03:02:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:02:28 INFO - 'ts_paint', 03:02:28 INFO - 'tpaint', 03:02:28 INFO - 'sessionrestore', 03:02:28 INFO - 'sessionrestore_no_auto_restore', 03:02:28 INFO - 'tabpaint']}, 03:02:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:02:28 INFO - 'ts_paint', 03:02:28 INFO - 'tpaint', 03:02:28 INFO - 'sessionrestore', 03:02:28 INFO - 'sessionrestore_no_auto_restore', 03:02:28 INFO - 'tabpaint']}, 03:02:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['a11yr', 03:02:28 INFO - 'ts_paint', 03:02:28 INFO - 'tpaint', 03:02:28 INFO - 'sessionrestore', 03:02:28 INFO - 'sessionrestore_no_auto_restore', 03:02:28 INFO - 'tabpaint']}, 03:02:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['a11yr', 03:02:28 INFO - 'ts_paint', 03:02:28 INFO - 'tpaint', 03:02:28 INFO - 'sessionrestore', 03:02:28 INFO - 'sessionrestore_no_auto_restore', 03:02:28 INFO - 'tabpaint']}, 03:02:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['tsvgx', 03:02:28 INFO - 'tsvgr_opacity', 03:02:28 INFO - 'tart', 03:02:28 INFO - 'tscrollx', 03:02:28 INFO - 'cart', 03:02:28 INFO - 'tsvg_static']}, 03:02:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:02:28 INFO - 'tsvgr_opacity', 03:02:28 INFO - 'tart', 03:02:28 INFO - 'tscrollx', 03:02:28 INFO - 'cart', 03:02:28 INFO - 'tsvg_static']}, 03:02:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:02:28 INFO - 'talos_options': ['--disable-e10s'], 03:02:28 INFO - 'tests': ['tp5o']}, 03:02:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:02:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:02:28 INFO - 'talos_options': ['--disable-e10s', 03:02:28 INFO - '--xperf_path', 03:02:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:02:28 INFO - 'tests': ['tp5n']}, 03:02:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:02:28 INFO - 'talos_options': ['--xperf_path', 03:02:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:02:28 INFO - 'tests': ['tp5n']}}, 03:02:28 INFO - 'talos.zip': {'path': '', 03:02:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:02:28 INFO - Running post-action listener: _resource_record_post_action 03:02:28 INFO - [mozharness: 2017-02-15 11:02:28.322914Z] Finished populate-webroot step (success) 03:02:28 INFO - [mozharness: 2017-02-15 11:02:28.323048Z] Running create-virtualenv step. 03:02:28 INFO - Running pre-action listener: _resource_record_pre_action 03:02:28 INFO - Running main action method: create_virtualenv 03:02:28 INFO - Creating virtualenv /builds/slave/test/build/venv 03:02:28 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 03:02:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:02:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:02:28 INFO - Using real prefix '/tools/python27' 03:02:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:02:29 INFO - Installing distribute.............................................................................................................................................................................................done. 03:02:32 INFO - Installing pip.................done. 03:02:32 INFO - Return code: 0 03:02:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:02:32 INFO - Reading from file tmpfile_stdout 03:02:32 INFO - Output received: 03:02:32 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) 03:02:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:32 INFO - 'HOME': '/Users/cltbld', 03:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:32 INFO - 'LOGNAME': 'cltbld', 03:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:32 INFO - 'MOZ_NO_REMOTE': '1', 03:02:32 INFO - 'NO_EM_RESTART': '1', 03:02:32 INFO - 'PAGER': '/bin/cat', 03:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:32 INFO - 'PWD': '/builds/slave/test', 03:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:32 INFO - 'SHELL': '/bin/bash', 03:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:02:32 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:32 INFO - 'USER': 'cltbld', 03:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:32 INFO - 'XPC_FLAGS': '0x0', 03:02:32 INFO - 'XPC_SERVICE_NAME': '0', 03:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:32 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 03:02:32 INFO - Cleaning up... 03:02:32 INFO - Return code: 0 03:02:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:32 INFO - 'HOME': '/Users/cltbld', 03:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:32 INFO - 'LOGNAME': 'cltbld', 03:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:32 INFO - 'MOZ_NO_REMOTE': '1', 03:02:32 INFO - 'NO_EM_RESTART': '1', 03:02:32 INFO - 'PAGER': '/bin/cat', 03:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:32 INFO - 'PWD': '/builds/slave/test', 03:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:32 INFO - 'SHELL': '/bin/bash', 03:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:02:32 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:32 INFO - 'USER': 'cltbld', 03:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:32 INFO - 'XPC_FLAGS': '0x0', 03:02:32 INFO - 'XPC_SERVICE_NAME': '0', 03:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:32 INFO - Downloading/unpacking psutil>=3.1.1 03:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:02:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:02:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:02:36 INFO - Installing collected packages: psutil 03:02:36 INFO - Running setup.py install for psutil 03:02:36 INFO - building 'psutil._psutil_osx' extension 03:02:36 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 03:02:37 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 03:02:37 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 03:02:37 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 03:02:37 INFO - building 'psutil._psutil_posix' extension 03:02:37 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 03:02:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:02:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:02:37 INFO - ^ 03:02:37 INFO - 1 warning generated. 03:02:37 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 03:02:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:02:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:02:37 INFO - Successfully installed psutil 03:02:37 INFO - Cleaning up... 03:02:37 INFO - Return code: 0 03:02:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:02: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')]} 03:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02: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')]} 03:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:37 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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:37 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 03:02:37 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 03:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:37 INFO - 'HOME': '/Users/cltbld', 03:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:37 INFO - 'LOGNAME': 'cltbld', 03:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:37 INFO - 'MOZ_NO_REMOTE': '1', 03:02:37 INFO - 'NO_EM_RESTART': '1', 03:02:37 INFO - 'PAGER': '/bin/cat', 03:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:37 INFO - 'PWD': '/builds/slave/test', 03:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:37 INFO - 'SHELL': '/bin/bash', 03:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:02:37 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:37 INFO - 'USER': 'cltbld', 03:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:37 INFO - 'XPC_FLAGS': '0x0', 03:02:37 INFO - 'XPC_SERVICE_NAME': '0', 03:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:02:37 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 03:02:37 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 03:02:37 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 03:02:37 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 03:02:37 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 03:02:37 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 03:02:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:02:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:02:40 INFO - Installing collected packages: mozsystemmonitor 03:02:40 INFO - Running setup.py install for mozsystemmonitor 03:02:40 INFO - Successfully installed mozsystemmonitor 03:02:40 INFO - Cleaning up... 03:02:40 INFO - Return code: 0 03:02:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:02:40 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')]} 03:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:40 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')]} 03:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:40 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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:40 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 03:02:40 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 03:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:40 INFO - 'HOME': '/Users/cltbld', 03:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:40 INFO - 'LOGNAME': 'cltbld', 03:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:40 INFO - 'MOZ_NO_REMOTE': '1', 03:02:40 INFO - 'NO_EM_RESTART': '1', 03:02:40 INFO - 'PAGER': '/bin/cat', 03:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:40 INFO - 'PWD': '/builds/slave/test', 03:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:40 INFO - 'SHELL': '/bin/bash', 03:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:02:40 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:40 INFO - 'USER': 'cltbld', 03:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:40 INFO - 'XPC_FLAGS': '0x0', 03:02:40 INFO - 'XPC_SERVICE_NAME': '0', 03:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:40 INFO - Downloading/unpacking jsonschema==2.5.1 03:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:02:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:44 INFO - Downloading functools32-3.2.3-2.tar.gz 03:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:02:44 INFO - warning: no files found matching '*.txt' 03:02:44 INFO - no previously-included directories found matching 'build' 03:02:44 INFO - no previously-included directories found matching 'dist' 03:02:44 INFO - no previously-included directories found matching '.git*' 03:02:44 INFO - Installing collected packages: jsonschema, functools32 03:02:44 INFO - Running setup.py install for functools32 03:02:44 INFO - warning: no files found matching '*.txt' 03:02:44 INFO - no previously-included directories found matching 'build' 03:02:44 INFO - no previously-included directories found matching 'dist' 03:02:44 INFO - no previously-included directories found matching '.git*' 03:02:44 INFO - Successfully installed jsonschema functools32 03:02:44 INFO - Cleaning up... 03:02:44 INFO - Return code: 0 03:02:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:02:44 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')]} 03:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:44 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')]} 03:02:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:44 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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:44 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 03:02:44 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 03:02:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:02:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:44 INFO - 'HOME': '/Users/cltbld', 03:02:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:44 INFO - 'LOGNAME': 'cltbld', 03:02:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:44 INFO - 'MOZ_NO_REMOTE': '1', 03:02:44 INFO - 'NO_EM_RESTART': '1', 03:02:44 INFO - 'PAGER': '/bin/cat', 03:02:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:44 INFO - 'PWD': '/builds/slave/test', 03:02:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:44 INFO - 'SHELL': '/bin/bash', 03:02:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:02:44 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:02:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:44 INFO - 'USER': 'cltbld', 03:02:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:44 INFO - 'XPC_FLAGS': '0x0', 03:02:44 INFO - 'XPC_SERVICE_NAME': '0', 03:02:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:02:44 INFO - Cleaning up... 03:02:44 INFO - Return code: 0 03:02:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:02:44 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')]} 03:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:44 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')]} 03:02:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:44 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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:44 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 03:02:44 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 03:02:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:02:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:44 INFO - 'HOME': '/Users/cltbld', 03:02:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:44 INFO - 'LOGNAME': 'cltbld', 03:02:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:44 INFO - 'MOZ_NO_REMOTE': '1', 03:02:44 INFO - 'NO_EM_RESTART': '1', 03:02:44 INFO - 'PAGER': '/bin/cat', 03:02:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:44 INFO - 'PWD': '/builds/slave/test', 03:02:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:44 INFO - 'SHELL': '/bin/bash', 03:02:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:02:44 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:02:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:44 INFO - 'USER': 'cltbld', 03:02:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:44 INFO - 'XPC_FLAGS': '0x0', 03:02:44 INFO - 'XPC_SERVICE_NAME': '0', 03:02:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:44 INFO - Downloading/unpacking blobuploader==1.2.4 03:02:44 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 03:02:44 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 03:02:44 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 03:02:44 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 03:02:44 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 03:02:44 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 03:02:47 INFO - Downloading blobuploader-1.2.4.tar.gz 03:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:02:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:02:47 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 03:02:47 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 03:02:47 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 03:02:47 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 03:02:47 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 03:02:47 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 03:02:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:02:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:02:48 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 03:02:48 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 03:02:48 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 03:02:48 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 03:02:48 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 03:02:48 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 03:02:48 INFO - Downloading docopt-0.6.1.tar.gz 03:02:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:02:48 INFO - Installing collected packages: blobuploader, requests, docopt 03:02:48 INFO - Running setup.py install for blobuploader 03:02:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:02:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:02:49 INFO - Running setup.py install for requests 03:02:49 INFO - Running setup.py install for docopt 03:02:49 INFO - Successfully installed blobuploader requests docopt 03:02:49 INFO - Cleaning up... 03:02:49 INFO - Return code: 0 03:02:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:49 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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:49 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 03:02:49 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 03:02:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:02:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:49 INFO - 'HOME': '/Users/cltbld', 03:02:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:49 INFO - 'LOGNAME': 'cltbld', 03:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:49 INFO - 'MOZ_NO_REMOTE': '1', 03:02:49 INFO - 'NO_EM_RESTART': '1', 03:02:49 INFO - 'PAGER': '/bin/cat', 03:02:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:49 INFO - 'PWD': '/builds/slave/test', 03:02:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:49 INFO - 'SHELL': '/bin/bash', 03:02:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:02:49 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:02:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:49 INFO - 'USER': 'cltbld', 03:02:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:49 INFO - 'XPC_FLAGS': '0x0', 03:02:49 INFO - 'XPC_SERVICE_NAME': '0', 03:02:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-n_3W1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-IkMgXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-eR31Gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-dAxCGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-yTuVq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-xu43C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-tWtVMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-zZTXL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-LZQC8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:02:50 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-qPjsxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:02:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-DKTi5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:02:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-__DJ1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:02:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-MY8AFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:02:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-wYfPnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-dI8QI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:02:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-obJFjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:02:51 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-TJKEL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:02:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:02:51 INFO - Running setup.py install for manifestparser 03:02:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:02:51 INFO - Running setup.py install for mozcrash 03:02:52 INFO - Running setup.py install for mozdebug 03:02:52 INFO - Running setup.py install for mozdevice 03:02:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:02:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:02:52 INFO - Running setup.py install for mozfile 03:02:52 INFO - Running setup.py install for mozhttpd 03:02:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:02:52 INFO - Running setup.py install for mozinfo 03:02:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:02:52 INFO - Running setup.py install for mozInstall 03:02:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:02:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:02:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:02:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:02:52 INFO - Running setup.py install for mozleak 03:02:53 INFO - Running setup.py install for mozlog 03:02:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Running setup.py install for moznetwork 03:02:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Running setup.py install for mozprocess 03:02:53 INFO - Running setup.py install for mozprofile 03:02:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Running setup.py install for mozrunner 03:02:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Running setup.py install for mozscreenshot 03:02:54 INFO - Running setup.py install for moztest 03:02:54 INFO - Running setup.py install for mozversion 03:02:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:02:54 INFO - Cleaning up... 03:02:54 INFO - Return code: 0 03:02:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03: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')]} 03:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03: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')]} 03:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:54 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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:02:54 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 03:02:54 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 03:02:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:02:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:54 INFO - 'HOME': '/Users/cltbld', 03:02:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:54 INFO - 'LOGNAME': 'cltbld', 03:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:54 INFO - 'MOZ_NO_REMOTE': '1', 03:02:54 INFO - 'NO_EM_RESTART': '1', 03:02:54 INFO - 'PAGER': '/bin/cat', 03:02:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:54 INFO - 'PWD': '/builds/slave/test', 03:02:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:54 INFO - 'SHELL': '/bin/bash', 03:02:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:02:54 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:02:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:54 INFO - 'USER': 'cltbld', 03:02:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:54 INFO - 'XPC_FLAGS': '0x0', 03:02:54 INFO - 'XPC_SERVICE_NAME': '0', 03:02:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:02:54 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-aJ5FAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:02:54 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)) 03:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:02:54 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-SDrf7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:02:54 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)) 03:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:02:54 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-wY6Iq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-kozsrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-SFhLA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Li1ZwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-vwxjGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-oDgSlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-c_nnlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-QbKVjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-3L8faC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-mg4hf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:02:55 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)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:02:55 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-cnlbzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:02:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-4cuVWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Z1cFKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:02:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-4MVpc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:02:56 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-_2S5t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:02:56 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)) 03:02:56 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)) 03:02:56 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)) 03:02:56 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)) 03:02:56 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)) 03:02:56 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)) 03:02:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:02:56 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 03:02:56 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 03:02:56 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 03:02:56 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 03:02:56 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 03:02:56 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 03:02:59 INFO - Downloading blessings-1.6.tar.gz 03:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:02:59 INFO - Installing collected packages: blessings 03:02:59 INFO - Running setup.py install for blessings 03:02:59 INFO - Successfully installed blessings 03:02:59 INFO - Cleaning up... 03:02:59 INFO - Return code: 0 03:02:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:02:59 INFO - Reading from file tmpfile_stdout 03:02:59 INFO - Current package versions: 03:02:59 INFO - blessings == 1.6 03:02:59 INFO - blobuploader == 1.2.4 03:02:59 INFO - docopt == 0.6.1 03:02:59 INFO - functools32 == 3.2.3-2 03:02:59 INFO - jsonschema == 2.5.1 03:02:59 INFO - manifestparser == 1.1 03:02:59 INFO - mozInstall == 1.12 03:02:59 INFO - mozcrash == 1.0 03:02:59 INFO - mozdebug == 0.1 03:02:59 INFO - mozdevice == 0.49 03:02:59 INFO - mozfile == 1.2 03:02:59 INFO - mozhttpd == 0.7 03:02:59 INFO - mozinfo == 0.9 03:02:59 INFO - mozleak == 0.1 03:02:59 INFO - mozlog == 3.4 03:02:59 INFO - moznetwork == 0.27 03:02:59 INFO - mozprocess == 0.24 03:02:59 INFO - mozprofile == 0.28 03:02:59 INFO - mozrunner == 6.13 03:02:59 INFO - mozscreenshot == 0.1 03:02:59 INFO - mozsystemmonitor == 0.3 03:02:59 INFO - moztest == 0.8 03:02:59 INFO - mozversion == 1.4 03:02:59 INFO - psutil == 3.1.1 03:02:59 INFO - requests == 1.2.3 03:02:59 INFO - wsgiref == 0.1.2 03:02:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:02:59 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')]} 03:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:00 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')]} 03:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:00 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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:03:00 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 03:03:00 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 03:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:00 INFO - 'HOME': '/Users/cltbld', 03:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:00 INFO - 'LOGNAME': 'cltbld', 03:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:00 INFO - 'MOZ_NO_REMOTE': '1', 03:03:00 INFO - 'NO_EM_RESTART': '1', 03:03:00 INFO - 'PAGER': '/bin/cat', 03:03:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:00 INFO - 'PWD': '/builds/slave/test', 03:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:00 INFO - 'SHELL': '/bin/bash', 03:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:03:00 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:00 INFO - 'USER': 'cltbld', 03:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:00 INFO - 'XPC_FLAGS': '0x0', 03:03:00 INFO - 'XPC_SERVICE_NAME': '0', 03:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 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)) 03:03:00 INFO - Cleaning up... 03:03:00 INFO - Return code: 0 03:03:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:03:00 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')]} 03:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:00 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')]} 03:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:01 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 0x106f8fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f92768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd87bda8730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, '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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 03:03:01 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 03:03:01 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 03:03:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:03:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:01 INFO - 'HOME': '/Users/cltbld', 03:03:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:01 INFO - 'LOGNAME': 'cltbld', 03:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:01 INFO - 'MOZ_NO_REMOTE': '1', 03:03:01 INFO - 'NO_EM_RESTART': '1', 03:03:01 INFO - 'PAGER': '/bin/cat', 03:03:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:01 INFO - 'PWD': '/builds/slave/test', 03:03:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:01 INFO - 'SHELL': '/bin/bash', 03:03:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:03:01 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:03:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:01 INFO - 'USER': 'cltbld', 03:03:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:01 INFO - 'XPC_FLAGS': '0x0', 03:03:01 INFO - 'XPC_SERVICE_NAME': '0', 03:03:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:03:01 INFO - Cleaning up... 03:03:01 INFO - Return code: 0 03:03:01 INFO - Running post-action listener: _resource_record_post_action 03:03:01 INFO - Running post-action listener: _start_resource_monitoring 03:03:01 INFO - Starting resource monitoring. 03:03:01 INFO - [mozharness: 2017-02-15 11:03:01.532628Z] Finished create-virtualenv step (success) 03:03:01 INFO - [mozharness: 2017-02-15 11:03:01.533372Z] Running install step. 03:03:01 INFO - Running pre-action listener: _resource_record_pre_action 03:03:01 INFO - Running main action method: install 03:03:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:03:01 INFO - Reading from file tmpfile_stdout 03:03:01 INFO - Detecting whether we're running mozinstall >=1.0... 03:03:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:03:01 INFO - Reading from file tmpfile_stdout 03:03:01 INFO - Output received: 03:03:01 INFO - Usage: mozinstall [options] installer 03:03:01 INFO - Options: 03:03:01 INFO - -h, --help show this help message and exit 03:03:01 INFO - -d DEST, --destination=DEST 03:03:01 INFO - Directory to install application into. [default: 03:03:01 INFO - "/builds/slave/test"] 03:03:01 INFO - --app=APP Application being installed. [default: firefox] 03:03:01 INFO - mkdir: /builds/slave/test/build/application 03:03:01 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'] 03:03:01 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 03:03:23 INFO - Reading from file tmpfile_stdout 03:03:23 INFO - Output received: 03:03:23 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 03:03:23 INFO - Running post-action listener: _resource_record_post_action 03:03:23 INFO - [mozharness: 2017-02-15 11:03:23.822861Z] Finished install step (success) 03:03:23 INFO - [mozharness: 2017-02-15 11:03:23.823005Z] Running run-tests step. 03:03:23 INFO - Running pre-action listener: _resource_record_pre_action 03:03:23 INFO - Running main action method: run_tests 03:03:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:03:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:03:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:03:23 INFO - 2017-02-15 03:03:23.837 screenresolution[2390:25076] starting screenresolution argv=screenresolution get 03:03:23 INFO - 2017-02-15 03:03:23.840 screenresolution[2390:25076] Display 0: 1600x1200x32@60 03:03:23 INFO - 2017-02-15 03:03:23.845 screenresolution[2391:25078] starting screenresolution argv=screenresolution list 03:03:23 INFO - Available Modes on Display 0 03:03:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:03:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:03:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:03:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:03:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:03:24 INFO - Intel Iris: 03:03:24 INFO - Chipset Model: Intel Iris 03:03:24 INFO - Type: GPU 03:03:24 INFO - Bus: Built-In 03:03:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:03:24 INFO - Vendor: Intel (0x8086) 03:03:24 INFO - Device ID: 0x0a2e 03:03:24 INFO - Revision ID: 0x0009 03:03:24 INFO - Displays: 03:03:24 INFO - Display: 03:03:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:03:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:03:24 INFO - Display Serial Number: 1600x1200 60 03:03:24 INFO - Main Display: Yes 03:03:24 INFO - Mirror: Off 03:03:24 INFO - Online: Yes 03:03:24 INFO - Rotation: Supported 03:03:24 INFO - Return code: 0 03:03:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:03:24 INFO - Python 2.7.3 03:03:24 INFO - Return code: 0 03:03:24 INFO - grabbing minidump binary from tooltool 03:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:24 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 0x106d6dd30>, '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 0x106c94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d62be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:03:24 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 03:03:24 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 03:03:24 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 03:03:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:03:24 INFO - Return code: 0 03:03:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:03:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:03:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:03:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:03:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:03:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:03:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wk54jZfbCL/Render', 03:03:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:24 INFO - 'HOME': '/Users/cltbld', 03:03:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:24 INFO - 'LOGNAME': 'cltbld', 03:03:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:03:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:24 INFO - 'MOZ_NO_REMOTE': '1', 03:03:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:03:24 INFO - 'NO_EM_RESTART': '1', 03:03:24 INFO - 'PAGER': '/bin/cat', 03:03:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:24 INFO - 'PWD': '/builds/slave/test', 03:03:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:03:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:24 INFO - 'SHELL': '/bin/bash', 03:03:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oIRt9CNhh8/Listeners', 03:03:24 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 03:03:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:24 INFO - 'USER': 'cltbld', 03:03:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:24 INFO - 'XPC_FLAGS': '0x0', 03:03:24 INFO - 'XPC_SERVICE_NAME': '0', 03:03:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:03:24 INFO - mozversion application_buildid: 20170210030206 03:03:24 INFO - mozversion application_changeset: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 03:03:24 INFO - mozversion application_display_name: Nightly 03:03:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:03:24 INFO - mozversion application_name: Firefox 03:03:24 INFO - mozversion application_remotingname: firefox 03:03:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:03:24 INFO - mozversion application_vendor: Mozilla 03:03:24 INFO - mozversion application_version: 54.0a1 03:03:24 INFO - mozversion platform_buildid: 20170210030206 03:03:24 INFO - mozversion platform_changeset: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 03:03:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:03:24 INFO - mozversion platform_version: 54.0a1 03:03:24 INFO - using testdate: 1487156604 03:03:24 INFO - actual date: 1487156604 03:03:24 INFO - starting webserver on 'localhost:55087' 03:03:24 INFO - SUITE-START | Running 2 tests 03:03:24 INFO - TEST-START | dromaeo_css 03:03:24 INFO - operating with platform_type : mac_ 03:03:24 INFO - Initialising browser for dromaeo_css test... 03:03:24 INFO - TEST-INFO | started process 2401 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmptlB6UE/profile http://localhost:55087/getInfo.html) 03:03:27 INFO - PROCESS | 2401 | 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] 03:03:27 INFO - PROCESS | 2401 | 2017-02-15 03:03:27.328 plugin-container[2404:25239] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 03:03:27 INFO - PROCESS | 2401 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:03:27 INFO - PROCESS | 2401 | 2017-02-15 03:03:27.329 plugin-container[2404:25239] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 03:03:27 INFO - PROCESS | 2401 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:03:27 INFO - PROCESS | 2401 | 2017-02-15 03:03:27.329 plugin-container[2404:25239] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:03:27 INFO - PROCESS | 2401 | __metrics Screen width/height:1600/1200 03:03:27 INFO - PROCESS | 2401 | colorDepth:24 03:03:27 INFO - PROCESS | 2401 | Browser inner width/height: 1024/689 03:03:27 INFO - PROCESS | 2401 | __metrics 03:03:27 ERROR - PROCESS | 2401 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 03:03:28 INFO - TEST-INFO | 2401: exit 0 03:03:28 INFO - Browser initialized. 03:03:28 INFO - Running cycle 1/1 for dromaeo_css test... 03:03:28 INFO - TEST-INFO | started process 2405 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmptlB6UE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:03:30 INFO - PROCESS | 2405 | 2017-02-15 03:03:30.647 plugin-container[2406:25358] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 03:03:30 INFO - PROCESS | 2405 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:03:30 INFO - PROCESS | 2405 | 2017-02-15 03:03:30.648 plugin-container[2406:25358] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 03:03:30 INFO - PROCESS | 2405 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:03:30 INFO - PROCESS | 2405 | 2017-02-15 03:03:30.648 plugin-container[2406:25358] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:03:40 INFO - PROCESS | 2405 | RSS: Main: 233713664 03:03:40 INFO - PROCESS | 2405 | 03:06:42 INFO - PROCESS | 2405 | Cycle 1(1): loaded http://localhost:55087/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55087/tests/dromaeo/cssquery-ext.html) 03:06:44 INFO - PROCESS | 2405 | RSS: Main: 258764800 03:06:44 INFO - PROCESS | 2405 | 03:08:28 INFO - PROCESS | 2405 | ************************* 03:08:28 INFO - PROCESS | 2405 | A coding exception was thrown and uncaught in a Task. 03:08:28 INFO - PROCESS | 2405 | 03:08:28 ERROR - PROCESS | 2405 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:08:28 INFO - PROCESS | 2405 | Full stack: 03:08:28 INFO - PROCESS | 2405 | ************************* 03:09:46 INFO - PROCESS | 2405 | Cycle 1(1): loaded http://localhost:55087/tests/dromaeo/cssquery-ext.html (next: http://localhost:55087/tests/dromaeo/cssquery-jquery.html) 03:09:47 INFO - PROCESS | 2405 | RSS: Main: 266858496 03:09:47 INFO - PROCESS | 2405 | 03:12:49 INFO - PROCESS | 2405 | Cycle 1(1): loaded http://localhost:55087/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55087/tests/dromaeo/cssquery-mootools.html) 03:12:49 INFO - PROCESS | 2405 | RSS: Main: 278257664 03:12:49 INFO - PROCESS | 2405 | 03:15:51 INFO - PROCESS | 2405 | Cycle 1(1): loaded http://localhost:55087/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55087/tests/dromaeo/cssquery-prototype.html) 03:15:52 INFO - PROCESS | 2405 | RSS: Main: 271159296 03:15:52 INFO - PROCESS | 2405 | 03:18:55 INFO - PROCESS | 2405 | Cycle 1(1): loaded http://localhost:55087/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55087/tests/dromaeo/cssquery-yui.html) 03:18:56 INFO - PROCESS | 2405 | RSS: Main: 277532672 03:18:56 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | Cycle 1(1): loaded http://localhost:55087/tests/dromaeo/cssquery-yui.html (next: http://localhost:55087/tests/dromaeo/cssquery-dojo.html) 03:21:59 INFO - PROCESS | 2405 | RSS: Main: 279470080 03:21:59 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | __start_tp_report 03:21:59 INFO - PROCESS | 2405 | _x_x_mozilla_page_load 03:21:59 INFO - PROCESS | 2405 | _x_x_mozilla_page_load_details 03:21:59 INFO - PROCESS | 2405 | |i|pagename|runs| 03:21:59 INFO - PROCESS | 2405 | |0;dojo.html;2594.961075583866;2863.16968079257;3386.204242003129;3389.9322013559745;3437.9999999999986;5638.238837756901;5655.57583181262;5673.716314184295;5698.974184646772;5705.714714264286;4938.2098864181735;4979.502049795019;5062.16474281744;5595.38450770415;5708.514776244025;4302.333138363559;5098.949010509903;5163.535281824635;5201.921971170435;5312.468753124676;5160.458151894053;5203.869903252411;5205.531502164805;5222.164453687411;5336.4129945705945;5128.666636668605;5146.150885103934;5186.962607478519;5227.320448341729;5274.208868669692;5180.611454140954;5191.662541934762;5196.948030519722;5197.168453047494;5253.50091741284;5030.270610761456;5171.301874246966;5187.0922588546855;5191.506806853343;5245;4493.955060449386;4606.779203511072;4675.4155730533685;5527.640703354309;5531;5976.701164941735;6036.8188954331445;6041.365656606037;6044.516438684895;6050.848728781772;6114.724837382329;6124.755009799603;6135.294441139237;6136.6011209271255;6140.846478838065;5544.16837474378;5601.467860553282;5764.596478246524;5806.477417032403;5836.474717275466;6223.159873417121;6248.50011999039;6269;6272.466840318624;6275.843103922439;5907.172995780594;5979.487585633332;5981.970090149608;6111.5309653553995;6163.445289923929;6225.3774622537385;6254.8123556293385;6275.466585340206;6281.120643109968;6283.937160628427;4991;5719.456651618173;6007.759689612367;6086.847828804315;6101.84745381369;6190.41191086846;6195.876082478325;6202.937970620326;6206.038064100072;6223.471004964629;4962.230854217602;4963.851084467399;4976.178930476503;4979.253112033151;5012.774425150877;4367.126574685076;4382.430284063052;4410.07388448423;4471.32930060487;4472.44094488189;4493.752843593568;5802.158686990363;5867.266591676041;5933.9406605934255;6236.968815155895;4689.50760170184;6262.34245404226;6280.466160376328;6289.937100629027;6303.905441418382;5599.636023658449;5625.578081643893;5856.238688970407;5858.209141765807;5863.794767183128;4525.298578720303;4798.856034318976;5326.627136100513;5404.216388623628;5439.5376393006245;5185.948140518546;5240.895182096413;6272.153259309935;6303.590266632654;6306.432421082033;4613.561711637389;6066.4540191382985;6225.595336303126;6245.8750824982335;6249.562530622995;5616.354119276172;5981.222441082631;6192.59748116389;6252.374762523711;6285.842853928689;6097.725602347817;6101.237345331833;6131.20294361748;6148.262208535004;6165.136880836776;5312.9468705314;6037.154798328325;6103.633781973096;6122.2959359674205;6147.293061298008;6065.696715164312;6095.542834287357;6108.038540470994;6135.325114237519;6139.631622102688;6065.909011364922;6093.847653808513;6104.17593624855;6122.173506576555;6129.785457508966;5728.828135155952;5769.336526299361;5843.94156058434;5895.793647222326;6002.069679199731;6025.397460253939;6032.125341825498;6054.273487181566;6060.6666633333825;6117.755289788359;5865.032269675456;5890.1164825276555;5928.466438020599;5933.940660593339;5944.019236826049 03:21:59 INFO - PROCESS | 2405 | |1;ext.html;423.7309308589045;473.03500858249174;492.2960166961246;511.84132918795177;540.891821635673;36611.4508282376;39080.60919390805;39175.608243917624;39177.99999999996;39285.60714392855;39238.803805981;39455.80272098642;39521.60478395215;39532;39916.80041599788;9020.81958360834;9280;9286.071392860727;10472.52873620685;10784.245102842804;10592;10642.201834862377;10681.626143084955;10683.946580267087;10713.410762408064;9430.528473576329;10402.947985260063;10683.038526532611;10685.091767199798;10708.678739637824;10620.362778233291;10656.840147397834;10677.466126693635;10688.412137332443;10726;9568.952155239249;10646.520906559146;10647.57409703611;10729.892701073046;10734.463276836204;35358.93923182309;35740.749073782295;35785.49501534893;35860;35904.281914361825;3731.8880433587037;3741.6747736286816;3783.2433513297174;3798.620137986207;3895.941560876589;26838.86580567104;26963.865180673973;27450.313742156406;27474.313142171406;27567.586486202723;3033.7876348655604;3067.4325249828853;3168.4613615685157;3208.51872219169;3208.951865722016;442351;442882;444551;445222;447965.99999999674;46141;47274.763626182335;52964.470355296726;53463;53700.73149634227;53946.651333716974;56586.43413565811;56725.71637141788;56921.715391422775;57056.57358566069;12426.502939882484;14129.78805317921;14492.565223043326;14564.198969056592;14645.853541464448;11182.496787644575;11246.381449020218;11253.887461125447;11254.943725281484;11289.830652540219;16318.918405408134;16848.74726879098;16947.661046778994;16978.74531881998;16995.66008679844;6190.783322583688;7313.683536963291;7366.300201480851;7396.519226250277;7442.441816863757;825.3026192867018;833.9708110216235;836.6820608168857;839.7816567692322;852.3181454836106;23547.76452235456;27387.49368784696;29275.85362073176;29286.97495587644;29776.404471910875;921.318224513855;939.2908354192541;988.0712130597216;1003.3829195045141;1182.5565412970136;14088.929555352157;16312.429064982669;16823.242954067096;17307.913460432617;17474.95150290961;495612.3948844621;504994;505418;507233;507396;60395.69802150961;60495;61489;61608.38391616116;61778;1214.6295379909213;1362.8364596248514;1393.1223329302677;1411.6047506698142;1420.453125546672;24553.63169552494;27650.723492764813;28494.287642809097;29621.51892405414;30022.399552009276;50215.497845021084;50554.74722626364;50731.73170670615;50744.49255507398;50807.74596127144;541871;545877;546164;550200;550213;31346.05961821149;32139.03582892517;37421.4386784193;38205.23589528138;38240.42639360468;40465.79767101146;41173.97065073397;41205.793971029954;41645.79177104095;41664.375034375116;34194;34264.65735342615;34404.48393274053;35295.47056794099;35478.113047174025;34210.80262190911;34333;35174.824125879204;35349.29301413906;35517.28965420625;33827.32345353128;33862.3227535443;34286;34534.48198277078;34578.308433831684;29146.41707165887;30392.848035759678;30479.695203048574;30509.54235686422;30577.847110764305;19416.90291548533;22773.430664233525;22981.54036919286;23135;23592.528149436574 03:21:59 INFO - PROCESS | 2405 | |2;jquery.html;317.83472594250975;385.50462655487684;392.2723348189109;396.3440505962632;419.4400475365387;18386.988715620635;19986.200551977923;20003.899980500097;20156.496087597818;20322.69515957258;20011.799882001174;20512.28207012755;20881.895590522065;20994.37016889492;21339.466513337175;5687.6303040302455;5927.110933359998;5980.79067232647;6050.485708714765;6101.572889897709;5709.31488221414;6034.426729460694;6060.060690592965;6108.847278818021;6116.204893363856;6064.727087281083;6107.450329470346;6127.4791642710425;6146.7541298348015;6155.8768824623485;6078.787242446515;6102.938970610281;6668.566543174678;6698.799036028927;6710.899336509908;5745.08078707409;6018.247719035121;6019.458248757589;6021.548383871227;6022.518198544106;24062.879685601634;24503.897324620433;24833.6274955874;25129;25162;2512.623106534016;2517.848929064262;2587.573050446674;2611.869406529685;2617.751313625203;9988.550515226832;10078.042585954323;10105.39367637944;10388.79222415555;10392.688219353431;1788.7406326082776;1850.4726153046317;1856.3781133320354;1873.9625207495774;1890.1022014543119;130228;136596.9434818377;137934.93097603644;138215.30892345475;140041;11753.118516111155;11753.823692644617;11904.880951190551;11948.522059117538;12116.454759535842;15633.68732625364;15639.609009774847;15642.921785391229;15644.608884777745;15698.215089245721;8127.878081828777;8214.712485062993;8339.166083391612;8368.748937531884;8429.03066147401;7848.175941526171;7852.214778522102;7980.521168729895;7991.68033278674;8008.27925486709;3966.6231707987695;3992.4210989406374;4674.766261686902;4742.762861856893;4772.236442169236;1997.9800201998085;2038.6636205026005;2061;2062.742157230346;2072.202202152182;988.658912675126;994.3586386780486;998.3111652959435;1010;1041.7916416716687;20581.38255852327;21303.78696213049;21323.46691332698;21423.46441338979;21632;1003.7289931718541;1019.7654539455818;1020.4336593190702;1055.1031623120286;1057.7620035491952;7901.367890568856;8231.588420578948;9754.317197796086;10754.89245107539;11346.432678366049;552579;552762;659782;671612;682985;93032.53483732673;114712.42643786728;140335.25902074118;143445;156852;429.5833041949304;456.47505368826114;462.643764301486;463.1732357741483;465.73220398271127;10803.621873234399;11152.330860148093;11156.609518667132;11518.193726439069;11719.179657423898;56948.14577781419;57249.57791932813;57645.711771440874;58879.70560147343;59784;73852.26147738454;87578.56210718905;87968;88429;89585.55207223922;5743;6355.586886852525;8265.545395003333;8315.708950186714;8584.871226931476;11972.102092343282;12817.551385701456;12858.67853303675;13352.332383380986;13556.93221533886;10883.292585981886;11120.499577518869;11388.487518061542;11404.885951140714;11545.826812597987;8632.395732298679;8802.779930501789;10059.69820905374;11124.110071194124;11172.162087843606;11103;11300.49147788368;11626;11670.358130302915;11791.233569817934;8456.957715211385;8561.82876342482;8584.91415085841;8656.870146947675;8673.956630216808;9777.657781977596;11162.38606876597;11423.371714555447;11598.956093951585;11604.535818567163 03:21:59 INFO - PROCESS | 2405 | |3;mootools.html;1226.705590658242;1379.3861731529462;1386.2375693368651;1414.3706050807389;1417.397606017443;6700.932990670091;8260.545669988149;8397.580120993956;8573.957130214356;8576.656933722652;8399.5380254086;8436;8502.829943401144;8570.142985701426;8571.828563428728;2274.1130958926074;2289.633658614622;2308.2382813671534;2308.4228942764307;2314.4329639238326;2262.0386335807298;2305.5965206088867;2309.2379514760087;2310.549442858635;2320.7795259450327;2259.7853203945683;2262.1064679451606;2279.282026161762;2287.4281429642588;2294.2314324701247;2179.139240000191;2255.5150642611834;2264.784845439681;2268.0814270220585;2274.647429648399;1944.9027548622512;2030.6750919852752;2220.622494175994;2279.7720227977234;2290.473191166041;8328.958355208186;8516.233538981522;8528.488290702579;8600.053994060649;8606.913930860737;5077.949220507785;5218.060749065166;5247.055530004598;5254.579633629339;5259.500347466985;3166.4617015107488;3308.759215294265;3343.9832800836084;3484.1986343140966;3512.8594856205723;1049.874015118183;1114.5987444519967;1221.9755604887853;1242.3912282981364;1260.3698150924538;9543.904560954441;10941.83587246192;10986.835197472124;11088.501017454233;11111.999920007238;3408.3183363327435;4401.581849724271;4420.359047938031;4462.129884672458;4465.754383508874;8536.658533658585;8668.176523230282;8705.608247628872;8731.170538798804;8754.47473151613;5058.367704036996;5059.418166910778;5063.620228482879;5080.923786143211;5081.23781432788;3967.484227050523;4584.381108550303;4628.39830821991;4744.074905393415;4747.098051370298;3014.6382434108045;3091.536269559539;3590.8743193988084;3633.7638053526434;3655.798931058764;1120.5349779841274;1124.775044991005;1126.0934947367448;1130.4856290387854;1137.4710759496875;752.3379426104992;833.9742117195883;889.5374405309203;891.2513488669549;917.3440989692268;5654.886902261932;5719.2279042328755;5759.769609215585;5795.768169273266;5994.100884867217;962.711186644018;967.8886928003229;967.9370840895319;989.2827699917646;1023.4268809466722;3696.390095634244;3802.2205447883116;3833.1950290438763;3940.408938659224;3941.1723538056754;9914.752131196778;10191.694249172537;10266;10281.485925703684;10283.845742313872;10084.445355505371;10132.594696212069;10207.030332118436;10275.691729248134;10277.794444111225;6666.300038495985;7322.82834746438;7935;8036.116027236999;8041.959790201012;5529.004779139633;5617.342883849193;5649.180868773922;5752.879936856118;5761.596688231783;9135.2235060018;9179.357444978787;9236.815263694823;9278.768030799283;9399.718008459757;9301.069893010645;9500.47747373901;9524.428534287965;9534.761630959281;9591.088846559565;6635.867282654223;6768.221654509597;6770.627615481196;6784.016317634014;6785.185777706707;7724.34343080822;7778.261065198797;7853.214678532101;7868.25251601097;7869.370450364072;7138.250483699137;7140.857182856417;7152.9284707152265;7254;7334.96332518334;5983.670898100472;7007.229204787367;7015.543989640657;7017.4386049116965;7077.221505634273;6342.936570634234;6352.777652782131;6415.454686351713;6431.067495213074;6447.742090316335;4801.639877009168;4904.681195722266;4976.5023497649945;5007.048660754445;5146.948530514647;4346.565343465628;4955.628327875352;4975.4278258000795;5108.003939231814;5139.177731562932 03:21:59 INFO - PROCESS | 2405 | |4;prototype.html;356.19855325517585;356.3229863259806;357.3960007587178;361.8258750355098;368.9981550092249;4429.379886815845;5285.867853303669;5385.434529374408;5538.335399752031;5622.072358060926;4254.468191476066;4926.777615727332;5156.407013193484;5342.893142137164;5369.818639899216;4206.768627725474;4247.277962746325;4247.83008679654;4283.614474697277;4289.549597292271;3736.2264113245546;4001.779902105383;4107.404426358176;4266.935995960063;4299.333603291479;3659.8170091495317;4102.446169767073;4150.626443620074;4172.833086676529;4181.393697913801;3745.5653825013687;4110.807865718938;4189.937150942738;4217.725847819897;4245.490541135053;3721.106934335767;3726.3851464508325;3730;3738.89702537752;3783.6783873370796;4236.813692166198;4544.909101817978;5061.746912654389;5276.498732620395;5339.145736682112;4898.0693668203285;4906.730129842857;4909.975450122763;4911.607071434277;4929.852104436873;5003.974980125113;5227.843164705065;5665.291838520185;5709.371969083398;5856.510982832279;3609.314230296235;4053.8175782089875;4091.672666186698;4095.344744840841;4095.4675892133832;4857.562819346276;5238.03096427166;6009.926575331504;6021.668808215546;6066;3773.716971227169;3925.9399962010098;4167.124903770242;4170.311898536768;4189.560096189917;3603;3804.087019115392;4118.519850726132;4243.596858298457;4248.532661407305;4492.2363198255725;4802.495737947465;4903.774056485879;4944.456109827916;4945.887175385581;4822.758862056883;4867.780949857265;5639.718014099279;5864.912026319608;5872.324682661549;1304.230504002643;1378.5519706095558;1398.2379603116326;1407.1346122134814;1410.8377536583218;870.6822009966317;887.8268737596236;965.3773316211145;987.521052289639;988.5008070924138;4449.710768800018;4746.727944849663;5104.719240441737;5173.198154286091;5212.8957420851375;5123.38519377677;5297.735113244323;5389.703566303812;5723.341815691166;5733.426657334317;4777.307290442866;4989.525995030466;5163;5202.063628546823;5208.801975545645;2616.8299060561003;2776.3336799168055;3012.758979281652;3018.396320735818;3029.727324540802;5366.785328586813;5423.4847689470225;5442.428545002796;5778.451047150514;5827.708614569255;4935.235038569028;5135.589152867761;5260.318855635904;5346.97326513365;5900.822975310747;5938.61399009063;5990.400959903975;5999.92001439745;6006.387221558636;6057.4851137653795;4853.549918584249;4874.415070191458;5244.816431424882;5780.94219057804;5833.67914764692;4874.610031197567;4902.7058376497525;5317.245309048082;5692.117721753135;5708.687981367553;4829.492903245108;5927.703614819328;5946.613470124428;6040.697965101639;6166.784162554289;3552.91117722059;3628.3650361187215;4294.420253265769;4334.9812793994015;4353.172897149531;5439.830436456023;5650.717464126859;5840.035969425954;5973.163757073926;6087.817365479043;5030.597552195889;5124.282600435868;5535.363433205233;5780.797672081457;5801.680907550125;5308.654937429054;5796.608813884553;5804.21643078179;5826.155207494974;5849.912251316148;5283.392409872914;5598.608097433141;5644.238027866186;5667.546596272371;5688.914666280092;5108.540231379194;5305.549028332635;5770.278715160605;5793.971030144822;5825.766969321349;5538.975289571442;5714.285714285715;5717.313392549291;5719.428057194247;5743.942560574341 03:21:59 INFO - PROCESS | 2405 | |5;yui.html;699.2518005733864;707.961062141582;760.5664771080486;795.1809636074843;809.6073404398867;14294.92852535737;15095.773563396531;15190;15201.619959501004;15210.239488025596;10832.458377081153;13736.656583585416;13920.303984800745;13950.790738138936;14096.436142554312;860.4450129666377;869.8042940338405;880.6961598248594;902.801383695586;909.1454033208763;694.9055238000145;905.0903841639143;908.1009800297724;910.9043550427209;912.0332447605559;933.3233405780788;937.039534477163;939.9718008459757;941.7363138321281;942.6842007927353;911.4713466189594;929.8651695504147;932.3753085432776;933.0063482391232;934.6354921580521;566.810118610266;575.9029049660404;692.5429216716993;694.0838093716246;695.9373656370951;14670.63323416923;14817.555473335817;15127.39490420393;15161.393544258217;15472.45846395382;12614.81077783834;13476.932615336958;13747.312634368242;13801.723965520734;13825.51610693621;9988.800223995551;11872.940635296769;11978.401079945968;12512.808209249912;12856.79421857638;4397.868063958086;4410.580994805488;4447.733136011817;4572.7713614319155;4641;93959.53020234856;95181.52409237978;95516;96196.55705164498;97657;8938.597763100546;10188.643397481053;10202.591896324213;10232.53953572091;10287.588496460208;20910.268140615004;20958.580828383652;20989.37031889046;21012.159513619285;21028.94855257231;7262;7295.963520182471;7299.4525410594415;7371.741989030465;7375.8156046099275;3897.941530877039;3997.6002399760373;3998.580149084304;4018.417329487208;4105.7331273467125;3241.9027429177163;3273.525338825857;3283.129970557804;3339.549160863301;3340.1315657929113;553.7480446396879;626.5864529410567;638.9073584330246;646.4828137489989;655.1254075808848;646.9482441404676;666.9966650166718;673.5285300289719;675.7128220506265;699.6711545573572;9541.2367010639;9780.902190978142;9857.507124643738;10128.189744820396;10129.835068967162;647.9611223326616;673.2997682410337;691.9965400173068;695.9060526828914;702.3924305475829;4568.840090596813;4831.9275210871865;4845.6608037437045;4857.514248575114;4891;86465;88625;94190;94791.52604236934;94819.05180948102;78174.82737758938;78598.60700696574;79507;80557.59721201357;80795.384092319;56823;58547.70726146342;63893.72212555816;63926.6803665988;66553.33446665568;10300.793984120126;10353.171746260432;10452.425116618659;10462.895371046192;10690.465476726287;41879.1624167521;43614;43845.342319865864;43919;44248.55751442444;57691;57762;59039.70480147572;59239.70380148072;59333.70333148307;12201.633950981484;12264.570740024057;12557.623271301876;12590.24458532491;12840.229586224856;13659.521916732867;14097.577072687836;14241.786373204617;14250.073745206528;14266.572002839932;12560.183588066746;12803.61589152327;12839.614811555668;13253.337333133497;13309.86690133125;9931.404115753077;12516.937415312865;12942.870571294543;13364.264965426995;13417.597472075853;12223.633291001284;12721.87278127244;12738;13103.541376051793;13286;10458.965791090473;10780.245382823126;10906.2365634408;10935;11131.777364452828;8163;9550.808983820423;10495.21285903545;10547.823393647575;11765.470553824927 03:21:59 INFO - PROCESS | 2405 | __end_tp_report 03:21:59 INFO - PROCESS | 2405 | __start_cc_report 03:21:59 INFO - PROCESS | 2405 | _x_x_mozilla_cycle_collect,398 03:21:59 INFO - PROCESS | 2405 | __end_cc_report 03:21:59 INFO - PROCESS | 2405 | __startTimestamp1487157719359__endTimestamp 03:21:59 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | ------- Summary: start ------- 03:21:59 INFO - PROCESS | 2405 | Number of tests: 6 03:21:59 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | [#0] dojo.html Cycles:165 Average:5607.44 Median:5863.79 stddev:696.98 (11.9%) stddev-sans-first:657.84 03:21:59 INFO - PROCESS | 2405 | Values: 2595.0 2863.2 3386.2 3389.9 3438.0 5638.2 5655.6 5673.7 5699.0 5705.7 4938.2 4979.5 5062.2 5595.4 5708.5 4302.3 5098.9 5163.5 5201.9 5312.5 5160.5 5203.9 5205.5 5222.2 5336.4 5128.7 5146.2 5187.0 5227.3 5274.2 5180.6 5191.7 5196.9 5197.2 5253.5 5030.3 5171.3 5187.1 5191.5 5245.0 4494.0 4606.8 4675.4 5527.6 5531.0 5976.7 6036.8 6041.4 6044.5 6050.8 6114.7 6124.8 6135.3 6136.6 6140.8 5544.2 5601.5 5764.6 5806.5 5836.5 6223.2 6248.5 6269.0 6272.5 6275.8 5907.2 5979.5 5982.0 6111.5 6163.4 6225.4 6254.8 6275.5 6281.1 6283.9 4991.0 5719.5 6007.8 6086.8 6101.8 6190.4 6195.9 6202.9 6206.0 6223.5 4962.2 4963.9 4976.2 4979.3 5012.8 4367.1 4382.4 4410.1 4471.3 4472.4 4493.8 5802.2 5867.3 5933.9 6237.0 4689.5 6262.3 6280.5 6289.9 6303.9 5599.6 5625.6 5856.2 5858.2 5863.8 4525.3 4798.9 5326.6 5404.2 5439.5 5185.9 5240.9 6272.2 6303.6 6306.4 4613.6 6066.5 6225.6 6245.9 6249.6 5616.4 5981.2 6192.6 6252.4 6285.8 6097.7 6101.2 6131.2 6148.3 6165.1 5312.9 6037.2 6103.6 6122.3 6147.3 6065.7 6095.5 6108.0 6135.3 6139.6 6065.9 6093.8 6104.2 6122.2 6129.8 5728.8 5769.3 5843.9 5895.8 6002.1 6025.4 6032.1 6054.3 6060.7 6117.8 5865.0 5890.1 5928.5 5933.9 5944.0 03:21:59 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | [#1] ext.html Cycles:180 Average:63133.87 Median:35322.38 stddev:133244.28 (377.2%) stddev-sans-first:133534.88 03:21:59 INFO - PROCESS | 2405 | Values: 423.7 473.0 492.3 511.8 540.9 36611.5 39080.6 39175.6 39178.0 39285.6 39238.8 39455.8 39521.6 39532.0 39916.8 9020.8 9280.0 9286.1 10472.5 10784.2 10592.0 10642.2 10681.6 10683.9 10713.4 9430.5 10402.9 10683.0 10685.1 10708.7 10620.4 10656.8 10677.5 10688.4 10726.0 9569.0 10646.5 10647.6 10729.9 10734.5 35358.9 35740.7 35785.5 35860.0 35904.3 3731.9 3741.7 3783.2 3798.6 3895.9 26838.9 26963.9 27450.3 27474.3 27567.6 3033.8 3067.4 3168.5 3208.5 3209.0 442351.0 442882.0 444551.0 445222.0 447966.0 46141.0 47274.8 52964.5 53463.0 53700.7 53946.7 56586.4 56725.7 56921.7 57056.6 12426.5 14129.8 14492.6 14564.2 14645.9 11182.5 11246.4 11253.9 11254.9 11289.8 16318.9 16848.7 16947.7 16978.7 16995.7 6190.8 7313.7 7366.3 7396.5 7442.4 825.3 834.0 836.7 839.8 852.3 23547.8 27387.5 29275.9 29287.0 29776.4 921.3 939.3 988.1 1003.4 1182.6 14088.9 16312.4 16823.2 17307.9 17475.0 495612.4 504994.0 505418.0 507233.0 507396.0 60395.7 60495.0 61489.0 61608.4 61778.0 1214.6 1362.8 1393.1 1411.6 1420.5 24553.6 27650.7 28494.3 29621.5 30022.4 50215.5 50554.7 50731.7 50744.5 50807.7 541871.0 545877.0 546164.0 550200.0 550213.0 31346.1 32139.0 37421.4 38205.2 38240.4 40465.8 41174.0 41205.8 41645.8 41664.4 34194.0 34264.7 34404.5 35295.5 35478.1 34210.8 34333.0 35174.8 35349.3 35517.3 33827.3 33862.3 34286.0 34534.5 34578.3 29146.4 30392.8 30479.7 30509.5 30577.8 19416.9 22773.4 22981.5 23135.0 23592.5 03:21:59 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | [#2] jquery.html Cycles:180 Average:36450.31 Median:2552.71 stddev:105090.68 (4116.8%) stddev-sans-first:105350.47 03:21:59 INFO - PROCESS | 2405 | Values: 317.8 385.5 392.3 396.3 419.4 18387.0 19986.2 20003.9 20156.5 20322.7 20011.8 20512.3 20881.9 20994.4 21339.5 5687.6 5927.1 5980.8 6050.5 6101.6 5709.3 6034.4 6060.1 6108.8 6116.2 6064.7 6107.5 6127.5 6146.8 6155.9 6078.8 6102.9 6668.6 6698.8 6710.9 5745.1 6018.2 6019.5 6021.5 6022.5 24062.9 24503.9 24833.6 25129.0 25162.0 2512.6 2517.8 2587.6 2611.9 2617.8 9988.6 10078.0 10105.4 10388.8 10392.7 1788.7 1850.5 1856.4 1874.0 1890.1 130228.0 136596.9 137934.9 138215.3 140041.0 11753.1 11753.8 11904.9 11948.5 12116.5 15633.7 15639.6 15642.9 15644.6 15698.2 8127.9 8214.7 8339.2 8368.7 8429.0 7848.2 7852.2 7980.5 7991.7 8008.3 3966.6 3992.4 4674.8 4742.8 4772.2 1998.0 2038.7 2061.0 2062.7 2072.2 988.7 994.4 998.3 1010.0 1041.8 20581.4 21303.8 21323.5 21423.5 21632.0 1003.7 1019.8 1020.4 1055.1 1057.8 7901.4 8231.6 9754.3 10754.9 11346.4 552579.0 552762.0 659782.0 671612.0 682985.0 93032.5 114712.4 140335.3 143445.0 156852.0 429.6 456.5 462.6 463.2 465.7 10803.6 11152.3 11156.6 11518.2 11719.2 56948.1 57249.6 57645.7 58879.7 59784.0 73852.3 87578.6 87968.0 88429.0 89585.6 5743.0 6355.6 8265.5 8315.7 8584.9 11972.1 12817.6 12858.7 13352.3 13556.9 10883.3 11120.5 11388.5 11404.9 11545.8 8632.4 8802.8 10059.7 11124.1 11172.2 11103.0 11300.5 11626.0 11670.4 11791.2 8457.0 8561.8 8584.9 8656.9 8674.0 9777.7 11162.4 11423.4 11599.0 11604.5 03:21:59 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | [#3] mootools.html Cycles:180 Average:5388.74 Median:5081.08 stddev:2986.95 (58.8%) stddev-sans-first:2978.95 03:21:59 INFO - PROCESS | 2405 | Values: 1226.7 1379.4 1386.2 1414.4 1417.4 6700.9 8260.5 8397.6 8574.0 8576.7 8399.5 8436.0 8502.8 8570.1 8571.8 2274.1 2289.6 2308.2 2308.4 2314.4 2262.0 2305.6 2309.2 2310.5 2320.8 2259.8 2262.1 2279.3 2287.4 2294.2 2179.1 2255.5 2264.8 2268.1 2274.6 1944.9 2030.7 2220.6 2279.8 2290.5 8329.0 8516.2 8528.5 8600.1 8606.9 5077.9 5218.1 5247.1 5254.6 5259.5 3166.5 3308.8 3344.0 3484.2 3512.9 1049.9 1114.6 1222.0 1242.4 1260.4 9543.9 10941.8 10986.8 11088.5 11112.0 3408.3 4401.6 4420.4 4462.1 4465.8 8536.7 8668.2 8705.6 8731.2 8754.5 5058.4 5059.4 5063.6 5080.9 5081.2 3967.5 4584.4 4628.4 4744.1 4747.1 3014.6 3091.5 3590.9 3633.8 3655.8 1120.5 1124.8 1126.1 1130.5 1137.5 752.3 834.0 889.5 891.3 917.3 5654.9 5719.2 5759.8 5795.8 5994.1 962.7 967.9 967.9 989.3 1023.4 3696.4 3802.2 3833.2 3940.4 3941.2 9914.8 10191.7 10266.0 10281.5 10283.8 10084.4 10132.6 10207.0 10275.7 10277.8 6666.3 7322.8 7935.0 8036.1 8042.0 5529.0 5617.3 5649.2 5752.9 5761.6 9135.2 9179.4 9236.8 9278.8 9399.7 9301.1 9500.5 9524.4 9534.8 9591.1 6635.9 6768.2 6770.6 6784.0 6785.2 7724.3 7778.3 7853.2 7868.3 7869.4 7138.3 7140.9 7152.9 7254.0 7335.0 5983.7 7007.2 7015.5 7017.4 7077.2 6342.9 6352.8 6415.5 6431.1 6447.7 4801.6 4904.7 4976.5 5007.0 5146.9 4346.6 4955.6 4975.4 5108.0 5139.2 03:21:59 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | [#4] prototype.html Cycles:180 Average:4606.56 Median:5083.23 stddev:1369.25 (26.9%) stddev-sans-first:1335.41 03:21:59 INFO - PROCESS | 2405 | Values: 356.2 356.3 357.4 361.8 369.0 4429.4 5285.9 5385.4 5538.3 5622.1 4254.5 4926.8 5156.4 5342.9 5369.8 4206.8 4247.3 4247.8 4283.6 4289.5 3736.2 4001.8 4107.4 4266.9 4299.3 3659.8 4102.4 4150.6 4172.8 4181.4 3745.6 4110.8 4189.9 4217.7 4245.5 3721.1 3726.4 3730.0 3738.9 3783.7 4236.8 4544.9 5061.7 5276.5 5339.1 4898.1 4906.7 4910.0 4911.6 4929.9 5004.0 5227.8 5665.3 5709.4 5856.5 3609.3 4053.8 4091.7 4095.3 4095.5 4857.6 5238.0 6009.9 6021.7 6066.0 3773.7 3925.9 4167.1 4170.3 4189.6 3603.0 3804.1 4118.5 4243.6 4248.5 4492.2 4802.5 4903.8 4944.5 4945.9 4822.8 4867.8 5639.7 5864.9 5872.3 1304.2 1378.6 1398.2 1407.1 1410.8 870.7 887.8 965.4 987.5 988.5 4449.7 4746.7 5104.7 5173.2 5212.9 5123.4 5297.7 5389.7 5723.3 5733.4 4777.3 4989.5 5163.0 5202.1 5208.8 2616.8 2776.3 3012.8 3018.4 3029.7 5366.8 5423.5 5442.4 5778.5 5827.7 4935.2 5135.6 5260.3 5347.0 5900.8 5938.6 5990.4 5999.9 6006.4 6057.5 4853.5 4874.4 5244.8 5780.9 5833.7 4874.6 4902.7 5317.2 5692.1 5708.7 4829.5 5927.7 5946.6 6040.7 6166.8 3552.9 3628.4 4294.4 4335.0 4353.2 5439.8 5650.7 5840.0 5973.2 6087.8 5030.6 5124.3 5535.4 5780.8 5801.7 5308.7 5796.6 5804.2 5826.2 5849.9 5283.4 5598.6 5644.2 5667.5 5688.9 5108.5 5305.5 5770.3 5794.0 5825.8 5539.0 5714.3 5717.3 5719.4 5743.9 03:21:59 INFO - PROCESS | 2405 | 03:21:59 INFO - PROCESS | 2405 | [#5] yui.html Cycles:180 Average:18547.33 Median:4570.81 stddev:25681.93 (561.9%) stddev-sans-first:25719.01 03:21:59 INFO - PROCESS | 2405 | Values: 699.3 708.0 760.6 795.2 809.6 14294.9 15095.8 15190.0 15201.6 15210.2 10832.5 13736.7 13920.3 13950.8 14096.4 860.4 869.8 880.7 902.8 909.1 694.9 905.1 908.1 910.9 912.0 933.3 937.0 940.0 941.7 942.7 911.5 929.9 932.4 933.0 934.6 566.8 575.9 692.5 694.1 695.9 14670.6 14817.6 15127.4 15161.4 15472.5 12614.8 13476.9 13747.3 13801.7 13825.5 9988.8 11872.9 11978.4 12512.8 12856.8 4397.9 4410.6 4447.7 4572.8 4641.0 93959.5 95181.5 95516.0 96196.6 97657.0 8938.6 10188.6 10202.6 10232.5 10287.6 20910.3 20958.6 20989.4 21012.2 21028.9 7262.0 7296.0 7299.5 7371.7 7375.8 3897.9 3997.6 3998.6 4018.4 4105.7 3241.9 3273.5 3283.1 3339.5 3340.1 553.7 626.6 638.9 646.5 655.1 646.9 667.0 673.5 675.7 699.7 9541.2 9780.9 9857.5 10128.2 10129.8 648.0 673.3 692.0 695.9 702.4 4568.8 4831.9 4845.7 4857.5 4891.0 86465.0 88625.0 94190.0 94791.5 94819.1 78174.8 78598.6 79507.0 80557.6 80795.4 56823.0 58547.7 63893.7 63926.7 66553.3 10300.8 10353.2 10452.4 10462.9 10690.5 41879.2 43614.0 43845.3 43919.0 44248.6 57691.0 57762.0 59039.7 59239.7 59333.7 12201.6 12264.6 12557.6 12590.2 12840.2 13659.5 14097.6 14241.8 14250.1 14266.6 12560.2 12803.6 12839.6 13253.3 13309.9 9931.4 12516.9 12942.9 13364.3 13417.6 12223.6 12721.9 12738.0 13103.5 13286.0 10459.0 10780.2 10906.2 10935.0 11131.8 8163.0 9550.8 10495.2 10547.8 11765.5 03:21:59 INFO - PROCESS | 2405 | -------- Summary: end -------- 03:21:59 INFO - PROCESS | 2405 | 03:22:00 INFO - TEST-INFO | 2405: exit 0 03:22:00 INFO - TEST-OK | dromaeo_css | took 1115603ms 03:22:00 INFO - TEST-START | kraken 03:22:00 INFO - operating with platform_type : mac_ 03:22:00 INFO - Initialising browser for kraken test... 03:22:00 INFO - TEST-INFO | started process 2410 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpdDEEBb/profile http://localhost:55087/getInfo.html) 03:22:02 INFO - PROCESS | 2410 | 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] 03:22:02 INFO - PROCESS | 2410 | 2017-02-15 03:22:02.802 plugin-container[2412:27264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 03:22:02 INFO - PROCESS | 2410 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:02 INFO - PROCESS | 2410 | 2017-02-15 03:22:02.804 plugin-container[2412:27264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 03:22:02 INFO - PROCESS | 2410 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:02 INFO - PROCESS | 2410 | 2017-02-15 03:22:02.804 plugin-container[2412:27264] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:22:02 INFO - PROCESS | 2410 | __metrics Screen width/height:1600/1200 03:22:02 INFO - PROCESS | 2410 | colorDepth:24 03:22:02 INFO - PROCESS | 2410 | Browser inner width/height: 1024/689 03:22:02 INFO - PROCESS | 2410 | __metrics 03:22:02 ERROR - PROCESS | 2410 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 03:22:03 INFO - TEST-INFO | 2410: exit 0 03:22:03 INFO - Browser initialized. 03:22:03 INFO - Running cycle 1/1 for kraken test... 03:22:03 INFO - TEST-INFO | started process 2413 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpdDEEBb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:06 INFO - PROCESS | 2413 | 2017-02-15 03:22:06.157 plugin-container[2414:27385] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8947, name = 'com.apple.tsm.portname' 03:22:06 INFO - PROCESS | 2413 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:06 INFO - PROCESS | 2413 | 2017-02-15 03:22:06.157 plugin-container[2414:27385] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 03:22:06 INFO - PROCESS | 2413 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:06 INFO - PROCESS | 2413 | 2017-02-15 03:22:06.157 plugin-container[2414:27385] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:22:15 INFO - PROCESS | 2413 | RSS: Main: 234356736 03:22:15 INFO - PROCESS | 2413 | 03:22:19 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55087/tests/kraken/driver.html?testName=audio-beat-detection) 03:22:19 INFO - PROCESS | 2413 | RSS: Main: 247615488 03:22:19 INFO - PROCESS | 2413 | 03:22:22 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55087/tests/kraken/driver.html?testName=audio-dft) 03:22:23 INFO - PROCESS | 2413 | RSS: Main: 256344064 03:22:23 INFO - PROCESS | 2413 | 03:22:26 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55087/tests/kraken/driver.html?testName=audio-fft) 03:22:27 INFO - PROCESS | 2413 | RSS: Main: 258183168 03:22:27 INFO - PROCESS | 2413 | 03:22:29 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55087/tests/kraken/driver.html?testName=audio-oscillator) 03:22:29 INFO - PROCESS | 2413 | RSS: Main: 260509696 03:22:29 INFO - PROCESS | 2413 | 03:22:31 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55087/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:22:32 INFO - PROCESS | 2413 | RSS: Main: 260841472 03:22:32 INFO - PROCESS | 2413 | 03:22:35 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55087/tests/kraken/driver.html?testName=imaging-darkroom) 03:22:36 INFO - PROCESS | 2413 | RSS: Main: 266366976 03:22:36 INFO - PROCESS | 2413 | 03:22:39 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55087/tests/kraken/driver.html?testName=imaging-desaturate) 03:22:39 INFO - PROCESS | 2413 | RSS: Main: 265695232 03:22:39 INFO - PROCESS | 2413 | 03:22:42 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55087/tests/kraken/driver.html?testName=json-parse-financial) 03:22:43 INFO - PROCESS | 2413 | RSS: Main: 265797632 03:22:43 INFO - PROCESS | 2413 | 03:22:45 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55087/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:22:45 INFO - PROCESS | 2413 | RSS: Main: 265404416 03:22:45 INFO - PROCESS | 2413 | 03:22:47 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55087/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:22:48 INFO - PROCESS | 2413 | RSS: Main: 265449472 03:22:48 INFO - PROCESS | 2413 | 03:22:50 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55087/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:22:51 INFO - PROCESS | 2413 | RSS: Main: 265441280 03:22:51 INFO - PROCESS | 2413 | 03:22:53 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55087/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:22:54 INFO - PROCESS | 2413 | RSS: Main: 265875456 03:22:54 INFO - PROCESS | 2413 | 03:22:57 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55087/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:22:58 INFO - PROCESS | 2413 | RSS: Main: 265650176 03:22:58 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | Cycle 1(1): loaded http://localhost:55087/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55087/tests/kraken/driver.html?testName=ai-astar) 03:23:00 INFO - PROCESS | 2413 | RSS: Main: 267620352 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | __start_tp_report 03:23:00 INFO - PROCESS | 2413 | _x_x_mozilla_page_load 03:23:00 INFO - PROCESS | 2413 | _x_x_mozilla_page_load_details 03:23:00 INFO - PROCESS | 2413 | |i|pagename|runs| 03:23:00 INFO - PROCESS | 2413 | |0;ai-astar;107;138;180;151;116;116;117;116;117;115 03:23:00 INFO - PROCESS | 2413 | |1;audio-beat-detection;158;114;101;229;146;145;119;114;136;116 03:23:00 INFO - PROCESS | 2413 | |2;audio-dft;198;204;205;220;197;197;194;194;193;190 03:23:00 INFO - PROCESS | 2413 | |3;audio-fft;81;60;86;110;84;79;59;60;74;58 03:23:00 INFO - PROCESS | 2413 | |4;audio-oscillator;90;71;84;59;88;106;68;101;104;114 03:23:00 INFO - PROCESS | 2413 | |5;imaging-gaussian-blur;132;88;98;137;105;136;89;137;77;138 03:23:00 INFO - PROCESS | 2413 | |6;imaging-darkroom;97;108;95;97;96;98;96;95;96;121 03:23:00 INFO - PROCESS | 2413 | |7;imaging-desaturate;93;94;94;92;92;92;91;93;92;91 03:23:00 INFO - PROCESS | 2413 | |8;json-parse-financial;63;65;64;63;64;65;64;64;65;65 03:23:00 INFO - PROCESS | 2413 | |9;json-stringify-tinderbox;69;78;69;76;76;68;70;69;73;71 03:23:00 INFO - PROCESS | 2413 | |10;stanford-crypto-aes;91;89;90;110;111;88;91;88;90;87 03:23:00 INFO - PROCESS | 2413 | |11;stanford-crypto-ccm;151;146;145;142;148;150;157;150;191;149 03:23:00 INFO - PROCESS | 2413 | |12;stanford-crypto-pbkdf2;200;179;188;181;238;260;175;156;170;226 03:23:00 INFO - PROCESS | 2413 | |13;stanford-crypto-sha256-iterative;68;67;72;68;71;71;67;67;80;91 03:23:00 INFO - PROCESS | 2413 | __end_tp_report 03:23:00 INFO - PROCESS | 2413 | __start_cc_report 03:23:00 INFO - PROCESS | 2413 | _x_x_mozilla_cycle_collect,975 03:23:00 INFO - PROCESS | 2413 | __end_cc_report 03:23:00 INFO - PROCESS | 2413 | __startTimestamp1487157780594__endTimestamp 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | ------- Summary: start ------- 03:23:00 INFO - PROCESS | 2413 | Number of tests: 14 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#0] ai-astar Cycles:10 Average:127.30 Median:117.00 stddev:22.64 (19.3%) stddev-sans-first:22.79 03:23:00 INFO - PROCESS | 2413 | Values: 107.0 138.0 180.0 151.0 116.0 116.0 117.0 116.0 117.0 115.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:140.50 stddev:36.76 (26.2%) stddev-sans-first:38.26 03:23:00 INFO - PROCESS | 2413 | Values: 158.0 114.0 101.0 229.0 146.0 145.0 119.0 114.0 136.0 116.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#2] audio-dft Cycles:10 Average:199.20 Median:197.50 stddev:8.68 (4.4%) stddev-sans-first:9.19 03:23:00 INFO - PROCESS | 2413 | Values: 198.0 204.0 205.0 220.0 197.0 197.0 194.0 194.0 193.0 190.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#3] audio-fft Cycles:10 Average:75.10 Median:76.50 stddev:16.58 (21.7%) stddev-sans-first:17.45 03:23:00 INFO - PROCESS | 2413 | Values: 81.0 60.0 86.0 110.0 84.0 79.0 59.0 60.0 74.0 58.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:69.50 stddev:18.17 (26.1%) stddev-sans-first:19.27 03:23:00 INFO - PROCESS | 2413 | Values: 90.0 71.0 84.0 59.0 88.0 106.0 68.0 101.0 104.0 114.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:107.50 stddev:24.60 (22.9%) stddev-sans-first:25.19 03:23:00 INFO - PROCESS | 2413 | Values: 132.0 88.0 98.0 137.0 105.0 136.0 89.0 137.0 77.0 138.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:96.00 stddev:8.33 (8.7%) stddev-sans-first:8.77 03:23:00 INFO - PROCESS | 2413 | Values: 97.0 108.0 95.0 97.0 96.0 98.0 96.0 95.0 96.0 121.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:92.50 stddev:1.07 (1.2%) stddev-sans-first:1.12 03:23:00 INFO - PROCESS | 2413 | Values: 93.0 94.0 94.0 92.0 92.0 92.0 91.0 93.0 92.0 91.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.50 stddev:0.79 (1.2%) stddev-sans-first:0.71 03:23:00 INFO - PROCESS | 2413 | Values: 63.0 65.0 64.0 63.0 64.0 65.0 64.0 64.0 65.0 65.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.00 stddev:3.60 (5.0%) stddev-sans-first:3.67 03:23:00 INFO - PROCESS | 2413 | Values: 69.0 78.0 69.0 76.0 76.0 68.0 70.0 69.0 73.0 71.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#10] stanford-crypto-aes Cycles:10 Average:93.50 Median:89.50 stddev:9.06 (10.1%) stddev-sans-first:9.56 03:23:00 INFO - PROCESS | 2413 | Values: 91.0 89.0 90.0 110.0 111.0 88.0 91.0 88.0 90.0 87.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#11] stanford-crypto-ccm Cycles:10 Average:152.90 Median:150.00 stddev:13.97 (9.3%) stddev-sans-first:14.80 03:23:00 INFO - PROCESS | 2413 | Values: 151.0 146.0 145.0 142.0 148.0 150.0 157.0 150.0 191.0 149.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.30 Median:194.00 stddev:33.42 (17.2%) stddev-sans-first:35.44 03:23:00 INFO - PROCESS | 2413 | Values: 200.0 179.0 188.0 181.0 238.0 260.0 175.0 156.0 170.0 226.0 03:23:00 INFO - PROCESS | 2413 | 03:23:00 INFO - PROCESS | 2413 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:71.00 stddev:7.70 (10.8%) stddev-sans-first:8.02 03:23:00 INFO - PROCESS | 2413 | Values: 68.0 67.0 72.0 68.0 71.0 71.0 67.0 67.0 80.0 91.0 03:23:00 INFO - PROCESS | 2413 | -------- Summary: end -------- 03:23:00 INFO - PROCESS | 2413 | 03:23:01 INFO - TEST-INFO | 2413: exit 0 03:23:01 INFO - TEST-OK | kraken | took 61483ms 03:23:01 INFO - SUITE-END | took 1177s 03:23:01 INFO - Completed test suite (00:19:37) 03:23:01 INFO - javascript benchmark 03:23:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7138.9239678231015, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2594.961075583866, 2863.16968079257, 3386.204242003129, 3389.9322013559745, 3437.9999999999986, 5638.238837756901, 5655.57583181262, 5673.716314184295, 5698.974184646772, 5705.714714264286, 4938.2098864181735, 4979.502049795019, 5062.16474281744, 5595.38450770415, 5708.514776244025, 4302.333138363559, 5098.949010509903, 5163.535281824635, 5201.921971170435, 5312.468753124676, 5160.458151894053, 5203.869903252411, 5205.531502164805, 5222.164453687411, 5336.4129945705945, 5128.666636668605, 5146.150885103934, 5186.962607478519, 5227.320448341729, 5274.208868669692, 5180.611454140954, 5191.662541934762, 5196.948030519722, 5197.168453047494, 5253.50091741284, 5030.270610761456, 5171.301874246966, 5187.0922588546855, 5191.506806853343, 5245.0, 4493.955060449386, 4606.779203511072, 4675.4155730533685, 5527.640703354309, 5531.0, 5976.701164941735, 6036.8188954331445, 6041.365656606037, 6044.516438684895, 6050.848728781772, 6114.724837382329, 6124.755009799603, 6135.294441139237, 6136.6011209271255, 6140.846478838065, 5544.16837474378, 5601.467860553282, 5764.596478246524, 5806.477417032403, 5836.474717275466, 6223.159873417121, 6248.50011999039, 6269.0, 6272.466840318624, 6275.843103922439, 5907.172995780594, 5979.487585633332, 5981.970090149608, 6111.5309653553995, 6163.445289923929, 6225.3774622537385, 6254.8123556293385, 6275.466585340206, 6281.120643109968, 6283.937160628427, 4991.0, 5719.456651618173, 6007.759689612367, 6086.847828804315, 6101.84745381369, 6190.41191086846, 6195.876082478325, 6202.937970620326, 6206.038064100072, 6223.471004964629, 4962.230854217602, 4963.851084467399, 4976.178930476503, 4979.253112033151, 5012.774425150877, 4367.126574685076, 4382.430284063052, 4410.07388448423, 4471.32930060487, 4472.44094488189, 4493.752843593568, 5802.158686990363, 5867.266591676041, 5933.9406605934255, 6236.968815155895, 4689.50760170184, 6262.34245404226, 6280.466160376328, 6289.937100629027, 6303.905441418382, 5599.636023658449, 5625.578081643893, 5856.238688970407, 5858.209141765807, 5863.794767183128, 4525.298578720303, 4798.856034318976, 5326.627136100513, 5404.216388623628, 5439.5376393006245, 5185.948140518546, 5240.895182096413, 6272.153259309935, 6303.590266632654, 6306.432421082033, 4613.561711637389, 6066.4540191382985, 6225.595336303126, 6245.8750824982335, 6249.562530622995, 5616.354119276172, 5981.222441082631, 6192.59748116389, 6252.374762523711, 6285.842853928689, 6097.725602347817, 6101.237345331833, 6131.20294361748, 6148.262208535004, 6165.136880836776, 5312.9468705314, 6037.154798328325, 6103.633781973096, 6122.2959359674205, 6147.293061298008, 6065.696715164312, 6095.542834287357, 6108.038540470994, 6135.325114237519, 6139.631622102688, 6065.909011364922, 6093.847653808513, 6104.17593624855, 6122.173506576555, 6129.785457508966, 5728.828135155952, 5769.336526299361, 5843.94156058434, 5895.793647222326, 6002.069679199731, 6025.397460253939, 6032.125341825498, 6054.273487181566, 6060.6666633333825, 6117.755289788359, 5865.032269675456, 5890.1164825276555, 5928.466438020599, 5933.940660593339, 5944.019236826049], "value": 5563.639158350636, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.7309308589045, 473.03500858249174, 492.2960166961246, 511.84132918795177, 540.891821635673, 36611.4508282376, 39080.60919390805, 39175.608243917624, 39177.99999999996, 39285.60714392855, 39238.803805981, 39455.80272098642, 39521.60478395215, 39532.0, 39916.80041599788, 9020.81958360834, 9280.0, 9286.071392860727, 10472.52873620685, 10784.245102842804, 10592.0, 10642.201834862377, 10681.626143084955, 10683.946580267087, 10713.410762408064, 9430.528473576329, 10402.947985260063, 10683.038526532611, 10685.091767199798, 10708.678739637824, 10620.362778233291, 10656.840147397834, 10677.466126693635, 10688.412137332443, 10726.0, 9568.952155239249, 10646.520906559146, 10647.57409703611, 10729.892701073046, 10734.463276836204, 35358.93923182309, 35740.749073782295, 35785.49501534893, 35860.0, 35904.281914361825, 3731.8880433587037, 3741.6747736286816, 3783.2433513297174, 3798.620137986207, 3895.941560876589, 26838.86580567104, 26963.865180673973, 27450.313742156406, 27474.313142171406, 27567.586486202723, 3033.7876348655604, 3067.4325249828853, 3168.4613615685157, 3208.51872219169, 3208.951865722016, 442351.0, 442882.0, 444551.0, 445222.0, 447965.99999999674, 46141.0, 47274.763626182335, 52964.470355296726, 53463.0, 53700.73149634227, 53946.651333716974, 56586.43413565811, 56725.71637141788, 56921.715391422775, 57056.57358566069, 12426.502939882484, 14129.78805317921, 14492.565223043326, 14564.198969056592, 14645.853541464448, 11182.496787644575, 11246.381449020218, 11253.887461125447, 11254.943725281484, 11289.830652540219, 16318.918405408134, 16848.74726879098, 16947.661046778994, 16978.74531881998, 16995.66008679844, 6190.783322583688, 7313.683536963291, 7366.300201480851, 7396.519226250277, 7442.441816863757, 825.3026192867018, 833.9708110216235, 836.6820608168857, 839.7816567692322, 852.3181454836106, 23547.76452235456, 27387.49368784696, 29275.85362073176, 29286.97495587644, 29776.404471910875, 921.318224513855, 939.2908354192541, 988.0712130597216, 1003.3829195045141, 1182.5565412970136, 14088.929555352157, 16312.429064982669, 16823.242954067096, 17307.913460432617, 17474.95150290961, 495612.3948844621, 504994.0, 505418.0, 507233.0, 507396.0, 60395.69802150961, 60495.0, 61489.0, 61608.38391616116, 61778.0, 1214.6295379909213, 1362.8364596248514, 1393.1223329302677, 1411.6047506698142, 1420.453125546672, 24553.63169552494, 27650.723492764813, 28494.287642809097, 29621.51892405414, 30022.399552009276, 50215.497845021084, 50554.74722626364, 50731.73170670615, 50744.49255507398, 50807.74596127144, 541871.0, 545877.0, 546164.0, 550200.0, 550213.0, 31346.05961821149, 32139.03582892517, 37421.4386784193, 38205.23589528138, 38240.42639360468, 40465.79767101146, 41173.97065073397, 41205.793971029954, 41645.79177104095, 41664.375034375116, 34194.0, 34264.65735342615, 34404.48393274053, 35295.47056794099, 35478.113047174025, 34210.80262190911, 34333.0, 35174.824125879204, 35349.29301413906, 35517.28965420625, 33827.32345353128, 33862.3227535443, 34286.0, 34534.48198277078, 34578.308433831684, 29146.41707165887, 30392.848035759678, 30479.695203048574, 30509.54235686422, 30577.847110764305, 19416.90291548533, 22773.430664233525, 22981.54036919286, 23135.0, 23592.528149436574], "value": 19096.80396965429, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.83472594250975, 385.50462655487684, 392.2723348189109, 396.3440505962632, 419.4400475365387, 18386.988715620635, 19986.200551977923, 20003.899980500097, 20156.496087597818, 20322.69515957258, 20011.799882001174, 20512.28207012755, 20881.895590522065, 20994.37016889492, 21339.466513337175, 5687.6303040302455, 5927.110933359998, 5980.79067232647, 6050.485708714765, 6101.572889897709, 5709.31488221414, 6034.426729460694, 6060.060690592965, 6108.847278818021, 6116.204893363856, 6064.727087281083, 6107.450329470346, 6127.4791642710425, 6146.7541298348015, 6155.8768824623485, 6078.787242446515, 6102.938970610281, 6668.566543174678, 6698.799036028927, 6710.899336509908, 5745.08078707409, 6018.247719035121, 6019.458248757589, 6021.548383871227, 6022.518198544106, 24062.879685601634, 24503.897324620433, 24833.6274955874, 25129.0, 25162.0, 2512.623106534016, 2517.848929064262, 2587.573050446674, 2611.869406529685, 2617.751313625203, 9988.550515226832, 10078.042585954323, 10105.39367637944, 10388.79222415555, 10392.688219353431, 1788.7406326082776, 1850.4726153046317, 1856.3781133320354, 1873.9625207495774, 1890.1022014543119, 130228.0, 136596.9434818377, 137934.93097603644, 138215.30892345475, 140041.0, 11753.118516111155, 11753.823692644617, 11904.880951190551, 11948.522059117538, 12116.454759535842, 15633.68732625364, 15639.609009774847, 15642.921785391229, 15644.608884777745, 15698.215089245721, 8127.878081828777, 8214.712485062993, 8339.166083391612, 8368.748937531884, 8429.03066147401, 7848.175941526171, 7852.214778522102, 7980.521168729895, 7991.68033278674, 8008.27925486709, 3966.6231707987695, 3992.4210989406374, 4674.766261686902, 4742.762861856893, 4772.236442169236, 1997.9800201998085, 2038.6636205026005, 2061.0, 2062.742157230346, 2072.202202152182, 988.658912675126, 994.3586386780486, 998.3111652959435, 1010.0, 1041.7916416716687, 20581.38255852327, 21303.78696213049, 21323.46691332698, 21423.46441338979, 21632.0, 1003.7289931718541, 1019.7654539455818, 1020.4336593190702, 1055.1031623120286, 1057.7620035491952, 7901.367890568856, 8231.588420578948, 9754.317197796086, 10754.89245107539, 11346.432678366049, 552579.0, 552762.0, 659782.0, 671612.0, 682985.0, 93032.53483732673, 114712.42643786728, 140335.25902074118, 143445.0, 156852.0, 429.5833041949304, 456.47505368826114, 462.643764301486, 463.1732357741483, 465.73220398271127, 10803.621873234399, 11152.330860148093, 11156.609518667132, 11518.193726439069, 11719.179657423898, 56948.14577781419, 57249.57791932813, 57645.711771440874, 58879.70560147343, 59784.0, 73852.26147738454, 87578.56210718905, 87968.0, 88429.0, 89585.55207223922, 5743.0, 6355.586886852525, 8265.545395003333, 8315.708950186714, 8584.871226931476, 11972.102092343282, 12817.551385701456, 12858.67853303675, 13352.332383380986, 13556.93221533886, 10883.292585981886, 11120.499577518869, 11388.487518061542, 11404.885951140714, 11545.826812597987, 8632.395732298679, 8802.779930501789, 10059.69820905374, 11124.110071194124, 11172.162087843606, 11103.0, 11300.49147788368, 11626.0, 11670.358130302915, 11791.233569817934, 8456.957715211385, 8561.82876342482, 8584.91415085841, 8656.870146947675, 8673.956630216808, 9777.657781977596, 11162.38606876597, 11423.371714555447, 11598.956093951585, 11604.535818567163], "value": 9439.382026368183, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1226.705590658242, 1379.3861731529462, 1386.2375693368651, 1414.3706050807389, 1417.397606017443, 6700.932990670091, 8260.545669988149, 8397.580120993956, 8573.957130214356, 8576.656933722652, 8399.5380254086, 8436.0, 8502.829943401144, 8570.142985701426, 8571.828563428728, 2274.1130958926074, 2289.633658614622, 2308.2382813671534, 2308.4228942764307, 2314.4329639238326, 2262.0386335807298, 2305.5965206088867, 2309.2379514760087, 2310.549442858635, 2320.7795259450327, 2259.7853203945683, 2262.1064679451606, 2279.282026161762, 2287.4281429642588, 2294.2314324701247, 2179.139240000191, 2255.5150642611834, 2264.784845439681, 2268.0814270220585, 2274.647429648399, 1944.9027548622512, 2030.6750919852752, 2220.622494175994, 2279.7720227977234, 2290.473191166041, 8328.958355208186, 8516.233538981522, 8528.488290702579, 8600.053994060649, 8606.913930860737, 5077.949220507785, 5218.060749065166, 5247.055530004598, 5254.579633629339, 5259.500347466985, 3166.4617015107488, 3308.759215294265, 3343.9832800836084, 3484.1986343140966, 3512.8594856205723, 1049.874015118183, 1114.5987444519967, 1221.9755604887853, 1242.3912282981364, 1260.3698150924538, 9543.904560954441, 10941.83587246192, 10986.835197472124, 11088.501017454233, 11111.999920007238, 3408.3183363327435, 4401.581849724271, 4420.359047938031, 4462.129884672458, 4465.754383508874, 8536.658533658585, 8668.176523230282, 8705.608247628872, 8731.170538798804, 8754.47473151613, 5058.367704036996, 5059.418166910778, 5063.620228482879, 5080.923786143211, 5081.23781432788, 3967.484227050523, 4584.381108550303, 4628.39830821991, 4744.074905393415, 4747.098051370298, 3014.6382434108045, 3091.536269559539, 3590.8743193988084, 3633.7638053526434, 3655.798931058764, 1120.5349779841274, 1124.775044991005, 1126.0934947367448, 1130.4856290387854, 1137.4710759496875, 752.3379426104992, 833.9742117195883, 889.5374405309203, 891.2513488669549, 917.3440989692268, 5654.886902261932, 5719.2279042328755, 5759.769609215585, 5795.768169273266, 5994.100884867217, 962.711186644018, 967.8886928003229, 967.9370840895319, 989.2827699917646, 1023.4268809466722, 3696.390095634244, 3802.2205447883116, 3833.1950290438763, 3940.408938659224, 3941.1723538056754, 9914.752131196778, 10191.694249172537, 10266.0, 10281.485925703684, 10283.845742313872, 10084.445355505371, 10132.594696212069, 10207.030332118436, 10275.691729248134, 10277.794444111225, 6666.300038495985, 7322.82834746438, 7935.0, 8036.116027236999, 8041.959790201012, 5529.004779139633, 5617.342883849193, 5649.180868773922, 5752.879936856118, 5761.596688231783, 9135.2235060018, 9179.357444978787, 9236.815263694823, 9278.768030799283, 9399.718008459757, 9301.069893010645, 9500.47747373901, 9524.428534287965, 9534.761630959281, 9591.088846559565, 6635.867282654223, 6768.221654509597, 6770.627615481196, 6784.016317634014, 6785.185777706707, 7724.34343080822, 7778.261065198797, 7853.214678532101, 7868.25251601097, 7869.370450364072, 7138.250483699137, 7140.857182856417, 7152.9284707152265, 7254.0, 7334.96332518334, 5983.670898100472, 7007.229204787367, 7015.543989640657, 7017.4386049116965, 7077.221505634273, 6342.936570634234, 6352.777652782131, 6415.454686351713, 6431.067495213074, 6447.742090316335, 4801.639877009168, 4904.681195722266, 4976.5023497649945, 5007.048660754445, 5146.948530514647, 4346.565343465628, 4955.628327875352, 4975.4278258000795, 5108.003939231814, 5139.177731562932], "value": 4353.371466621276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.19855325517585, 356.3229863259806, 357.3960007587178, 361.8258750355098, 368.9981550092249, 4429.379886815845, 5285.867853303669, 5385.434529374408, 5538.335399752031, 5622.072358060926, 4254.468191476066, 4926.777615727332, 5156.407013193484, 5342.893142137164, 5369.818639899216, 4206.768627725474, 4247.277962746325, 4247.83008679654, 4283.614474697277, 4289.549597292271, 3736.2264113245546, 4001.779902105383, 4107.404426358176, 4266.935995960063, 4299.333603291479, 3659.8170091495317, 4102.446169767073, 4150.626443620074, 4172.833086676529, 4181.393697913801, 3745.5653825013687, 4110.807865718938, 4189.937150942738, 4217.725847819897, 4245.490541135053, 3721.106934335767, 3726.3851464508325, 3730.0, 3738.89702537752, 3783.6783873370796, 4236.813692166198, 4544.909101817978, 5061.746912654389, 5276.498732620395, 5339.145736682112, 4898.0693668203285, 4906.730129842857, 4909.975450122763, 4911.607071434277, 4929.852104436873, 5003.974980125113, 5227.843164705065, 5665.291838520185, 5709.371969083398, 5856.510982832279, 3609.314230296235, 4053.8175782089875, 4091.672666186698, 4095.344744840841, 4095.4675892133832, 4857.562819346276, 5238.03096427166, 6009.926575331504, 6021.668808215546, 6066.0, 3773.716971227169, 3925.9399962010098, 4167.124903770242, 4170.311898536768, 4189.560096189917, 3603.0, 3804.087019115392, 4118.519850726132, 4243.596858298457, 4248.532661407305, 4492.2363198255725, 4802.495737947465, 4903.774056485879, 4944.456109827916, 4945.887175385581, 4822.758862056883, 4867.780949857265, 5639.718014099279, 5864.912026319608, 5872.324682661549, 1304.230504002643, 1378.5519706095558, 1398.2379603116326, 1407.1346122134814, 1410.8377536583218, 870.6822009966317, 887.8268737596236, 965.3773316211145, 987.521052289639, 988.5008070924138, 4449.710768800018, 4746.727944849663, 5104.719240441737, 5173.198154286091, 5212.8957420851375, 5123.38519377677, 5297.735113244323, 5389.703566303812, 5723.341815691166, 5733.426657334317, 4777.307290442866, 4989.525995030466, 5163.0, 5202.063628546823, 5208.801975545645, 2616.8299060561003, 2776.3336799168055, 3012.758979281652, 3018.396320735818, 3029.727324540802, 5366.785328586813, 5423.4847689470225, 5442.428545002796, 5778.451047150514, 5827.708614569255, 4935.235038569028, 5135.589152867761, 5260.318855635904, 5346.97326513365, 5900.822975310747, 5938.61399009063, 5990.400959903975, 5999.92001439745, 6006.387221558636, 6057.4851137653795, 4853.549918584249, 4874.415070191458, 5244.816431424882, 5780.94219057804, 5833.67914764692, 4874.610031197567, 4902.7058376497525, 5317.245309048082, 5692.117721753135, 5708.687981367553, 4829.492903245108, 5927.703614819328, 5946.613470124428, 6040.697965101639, 6166.784162554289, 3552.91117722059, 3628.3650361187215, 4294.420253265769, 4334.9812793994015, 4353.172897149531, 5439.830436456023, 5650.717464126859, 5840.035969425954, 5973.163757073926, 6087.817365479043, 5030.597552195889, 5124.282600435868, 5535.363433205233, 5780.797672081457, 5801.680907550125, 5308.654937429054, 5796.608813884553, 5804.21643078179, 5826.155207494974, 5849.912251316148, 5283.392409872914, 5598.608097433141, 5644.238027866186, 5667.546596272371, 5688.914666280092, 5108.540231379194, 5305.549028332635, 5770.278715160605, 5793.971030144822, 5825.766969321349, 5538.975289571442, 5714.285714285715, 5717.313392549291, 5719.428057194247, 5743.942560574341], "value": 4187.157134529171, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.2518005733864, 707.961062141582, 760.5664771080486, 795.1809636074843, 809.6073404398867, 14294.92852535737, 15095.773563396531, 15190.0, 15201.619959501004, 15210.239488025596, 10832.458377081153, 13736.656583585416, 13920.303984800745, 13950.790738138936, 14096.436142554312, 860.4450129666377, 869.8042940338405, 880.6961598248594, 902.801383695586, 909.1454033208763, 694.9055238000145, 905.0903841639143, 908.1009800297724, 910.9043550427209, 912.0332447605559, 933.3233405780788, 937.039534477163, 939.9718008459757, 941.7363138321281, 942.6842007927353, 911.4713466189594, 929.8651695504147, 932.3753085432776, 933.0063482391232, 934.6354921580521, 566.810118610266, 575.9029049660404, 692.5429216716993, 694.0838093716246, 695.9373656370951, 14670.63323416923, 14817.555473335817, 15127.39490420393, 15161.393544258217, 15472.45846395382, 12614.81077783834, 13476.932615336958, 13747.312634368242, 13801.723965520734, 13825.51610693621, 9988.800223995551, 11872.940635296769, 11978.401079945968, 12512.808209249912, 12856.79421857638, 4397.868063958086, 4410.580994805488, 4447.733136011817, 4572.7713614319155, 4641.0, 93959.53020234856, 95181.52409237978, 95516.0, 96196.55705164498, 97657.0, 8938.597763100546, 10188.643397481053, 10202.591896324213, 10232.53953572091, 10287.588496460208, 20910.268140615004, 20958.580828383652, 20989.37031889046, 21012.159513619285, 21028.94855257231, 7262.0, 7295.963520182471, 7299.4525410594415, 7371.741989030465, 7375.8156046099275, 3897.941530877039, 3997.6002399760373, 3998.580149084304, 4018.417329487208, 4105.7331273467125, 3241.9027429177163, 3273.525338825857, 3283.129970557804, 3339.549160863301, 3340.1315657929113, 553.7480446396879, 626.5864529410567, 638.9073584330246, 646.4828137489989, 655.1254075808848, 646.9482441404676, 666.9966650166718, 673.5285300289719, 675.7128220506265, 699.6711545573572, 9541.2367010639, 9780.902190978142, 9857.507124643738, 10128.189744820396, 10129.835068967162, 647.9611223326616, 673.2997682410337, 691.9965400173068, 695.9060526828914, 702.3924305475829, 4568.840090596813, 4831.9275210871865, 4845.6608037437045, 4857.514248575114, 4891.0, 86465.0, 88625.0, 94190.0, 94791.52604236934, 94819.05180948102, 78174.82737758938, 78598.60700696574, 79507.0, 80557.59721201357, 80795.384092319, 56823.0, 58547.70726146342, 63893.72212555816, 63926.6803665988, 66553.33446665568, 10300.793984120126, 10353.171746260432, 10452.425116618659, 10462.895371046192, 10690.465476726287, 41879.1624167521, 43614.0, 43845.342319865864, 43919.0, 44248.55751442444, 57691.0, 57762.0, 59039.70480147572, 59239.70380148072, 59333.70333148307, 12201.633950981484, 12264.570740024057, 12557.623271301876, 12590.24458532491, 12840.229586224856, 13659.521916732867, 14097.577072687836, 14241.786373204617, 14250.073745206528, 14266.572002839932, 12560.183588066746, 12803.61589152327, 12839.614811555668, 13253.337333133497, 13309.86690133125, 9931.404115753077, 12516.937415312865, 12942.870571294543, 13364.264965426995, 13417.597472075853, 12223.633291001284, 12721.87278127244, 12738.0, 13103.541376051793, 13286.0, 10458.965791090473, 10780.245382823126, 10906.2365634408, 10935.0, 11131.777364452828, 8163.0, 9550.808983820423, 10495.21285903545, 10547.823393647575, 11765.470553824927], "value": 7240.075424949108, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 138.0, 180.0, 151.0, 116.0, 116.0, 117.0, 116.0, 117.0, 115.0], "value": 127.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 114.0, 101.0, 229.0, 146.0, 145.0, 119.0, 114.0, 136.0, 116.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 204.0, 205.0, 220.0, 197.0, 197.0, 194.0, 194.0, 193.0, 190.0], "value": 199.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 86.0, 110.0, 84.0, 79.0, 59.0, 60.0, 74.0, 58.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 84.0, 59.0, 88.0, 106.0, 68.0, 101.0, 104.0, 114.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 88.0, 98.0, 137.0, 105.0, 136.0, 89.0, 137.0, 77.0, 138.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 108.0, 95.0, 97.0, 96.0, 98.0, 96.0, 95.0, 96.0, 121.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 92.0, 92.0, 92.0, 91.0, 93.0, 92.0, 91.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 64.0, 63.0, 64.0, 65.0, 64.0, 64.0, 65.0, 65.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 69.0, 76.0, 76.0, 68.0, 70.0, 69.0, 73.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 90.0, 110.0, 111.0, 88.0, 91.0, 88.0, 90.0, 87.0], "value": 93.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 146.0, 145.0, 142.0, 148.0, 150.0, 157.0, 150.0, 191.0, 149.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 179.0, 188.0, 181.0, 238.0, 260.0, 175.0, 156.0, 170.0, 226.0], "value": 197.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 68.0, 71.0, 71.0, 67.0, 67.0, 80.0, 91.0], "value": 72.2, "unit": "score"}]}]} 03:23:01 INFO - Return code: 0 03:23:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:23:01 ERROR - # TBPL SUCCESS # 03:23:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:23:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:23:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:23:01 INFO - 2017-02-15 03:23:01.866 screenresolution[2418:27748] starting screenresolution argv=screenresolution get 03:23:01 INFO - 2017-02-15 03:23:01.868 screenresolution[2418:27748] Display 0: 1600x1200x32@60 03:23:01 INFO - 2017-02-15 03:23:01.874 screenresolution[2419:27751] starting screenresolution argv=screenresolution list 03:23:01 INFO - Available Modes on Display 0 03:23:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:23:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:23:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:23:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:23:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:23:02 INFO - Intel Iris: 03:23:02 INFO - Chipset Model: Intel Iris 03:23:02 INFO - Type: GPU 03:23:02 INFO - Bus: Built-In 03:23:02 INFO - VRAM (Dynamic, Max): 1536 MB 03:23:02 INFO - Vendor: Intel (0x8086) 03:23:02 INFO - Device ID: 0x0a2e 03:23:02 INFO - Revision ID: 0x0009 03:23:02 INFO - Displays: 03:23:02 INFO - Display: 03:23:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:23:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:23:02 INFO - Display Serial Number: 1600x1200 60 03:23:02 INFO - Main Display: Yes 03:23:02 INFO - Mirror: Off 03:23:02 INFO - Online: Yes 03:23:02 INFO - Rotation: Supported 03:23:02 INFO - Return code: 0 03:23:02 INFO - Running post-action listener: _resource_record_post_action 03:23:02 INFO - [mozharness: 2017-02-15 11:23:02.115300Z] Finished run-tests step (success) 03:23:02 INFO - Running post-run listener: _resource_record_post_run 03:23:02 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 1642496; Write bytes: 555616768; Read time: 17; Write time: 1805 03:23:02 INFO - TinderboxPrint: CPU usage
54.5% 03:23:02 INFO - TinderboxPrint: I/O read bytes / time
1,642,496 / 17 03:23:02 INFO - TinderboxPrint: I/O write bytes / time
555,616,768 / 1,805 03:23:02 INFO - TinderboxPrint: CPU idle
2,183.8 (45.5%) 03:23:02 INFO - TinderboxPrint: CPU system
969.1 (20.2%) 03:23:02 INFO - TinderboxPrint: CPU user
1,648.9 (34.3%) 03:23:02 INFO - TinderboxPrint: Swap in / out
253,915,136 / 0 03:23:02 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248441344; Write bytes: 242259456; Read time: 18063; Write time: 460 03:23:02 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 1634304; Write bytes: 300033024; Read time: 17; Write time: 1311 03:23:02 INFO - Running post-run listener: _upload_blobber_files 03:23:02 INFO - Blob upload gear active. 03:23:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:23:02 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 03:23:02 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'] 03:23:02 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 03:23:03 INFO - (blobuploader) - INFO - Open directory for files ... 03:23:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:23:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:23:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:04 INFO - (blobuploader) - INFO - Done attempting. 03:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:05 INFO - (blobuploader) - INFO - Done attempting. 03:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:06 INFO - (blobuploader) - INFO - Done attempting. 03:23:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:23:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:23:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:08 INFO - (blobuploader) - INFO - Done attempting. 03:23:08 INFO - (blobuploader) - INFO - Iteration through files over. 03:23:08 INFO - Return code: 0 03:23:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:23:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:23:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70a15cee79bae2c1d8f3d7850404632eed9f585df0f5c5b07d08fb899970534fc1fc252bc96a1498cc3a06220e1bf88fbb26a9fc962fab262a4b6ac2e38a42df", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce59c549e688513f33191cf52c1eb71da3364bfcf515177cd0e9edf7edc90280599bb76a1d1558e87b6440d29dff01600aae93c6b13da96f635f28233ed11fea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9afeb313009168b72947806cba0d51f0acd17169ccb9a8af304e17ae7ce9c11d7a2a656083f025ee1614ce4c5eebd2a4de7cfcae770f8049d0a2e7b6157e8619", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a48f2c4c7640f1f0bc055077c3e0211355a50eee93b3c9480393ce2d134194e53d445c86d80434778db1fe3cf392920d0ab53807c2e83a45b99c2e8eac3b6cd9"} 03:23:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:23:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:23:08 INFO - Contents: 03:23:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70a15cee79bae2c1d8f3d7850404632eed9f585df0f5c5b07d08fb899970534fc1fc252bc96a1498cc3a06220e1bf88fbb26a9fc962fab262a4b6ac2e38a42df", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce59c549e688513f33191cf52c1eb71da3364bfcf515177cd0e9edf7edc90280599bb76a1d1558e87b6440d29dff01600aae93c6b13da96f635f28233ed11fea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9afeb313009168b72947806cba0d51f0acd17169ccb9a8af304e17ae7ce9c11d7a2a656083f025ee1614ce4c5eebd2a4de7cfcae770f8049d0a2e7b6157e8619", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a48f2c4c7640f1f0bc055077c3e0211355a50eee93b3c9480393ce2d134194e53d445c86d80434778db1fe3cf392920d0ab53807c2e83a45b99c2e8eac3b6cd9"} 03:23:08 INFO - Running post-run listener: copy_logs_to_upload_dir 03:23:08 INFO - Copying logs to upload dir... 03:23:08 INFO - mkdir: /builds/slave/test/build/upload/logs 03:23:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.331431 ========= master_lag: 1.86 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-15 03:23:10.723156) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-15 03:23:10.727881) ========= 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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70a15cee79bae2c1d8f3d7850404632eed9f585df0f5c5b07d08fb899970534fc1fc252bc96a1498cc3a06220e1bf88fbb26a9fc962fab262a4b6ac2e38a42df", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce59c549e688513f33191cf52c1eb71da3364bfcf515177cd0e9edf7edc90280599bb76a1d1558e87b6440d29dff01600aae93c6b13da96f635f28233ed11fea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9afeb313009168b72947806cba0d51f0acd17169ccb9a8af304e17ae7ce9c11d7a2a656083f025ee1614ce4c5eebd2a4de7cfcae770f8049d0a2e7b6157e8619", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a48f2c4c7640f1f0bc055077c3e0211355a50eee93b3c9480393ce2d134194e53d445c86d80434778db1fe3cf392920d0ab53807c2e83a45b99c2e8eac3b6cd9"} build_url:https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70a15cee79bae2c1d8f3d7850404632eed9f585df0f5c5b07d08fb899970534fc1fc252bc96a1498cc3a06220e1bf88fbb26a9fc962fab262a4b6ac2e38a42df", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce59c549e688513f33191cf52c1eb71da3364bfcf515177cd0e9edf7edc90280599bb76a1d1558e87b6440d29dff01600aae93c6b13da96f635f28233ed11fea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9afeb313009168b72947806cba0d51f0acd17169ccb9a8af304e17ae7ce9c11d7a2a656083f025ee1614ce4c5eebd2a4de7cfcae770f8049d0a2e7b6157e8619", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a48f2c4c7640f1f0bc055077c3e0211355a50eee93b3c9480393ce2d134194e53d445c86d80434778db1fe3cf392920d0ab53807c2e83a45b99c2e8eac3b6cd9"}' ========= master_lag: 1.13 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-15 03:23:11.872608) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:23:11.872946) ========= 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.Wk54jZfbCL/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.oIRt9CNhh8/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.005210 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:23:11.994472) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 03:23:11.994780) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 03:23:11.996670) ========= ========= Total master_lag: 3.19 =========