builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0133 starttime: 1490441265.9 results: success (0) buildid: 20170325030203 builduid: 98b62b7ec1e34fa686d00fb27fe4295f revision: 65b0ac174753b22c01156d72fb42d2abd3176dd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:45.903003) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:45.903471) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:45.903760) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:45.928886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-25 04:27:45.929270) ========= 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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-25 04:27:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 50.3M=0s 2017-03-25 04:27:47 (50.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.465520 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-25 04:27:47.418144) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:47.418482) ========= 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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.021858 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:47.467021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2017-03-25 04:27:47.467393) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65b0ac174753b22c01156d72fb42d2abd3176dd1 --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 65b0ac174753b22c01156d72fb42d2abd3176dd1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nSRkO1XoNk/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.2Vq9OViNr7/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-25 04:27:47,569 truncating revision to first 12 chars 2017-03-25 04:27:47,569 Setting DEBUG logging. 2017-03-25 04:27:47,569 attempt 1/10 2017-03-25 04:27:47,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65b0ac174753?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-25 04:27:54,527 unpacking tar archive at: mozilla-central-65b0ac174753/testing/mozharness/ program finished with exit code 0 elapsedTime=7.217921 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2017-03-25 04:27:54.709257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:54.709840) ========= script_repo_revision: 65b0ac174753b22c01156d72fb42d2abd3176dd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:54.710447) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:54.710837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 04:27:54.732740) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 24 mins, 12 secs) (at 2017-03-25 04:27:54.733094) ========= /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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:27:54 INFO - MultiFileLogger online at 20170325 04:27:54 in /builds/slave/test 04:27:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:27:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:27:54 INFO - {'append_to_log': False, 04:27:54 INFO - 'base_work_dir': '/builds/slave/test', 04:27:54 INFO - 'blob_upload_branch': 'Firefox', 04:27:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:27:54 INFO - 'branch': 'Firefox', 04:27:54 INFO - 'buildbot_json_path': 'buildprops.json', 04:27:54 INFO - 'config_files': ('talos/mac_config.py',), 04:27:54 INFO - 'default_actions': ('clobber', 04:27:54 INFO - 'read-buildbot-config', 04:27:54 INFO - 'download-and-extract', 04:27:54 INFO - 'populate-webroot', 04:27:54 INFO - 'create-virtualenv', 04:27:54 INFO - 'install', 04:27:54 INFO - 'run-tests'), 04:27:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:27:54 INFO - 'download_minidump_stackwalk': True, 04:27:54 INFO - 'download_symbols': 'ondemand', 04:27:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:27:54 INFO - 'tooltool.py': '/tools/tooltool.py', 04:27:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:27:54 INFO - '/tools/misc-python/virtualenv.py')}, 04:27:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:27:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:27:54 INFO - 'gecko_profile': False, 04:27:54 INFO - 'gecko_profile_interval': 0, 04:27:54 INFO - 'installer_path': 'installer.exe', 04:27:54 INFO - 'log_level': 'info', 04:27:54 INFO - 'log_name': 'talos', 04:27:54 INFO - 'log_to_console': True, 04:27:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:27:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:27:54 INFO - 'opt_config_files': (), 04:27:54 INFO - 'pip_index': False, 04:27:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:27:54 INFO - 'cmd': ('bash', 04:27:54 INFO - '-c', 04:27:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:27:54 INFO - 'enabled': True, 04:27:54 INFO - 'halt_on_failure': False, 04:27:54 INFO - 'name': 'check_screen_resolution'},), 04:27:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:27:54 INFO - 'cmd': ('bash', 04:27:54 INFO - '-c', 04:27:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:27:54 INFO - 'enabled': True, 04:27:54 INFO - 'halt_on_failure': False, 04:27:54 INFO - 'name': 'check_screen_resolution'},), 04:27:54 INFO - 'run_cmd_checks_enabled': True, 04:27:54 INFO - 'suite': 'dromaeojs-e10s', 04:27:54 INFO - 'system_bits': '32', 04:27:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:27:54 INFO - 'title': 't-yosemite-r7-0133', 04:27:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:27:54 INFO - 'use_talos_json': True, 04:27:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:27:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:27:54 INFO - 'work_dir': 'build'} 04:27:54 INFO - [mozharness: 2017-03-25 11:27:54.962633Z] Running clobber step. 04:27:54 INFO - Running pre-action listener: _resource_record_pre_action 04:27:54 INFO - Running main action method: clobber 04:27:54 INFO - rmtree: /builds/slave/test/build 04:27:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:27:58 INFO - Running post-action listener: _resource_record_post_action 04:27:58 INFO - [mozharness: 2017-03-25 11:27:58.014392Z] Finished clobber step (success) 04:27:58 INFO - [mozharness: 2017-03-25 11:27:58.014516Z] Running read-buildbot-config step. 04:27:58 INFO - Running pre-action listener: _resource_record_pre_action 04:27:58 INFO - Running main action method: read_buildbot_config 04:27:58 INFO - Using buildbot properties: 04:27:58 INFO - { 04:27:58 INFO - "project": "", 04:27:58 INFO - "product": "firefox", 04:27:58 INFO - "script_repo_revision": "production", 04:27:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:27:58 INFO - "repository": "", 04:27:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:27:58 INFO - "buildid": "20170325030203", 04:27:58 INFO - "stage_platform": "macosx64", 04:27:58 INFO - "basedir": "/builds/slave/test", 04:27:58 INFO - "nightly_build": "True", 04:27:58 INFO - "buildnumber": 15, 04:27:58 INFO - "slavename": "t-yosemite-r7-0133", 04:27:58 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:27:58 INFO - "platform": "yosemite_r7", 04:27:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:27:58 INFO - "branch": "mozilla-central", 04:27:58 INFO - "repo_path": "mozilla-central", 04:27:58 INFO - "revision": "65b0ac174753b22c01156d72fb42d2abd3176dd1", 04:27:58 INFO - "pgo_build": "False", 04:27:58 INFO - "builduid": "98b62b7ec1e34fa686d00fb27fe4295f", 04:27:58 INFO - "slavebuilddir": "test" 04:27:58 INFO - } 04:27:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:27:58 INFO - Running post-action listener: _resource_record_post_action 04:27:58 INFO - [mozharness: 2017-03-25 11:27:58.018017Z] Finished read-buildbot-config step (success) 04:27:58 INFO - [mozharness: 2017-03-25 11:27:58.018130Z] Running download-and-extract step. 04:27:58 INFO - Running pre-action listener: _resource_record_pre_action 04:27:58 INFO - Running main action method: download_and_extract 04:27:58 INFO - mkdir: /builds/slave/test/build/tests 04:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:58 INFO - trying https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:27:58 INFO - Downloading https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:27:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:28:33 INFO - retry: attempt #1 caught exception: '' 04:28:33 INFO - retry: Failed, sleeping 30 seconds before retrying 04:29:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #2 04:29:35 INFO - retry: attempt #2 caught exception: '' 04:29:35 INFO - retry: Failed, sleeping 60 seconds before retrying 04:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #3 04:30:36 INFO - Downloaded 1325 bytes. 04:30:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:30:36 INFO - Using the following test package requirements: 04:30:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:30:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:30:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:30:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:30:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'jsshell-mac.zip'], 04:30:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:30:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:30:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:30:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:30:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:30:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:30:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:30:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:30:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:30:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:30:36 INFO - Fetch https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:30:38 INFO - Expected file size: 52413521 04:30:38 INFO - Obtained file size: 52413521 04:30:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:30:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:30:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:30:54 INFO - Fetch https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:30:57 INFO - Expected file size: 52413521 04:30:57 INFO - Obtained file size: 52413521 04:31:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:31:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:31:12 INFO - Fetch https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:31:14 INFO - Expected file size: 14404171 04:31:14 INFO - Obtained file size: 14404171 04:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:14 INFO - trying https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:31:14 INFO - Downloading https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:31:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:31:23 INFO - Downloaded 75667010 bytes. 04:31:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:31:23 INFO - mkdir: /builds/slave/test/properties 04:31:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:31:23 INFO - Writing to file /builds/slave/test/properties/build_url 04:31:23 INFO - Contents: 04:31:23 INFO - build_url:https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:31:23 INFO - Running post-action listener: _resource_record_post_action 04:31:23 INFO - Running post-action listener: set_extra_try_arguments 04:31:23 INFO - [mozharness: 2017-03-25 11:31:23.785007Z] Finished download-and-extract step (success) 04:31:23 INFO - [mozharness: 2017-03-25 11:31:23.785131Z] Running populate-webroot step. 04:31:23 INFO - Running pre-action listener: _resource_record_pre_action 04:31:23 INFO - Running main action method: populate_webroot 04:31:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:31:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:31:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:31:23 INFO - '--tppagecycles', 04:31:23 INFO - '7'], 04:31:23 INFO - 'tests': ['tsvgm']}}, 04:31:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:31:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:31:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:31:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:31:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:31:23 INFO - 'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:31:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:31:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:31:23 INFO - 'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['damp', 'tps']}, 04:31:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:31:23 INFO - 'tests': ['damp', 'tps']}, 04:31:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['dromaeo_dom']}, 04:31:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:31:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['basic_compositor_video']}, 04:31:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:31:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['a11yr', 04:31:23 INFO - 'ts_paint', 04:31:23 INFO - 'tpaint', 04:31:23 INFO - 'sessionrestore', 04:31:23 INFO - 'sessionrestore_no_auto_restore', 04:31:23 INFO - 'tabpaint']}, 04:31:23 INFO - 'other-e10s': {'tests': ['a11yr', 04:31:23 INFO - 'ts_paint', 04:31:23 INFO - 'tpaint', 04:31:23 INFO - 'sessionrestore', 04:31:23 INFO - 'sessionrestore_no_auto_restore', 04:31:23 INFO - 'tabpaint']}, 04:31:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:31:23 INFO - 'ts_paint', 04:31:23 INFO - 'tpaint', 04:31:23 INFO - 'sessionrestore', 04:31:23 INFO - 'sessionrestore_no_auto_restore', 04:31:23 INFO - 'tabpaint']}, 04:31:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:31:23 INFO - 'ts_paint', 04:31:23 INFO - 'tpaint', 04:31:23 INFO - 'sessionrestore', 04:31:23 INFO - 'sessionrestore_no_auto_restore', 04:31:23 INFO - 'tabpaint']}, 04:31:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['a11yr', 04:31:23 INFO - 'ts_paint', 04:31:23 INFO - 'tpaint', 04:31:23 INFO - 'sessionrestore', 04:31:23 INFO - 'sessionrestore_no_auto_restore', 04:31:23 INFO - 'tabpaint']}, 04:31:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['a11yr', 04:31:23 INFO - 'ts_paint', 04:31:23 INFO - 'tpaint', 04:31:23 INFO - 'sessionrestore', 04:31:23 INFO - 'sessionrestore_no_auto_restore', 04:31:23 INFO - 'tabpaint']}, 04:31:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['tsvgx', 04:31:23 INFO - 'tsvgr_opacity', 04:31:23 INFO - 'tart', 04:31:23 INFO - 'tscrollx', 04:31:23 INFO - 'cart', 04:31:23 INFO - 'tsvg_static']}, 04:31:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:31:23 INFO - 'tsvgr_opacity', 04:31:23 INFO - 'tart', 04:31:23 INFO - 'tscrollx', 04:31:23 INFO - 'cart', 04:31:23 INFO - 'tsvg_static']}, 04:31:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:31:23 INFO - 'talos_options': ['--disable-e10s'], 04:31:23 INFO - 'tests': ['tp5o']}, 04:31:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:31:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:31:23 INFO - 'talos_options': ['--disable-e10s', 04:31:23 INFO - '--xperf_path', 04:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:31:23 INFO - 'tests': ['tp5n']}, 04:31:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:31:23 INFO - 'talos_options': ['--xperf_path', 04:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:31:23 INFO - 'tests': ['tp5n']}}, 04:31:23 INFO - 'talos.zip': {'path': '', 04:31:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:31:23 INFO - Running post-action listener: _resource_record_post_action 04:31:23 INFO - [mozharness: 2017-03-25 11:31:23.803138Z] Finished populate-webroot step (success) 04:31:23 INFO - [mozharness: 2017-03-25 11:31:23.803262Z] Running create-virtualenv step. 04:31:23 INFO - Running pre-action listener: _resource_record_pre_action 04:31:23 INFO - Running main action method: create_virtualenv 04:31:23 INFO - Creating virtualenv /builds/slave/test/build/venv 04:31:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:31:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:31:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:31:23 INFO - Using real prefix '/tools/python27' 04:31:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:31:24 INFO - Installing distribute.............................................................................................................................................................................................done. 04:31:27 INFO - Installing pip.................done. 04:31:27 INFO - Return code: 0 04:31:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:31:27 INFO - Reading from file tmpfile_stdout 04:31:27 INFO - Output received: 04:31:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:31:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:27 INFO - 'HOME': '/Users/cltbld', 04:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:27 INFO - 'LOGNAME': 'cltbld', 04:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:27 INFO - 'MOZ_NO_REMOTE': '1', 04:31:27 INFO - 'NO_EM_RESTART': '1', 04:31:27 INFO - 'PAGER': '/bin/cat', 04:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:27 INFO - 'PWD': '/builds/slave/test', 04:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:27 INFO - 'SHELL': '/bin/bash', 04:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:27 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:27 INFO - 'USER': 'cltbld', 04:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:27 INFO - 'XPC_FLAGS': '0x0', 04:31:27 INFO - 'XPC_SERVICE_NAME': '0', 04:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:31:28 INFO - Cleaning up... 04:31:28 INFO - Return code: 0 04:31:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:28 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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:31:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:28 INFO - 'HOME': '/Users/cltbld', 04:31:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:28 INFO - 'LOGNAME': 'cltbld', 04:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:28 INFO - 'MOZ_NO_REMOTE': '1', 04:31:28 INFO - 'NO_EM_RESTART': '1', 04:31:28 INFO - 'PAGER': '/bin/cat', 04:31:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:28 INFO - 'PWD': '/builds/slave/test', 04:31:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:28 INFO - 'SHELL': '/bin/bash', 04:31:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:28 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:28 INFO - 'USER': 'cltbld', 04:31:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:28 INFO - 'XPC_FLAGS': '0x0', 04:31:28 INFO - 'XPC_SERVICE_NAME': '0', 04:31:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:28 INFO - Downloading/unpacking psutil>=3.1.1 04:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:31:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:31:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:31:33 INFO - Installing collected packages: psutil 04:31:33 INFO - Running setup.py install for psutil 04:31:33 INFO - building 'psutil._psutil_osx' extension 04:31:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:31:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:31:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:31:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:31:33 INFO - building 'psutil._psutil_posix' extension 04:31:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:31:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:31:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:31:33 INFO - ^ 04:31:33 INFO - 1 warning generated. 04:31:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:31:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:31:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:31:33 INFO - Successfully installed psutil 04:31:33 INFO - Cleaning up... 04:31:34 INFO - Return code: 0 04:31:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:34 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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:31:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:34 INFO - 'HOME': '/Users/cltbld', 04:31:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:34 INFO - 'LOGNAME': 'cltbld', 04:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:34 INFO - 'MOZ_NO_REMOTE': '1', 04:31:34 INFO - 'NO_EM_RESTART': '1', 04:31:34 INFO - 'PAGER': '/bin/cat', 04:31:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:34 INFO - 'PWD': '/builds/slave/test', 04:31:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:34 INFO - 'SHELL': '/bin/bash', 04:31:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:34 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:34 INFO - 'USER': 'cltbld', 04:31:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:34 INFO - 'XPC_FLAGS': '0x0', 04:31:34 INFO - 'XPC_SERVICE_NAME': '0', 04:31:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:31:37 INFO - Installing collected packages: mozsystemmonitor 04:31:37 INFO - Running setup.py install for mozsystemmonitor 04:31:37 INFO - Successfully installed mozsystemmonitor 04:31:37 INFO - Cleaning up... 04:31:37 INFO - Return code: 0 04:31:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:31: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')]} 04:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31: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')]} 04:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31: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', '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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:31: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 jsonschema==2.5.1 04:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:37 INFO - 'HOME': '/Users/cltbld', 04:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:37 INFO - 'LOGNAME': 'cltbld', 04:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:37 INFO - 'MOZ_NO_REMOTE': '1', 04:31:37 INFO - 'NO_EM_RESTART': '1', 04:31:37 INFO - 'PAGER': '/bin/cat', 04:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:37 INFO - 'PWD': '/builds/slave/test', 04:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:37 INFO - 'SHELL': '/bin/bash', 04:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:37 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:37 INFO - 'USER': 'cltbld', 04:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:37 INFO - 'XPC_FLAGS': '0x0', 04:31:37 INFO - 'XPC_SERVICE_NAME': '0', 04:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:38 INFO - Downloading/unpacking jsonschema==2.5.1 04:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:31:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:41 INFO - Downloading functools32-3.2.3-2.tar.gz 04:31:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:31:41 INFO - warning: no files found matching '*.txt' 04:31:41 INFO - no previously-included directories found matching 'build' 04:31:41 INFO - no previously-included directories found matching 'dist' 04:31:41 INFO - no previously-included directories found matching '.git*' 04:31:41 INFO - Installing collected packages: jsonschema, functools32 04:31:41 INFO - Running setup.py install for functools32 04:31:41 INFO - warning: no files found matching '*.txt' 04:31:41 INFO - no previously-included directories found matching 'build' 04:31:41 INFO - no previously-included directories found matching 'dist' 04:31:41 INFO - no previously-included directories found matching '.git*' 04:31:42 INFO - Successfully installed jsonschema functools32 04:31:42 INFO - Cleaning up... 04:31:42 INFO - Return code: 0 04:31:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:31:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:42 INFO - 'HOME': '/Users/cltbld', 04:31:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:42 INFO - 'LOGNAME': 'cltbld', 04:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:42 INFO - 'MOZ_NO_REMOTE': '1', 04:31:42 INFO - 'NO_EM_RESTART': '1', 04:31:42 INFO - 'PAGER': '/bin/cat', 04:31:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:42 INFO - 'PWD': '/builds/slave/test', 04:31:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:42 INFO - 'SHELL': '/bin/bash', 04:31:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:42 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:42 INFO - 'USER': 'cltbld', 04:31:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:42 INFO - 'XPC_FLAGS': '0x0', 04:31:42 INFO - 'XPC_SERVICE_NAME': '0', 04:31:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:31:42 INFO - Cleaning up... 04:31:42 INFO - Return code: 0 04:31:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:31:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:42 INFO - 'HOME': '/Users/cltbld', 04:31:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:42 INFO - 'LOGNAME': 'cltbld', 04:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:42 INFO - 'MOZ_NO_REMOTE': '1', 04:31:42 INFO - 'NO_EM_RESTART': '1', 04:31:42 INFO - 'PAGER': '/bin/cat', 04:31:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:42 INFO - 'PWD': '/builds/slave/test', 04:31:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:42 INFO - 'SHELL': '/bin/bash', 04:31:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:42 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:42 INFO - 'USER': 'cltbld', 04:31:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:42 INFO - 'XPC_FLAGS': '0x0', 04:31:42 INFO - 'XPC_SERVICE_NAME': '0', 04:31:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:42 INFO - Downloading/unpacking blobuploader==1.2.4 04:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:45 INFO - Downloading blobuploader-1.2.4.tar.gz 04:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:31:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:31:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:47 INFO - Downloading docopt-0.6.1.tar.gz 04:31:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:31:47 INFO - Installing collected packages: blobuploader, requests, docopt 04:31:47 INFO - Running setup.py install for blobuploader 04:31:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:31:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:31:47 INFO - Running setup.py install for requests 04:31:47 INFO - Running setup.py install for docopt 04:31:48 INFO - Successfully installed blobuploader requests docopt 04:31:48 INFO - Cleaning up... 04:31:48 INFO - Return code: 0 04:31:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:48 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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:31:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:48 INFO - 'HOME': '/Users/cltbld', 04:31:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:48 INFO - 'LOGNAME': 'cltbld', 04:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:48 INFO - 'MOZ_NO_REMOTE': '1', 04:31:48 INFO - 'NO_EM_RESTART': '1', 04:31:48 INFO - 'PAGER': '/bin/cat', 04:31:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:48 INFO - 'PWD': '/builds/slave/test', 04:31:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:48 INFO - 'SHELL': '/bin/bash', 04:31:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:48 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:48 INFO - 'USER': 'cltbld', 04:31:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:48 INFO - 'XPC_FLAGS': '0x0', 04:31:48 INFO - 'XPC_SERVICE_NAME': '0', 04:31:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:48 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-sHZpIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:48 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-IfkEj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:48 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-2RQGrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:48 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-iLuHbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:48 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-W9wgtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:48 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ByiEG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-u08NSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-9kMk8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-B_RUIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-i9uC7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-o1uYMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-A4M1Qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-1DbAdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-wXPmhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:49 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-G9du9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:50 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-0j1FeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:50 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-CEFlAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:31:50 INFO - Running setup.py install for manifestparser 04:31:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:31:50 INFO - Running setup.py install for mozcrash 04:31:50 INFO - Running setup.py install for mozdebug 04:31:50 INFO - Running setup.py install for mozdevice 04:31:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:31:50 INFO - Running setup.py install for mozfile 04:31:50 INFO - Running setup.py install for mozhttpd 04:31:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:31:51 INFO - Running setup.py install for mozinfo 04:31:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:31:51 INFO - Running setup.py install for mozInstall 04:31:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:31:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:31:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:31:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:31:51 INFO - Running setup.py install for mozleak 04:31:51 INFO - Running setup.py install for mozlog 04:31:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:31:51 INFO - Running setup.py install for moznetwork 04:31:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:31:51 INFO - Running setup.py install for mozprocess 04:31:51 INFO - Running setup.py install for mozprofile 04:31:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:31:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:31:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:31:52 INFO - Running setup.py install for mozrunner 04:31:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:31:52 INFO - Running setup.py install for mozscreenshot 04:31:52 INFO - Running setup.py install for moztest 04:31:52 INFO - Running setup.py install for mozversion 04:31:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:31:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:31:52 INFO - Cleaning up... 04:31:52 INFO - Return code: 0 04:31:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:52 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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:52 INFO - 'HOME': '/Users/cltbld', 04:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:52 INFO - 'LOGNAME': 'cltbld', 04:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:52 INFO - 'MOZ_NO_REMOTE': '1', 04:31:52 INFO - 'NO_EM_RESTART': '1', 04:31:52 INFO - 'PAGER': '/bin/cat', 04:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:52 INFO - 'PWD': '/builds/slave/test', 04:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:52 INFO - 'SHELL': '/bin/bash', 04:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:52 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:52 INFO - 'USER': 'cltbld', 04:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:52 INFO - 'XPC_FLAGS': '0x0', 04:31:52 INFO - 'XPC_SERVICE_NAME': '0', 04:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-4QKWAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Xzfs9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-wYbhSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-cHIBmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-1ZGleS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-0Ubzhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-jOr_45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-s1DFb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Su__9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:53 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-qqvn76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:54 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-wq7HA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:54 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-w3CzXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:54 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-F4HcWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:54 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-u9bXVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:54 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-vggEoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:54 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-0GSSD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:54 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Sw_C5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:58 INFO - Downloading blessings-1.6.tar.gz 04:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:31:58 INFO - Installing collected packages: blessings 04:31:58 INFO - Running setup.py install for blessings 04:31:58 INFO - Successfully installed blessings 04:31:58 INFO - Cleaning up... 04:31:58 INFO - Return code: 0 04:31:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:31:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:58 INFO - Reading from file tmpfile_stdout 04:31:58 INFO - Current package versions: 04:31:58 INFO - blessings == 1.6 04:31:58 INFO - blobuploader == 1.2.4 04:31:58 INFO - docopt == 0.6.1 04:31:58 INFO - functools32 == 3.2.3-2 04:31:58 INFO - jsonschema == 2.5.1 04:31:58 INFO - manifestparser == 1.1 04:31:58 INFO - mozInstall == 1.12 04:31:58 INFO - mozcrash == 1.0 04:31:58 INFO - mozdebug == 0.1 04:31:58 INFO - mozdevice == 0.49 04:31:58 INFO - mozfile == 1.2 04:31:58 INFO - mozhttpd == 0.7 04:31:58 INFO - mozinfo == 0.9 04:31:58 INFO - mozleak == 0.1 04:31:58 INFO - mozlog == 3.4 04:31:58 INFO - moznetwork == 0.27 04:31:58 INFO - mozprocess == 0.25 04:31:58 INFO - mozprofile == 0.28 04:31:58 INFO - mozrunner == 6.13 04:31:58 INFO - mozscreenshot == 0.1 04:31:58 INFO - mozsystemmonitor == 0.3 04:31:58 INFO - moztest == 0.8 04:31:58 INFO - mozversion == 1.4 04:31:58 INFO - psutil == 3.1.1 04:31:58 INFO - requests == 1.2.3 04:31:58 INFO - wsgiref == 0.1.2 04:31:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:58 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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:58 INFO - 'HOME': '/Users/cltbld', 04:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:58 INFO - 'LOGNAME': 'cltbld', 04:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:58 INFO - 'MOZ_NO_REMOTE': '1', 04:31:58 INFO - 'NO_EM_RESTART': '1', 04:31:58 INFO - 'PAGER': '/bin/cat', 04:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:58 INFO - 'PWD': '/builds/slave/test', 04:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:58 INFO - 'SHELL': '/bin/bash', 04:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:58 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:58 INFO - 'USER': 'cltbld', 04:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:58 INFO - 'XPC_FLAGS': '0x0', 04:31:58 INFO - 'XPC_SERVICE_NAME': '0', 04:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:31:59 INFO - Cleaning up... 04:31:59 INFO - Return code: 0 04:31:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:31: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')]} 04:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31: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')]} 04:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:59 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 0x10b1d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f992a588f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, '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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:31:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:31:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:31:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:31:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:59 INFO - 'HOME': '/Users/cltbld', 04:31:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:59 INFO - 'LOGNAME': 'cltbld', 04:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:59 INFO - 'MOZ_NO_REMOTE': '1', 04:31:59 INFO - 'NO_EM_RESTART': '1', 04:31:59 INFO - 'PAGER': '/bin/cat', 04:31:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:59 INFO - 'PWD': '/builds/slave/test', 04:31:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:59 INFO - 'SHELL': '/bin/bash', 04:31:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:31:59 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:31:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:59 INFO - 'USER': 'cltbld', 04:31:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:59 INFO - 'XPC_FLAGS': '0x0', 04:31:59 INFO - 'XPC_SERVICE_NAME': '0', 04:31:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:31:59 INFO - Cleaning up... 04:31:59 INFO - Return code: 0 04:31:59 INFO - Running post-action listener: _resource_record_post_action 04:31:59 INFO - Running post-action listener: _start_resource_monitoring 04:31:59 INFO - Starting resource monitoring. 04:31:59 INFO - [mozharness: 2017-03-25 11:31:59.348215Z] Finished create-virtualenv step (success) 04:31:59 INFO - [mozharness: 2017-03-25 11:31:59.349159Z] Running install step. 04:31:59 INFO - Running pre-action listener: _resource_record_pre_action 04:31:59 INFO - Running main action method: install 04:31:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:59 INFO - Reading from file tmpfile_stdout 04:31:59 INFO - Detecting whether we're running mozinstall >=1.0... 04:31:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:31:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:31:59 INFO - Reading from file tmpfile_stdout 04:31:59 INFO - Output received: 04:31:59 INFO - Usage: mozinstall [options] installer 04:31:59 INFO - Options: 04:31:59 INFO - -h, --help show this help message and exit 04:31:59 INFO - -d DEST, --destination=DEST 04:31:59 INFO - Directory to install application into. [default: 04:31:59 INFO - "/builds/slave/test"] 04:31:59 INFO - --app=APP Application being installed. [default: firefox] 04:31:59 INFO - mkdir: /builds/slave/test/build/application 04:31:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:31:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:32:20 INFO - Reading from file tmpfile_stdout 04:32:20 INFO - Output received: 04:32:20 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:32:20 INFO - Running post-action listener: _resource_record_post_action 04:32:20 INFO - [mozharness: 2017-03-25 11:32:20.012975Z] Finished install step (success) 04:32:20 INFO - [mozharness: 2017-03-25 11:32:20.013119Z] Running run-tests step. 04:32:20 INFO - Running pre-action listener: _resource_record_pre_action 04:32:20 INFO - Running main action method: run_tests 04:32:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:32:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:32:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:32:20 INFO - 2017-03-25 04:32:20.027 screenresolution[1934:24109] starting screenresolution argv=screenresolution get 04:32:20 INFO - 2017-03-25 04:32:20.030 screenresolution[1934:24109] Display 0: 1600x1200x32@60 04:32:20 INFO - 2017-03-25 04:32:20.036 screenresolution[1935:24112] starting screenresolution argv=screenresolution list 04:32:20 INFO - Available Modes on Display 0 04:32:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:32:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:32:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:32:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:32:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:32:20 INFO - Intel Iris: 04:32:20 INFO - Chipset Model: Intel Iris 04:32:20 INFO - Type: GPU 04:32:20 INFO - Bus: Built-In 04:32:20 INFO - VRAM (Dynamic, Max): 1536 MB 04:32:20 INFO - Vendor: Intel (0x8086) 04:32:20 INFO - Device ID: 0x0a2e 04:32:20 INFO - Revision ID: 0x0009 04:32:20 INFO - Displays: 04:32:20 INFO - Display: 04:32:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:32:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:32:20 INFO - Display Serial Number: 1600x1200 60 04:32:20 INFO - Main Display: Yes 04:32:20 INFO - Mirror: Off 04:32:20 INFO - Online: Yes 04:32:20 INFO - Rotation: Supported 04:32:20 INFO - Return code: 0 04:32:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:32:20 INFO - Python 2.7.3 04:32:20 INFO - Return code: 0 04:32:20 INFO - grabbing minidump binary from tooltool 04:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:20 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 0x10afb5d30>, '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 0x10aedcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afaabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:32:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:32:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:32:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:32:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:32:20 INFO - Return code: 0 04:32:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:32:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:32:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:32:20 INFO - ENV: RUST_BACKTRACE is now 1 04:32:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:32:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:32:20 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/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:32:20 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/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:32:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nSRkO1XoNk/Render', 04:32:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:20 INFO - 'HOME': '/Users/cltbld', 04:32:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:20 INFO - 'LOGNAME': 'cltbld', 04:32:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:32:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:32:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:20 INFO - 'MOZ_NO_REMOTE': '1', 04:32:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:32:20 INFO - 'NO_EM_RESTART': '1', 04:32:20 INFO - 'PAGER': '/bin/cat', 04:32:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:20 INFO - 'PWD': '/builds/slave/test', 04:32:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:32:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:20 INFO - 'RUST_BACKTRACE': '1', 04:32:20 INFO - 'SHELL': '/bin/bash', 04:32:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2Vq9OViNr7/Listeners', 04:32:20 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 04:32:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:20 INFO - 'USER': 'cltbld', 04:32:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:20 INFO - 'XPC_FLAGS': '0x0', 04:32:20 INFO - 'XPC_SERVICE_NAME': '0', 04:32:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:20 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/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:32:20 INFO - mozversion application_buildid: 20170325030203 04:32:20 INFO - mozversion application_changeset: 65b0ac174753b22c01156d72fb42d2abd3176dd1 04:32:20 INFO - mozversion application_display_name: Nightly 04:32:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:32:20 INFO - mozversion application_name: Firefox 04:32:20 INFO - mozversion application_remotingname: firefox 04:32:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:32:20 INFO - mozversion application_vendor: Mozilla 04:32:20 INFO - mozversion application_version: 55.0a1 04:32:20 INFO - mozversion platform_buildid: 20170325030203 04:32:20 INFO - mozversion platform_changeset: 65b0ac174753b22c01156d72fb42d2abd3176dd1 04:32:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:32:20 INFO - mozversion platform_version: 55.0a1 04:32:20 INFO - using testdate: 1490441540 04:32:20 INFO - actual date: 1490441540 04:32:20 INFO - starting webserver on 'localhost:57043' 04:32:20 INFO - SUITE-START | Running 2 tests 04:32:20 INFO - TEST-START | dromaeo_css 04:32:20 INFO - operating with platform_type : mac_ 04:32:20 INFO - Initialising browser for dromaeo_css test... 04:32:20 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpiRXgAC/profile http://localhost:57043/getInfo.html) 04:32:23 INFO - PID 1945 | 2017-03-25 04:32:23.953 plugin-container[1948:24286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 04:32:23 INFO - PID 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:32:23 INFO - PID 1945 | 2017-03-25 04:32:23.954 plugin-container[1948:24286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 04:32:23 INFO - PID 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:32:23 INFO - PID 1945 | 2017-03-25 04:32:23.954 plugin-container[1948:24286] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:32:24 INFO - PID 1945 | __metrics Screen width/height:1600/1200 04:32:24 INFO - PID 1945 | colorDepth:24 04:32:24 INFO - PID 1945 | Browser inner width/height: 1024/689 04:32:24 INFO - PID 1945 | __metrics 04:32:24 ERROR - PID 1945 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3392: TypeError: this.tabs is undefined 04:32:24 INFO - TEST-INFO | 1945: exit 0 04:32:24 INFO - Browser initialized. 04:32:24 INFO - Running cycle 1/1 for dromaeo_css test... 04:32:24 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpiRXgAC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:27 INFO - PID 1949 | 2017-03-25 04:32:27.181 plugin-container[1950:24412] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 04:32:27 INFO - PID 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:32:27 INFO - PID 1949 | 2017-03-25 04:32:27.182 plugin-container[1950:24412] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 04:32:27 INFO - PID 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:32:27 INFO - PID 1949 | 2017-03-25 04:32:27.182 plugin-container[1950:24412] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:32:36 INFO - PID 1949 | RSS: Main: 227831808 04:32:36 INFO - PID 1949 | 04:35:41 INFO - PID 1949 | Cycle 1(1): loaded http://localhost:57043/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57043/tests/dromaeo/cssquery-ext.html) 04:35:42 INFO - PID 1949 | RSS: Main: 254156800 04:35:42 INFO - PID 1949 | 04:37:25 INFO - PID 1949 | ************************* 04:37:25 INFO - PID 1949 | A coding exception was thrown and uncaught in a Task. 04:37:25 INFO - PID 1949 | 04:37:25 ERROR - PID 1949 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:37:25 INFO - PID 1949 | Full stack: 04:37:25 INFO - PID 1949 | ************************* 04:38:45 INFO - PID 1949 | Cycle 1(1): loaded http://localhost:57043/tests/dromaeo/cssquery-ext.html (next: http://localhost:57043/tests/dromaeo/cssquery-jquery.html) 04:38:45 INFO - PID 1949 | RSS: Main: 259862528 04:38:45 INFO - PID 1949 | 04:41:47 INFO - PID 1949 | Cycle 1(1): loaded http://localhost:57043/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57043/tests/dromaeo/cssquery-mootools.html) 04:41:48 INFO - PID 1949 | RSS: Main: 270983168 04:41:48 INFO - PID 1949 | 04:44:50 INFO - PID 1949 | Cycle 1(1): loaded http://localhost:57043/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57043/tests/dromaeo/cssquery-prototype.html) 04:44:50 INFO - PID 1949 | RSS: Main: 263380992 04:44:50 INFO - PID 1949 | 04:47:53 INFO - PID 1949 | Cycle 1(1): loaded http://localhost:57043/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57043/tests/dromaeo/cssquery-yui.html) 04:47:54 INFO - PID 1949 | RSS: Main: 273580032 04:47:54 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | Cycle 1(1): loaded http://localhost:57043/tests/dromaeo/cssquery-yui.html (next: http://localhost:57043/tests/dromaeo/cssquery-dojo.html) 04:50:57 INFO - PID 1949 | RSS: Main: 267268096 04:50:57 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | __start_tp_report 04:50:57 INFO - PID 1949 | _x_x_mozilla_page_load 04:50:57 INFO - PID 1949 | _x_x_mozilla_page_load_details 04:50:57 INFO - PID 1949 | |i|pagename|runs| 04:50:57 INFO - PID 1949 | |0;dojo.html;3480.443129099346;3517.1734642359047;3551.5205447264643;3567.0903919500524;3581.5344005279317;6057.606255593394;6185.690715464231;6191.102290167929;6200.875982480347;6235.625862448245;6089.78685745999;6111.105587097666;6130.398136741991;6136.877262454759;6233.594816336947;5545.556355491551;5575.330960284761;5594.328680558338;5629.4652008059165;5677.886442271152;5485.862853428656;5601.943980560203;5656.660600363971;5664.0937450008005;5685.204071430003;5606.046972014727;5630.15547667849;5642.294713160855;5649.8587535311535;5668.319801623811;5531.9723401383135;5542.473465020817;5586.301712285965;5611.102223644238;5622.550195984311;3965.385365268388;5417.377001644822;5445.128779395278;5472.616916815825;5478.233047373371;5125.872308092214;5460.153676180198;5889.469947704684;6076.179715738363;6173.845653858644;5309.41596424393;6112.877742445171;6469.51478639104;6554.868902621921;6555.278919318871;5627.352854421712;6716.93283067168;6719.798406047827;6722.193336799566;6724.495662825308;5330.8538664187;6207.16203312547;6261.8747625047245;6307.810765677037;6328.050792381179;6504.772332968323;6670.733170673216;6774.796756097325;6844.041834143224;6847.1783385994;5477.534409575151;6249.9062514062325;6538.640374779337;6558.770443034567;6643.5017373697165;6177;6495.545311828127;6500.959846424644;6509.934900650933;6513.837154071186;6616.371444712745;6624.9337506624315;6629.872921603339;6645.634490103091;6650.9667451662435;6641.601503909781;6647.368499992493;6653.434458071119;6659.567128136656;6661.2006559213205;5199.948000520022;5220.9477905220465;5226.764795584208;5231.372235331784;5316.548093412026;4691.296305554126;4726.243800991825;4727.196376615983;4759.643026773006;4807.062622788592;6312.116303717483;6319.399657032574;6326.557141000086;6331.145295385064;6333.96833015832;6456.935430645727;6545.541812073205;6662.5003124765835;6710.563813352116;6730.764423245163;6240.282367527701;6250.781222657185;6302.306746257908;6309.905351419732;6350.714217860207;5904.321003084614;5919.3192782829665;5927.081302398135;5961.642301461926;6022.638641681513;6574.4740420765265;6657.800265992028;6828.351306625863;6840.905455127355;6916.654167291515;6619.867602647823;6678.866422671616;6686.69909854048;6733.595984240961;6743.426808721314;6772.52592318533;6834.589924604539;6874.65626718652;6878.071460352789;6881.421660736489;6384.07430922523;6460.515461340512;6551.967240163959;6585.242697089704;6611.900821487586;6662.800115996528;6663.966680166568;6686.53194276396;6733.831654208684;6749.0888730020815;5310.761015754223;6506.707198175999;6527.379898909596;6529.216494020748;6542.8364290893105;6204.844878878064;6352.333005034405;6369.936300636934;6477.967610161919;6537.444317232898;6137.60105593135;6339.397757213057;6344.524160687874;6355.428011478989;6390.616563006234;6514.804555863332;6522.5760325578685;6535.411812936859;6539.869202616016;6559.672016399066;6244.094606282155;6287.2140982377205;6361.586496877688;6371.808845734636;6397.904031439625 04:50:57 INFO - PID 1949 | |1;ext.html;744.9031625888633;815.8409110223506;838.5639467476913;1234.8456442944632;1285.1004296992107;36459.81770091149;37235.441468378;37248.25503489935;37260;37278.62721372789;37664.87005389836;37962.430563541566;38041.23917521648;38050.619493805054;38051.80974095133;7867.763967080982;8516.361272904525;8739.650413983432;8762.912370876305;8788.824223515527;8606.225439710424;8718.17177368149;8752.431091979033;8754.299656027502;8765.94808622963;8239.176082391772;8702.260307873838;8720.215180633742;8770.6930257441;8781.42920710155;8191.303739182176;8756.8686469703;8760.167784060504;8766.254868336198;8846.115388461167;8629.30965522763;8669.826603467958;8692.434991725517;8712.956435217846;8742.694005709833;38100.23799524022;38372.80813595942;38388.42417363742;38396.616033839586;38493.23013539742;2817.4646817104685;2959.9260018499494;3028.9545656815167;3032.090372888125;3060.556219348204;25776;26098.478030439284;26106.86946565255;26142.82357293926;26440.603390949153;2806.564982427727;2818;2823.378856651533;2828.5191517442086;2840.7727381809405;253442;253889;254159;255062;258879.9999999981;46630.067398652514;46633;46674.76662616733;46722.065558688635;46841;46611.368602098766;48733;48809;48853.51146488561;49276.75361623169;14439.711205776035;14711.632209194855;14764.114153150846;14788.334524946404;14810.370859205479;11917.582884599171;11927.582534611247;11928.940355298168;11943.880561194277;11967.940160299142;11187.384693841754;13914.373853176632;13979.580612581638;14009.929950350386;14089.929550352183;5535.280413546213;5890.969080410997;6124.877502449926;6200.193974783249;6201.069839524108;983.1348413396308;990.2474119669104;994.3685759542742;999.3304485994402;1034.2915103154364;20951.096255225835;24212.840852999103;24423.14518991827;24636.260912172664;24766.628500572508;1105.237386203517;1106.3859557945257;1145.6391236760394;1153.1409100220221;1156.4333476596576;12433.254004759743;12442.875571244353;12474.377453156205;12474.75050498985;12519;461242;486119;486985;488415;488666;39922.600773991886;54623.90752184934;54744.45255547394;55050.724746376814;55296.17055744167;1319.940602672882;1350.5610676530166;1370.8012338210904;1377.0498356134235;1439.8704116629553;25424;27625.61871906437;28501.287467813472;28655.997040103495;28855.5671664921;37351;37450.8127459361;37552;37581.62418375781;37590.06024849401;345199;346136;346259;346376.2681186578;347341.2632936819;29053.983110591027;31935.84032079825;33207.50188747219;33618;33827.98516044523;42930;43334.483293085854;43372.13255734931;43537.10514982139;44223;29537.852310738308;29857;30478.390432191674;30611.387772244874;30974.38051239008;26934.518601477106;27917;30519.389612208077;30559.847200764743;30563.930262440706;34292;34969.475457867644;36332.81833590815;36632.633673663986;36717.89846304615;29055.418891621626;30310.54534181945;30368.39263214679;30395.544066838575;30498.84750576233;22334.441638959157;24206.394840129138;24324.87837560801;24535.89588468559;24656.50686986286 04:50:57 INFO - PID 1949 | |2;jquery.html;428.92493813582615;438.84201687392533;442.429266246542;455.76300323831606;456.5753848920507;18872.056397180153;20346.491337716565;20840.270590529333;20927.686084708705;21067.2626458074;16922.492325230232;20829.375118746422;21711.3486595402;21720.674189887126;21964.121435142573;6293.150424692665;6460.192475940507;6490.415862572368;6498.480121590285;6512.804615861508;6260.530460215479;6291.559590828644;6352.650604216767;6368.936310636881;6394.872102557946;6114.388561143895;6346.30190679025;6357.618542887441;6358.364163583651;6368.395002474757;6266.091416744569;6382.1384113144595;6388.456981156607;6399.584027038228;6408.61548307103;5478.397376288605;5630.8029218977135;5633.8309850704545;5675.5743319250805;5682.289713785777;25112.995480180954;26057.869710651514;27613.585796213072;28249.717502824915;28576.14271571856;1995.4712001319663;2003.7495313085865;2028.0873606876964;2030.8781473111512;2097.1087287902733;15774.842251577453;16599.170041497997;16841;16979.57551061221;17387.73918391225;1503.616577772661;1717.785276840395;1734.1762662735225;1742.6950283700428;1743.8692098092567;105017.6397515528;130462;158139;178986.10506947382;181426.9999999987;12717.554885579146;12802.871971280169;12882.355882205853;12912;12962;16232.188390580424;16436.096014719304;16461.683065354744;16531.33874645001;16556.254968526446;8963.118578436062;9276.443413395218;9294.814103717888;9364.438133712;9369.90630093704;8983.46099234048;9012.27901767857;9024.09727159074;9050.366474346863;9139;7226.7109315626785;7537.982372379659;8078.798030049295;8122.350211983027;8166.305864001508;1887.3677318098592;1921.8270355667778;2078.511549785799;2093.3196711068963;2225.866448013124;925.2181906289175;935.9859602105973;937.7983733497331;938.9108034736689;971.8882328532168;16084;18733.34433294828;19559.706604400944;20924;21272.680909786366;962.138885697297;988.2884323287221;990.5245482168456;993.8558908958161;1003.8393856982993;10141.847872281922;10260;10741.89258107409;10794.568217271379;10833.729156771144;670293;689172;689841;709112;723952;129386;153448;162216;163316;163702.1814890918;1126.4255229832845;1144.6165534545853;1165.2367699156896;1165.9008984236434;1165.918385712992;17701.114944252477;18034;18111.275548977894;18155.36456224026;18701.438956830774;52403.73798130985;65235;65533.34466655272;66180.66909665421;66435;86464;86700.56649716964;87219;87465;89428.6585701228;7448.813779655336;7723.686973214455;8271.13153118938;8607.268382187583;8728.56357182151;13212.471501139848;13231.66920826987;13315.267660278778;13705.862941370857;13706.725865482435;9413.811723765622;10168.644097456554;11372;11439.542418303508;11678.24091434054;9703.805923881624;10546.472676365998;10620.52207650673;10840.970107839743;10912.945435272773;10180.541875615467;11053.944730276296;11403.942980285046;11881.168318217642;12033.278003319829;8114.675412983651;8261.463004904663;8515.914840851512;8529.3602979778;8530.786730331543;10847.349159050482;10895;11194.160437967023;11285.887141128813;11293.491792869505 04:50:57 INFO - PID 1949 | |3;mootools.html;1207.4868181023062;1403.1020147105849;1417.82986041675;1418.7727615612491;1434.7704367301228;10562.78874422511;10771.461426928661;10976.670699879016;11025.062869656078;11038.944805275962;10782.946085269563;10941.671749847497;10942.781144377128;10967.177461690366;10983.066439352664;2453.214971209209;2487.315988103268;2495.089292408276;2503.1364179358184;2507.109975958536;2422.842515236504;2437.4881274932195;2462.101333013446;2462.5567397868376;2481.9751802481924;2449.3386785567977;2449.363165576945;2457.950840983179;2471.369800700819;2484.366486545928;2423.4183795889035;2430.2101816909485;2447.902083916641;2453.58289090855;2472;1931.2757715856553;1934.6807776716826;2443.853368797878;2458.6189140683223;2467.124170919316;11086.445677716081;11092.168087393478;11171.273867198688;11180.888191118147;11211.775764484664;6262.091996660459;6414.390632889868;6454.257760357572;6460.3862633049785;6471.967640161816;4007.8797636070963;4041.2927737646087;4068.471098757172;4089.5705950875326;4095.5699651536456;1191.1661836714247;1193.3078814287692;1237.5977807212691;1240.3178251961476;1245.9875401245963;13303.66963303662;14321.35553900077;15182.924085379613;15278.31247593861;15414.383424663;5040.84877453677;5336.252924590561;6019.428154325332;6029.728662210211;6038.154658347747;10132.696019119438;12630.736926307296;12825.615231543068;12930.547430839875;12965.611031669065;6404.295527491972;6464.353564643592;6512.6092434454085;6517.576357536745;6521.739130434825;4401.559844015573;5844.620099693507;5846.4153584642045;5849.3858144894175;5878.911816322759;4404.603585677304;4509.729416235036;4638.652101092432;4680.53194680536;4693.647976401715;1405.5431984605045;1430.984001359026;1439.431020188;1453.6075259679826;1455.7161353536171;752.4883079505879;759.5556599389308;781.7498400511781;784.4273680213477;793.0998316910349;5263.236830659533;5562.415946325577;6499.610023398611;6594.0108983652835;6708.396244338033;488.53345055471937;589.5018709190726;866.3502373220085;884.3632584539122;899.4648184330313;4026.7785271809744;4029.4761680981283;4123.587641235912;4135.97932010338;4258.914821703611;10532.578696852193;14020.439182432792;14021.649458763408;14035.649108772363;14046.719065618834;12176.878231217752;12669.366531673379;13931;13950;13982.930085349712;8743.38796284254;9019.368644194843;9834.213262938947;10072.949635251776;10182.439965801952;6481.740730370732;6521.934780652133;6711.530192886647;6757.831054223487;6815.556988795713;12350.81473777876;12368.938155309166;12567.120301578801;12644.683882903;12657.683557911127;12532.874671253536;12643.87356126427;12781.169224000781;12784.424700888296;12827.551035713705;8020.398470114881;9017;9107.043760405062;9311;9315.953420232856;10141.442220677933;10276.537555810157;10402.27184097106;10409.323393979366;10414.47927603632;8656.783580410289;8689.391742577958;8758.167974042455;8787.03342632297;8943.105689431004;8485.830283394422;8512.063672995842;8563.314934805325;8649.91350086491;8783.472991620523;7454.6645400956;7548.245175482408;7680.9231907680205;7698.653560589899;7709.267619576131;5776.537876969916;5811.360750317377;5919.940800592111;5950.375210602998;6018.277806663228;5125.308083408692;5874.706264686834;5947.553933455095;5969.850753731192;6054.3642917493025 04:50:57 INFO - PID 1949 | |4;prototype.html;362.6572888620254;389.70577214203274;402.27389561840886;412.700791925854;416.9770662613556;5180.352455943007;5374.328208973879;5473.370562385327;5873.73568189432;6137.754489820408;5887.793927212549;5968.224130862993;6051.273847138338;6061.333253342129;6074.63552186868;4008.2785098682225;4571.6114130298965;4595.058013107305;4617.722936623796;4647.790849411785;3948.492707130978;4434.733915965036;4607.63138948885;4612.884677883063;4682.367880336145;4575.405197324343;4584.381108550337;4594.885127871813;4616.884577885546;4665.76671166442;4473.194824931495;4534.229181039232;4547.567981041796;4606.700564463299;4623.86128416148;3859.459675645412;3890.455336252927;3901.5318161820765;3911.374180131194;3917.2948869203533;4761.738104404257;5833.32916714579;5891.587588868781;5928.7628494860155;5933.673647949361;5178.40448348441;5342.412334643186;5365.597580181463;5380.461953804627;5437.133765280092;6131.969340153315;6485.805425837232;6518.28298887122;6521.576097553644;6523.967380163069;4006.278869803434;4343.84888004679;4415.20526305265;4423.704340266522;4430.556944305576;6454.386833250834;6520.510961677893;6581.7367305307735;6642.269350371455;6727.015530418587;3819.618038196158;4440.711353761995;4477.574630410106;4487.214737420938;4488.326750987313;4541.2733962565835;4564.083085104264;4564.2240819060835;4565.086982603492;4566.360709500673;4949.861531847623;5079.31429257053;5145.562627176657;5281.12861377876;5318.521333079964;6316.968415157895;6345.587536810092;6602.500674642351;6642.867142657216;6646.302138275411;1541.3218183999004;1559.8830087743465;1560.0639616230171;1560.5162399656142;1565.1626379886707;874.3704532736419;946.195733626412;1023.7696518283327;1030.7268573827942;1037.3516552154904;6069.302030266487;6091.451769340781;6102.389761023951;6129.387061293835;6136;5940.851478713067;6683.298253683293;6715.227748808949;6730.663466826639;6730.8653826924165;5773.191753154561;6046.397704608283;6076.392360763888;6135;6147;3255.8372081395837;3538.8675623800136;3542.5926018508203;3543.3090547343536;3547.3792086385297;6338.524610654219;6592;6967.616781077089;7005.649717514104;7019.543729657556;5867.325257595346;6659.267480577133;6917.550359226634;6938;6962.338577835098;5622.803201887914;6570.77002304917;6913.5506192097355;6923.549969252184;6974.79075627732;6727.932720672927;6741.52809303344;6742.8651426972165;6751.29111443311;6806.6256355900905;5852.707364631666;6221.3156552778255;6465.127207826883;6620.768273110418;6649.002649602405;5505.6421332614855;5608.663480191201;6027.457528822427;6801.8639627206185;6847.349501797321;3936.527616686016;4290.871273861789;4452.666050046324;4459.6655250855665;4466.5086840448175;6140.078988151634;6374.107624932606;6574.80275591733;6638.668066596748;6733.562318449285;5381.811636592701;6491.772787952399;6516.73933042673;6521.413072823469;6544.233057074468;6161.353057929032;6208.596441231305;6449.193850768654;6490.837729056622;6547.214334279917;5948.167256584168;6399.008153736178;6443.7744678936015;6496.920985284663;6515;5810.244668193108;6624.403803657694;6652.334766523309;6674.096767905977;6689;6121.693915304306;6372.235331760033;6411.711472983634;6426.325235850169;6437.742490300523 04:50:57 INFO - PID 1949 | |5;yui.html;699.5697645947737;729.62424351459;730.8428687832115;732.7655150351886;741.5587725303443;14852.183129927864;15599.376024959016;15602.453914113008;15625.453109141183;15631.140287284208;14355.497557585486;14494.927525362358;14514;14562.199079050675;15009.174495402749;903.1149473515958;927.4435338796702;930.9953450232739;937.320442679059;962.9470379129145;826.5619221812419;953.7472569769017;954.904509549043;962.2302158273388;966.405660518783;1006.8389057750761;1026.9537870795796;1028.6605420211313;1031.448175226254;1031.8710161229847;1013.8073765984476;1021.6168936648756;1022.1720406470708;1023.0843395161326;1026.9948650256702;756.9205233450518;758.7003133762188;759.9924000759977;760.2435576601317;761.2007392238183;15588.688226235525;15736.370545178179;15846.44537441184;16572.337106515726;16765.7485137723;14484.49304274356;14701.411943522353;14774.852251477563;14775.113493190443;14800.868647022484;11602;11769;11803.350815705133;12112.333821639806;12163.51345946161;2553.7956963443066;2975.226441125323;2976.382726048574;3069.1406406206297;3128.4994400895976;107375.46312268467;110549.44725276403;112851.43574282159;115573.84426155715;119658.80341196651;11470.864918943422;11594.536218551331;11637.765317382373;11665.533378664759;12015.339156346485;23733.288001359986;25070.247892563253;25149.62275565868;25248.36879078001;25379.61930571043;7646.3882889368715;7821.139674635786;7837.49056311338;7908.051033875971;8039.155888631726;4108.506979162519;4192.098698779777;4195.349720793282;4196.328587426058;4199.4120823084795;4108.897277568025;4176.018635620688;4210.179015092028;4222.978885105555;4224.493060832659;697.7928184241291;740.9249129786176;773.4122067228947;790.9090454597795;822.7984143884667;529.1850550152722;558.3997203006784;571.4828080587075;572.7594410347664;583.7373182068086;9414.482064702885;10099.012852295502;10181.236407269484;10223.182145428347;10323;513.396758808406;526.7076772391359;535.7374886305682;558.6117648234438;566.3769853161572;4522.321651752263;4685.250359942393;4727.881802954954;4730.4549243955835;4748.240281555004;102637;104399;109240.4537977305;110063.89936100697;111343;63963.720725585226;72804.27195728081;77076.84384734233;79583;79955;54133.72933135309;62774.68612656999;63506.36493635097;64505.70988580202;66095.66952165305;10187.337823041478;10370.585176592851;10554.0475012123;10717.89282107169;11128.332300062022;36788;37603.24793504169;38205;38326.23347532978;40015.59984400119;63673;63802;68617;71438.64280678771;72045.27954720386;13626.727465450835;13634.795478068036;13655.522056727967;13852.16886986784;14047.719045619235;14556.708865822837;14706.485273015822;14736;14809.259537023321;14913.92543037278;13648.454061837416;13816.93091534536;13880.51418200358;14221.075630084011;14475.92762036183;13554.796678050034;13600.65998350049;13702.794458083335;13715.746552979599;14204;13088.214707117604;13090.14914030585;13155.802662959872;13277.867221327662;13764.93117534406;9529.428234305964;9720.527396648698;9760.951195243979;9799.755006124904;10419.94790026045;7320.352920592824;9285;10648.574057037631;10724;11304.265222760494 04:50:57 INFO - PID 1949 | __end_tp_report 04:50:57 INFO - PID 1949 | __start_cc_report 04:50:57 INFO - PID 1949 | _x_x_mozilla_cycle_collect,434 04:50:57 INFO - PID 1949 | __end_cc_report 04:50:57 INFO - PID 1949 | __startTimestamp1490442657396__endTimestamp 04:50:57 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | ------- Summary: start ------- 04:50:57 INFO - PID 1949 | Number of tests: 6 04:50:57 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | [#0] dojo.html Cycles:165 Average:6099.20 Median:6328.05 stddev:714.01 (11.3%) stddev-sans-first:686.01 04:50:57 INFO - PID 1949 | Values: 3480.4 3517.2 3551.5 3567.1 3581.5 6057.6 6185.7 6191.1 6200.9 6235.6 6089.8 6111.1 6130.4 6136.9 6233.6 5545.6 5575.3 5594.3 5629.5 5677.9 5485.9 5601.9 5656.7 5664.1 5685.2 5606.0 5630.2 5642.3 5649.9 5668.3 5532.0 5542.5 5586.3 5611.1 5622.6 3965.4 5417.4 5445.1 5472.6 5478.2 5125.9 5460.2 5889.5 6076.2 6173.8 5309.4 6112.9 6469.5 6554.9 6555.3 5627.4 6716.9 6719.8 6722.2 6724.5 5330.9 6207.2 6261.9 6307.8 6328.1 6504.8 6670.7 6774.8 6844.0 6847.2 5477.5 6249.9 6538.6 6558.8 6643.5 6177.0 6495.5 6501.0 6509.9 6513.8 6616.4 6624.9 6629.9 6645.6 6651.0 6641.6 6647.4 6653.4 6659.6 6661.2 5199.9 5220.9 5226.8 5231.4 5316.5 4691.3 4726.2 4727.2 4759.6 4807.1 6312.1 6319.4 6326.6 6331.1 6334.0 6456.9 6545.5 6662.5 6710.6 6730.8 6240.3 6250.8 6302.3 6309.9 6350.7 5904.3 5919.3 5927.1 5961.6 6022.6 6574.5 6657.8 6828.4 6840.9 6916.7 6619.9 6678.9 6686.7 6733.6 6743.4 6772.5 6834.6 6874.7 6878.1 6881.4 6384.1 6460.5 6552.0 6585.2 6611.9 6662.8 6664.0 6686.5 6733.8 6749.1 5310.8 6506.7 6527.4 6529.2 6542.8 6204.8 6352.3 6369.9 6478.0 6537.4 6137.6 6339.4 6344.5 6355.4 6390.6 6514.8 6522.6 6535.4 6539.9 6559.7 6244.1 6287.2 6361.6 6371.8 6397.9 04:50:57 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | [#1] ext.html Cycles:180 Average:49909.78 Median:346197.50 stddev:99102.29 (28.6%) stddev-sans-first:99311.55 04:50:57 INFO - PID 1949 | Values: 744.9 815.8 838.6 1234.8 1285.1 36459.8 37235.4 37248.3 37260.0 37278.6 37664.9 37962.4 38041.2 38050.6 38051.8 7867.8 8516.4 8739.7 8762.9 8788.8 8606.2 8718.2 8752.4 8754.3 8765.9 8239.2 8702.3 8720.2 8770.7 8781.4 8191.3 8756.9 8760.2 8766.3 8846.1 8629.3 8669.8 8692.4 8713.0 8742.7 38100.2 38372.8 38388.4 38396.6 38493.2 2817.5 2959.9 3029.0 3032.1 3060.6 25776.0 26098.5 26106.9 26142.8 26440.6 2806.6 2818.0 2823.4 2828.5 2840.8 253442.0 253889.0 254159.0 255062.0 258880.0 46630.1 46633.0 46674.8 46722.1 46841.0 46611.4 48733.0 48809.0 48853.5 49276.8 14439.7 14711.6 14764.1 14788.3 14810.4 11917.6 11927.6 11928.9 11943.9 11967.9 11187.4 13914.4 13979.6 14009.9 14089.9 5535.3 5891.0 6124.9 6200.2 6201.1 983.1 990.2 994.4 999.3 1034.3 20951.1 24212.8 24423.1 24636.3 24766.6 1105.2 1106.4 1145.6 1153.1 1156.4 12433.3 12442.9 12474.4 12474.8 12519.0 461242.0 486119.0 486985.0 488415.0 488666.0 39922.6 54623.9 54744.5 55050.7 55296.2 1319.9 1350.6 1370.8 1377.0 1439.9 25424.0 27625.6 28501.3 28656.0 28855.6 37351.0 37450.8 37552.0 37581.6 37590.1 345199.0 346136.0 346259.0 346376.3 347341.3 29054.0 31935.8 33207.5 33618.0 33828.0 42930.0 43334.5 43372.1 43537.1 44223.0 29537.9 29857.0 30478.4 30611.4 30974.4 26934.5 27917.0 30519.4 30559.8 30563.9 34292.0 34969.5 36332.8 36632.6 36717.9 29055.4 30310.5 30368.4 30395.5 30498.8 22334.4 24206.4 24324.9 24535.9 24656.5 04:50:57 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | [#2] jquery.html Cycles:180 Average:40408.94 Median:21716.01 stddev:117023.95 (538.9%) stddev-sans-first:117313.73 04:50:57 INFO - PID 1949 | Values: 428.9 438.8 442.4 455.8 456.6 18872.1 20346.5 20840.3 20927.7 21067.3 16922.5 20829.4 21711.3 21720.7 21964.1 6293.2 6460.2 6490.4 6498.5 6512.8 6260.5 6291.6 6352.7 6368.9 6394.9 6114.4 6346.3 6357.6 6358.4 6368.4 6266.1 6382.1 6388.5 6399.6 6408.6 5478.4 5630.8 5633.8 5675.6 5682.3 25113.0 26057.9 27613.6 28249.7 28576.1 1995.5 2003.7 2028.1 2030.9 2097.1 15774.8 16599.2 16841.0 16979.6 17387.7 1503.6 1717.8 1734.2 1742.7 1743.9 105017.6 130462.0 158139.0 178986.1 181427.0 12717.6 12802.9 12882.4 12912.0 12962.0 16232.2 16436.1 16461.7 16531.3 16556.3 8963.1 9276.4 9294.8 9364.4 9369.9 8983.5 9012.3 9024.1 9050.4 9139.0 7226.7 7538.0 8078.8 8122.4 8166.3 1887.4 1921.8 2078.5 2093.3 2225.9 925.2 936.0 937.8 938.9 971.9 16084.0 18733.3 19559.7 20924.0 21272.7 962.1 988.3 990.5 993.9 1003.8 10141.8 10260.0 10741.9 10794.6 10833.7 670293.0 689172.0 689841.0 709112.0 723952.0 129386.0 153448.0 162216.0 163316.0 163702.2 1126.4 1144.6 1165.2 1165.9 1165.9 17701.1 18034.0 18111.3 18155.4 18701.4 52403.7 65235.0 65533.3 66180.7 66435.0 86464.0 86700.6 87219.0 87465.0 89428.7 7448.8 7723.7 8271.1 8607.3 8728.6 13212.5 13231.7 13315.3 13705.9 13706.7 9413.8 10168.6 11372.0 11439.5 11678.2 9703.8 10546.5 10620.5 10841.0 10912.9 10180.5 11053.9 11403.9 11881.2 12033.3 8114.7 8261.5 8515.9 8529.4 8530.8 10847.3 10895.0 11194.2 11285.9 11293.5 04:50:57 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | [#3] mootools.html Cycles:180 Average:6797.98 Median:2505.12 stddev:4162.13 (166.1%) stddev-sans-first:4152.60 04:50:57 INFO - PID 1949 | Values: 1207.5 1403.1 1417.8 1418.8 1434.8 10562.8 10771.5 10976.7 11025.1 11038.9 10782.9 10941.7 10942.8 10967.2 10983.1 2453.2 2487.3 2495.1 2503.1 2507.1 2422.8 2437.5 2462.1 2462.6 2482.0 2449.3 2449.4 2458.0 2471.4 2484.4 2423.4 2430.2 2447.9 2453.6 2472.0 1931.3 1934.7 2443.9 2458.6 2467.1 11086.4 11092.2 11171.3 11180.9 11211.8 6262.1 6414.4 6454.3 6460.4 6472.0 4007.9 4041.3 4068.5 4089.6 4095.6 1191.2 1193.3 1237.6 1240.3 1246.0 13303.7 14321.4 15182.9 15278.3 15414.4 5040.8 5336.3 6019.4 6029.7 6038.2 10132.7 12630.7 12825.6 12930.5 12965.6 6404.3 6464.4 6512.6 6517.6 6521.7 4401.6 5844.6 5846.4 5849.4 5878.9 4404.6 4509.7 4638.7 4680.5 4693.6 1405.5 1431.0 1439.4 1453.6 1455.7 752.5 759.6 781.7 784.4 793.1 5263.2 5562.4 6499.6 6594.0 6708.4 488.5 589.5 866.4 884.4 899.5 4026.8 4029.5 4123.6 4136.0 4258.9 10532.6 14020.4 14021.6 14035.6 14046.7 12176.9 12669.4 13931.0 13950.0 13982.9 8743.4 9019.4 9834.2 10072.9 10182.4 6481.7 6521.9 6711.5 6757.8 6815.6 12350.8 12368.9 12567.1 12644.7 12657.7 12532.9 12643.9 12781.2 12784.4 12827.6 8020.4 9017.0 9107.0 9311.0 9316.0 10141.4 10276.5 10402.3 10409.3 10414.5 8656.8 8689.4 8758.2 8787.0 8943.1 8485.8 8512.1 8563.3 8649.9 8783.5 7454.7 7548.2 7680.9 7698.7 7709.3 5776.5 5811.4 5919.9 5950.4 6018.3 5125.3 5874.7 5947.6 5969.9 6054.4 04:50:57 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | [#4] prototype.html Cycles:180 Average:5268.90 Median:5937.26 stddev:1627.10 (27.4%) stddev-sans-first:1589.45 04:50:57 INFO - PID 1949 | Values: 362.7 389.7 402.3 412.7 417.0 5180.4 5374.3 5473.4 5873.7 6137.8 5887.8 5968.2 6051.3 6061.3 6074.6 4008.3 4571.6 4595.1 4617.7 4647.8 3948.5 4434.7 4607.6 4612.9 4682.4 4575.4 4584.4 4594.9 4616.9 4665.8 4473.2 4534.2 4547.6 4606.7 4623.9 3859.5 3890.5 3901.5 3911.4 3917.3 4761.7 5833.3 5891.6 5928.8 5933.7 5178.4 5342.4 5365.6 5380.5 5437.1 6132.0 6485.8 6518.3 6521.6 6524.0 4006.3 4343.8 4415.2 4423.7 4430.6 6454.4 6520.5 6581.7 6642.3 6727.0 3819.6 4440.7 4477.6 4487.2 4488.3 4541.3 4564.1 4564.2 4565.1 4566.4 4949.9 5079.3 5145.6 5281.1 5318.5 6317.0 6345.6 6602.5 6642.9 6646.3 1541.3 1559.9 1560.1 1560.5 1565.2 874.4 946.2 1023.8 1030.7 1037.4 6069.3 6091.5 6102.4 6129.4 6136.0 5940.9 6683.3 6715.2 6730.7 6730.9 5773.2 6046.4 6076.4 6135.0 6147.0 3255.8 3538.9 3542.6 3543.3 3547.4 6338.5 6592.0 6967.6 7005.6 7019.5 5867.3 6659.3 6917.6 6938.0 6962.3 5622.8 6570.8 6913.6 6923.5 6974.8 6727.9 6741.5 6742.9 6751.3 6806.6 5852.7 6221.3 6465.1 6620.8 6649.0 5505.6 5608.7 6027.5 6801.9 6847.3 3936.5 4290.9 4452.7 4459.7 4466.5 6140.1 6374.1 6574.8 6638.7 6733.6 5381.8 6491.8 6516.7 6521.4 6544.2 6161.4 6208.6 6449.2 6490.8 6547.2 5948.2 6399.0 6443.8 6496.9 6515.0 5810.2 6624.4 6652.3 6674.1 6689.0 6121.7 6372.2 6411.7 6426.3 6437.7 04:50:57 INFO - PID 1949 | 04:50:57 INFO - PID 1949 | [#5] yui.html Cycles:180 Average:19834.99 Median:25199.00 stddev:28543.12 (113.3%) stddev-sans-first:28587.03 04:50:57 INFO - PID 1949 | Values: 699.6 729.6 730.8 732.8 741.6 14852.2 15599.4 15602.5 15625.5 15631.1 14355.5 14494.9 14514.0 14562.2 15009.2 903.1 927.4 931.0 937.3 962.9 826.6 953.7 954.9 962.2 966.4 1006.8 1027.0 1028.7 1031.4 1031.9 1013.8 1021.6 1022.2 1023.1 1027.0 756.9 758.7 760.0 760.2 761.2 15588.7 15736.4 15846.4 16572.3 16765.7 14484.5 14701.4 14774.9 14775.1 14800.9 11602.0 11769.0 11803.4 12112.3 12163.5 2553.8 2975.2 2976.4 3069.1 3128.5 107375.5 110549.4 112851.4 115573.8 119658.8 11470.9 11594.5 11637.8 11665.5 12015.3 23733.3 25070.2 25149.6 25248.4 25379.6 7646.4 7821.1 7837.5 7908.1 8039.2 4108.5 4192.1 4195.3 4196.3 4199.4 4108.9 4176.0 4210.2 4223.0 4224.5 697.8 740.9 773.4 790.9 822.8 529.2 558.4 571.5 572.8 583.7 9414.5 10099.0 10181.2 10223.2 10323.0 513.4 526.7 535.7 558.6 566.4 4522.3 4685.3 4727.9 4730.5 4748.2 102637.0 104399.0 109240.5 110063.9 111343.0 63963.7 72804.3 77076.8 79583.0 79955.0 54133.7 62774.7 63506.4 64505.7 66095.7 10187.3 10370.6 10554.0 10717.9 11128.3 36788.0 37603.2 38205.0 38326.2 40015.6 63673.0 63802.0 68617.0 71438.6 72045.3 13626.7 13634.8 13655.5 13852.2 14047.7 14556.7 14706.5 14736.0 14809.3 14913.9 13648.5 13816.9 13880.5 14221.1 14475.9 13554.8 13600.7 13702.8 13715.7 14204.0 13088.2 13090.1 13155.8 13277.9 13764.9 9529.4 9720.5 9761.0 9799.8 10419.9 7320.4 9285.0 10648.6 10724.0 11304.3 04:50:57 INFO - PID 1949 | -------- Summary: end -------- 04:50:57 INFO - PID 1949 | 04:50:58 INFO - TEST-INFO | 1949: exit 0 04:50:58 INFO - TEST-OK | dromaeo_css | took 1117502ms 04:50:58 INFO - TEST-START | kraken 04:50:58 INFO - operating with platform_type : mac_ 04:50:58 INFO - Initialising browser for kraken test... 04:50:58 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmp4rna6i/profile http://localhost:57043/getInfo.html) 04:51:00 INFO - PID 1953 | 2017-03-25 04:51:00.898 plugin-container[1955:26559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 04:51:00 INFO - PID 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:51:00 INFO - PID 1953 | 2017-03-25 04:51:00.899 plugin-container[1955:26559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e17, name = 'com.apple.CFPasteboardClient' 04:51:00 INFO - PID 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:51:00 INFO - PID 1953 | 2017-03-25 04:51:00.899 plugin-container[1955:26559] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:51:00 INFO - PID 1953 | __metrics Screen width/height:1600/1200 04:51:00 INFO - PID 1953 | colorDepth:24 04:51:00 INFO - PID 1953 | Browser inner width/height: 1024/689 04:51:00 INFO - PID 1953 | __metrics 04:51:01 ERROR - PID 1953 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3392: TypeError: this.tabs is undefined 04:51:01 INFO - TEST-INFO | 1953: exit 0 04:51:01 INFO - Browser initialized. 04:51:01 INFO - Running cycle 1/1 for kraken test... 04:51:01 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmp4rna6i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:51:04 INFO - PID 1956 | 2017-03-25 04:51:04.607 plugin-container[1957:26682] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 04:51:04 INFO - PID 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:51:04 INFO - PID 1956 | 2017-03-25 04:51:04.608 plugin-container[1957:26682] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c23, name = 'com.apple.CFPasteboardClient' 04:51:04 INFO - PID 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:51:04 INFO - PID 1956 | 2017-03-25 04:51:04.608 plugin-container[1957:26682] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:51:14 INFO - PID 1956 | RSS: Main: 228184064 04:51:14 INFO - PID 1956 | 04:51:17 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57043/tests/kraken/driver.html?testName=audio-beat-detection) 04:51:18 INFO - PID 1956 | RSS: Main: 241856512 04:51:18 INFO - PID 1956 | 04:51:20 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57043/tests/kraken/driver.html?testName=audio-dft) 04:51:21 INFO - PID 1956 | RSS: Main: 250269696 04:51:21 INFO - PID 1956 | 04:51:24 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57043/tests/kraken/driver.html?testName=audio-fft) 04:51:25 INFO - PID 1956 | RSS: Main: 252989440 04:51:25 INFO - PID 1956 | 04:51:27 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57043/tests/kraken/driver.html?testName=audio-oscillator) 04:51:28 INFO - PID 1956 | RSS: Main: 255193088 04:51:28 INFO - PID 1956 | 04:51:30 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57043/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:51:30 INFO - PID 1956 | RSS: Main: 255516672 04:51:30 INFO - PID 1956 | 04:51:33 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57043/tests/kraken/driver.html?testName=imaging-darkroom) 04:51:34 INFO - PID 1956 | RSS: Main: 261394432 04:51:34 INFO - PID 1956 | 04:51:37 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57043/tests/kraken/driver.html?testName=imaging-desaturate) 04:51:38 INFO - PID 1956 | RSS: Main: 261435392 04:51:38 INFO - PID 1956 | 04:51:41 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57043/tests/kraken/driver.html?testName=json-parse-financial) 04:51:41 INFO - PID 1956 | RSS: Main: 261468160 04:51:41 INFO - PID 1956 | 04:51:43 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57043/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:51:44 INFO - PID 1956 | RSS: Main: 261341184 04:51:44 INFO - PID 1956 | 04:51:46 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57043/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:51:47 INFO - PID 1956 | RSS: Main: 261586944 04:51:47 INFO - PID 1956 | 04:51:49 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57043/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:51:49 INFO - PID 1956 | RSS: Main: 261324800 04:51:49 INFO - PID 1956 | 04:51:52 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57043/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:51:53 INFO - PID 1956 | RSS: Main: 262238208 04:51:53 INFO - PID 1956 | 04:51:57 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57043/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:51:57 INFO - PID 1956 | RSS: Main: 259158016 04:51:57 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:57043/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57043/tests/kraken/driver.html?testName=ai-astar) 04:51:59 INFO - PID 1956 | RSS: Main: 262447104 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | __start_tp_report 04:51:59 INFO - PID 1956 | _x_x_mozilla_page_load 04:51:59 INFO - PID 1956 | _x_x_mozilla_page_load_details 04:51:59 INFO - PID 1956 | |i|pagename|runs| 04:51:59 INFO - PID 1956 | |0;ai-astar;103;137;146;169;174;124;118;116;118;117 04:51:59 INFO - PID 1956 | |1;audio-beat-detection;145;115;112;153;113;113;113;105;178;148 04:51:59 INFO - PID 1956 | |2;audio-dft;194;213;218;283;245;279;210;200;194;198 04:51:59 INFO - PID 1956 | |3;audio-fft;91;89;88;83;59;62;82;58;102;87 04:51:59 INFO - PID 1956 | |4;audio-oscillator;84;72;85;73;72;75;66;91;104;119 04:51:59 INFO - PID 1956 | |5;imaging-gaussian-blur;87;88;87;94;109;88;87;90;136;139 04:51:59 INFO - PID 1956 | |6;imaging-darkroom;97;123;155;97;95;95;97;96;96;99 04:51:59 INFO - PID 1956 | |7;imaging-desaturate;89;90;91;95;93;92;94;91;134;99 04:51:59 INFO - PID 1956 | |8;json-parse-financial;66;99;68;103;102;67;68;67;70;90 04:51:59 INFO - PID 1956 | |9;json-stringify-tinderbox;58;58;75;97;90;74;59;60;58;59 04:51:59 INFO - PID 1956 | |10;stanford-crypto-aes;85;85;87;82;83;84;72;101;101;109 04:51:59 INFO - PID 1956 | |11;stanford-crypto-ccm;148;158;153;162;150;146;162;152;214;161 04:51:59 INFO - PID 1956 | |12;stanford-crypto-pbkdf2;200;197;195;201;198;255;186;258;205;196 04:51:59 INFO - PID 1956 | |13;stanford-crypto-sha256-iterative;71;71;72;69;69;71;70;71;69;74 04:51:59 INFO - PID 1956 | __end_tp_report 04:51:59 INFO - PID 1956 | __start_cc_report 04:51:59 INFO - PID 1956 | _x_x_mozilla_cycle_collect,993 04:51:59 INFO - PID 1956 | __end_cc_report 04:51:59 INFO - PID 1956 | __startTimestamp1490442719693__endTimestamp 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | ------- Summary: start ------- 04:51:59 INFO - PID 1956 | Number of tests: 14 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#0] ai-astar Cycles:10 Average:132.20 Median:130.50 stddev:23.88 (18.3%) stddev-sans-first:22.87 04:51:59 INFO - PID 1956 | Values: 103.0 137.0 146.0 169.0 174.0 124.0 118.0 116.0 118.0 117.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:130.00 stddev:24.54 (18.9%) stddev-sans-first:25.38 04:51:59 INFO - PID 1956 | Values: 145.0 115.0 112.0 153.0 113.0 113.0 113.0 105.0 178.0 148.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#2] audio-dft Cycles:10 Average:223.40 Median:215.50 stddev:33.91 (15.7%) stddev-sans-first:34.26 04:51:59 INFO - PID 1956 | Values: 194.0 213.0 218.0 283.0 245.0 279.0 210.0 200.0 194.0 198.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#3] audio-fft Cycles:10 Average:80.10 Median:85.00 stddev:15.13 (17.8%) stddev-sans-first:15.53 04:51:59 INFO - PID 1956 | Values: 91.0 89.0 88.0 83.0 59.0 62.0 82.0 58.0 102.0 87.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:74.00 stddev:16.63 (22.5%) stddev-sans-first:17.64 04:51:59 INFO - PID 1956 | Values: 84.0 72.0 85.0 73.0 72.0 75.0 66.0 91.0 104.0 119.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:100.50 Median:87.50 stddev:20.62 (23.6%) stddev-sans-first:21.28 04:51:59 INFO - PID 1956 | Values: 87.0 88.0 87.0 94.0 109.0 88.0 87.0 90.0 136.0 139.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:96.50 stddev:19.48 (20.2%) stddev-sans-first:20.44 04:51:59 INFO - PID 1956 | Values: 97.0 123.0 155.0 97.0 95.0 95.0 97.0 96.0 96.0 99.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:92.50 stddev:13.38 (14.5%) stddev-sans-first:13.89 04:51:59 INFO - PID 1956 | Values: 89.0 90.0 91.0 95.0 93.0 92.0 94.0 91.0 134.0 99.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#8] json-parse-financial Cycles:10 Average:80.00 Median:68.00 stddev:16.32 (24.0%) stddev-sans-first:16.50 04:51:59 INFO - PID 1956 | Values: 66.0 99.0 68.0 103.0 102.0 67.0 68.0 67.0 70.0 90.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:67.00 stddev:14.64 (21.9%) stddev-sans-first:15.00 04:51:59 INFO - PID 1956 | Values: 58.0 58.0 75.0 97.0 90.0 74.0 59.0 60.0 58.0 59.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:83.50 stddev:11.17 (13.4%) stddev-sans-first:11.76 04:51:59 INFO - PID 1956 | Values: 85.0 85.0 87.0 82.0 83.0 84.0 72.0 101.0 101.0 109.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:160.60 Median:159.50 stddev:19.66 (12.3%) stddev-sans-first:20.32 04:51:59 INFO - PID 1956 | Values: 148.0 158.0 153.0 162.0 150.0 146.0 162.0 152.0 214.0 161.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.10 Median:200.50 stddev:25.47 (12.7%) stddev-sans-first:26.80 04:51:59 INFO - PID 1956 | Values: 200.0 197.0 195.0 201.0 198.0 255.0 186.0 258.0 205.0 196.0 04:51:59 INFO - PID 1956 | 04:51:59 INFO - PID 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:71.00 stddev:1.57 (2.2%) stddev-sans-first:1.66 04:51:59 INFO - PID 1956 | Values: 71.0 71.0 72.0 69.0 69.0 71.0 70.0 71.0 69.0 74.0 04:51:59 INFO - PID 1956 | -------- Summary: end -------- 04:51:59 INFO - PID 1956 | 04:52:00 INFO - TEST-INFO | 1956: exit 0 04:52:00 INFO - TEST-OK | kraken | took 62664ms 04:52:00 INFO - SUITE-END | took 1180s 04:52:00 INFO - Completed test suite (00:19:40) 04:52:00 INFO - javascript benchmark 04:52:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7672.656552018246, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3480.443129099346, 3517.1734642359047, 3551.5205447264643, 3567.0903919500524, 3581.5344005279317, 6057.606255593394, 6185.690715464231, 6191.102290167929, 6200.875982480347, 6235.625862448245, 6089.78685745999, 6111.105587097666, 6130.398136741991, 6136.877262454759, 6233.594816336947, 5545.556355491551, 5575.330960284761, 5594.328680558338, 5629.4652008059165, 5677.886442271152, 5485.862853428656, 5601.943980560203, 5656.660600363971, 5664.0937450008005, 5685.204071430003, 5606.046972014727, 5630.15547667849, 5642.294713160855, 5649.8587535311535, 5668.319801623811, 5531.9723401383135, 5542.473465020817, 5586.301712285965, 5611.102223644238, 5622.550195984311, 3965.385365268388, 5417.377001644822, 5445.128779395278, 5472.616916815825, 5478.233047373371, 5125.872308092214, 5460.153676180198, 5889.469947704684, 6076.179715738363, 6173.845653858644, 5309.41596424393, 6112.877742445171, 6469.51478639104, 6554.868902621921, 6555.278919318871, 5627.352854421712, 6716.93283067168, 6719.798406047827, 6722.193336799566, 6724.495662825308, 5330.8538664187, 6207.16203312547, 6261.8747625047245, 6307.810765677037, 6328.050792381179, 6504.772332968323, 6670.733170673216, 6774.796756097325, 6844.041834143224, 6847.1783385994, 5477.534409575151, 6249.9062514062325, 6538.640374779337, 6558.770443034567, 6643.5017373697165, 6177.0, 6495.545311828127, 6500.959846424644, 6509.934900650933, 6513.837154071186, 6616.371444712745, 6624.9337506624315, 6629.872921603339, 6645.634490103091, 6650.9667451662435, 6641.601503909781, 6647.368499992493, 6653.434458071119, 6659.567128136656, 6661.2006559213205, 5199.948000520022, 5220.9477905220465, 5226.764795584208, 5231.372235331784, 5316.548093412026, 4691.296305554126, 4726.243800991825, 4727.196376615983, 4759.643026773006, 4807.062622788592, 6312.116303717483, 6319.399657032574, 6326.557141000086, 6331.145295385064, 6333.96833015832, 6456.935430645727, 6545.541812073205, 6662.5003124765835, 6710.563813352116, 6730.764423245163, 6240.282367527701, 6250.781222657185, 6302.306746257908, 6309.905351419732, 6350.714217860207, 5904.321003084614, 5919.3192782829665, 5927.081302398135, 5961.642301461926, 6022.638641681513, 6574.4740420765265, 6657.800265992028, 6828.351306625863, 6840.905455127355, 6916.654167291515, 6619.867602647823, 6678.866422671616, 6686.69909854048, 6733.595984240961, 6743.426808721314, 6772.52592318533, 6834.589924604539, 6874.65626718652, 6878.071460352789, 6881.421660736489, 6384.07430922523, 6460.515461340512, 6551.967240163959, 6585.242697089704, 6611.900821487586, 6662.800115996528, 6663.966680166568, 6686.53194276396, 6733.831654208684, 6749.0888730020815, 5310.761015754223, 6506.707198175999, 6527.379898909596, 6529.216494020748, 6542.8364290893105, 6204.844878878064, 6352.333005034405, 6369.936300636934, 6477.967610161919, 6537.444317232898, 6137.60105593135, 6339.397757213057, 6344.524160687874, 6355.428011478989, 6390.616563006234, 6514.804555863332, 6522.5760325578685, 6535.411812936859, 6539.869202616016, 6559.672016399066, 6244.094606282155, 6287.2140982377205, 6361.586496877688, 6371.808845734636, 6397.904031439625], "value": 6054.248582033787, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.9031625888633, 815.8409110223506, 838.5639467476913, 1234.8456442944632, 1285.1004296992107, 36459.81770091149, 37235.441468378, 37248.25503489935, 37260.0, 37278.62721372789, 37664.87005389836, 37962.430563541566, 38041.23917521648, 38050.619493805054, 38051.80974095133, 7867.763967080982, 8516.361272904525, 8739.650413983432, 8762.912370876305, 8788.824223515527, 8606.225439710424, 8718.17177368149, 8752.431091979033, 8754.299656027502, 8765.94808622963, 8239.176082391772, 8702.260307873838, 8720.215180633742, 8770.6930257441, 8781.42920710155, 8191.303739182176, 8756.8686469703, 8760.167784060504, 8766.254868336198, 8846.115388461167, 8629.30965522763, 8669.826603467958, 8692.434991725517, 8712.956435217846, 8742.694005709833, 38100.23799524022, 38372.80813595942, 38388.42417363742, 38396.616033839586, 38493.23013539742, 2817.4646817104685, 2959.9260018499494, 3028.9545656815167, 3032.090372888125, 3060.556219348204, 25776.0, 26098.478030439284, 26106.86946565255, 26142.82357293926, 26440.603390949153, 2806.564982427727, 2818.0, 2823.378856651533, 2828.5191517442086, 2840.7727381809405, 253442.0, 253889.0, 254159.0, 255062.0, 258879.9999999981, 46630.067398652514, 46633.0, 46674.76662616733, 46722.065558688635, 46841.0, 46611.368602098766, 48733.0, 48809.0, 48853.51146488561, 49276.75361623169, 14439.711205776035, 14711.632209194855, 14764.114153150846, 14788.334524946404, 14810.370859205479, 11917.582884599171, 11927.582534611247, 11928.940355298168, 11943.880561194277, 11967.940160299142, 11187.384693841754, 13914.373853176632, 13979.580612581638, 14009.929950350386, 14089.929550352183, 5535.280413546213, 5890.969080410997, 6124.877502449926, 6200.193974783249, 6201.069839524108, 983.1348413396308, 990.2474119669104, 994.3685759542742, 999.3304485994402, 1034.2915103154364, 20951.096255225835, 24212.840852999103, 24423.14518991827, 24636.260912172664, 24766.628500572508, 1105.237386203517, 1106.3859557945257, 1145.6391236760394, 1153.1409100220221, 1156.4333476596576, 12433.254004759743, 12442.875571244353, 12474.377453156205, 12474.75050498985, 12519.0, 461242.0, 486119.0, 486985.0, 488415.0, 488666.0, 39922.600773991886, 54623.90752184934, 54744.45255547394, 55050.724746376814, 55296.17055744167, 1319.940602672882, 1350.5610676530166, 1370.8012338210904, 1377.0498356134235, 1439.8704116629553, 25424.0, 27625.61871906437, 28501.287467813472, 28655.997040103495, 28855.5671664921, 37351.0, 37450.8127459361, 37552.0, 37581.62418375781, 37590.06024849401, 345199.0, 346136.0, 346259.0, 346376.2681186578, 347341.2632936819, 29053.983110591027, 31935.84032079825, 33207.50188747219, 33618.0, 33827.98516044523, 42930.0, 43334.483293085854, 43372.13255734931, 43537.10514982139, 44223.0, 29537.852310738308, 29857.0, 30478.390432191674, 30611.387772244874, 30974.38051239008, 26934.518601477106, 27917.0, 30519.389612208077, 30559.847200764743, 30563.930262440706, 34292.0, 34969.475457867644, 36332.81833590815, 36632.633673663986, 36717.89846304615, 29055.418891621626, 30310.54534181945, 30368.39263214679, 30395.544066838575, 30498.84750576233, 22334.441638959157, 24206.394840129138, 24324.87837560801, 24535.89588468559, 24656.50686986286], "value": 17551.077718522094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.92493813582615, 438.84201687392533, 442.429266246542, 455.76300323831606, 456.5753848920507, 18872.056397180153, 20346.491337716565, 20840.270590529333, 20927.686084708705, 21067.2626458074, 16922.492325230232, 20829.375118746422, 21711.3486595402, 21720.674189887126, 21964.121435142573, 6293.150424692665, 6460.192475940507, 6490.415862572368, 6498.480121590285, 6512.804615861508, 6260.530460215479, 6291.559590828644, 6352.650604216767, 6368.936310636881, 6394.872102557946, 6114.388561143895, 6346.30190679025, 6357.618542887441, 6358.364163583651, 6368.395002474757, 6266.091416744569, 6382.1384113144595, 6388.456981156607, 6399.584027038228, 6408.61548307103, 5478.397376288605, 5630.8029218977135, 5633.8309850704545, 5675.5743319250805, 5682.289713785777, 25112.995480180954, 26057.869710651514, 27613.585796213072, 28249.717502824915, 28576.14271571856, 1995.4712001319663, 2003.7495313085865, 2028.0873606876964, 2030.8781473111512, 2097.1087287902733, 15774.842251577453, 16599.170041497997, 16841.0, 16979.57551061221, 17387.73918391225, 1503.616577772661, 1717.785276840395, 1734.1762662735225, 1742.6950283700428, 1743.8692098092567, 105017.6397515528, 130462.0, 158139.0, 178986.10506947382, 181426.9999999987, 12717.554885579146, 12802.871971280169, 12882.355882205853, 12912.0, 12962.0, 16232.188390580424, 16436.096014719304, 16461.683065354744, 16531.33874645001, 16556.254968526446, 8963.118578436062, 9276.443413395218, 9294.814103717888, 9364.438133712, 9369.90630093704, 8983.46099234048, 9012.27901767857, 9024.09727159074, 9050.366474346863, 9139.0, 7226.7109315626785, 7537.982372379659, 8078.798030049295, 8122.350211983027, 8166.305864001508, 1887.3677318098592, 1921.8270355667778, 2078.511549785799, 2093.3196711068963, 2225.866448013124, 925.2181906289175, 935.9859602105973, 937.7983733497331, 938.9108034736689, 971.8882328532168, 16084.0, 18733.34433294828, 19559.706604400944, 20924.0, 21272.680909786366, 962.138885697297, 988.2884323287221, 990.5245482168456, 993.8558908958161, 1003.8393856982993, 10141.847872281922, 10260.0, 10741.89258107409, 10794.568217271379, 10833.729156771144, 670293.0, 689172.0, 689841.0, 709112.0, 723952.0, 129386.0, 153448.0, 162216.0, 163316.0, 163702.1814890918, 1126.4255229832845, 1144.6165534545853, 1165.2367699156896, 1165.9008984236434, 1165.918385712992, 17701.114944252477, 18034.0, 18111.275548977894, 18155.36456224026, 18701.438956830774, 52403.73798130985, 65235.0, 65533.34466655272, 66180.66909665421, 66435.0, 86464.0, 86700.56649716964, 87219.0, 87465.0, 89428.6585701228, 7448.813779655336, 7723.686973214455, 8271.13153118938, 8607.268382187583, 8728.56357182151, 13212.471501139848, 13231.66920826987, 13315.267660278778, 13705.862941370857, 13706.725865482435, 9413.811723765622, 10168.644097456554, 11372.0, 11439.542418303508, 11678.24091434054, 9703.805923881624, 10546.472676365998, 10620.52207650673, 10840.970107839743, 10912.945435272773, 10180.541875615467, 11053.944730276296, 11403.942980285046, 11881.168318217642, 12033.278003319829, 8114.675412983651, 8261.463004904663, 8515.914840851512, 8529.3602979778, 8530.786730331543, 10847.349159050482, 10895.0, 11194.160437967023, 11285.887141128813, 11293.491792869505], "value": 10359.465031646621, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1207.4868181023062, 1403.1020147105849, 1417.82986041675, 1418.7727615612491, 1434.7704367301228, 10562.78874422511, 10771.461426928661, 10976.670699879016, 11025.062869656078, 11038.944805275962, 10782.946085269563, 10941.671749847497, 10942.781144377128, 10967.177461690366, 10983.066439352664, 2453.214971209209, 2487.315988103268, 2495.089292408276, 2503.1364179358184, 2507.109975958536, 2422.842515236504, 2437.4881274932195, 2462.101333013446, 2462.5567397868376, 2481.9751802481924, 2449.3386785567977, 2449.363165576945, 2457.950840983179, 2471.369800700819, 2484.366486545928, 2423.4183795889035, 2430.2101816909485, 2447.902083916641, 2453.58289090855, 2472.0, 1931.2757715856553, 1934.6807776716826, 2443.853368797878, 2458.6189140683223, 2467.124170919316, 11086.445677716081, 11092.168087393478, 11171.273867198688, 11180.888191118147, 11211.775764484664, 6262.091996660459, 6414.390632889868, 6454.257760357572, 6460.3862633049785, 6471.967640161816, 4007.8797636070963, 4041.2927737646087, 4068.471098757172, 4089.5705950875326, 4095.5699651536456, 1191.1661836714247, 1193.3078814287692, 1237.5977807212691, 1240.3178251961476, 1245.9875401245963, 13303.66963303662, 14321.35553900077, 15182.924085379613, 15278.31247593861, 15414.383424663, 5040.84877453677, 5336.252924590561, 6019.428154325332, 6029.728662210211, 6038.154658347747, 10132.696019119438, 12630.736926307296, 12825.615231543068, 12930.547430839875, 12965.611031669065, 6404.295527491972, 6464.353564643592, 6512.6092434454085, 6517.576357536745, 6521.739130434825, 4401.559844015573, 5844.620099693507, 5846.4153584642045, 5849.3858144894175, 5878.911816322759, 4404.603585677304, 4509.729416235036, 4638.652101092432, 4680.53194680536, 4693.647976401715, 1405.5431984605045, 1430.984001359026, 1439.431020188, 1453.6075259679826, 1455.7161353536171, 752.4883079505879, 759.5556599389308, 781.7498400511781, 784.4273680213477, 793.0998316910349, 5263.236830659533, 5562.415946325577, 6499.610023398611, 6594.0108983652835, 6708.396244338033, 488.53345055471937, 589.5018709190726, 866.3502373220085, 884.3632584539122, 899.4648184330313, 4026.7785271809744, 4029.4761680981283, 4123.587641235912, 4135.97932010338, 4258.914821703611, 10532.578696852193, 14020.439182432792, 14021.649458763408, 14035.649108772363, 14046.719065618834, 12176.878231217752, 12669.366531673379, 13931.0, 13950.0, 13982.930085349712, 8743.38796284254, 9019.368644194843, 9834.213262938947, 10072.949635251776, 10182.439965801952, 6481.740730370732, 6521.934780652133, 6711.530192886647, 6757.831054223487, 6815.556988795713, 12350.81473777876, 12368.938155309166, 12567.120301578801, 12644.683882903, 12657.683557911127, 12532.874671253536, 12643.87356126427, 12781.169224000781, 12784.424700888296, 12827.551035713705, 8020.398470114881, 9017.0, 9107.043760405062, 9311.0, 9315.953420232856, 10141.442220677933, 10276.537555810157, 10402.27184097106, 10409.323393979366, 10414.47927603632, 8656.783580410289, 8689.391742577958, 8758.167974042455, 8787.03342632297, 8943.105689431004, 8485.830283394422, 8512.063672995842, 8563.314934805325, 8649.91350086491, 8783.472991620523, 7454.6645400956, 7548.245175482408, 7680.9231907680205, 7698.653560589899, 7709.267619576131, 5776.537876969916, 5811.360750317377, 5919.940800592111, 5950.375210602998, 6018.277806663228, 5125.308083408692, 5874.706264686834, 5947.553933455095, 5969.850753731192, 6054.3642917493025], "value": 5190.7861768655985, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.6572888620254, 389.70577214203274, 402.27389561840886, 412.700791925854, 416.9770662613556, 5180.352455943007, 5374.328208973879, 5473.370562385327, 5873.73568189432, 6137.754489820408, 5887.793927212549, 5968.224130862993, 6051.273847138338, 6061.333253342129, 6074.63552186868, 4008.2785098682225, 4571.6114130298965, 4595.058013107305, 4617.722936623796, 4647.790849411785, 3948.492707130978, 4434.733915965036, 4607.63138948885, 4612.884677883063, 4682.367880336145, 4575.405197324343, 4584.381108550337, 4594.885127871813, 4616.884577885546, 4665.76671166442, 4473.194824931495, 4534.229181039232, 4547.567981041796, 4606.700564463299, 4623.86128416148, 3859.459675645412, 3890.455336252927, 3901.5318161820765, 3911.374180131194, 3917.2948869203533, 4761.738104404257, 5833.32916714579, 5891.587588868781, 5928.7628494860155, 5933.673647949361, 5178.40448348441, 5342.412334643186, 5365.597580181463, 5380.461953804627, 5437.133765280092, 6131.969340153315, 6485.805425837232, 6518.28298887122, 6521.576097553644, 6523.967380163069, 4006.278869803434, 4343.84888004679, 4415.20526305265, 4423.704340266522, 4430.556944305576, 6454.386833250834, 6520.510961677893, 6581.7367305307735, 6642.269350371455, 6727.015530418587, 3819.618038196158, 4440.711353761995, 4477.574630410106, 4487.214737420938, 4488.326750987313, 4541.2733962565835, 4564.083085104264, 4564.2240819060835, 4565.086982603492, 4566.360709500673, 4949.861531847623, 5079.31429257053, 5145.562627176657, 5281.12861377876, 5318.521333079964, 6316.968415157895, 6345.587536810092, 6602.500674642351, 6642.867142657216, 6646.302138275411, 1541.3218183999004, 1559.8830087743465, 1560.0639616230171, 1560.5162399656142, 1565.1626379886707, 874.3704532736419, 946.195733626412, 1023.7696518283327, 1030.7268573827942, 1037.3516552154904, 6069.302030266487, 6091.451769340781, 6102.389761023951, 6129.387061293835, 6136.0, 5940.851478713067, 6683.298253683293, 6715.227748808949, 6730.663466826639, 6730.8653826924165, 5773.191753154561, 6046.397704608283, 6076.392360763888, 6135.0, 6147.0, 3255.8372081395837, 3538.8675623800136, 3542.5926018508203, 3543.3090547343536, 3547.3792086385297, 6338.524610654219, 6592.0, 6967.616781077089, 7005.649717514104, 7019.543729657556, 5867.325257595346, 6659.267480577133, 6917.550359226634, 6938.0, 6962.338577835098, 5622.803201887914, 6570.77002304917, 6913.5506192097355, 6923.549969252184, 6974.79075627732, 6727.932720672927, 6741.52809303344, 6742.8651426972165, 6751.29111443311, 6806.6256355900905, 5852.707364631666, 6221.3156552778255, 6465.127207826883, 6620.768273110418, 6649.002649602405, 5505.6421332614855, 5608.663480191201, 6027.457528822427, 6801.8639627206185, 6847.349501797321, 3936.527616686016, 4290.871273861789, 4452.666050046324, 4459.6655250855665, 4466.5086840448175, 6140.078988151634, 6374.107624932606, 6574.80275591733, 6638.668066596748, 6733.562318449285, 5381.811636592701, 6491.772787952399, 6516.73933042673, 6521.413072823469, 6544.233057074468, 6161.353057929032, 6208.596441231305, 6449.193850768654, 6490.837729056622, 6547.214334279917, 5948.167256584168, 6399.008153736178, 6443.7744678936015, 6496.920985284663, 6515.0, 5810.244668193108, 6624.403803657694, 6652.334766523309, 6674.096767905977, 6689.0, 6121.693915304306, 6372.235331760033, 6411.711472983634, 6426.325235850169, 6437.742490300523], "value": 4757.6263436947775, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.5697645947737, 729.62424351459, 730.8428687832115, 732.7655150351886, 741.5587725303443, 14852.183129927864, 15599.376024959016, 15602.453914113008, 15625.453109141183, 15631.140287284208, 14355.497557585486, 14494.927525362358, 14514.0, 14562.199079050675, 15009.174495402749, 903.1149473515958, 927.4435338796702, 930.9953450232739, 937.320442679059, 962.9470379129145, 826.5619221812419, 953.7472569769017, 954.904509549043, 962.2302158273388, 966.405660518783, 1006.8389057750761, 1026.9537870795796, 1028.6605420211313, 1031.448175226254, 1031.8710161229847, 1013.8073765984476, 1021.6168936648756, 1022.1720406470708, 1023.0843395161326, 1026.9948650256702, 756.9205233450518, 758.7003133762188, 759.9924000759977, 760.2435576601317, 761.2007392238183, 15588.688226235525, 15736.370545178179, 15846.44537441184, 16572.337106515726, 16765.7485137723, 14484.49304274356, 14701.411943522353, 14774.852251477563, 14775.113493190443, 14800.868647022484, 11602.0, 11769.0, 11803.350815705133, 12112.333821639806, 12163.51345946161, 2553.7956963443066, 2975.226441125323, 2976.382726048574, 3069.1406406206297, 3128.4994400895976, 107375.46312268467, 110549.44725276403, 112851.43574282159, 115573.84426155715, 119658.80341196651, 11470.864918943422, 11594.536218551331, 11637.765317382373, 11665.533378664759, 12015.339156346485, 23733.288001359986, 25070.247892563253, 25149.62275565868, 25248.36879078001, 25379.61930571043, 7646.3882889368715, 7821.139674635786, 7837.49056311338, 7908.051033875971, 8039.155888631726, 4108.506979162519, 4192.098698779777, 4195.349720793282, 4196.328587426058, 4199.4120823084795, 4108.897277568025, 4176.018635620688, 4210.179015092028, 4222.978885105555, 4224.493060832659, 697.7928184241291, 740.9249129786176, 773.4122067228947, 790.9090454597795, 822.7984143884667, 529.1850550152722, 558.3997203006784, 571.4828080587075, 572.7594410347664, 583.7373182068086, 9414.482064702885, 10099.012852295502, 10181.236407269484, 10223.182145428347, 10323.0, 513.396758808406, 526.7076772391359, 535.7374886305682, 558.6117648234438, 566.3769853161572, 4522.321651752263, 4685.250359942393, 4727.881802954954, 4730.4549243955835, 4748.240281555004, 102637.0, 104399.0, 109240.4537977305, 110063.89936100697, 111343.0, 63963.720725585226, 72804.27195728081, 77076.84384734233, 79583.0, 79955.0, 54133.72933135309, 62774.68612656999, 63506.36493635097, 64505.70988580202, 66095.66952165305, 10187.337823041478, 10370.585176592851, 10554.0475012123, 10717.89282107169, 11128.332300062022, 36788.0, 37603.24793504169, 38205.0, 38326.23347532978, 40015.59984400119, 63673.0, 63802.0, 68617.0, 71438.64280678771, 72045.27954720386, 13626.727465450835, 13634.795478068036, 13655.522056727967, 13852.16886986784, 14047.719045619235, 14556.708865822837, 14706.485273015822, 14736.0, 14809.259537023321, 14913.92543037278, 13648.454061837416, 13816.93091534536, 13880.51418200358, 14221.075630084011, 14475.92762036183, 13554.796678050034, 13600.65998350049, 13702.794458083335, 13715.746552979599, 14204.0, 13088.214707117604, 13090.14914030585, 13155.802662959872, 13277.867221327662, 13764.93117534406, 9529.428234305964, 9720.527396648698, 9760.951195243979, 9799.755006124904, 10419.94790026045, 7320.352920592824, 9285.0, 10648.574057037631, 10724.0, 11304.265222760494], "value": 7504.437013753775, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1629.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 137.0, 146.0, 169.0, 174.0, 124.0, 118.0, 116.0, 118.0, 117.0], "value": 132.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 112.0, 153.0, 113.0, 113.0, 113.0, 105.0, 178.0, 148.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 213.0, 218.0, 283.0, 245.0, 279.0, 210.0, 200.0, 194.0, 198.0], "value": 223.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 88.0, 83.0, 59.0, 62.0, 82.0, 58.0, 102.0, 87.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 85.0, 73.0, 72.0, 75.0, 66.0, 91.0, 104.0, 119.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 94.0, 109.0, 88.0, 87.0, 90.0, 136.0, 139.0], "value": 100.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 123.0, 155.0, 97.0, 95.0, 95.0, 97.0, 96.0, 96.0, 99.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 91.0, 95.0, 93.0, 92.0, 94.0, 91.0, 134.0, 99.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 99.0, 68.0, 103.0, 102.0, 67.0, 68.0, 67.0, 70.0, 90.0], "value": 80.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 75.0, 97.0, 90.0, 74.0, 59.0, 60.0, 58.0, 59.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 82.0, 83.0, 84.0, 72.0, 101.0, 101.0, 109.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 158.0, 153.0, 162.0, 150.0, 146.0, 162.0, 152.0, 214.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 197.0, 195.0, 201.0, 198.0, 255.0, 186.0, 258.0, 205.0, 196.0], "value": 209.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 72.0, 69.0, 69.0, 71.0, 70.0, 71.0, 69.0, 74.0], "value": 70.7, "unit": "score"}]}]} 04:52:00 INFO - Return code: 0 04:52:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:52:01 ERROR - # TBPL SUCCESS # 04:52:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:52:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:52:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:52:01 INFO - 2017-03-25 04:52:01.031 screenresolution[1961:27072] starting screenresolution argv=screenresolution get 04:52:01 INFO - 2017-03-25 04:52:01.034 screenresolution[1961:27072] Display 0: 1600x1200x32@60 04:52:01 INFO - 2017-03-25 04:52:01.040 screenresolution[1962:27075] starting screenresolution argv=screenresolution list 04:52:01 INFO - Available Modes on Display 0 04:52:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:52:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:52:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:52:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:52:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:52:01 INFO - Intel Iris: 04:52:01 INFO - Chipset Model: Intel Iris 04:52:01 INFO - Type: GPU 04:52:01 INFO - Bus: Built-In 04:52:01 INFO - VRAM (Dynamic, Max): 1536 MB 04:52:01 INFO - Vendor: Intel (0x8086) 04:52:01 INFO - Device ID: 0x0a2e 04:52:01 INFO - Revision ID: 0x0009 04:52:01 INFO - Displays: 04:52:01 INFO - Display: 04:52:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:52:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:52:01 INFO - Display Serial Number: 1600x1200 60 04:52:01 INFO - Main Display: Yes 04:52:01 INFO - Mirror: Off 04:52:01 INFO - Online: Yes 04:52:01 INFO - Rotation: Supported 04:52:01 INFO - Return code: 0 04:52:01 INFO - Running post-action listener: _resource_record_post_action 04:52:01 INFO - [mozharness: 2017-03-25 11:52:01.282964Z] Finished run-tests step (success) 04:52:01 INFO - Running post-run listener: _resource_record_post_run 04:52:01 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 3420160; Write bytes: 519788032; Read time: 16; Write time: 1764 04:52:01 INFO - TinderboxPrint: CPU usage
54.1% 04:52:01 INFO - TinderboxPrint: I/O read bytes / time
3,420,160 / 16 04:52:01 INFO - TinderboxPrint: I/O write bytes / time
519,788,032 / 1,764 04:52:01 INFO - TinderboxPrint: CPU idle
2,203.7 (45.9%) 04:52:01 INFO - TinderboxPrint: CPU system
963.5 (20.0%) 04:52:01 INFO - TinderboxPrint: CPU user
1,638.6 (34.1%) 04:52:01 INFO - TinderboxPrint: Swap in / out
214,290,432 / 0 04:52:01 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204739072; Write bytes: 205223424; Read time: 15634; Write time: 449 04:52:01 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 3420160; Write bytes: 303742976; Read time: 16; Write time: 1280 04:52:01 INFO - Running post-run listener: _upload_blobber_files 04:52:01 INFO - Blob upload gear active. 04:52:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:52:01 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 04:52:01 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'] 04:52:01 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 04:52:02 INFO - (blobuploader) - INFO - Open directory for files ... 04:52:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:52:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:52:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:02 INFO - (blobuploader) - INFO - Done attempting. 04:52:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:52:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:52:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:03 INFO - (blobuploader) - INFO - Done attempting. 04:52:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:52:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:52:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:05 INFO - (blobuploader) - INFO - Done attempting. 04:52:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:52:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:52:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:07 INFO - (blobuploader) - INFO - Done attempting. 04:52:07 INFO - (blobuploader) - INFO - Iteration through files over. 04:52:07 INFO - Return code: 0 04:52:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:52:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:52:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95b2b4c2981660fc834f4a128c5f9694f10f22d5d43ba2b9311f8b71b9bcb80417cbfc86b6639bd9cc8720b533802540f24205b272c5307fc77f3e99964dfd14", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/382ed1dd557a89ae04151df2ae9dd6b17e5b7d61fcd4774d96a7b761ae6a8aee94401c3ec6b54c2b502f976dcf6256f3bb61284d02b5f65988a3974f18f8f829", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e7ba4a54e6ea57bf7e5cea9c90f602dd810107a54c1f5920536756efe0a08f28e4d895e1ca54cbee3bb1843442ea0c0e9e1b20d7bafacad7bba70d3f4602349", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04eac8a100e7824f65ff8b0387e297c746ecdd72cdd5c3e7269b673ae37a73e8df5db5a1085649bde6e1034abbde6e3e70dd84fbb27d9da0fce10962c8687bd0"} 04:52:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:52:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:52:07 INFO - Contents: 04:52:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95b2b4c2981660fc834f4a128c5f9694f10f22d5d43ba2b9311f8b71b9bcb80417cbfc86b6639bd9cc8720b533802540f24205b272c5307fc77f3e99964dfd14", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/382ed1dd557a89ae04151df2ae9dd6b17e5b7d61fcd4774d96a7b761ae6a8aee94401c3ec6b54c2b502f976dcf6256f3bb61284d02b5f65988a3974f18f8f829", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e7ba4a54e6ea57bf7e5cea9c90f602dd810107a54c1f5920536756efe0a08f28e4d895e1ca54cbee3bb1843442ea0c0e9e1b20d7bafacad7bba70d3f4602349", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04eac8a100e7824f65ff8b0387e297c746ecdd72cdd5c3e7269b673ae37a73e8df5db5a1085649bde6e1034abbde6e3e70dd84fbb27d9da0fce10962c8687bd0"} 04:52:07 INFO - Running post-run listener: copy_logs_to_upload_dir 04:52:07 INFO - Copying logs to upload dir... 04:52:07 INFO - mkdir: /builds/slave/test/build/upload/logs 04:52:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1452.342548 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 24 mins, 12 secs) (at 2017-03-25 04:52:07.116446) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 04:52:07.119786) ========= 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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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/95b2b4c2981660fc834f4a128c5f9694f10f22d5d43ba2b9311f8b71b9bcb80417cbfc86b6639bd9cc8720b533802540f24205b272c5307fc77f3e99964dfd14", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/382ed1dd557a89ae04151df2ae9dd6b17e5b7d61fcd4774d96a7b761ae6a8aee94401c3ec6b54c2b502f976dcf6256f3bb61284d02b5f65988a3974f18f8f829", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e7ba4a54e6ea57bf7e5cea9c90f602dd810107a54c1f5920536756efe0a08f28e4d895e1ca54cbee3bb1843442ea0c0e9e1b20d7bafacad7bba70d3f4602349", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04eac8a100e7824f65ff8b0387e297c746ecdd72cdd5c3e7269b673ae37a73e8df5db5a1085649bde6e1034abbde6e3e70dd84fbb27d9da0fce10962c8687bd0"} build_url:https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/JKepcFohQUiC_8HCzeI2vw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95b2b4c2981660fc834f4a128c5f9694f10f22d5d43ba2b9311f8b71b9bcb80417cbfc86b6639bd9cc8720b533802540f24205b272c5307fc77f3e99964dfd14", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/382ed1dd557a89ae04151df2ae9dd6b17e5b7d61fcd4774d96a7b761ae6a8aee94401c3ec6b54c2b502f976dcf6256f3bb61284d02b5f65988a3974f18f8f829", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e7ba4a54e6ea57bf7e5cea9c90f602dd810107a54c1f5920536756efe0a08f28e4d895e1ca54cbee3bb1843442ea0c0e9e1b20d7bafacad7bba70d3f4602349", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04eac8a100e7824f65ff8b0387e297c746ecdd72cdd5c3e7269b673ae37a73e8df5db5a1085649bde6e1034abbde6e3e70dd84fbb27d9da0fce10962c8687bd0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 04:52:07.154319) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 04:52:07.154650) ========= 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.nSRkO1XoNk/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.2Vq9OViNr7/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005407 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 04:52:07.203241) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 04:52:07.203570) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 04:52:07.203891) ========= ========= Total master_lag: 0.18 =========