builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0287 starttime: 1491826098.86 results: success (0) buildid: 20170410030221 builduid: 095bc7be1faf49689ab66bd5d04df180 revision: 731639fccc709a4dd95fed7e9dda88efb2227906 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:18.855960) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:18.856418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:18.856728) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:18.884213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:18.884601) ========= 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.zvn5iKdG4W/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.IJVagdc5u8/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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-04-10 05:08:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.4M=0s 2017-04-10 05:08:19 (23.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146542 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:19.055681) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:19.055992) ========= 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.zvn5iKdG4W/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.IJVagdc5u8/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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.027699 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:19.105173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 05:08:19.105523) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 731639fccc709a4dd95fed7e9dda88efb2227906 --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 731639fccc709a4dd95fed7e9dda88efb2227906 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zvn5iKdG4W/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.IJVagdc5u8/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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-04-10 05:08:19,194 truncating revision to first 12 chars 2017-04-10 05:08:19,194 Setting DEBUG logging. 2017-04-10 05:08:19,194 attempt 1/10 2017-04-10 05:08:19,195 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/731639fccc70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 05:08:20,627 unpacking tar archive at: mozilla-central-731639fccc70/testing/mozharness/ program finished with exit code 0 elapsedTime=1.711272 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 05:08:20.840508) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:20.840850) ========= script_repo_revision: 731639fccc709a4dd95fed7e9dda88efb2227906 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:20.841231) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:20.841492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 05:08:20.861328) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-10 05:08:20.861761) ========= /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.zvn5iKdG4W/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.IJVagdc5u8/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:08:21 INFO - MultiFileLogger online at 20170410 05:08:21 in /builds/slave/test 05:08:21 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 05:08:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:21 INFO - {'append_to_log': False, 05:08:21 INFO - 'base_work_dir': '/builds/slave/test', 05:08:21 INFO - 'blob_upload_branch': 'Firefox', 05:08:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:21 INFO - 'branch': 'Firefox', 05:08:21 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:21 INFO - 'config_files': ('talos/mac_config.py',), 05:08:21 INFO - 'default_actions': ('clobber', 05:08:21 INFO - 'read-buildbot-config', 05:08:21 INFO - 'download-and-extract', 05:08:21 INFO - 'populate-webroot', 05:08:21 INFO - 'create-virtualenv', 05:08:21 INFO - 'install', 05:08:21 INFO - 'run-tests'), 05:08:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:21 INFO - 'download_minidump_stackwalk': True, 05:08:21 INFO - 'download_symbols': 'ondemand', 05:08:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:21 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:21 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:21 INFO - 'gecko_profile': False, 05:08:21 INFO - 'gecko_profile_interval': 0, 05:08:21 INFO - 'installer_path': 'installer.exe', 05:08:21 INFO - 'log_level': 'info', 05:08:21 INFO - 'log_name': 'talos', 05:08:21 INFO - 'log_to_console': True, 05:08:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:08:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:08:21 INFO - 'opt_config_files': (), 05:08:21 INFO - 'pip_index': False, 05:08:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:08:21 INFO - 'cmd': ('bash', 05:08:21 INFO - '-c', 05:08:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:08:21 INFO - 'enabled': True, 05:08:21 INFO - 'halt_on_failure': False, 05:08:21 INFO - 'name': 'check_screen_resolution'},), 05:08:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:08:21 INFO - 'cmd': ('bash', 05:08:21 INFO - '-c', 05:08:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:08:21 INFO - 'enabled': True, 05:08:21 INFO - 'halt_on_failure': False, 05:08:21 INFO - 'name': 'check_screen_resolution'},), 05:08:21 INFO - 'run_cmd_checks_enabled': True, 05:08:21 INFO - 'suite': 'dromaeojs-e10s', 05:08:21 INFO - 'system_bits': '32', 05:08:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:21 INFO - 'title': 't-yosemite-r7-0287', 05:08:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:21 INFO - 'use_talos_json': True, 05:08:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:21 INFO - 'work_dir': 'build'} 05:08:21 INFO - [mozharness: 2017-04-10 12:08:21.059254Z] Running clobber step. 05:08:21 INFO - Running pre-action listener: _resource_record_pre_action 05:08:21 INFO - Running main action method: clobber 05:08:21 INFO - rmtree: /builds/slave/test/build 05:08:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:25 INFO - Running post-action listener: _resource_record_post_action 05:08:25 INFO - [mozharness: 2017-04-10 12:08:25.363708Z] Finished clobber step (success) 05:08:25 INFO - [mozharness: 2017-04-10 12:08:25.363838Z] Running read-buildbot-config step. 05:08:25 INFO - Running pre-action listener: _resource_record_pre_action 05:08:25 INFO - Running main action method: read_buildbot_config 05:08:25 INFO - Using buildbot properties: 05:08:25 INFO - { 05:08:25 INFO - "project": "", 05:08:25 INFO - "product": "firefox", 05:08:25 INFO - "script_repo_revision": "production", 05:08:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:08:25 INFO - "repository": "", 05:08:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:08:25 INFO - "buildid": "20170410030221", 05:08:25 INFO - "stage_platform": "macosx64", 05:08:25 INFO - "basedir": "/builds/slave/test", 05:08:25 INFO - "nightly_build": "True", 05:08:25 INFO - "buildnumber": 117, 05:08:25 INFO - "slavename": "t-yosemite-r7-0287", 05:08:25 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:08:25 INFO - "platform": "yosemite_r7", 05:08:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:08:25 INFO - "branch": "mozilla-central", 05:08:25 INFO - "repo_path": "mozilla-central", 05:08:25 INFO - "revision": "731639fccc709a4dd95fed7e9dda88efb2227906", 05:08:25 INFO - "pgo_build": "False", 05:08:25 INFO - "builduid": "095bc7be1faf49689ab66bd5d04df180", 05:08:25 INFO - "slavebuilddir": "test" 05:08:25 INFO - } 05:08:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:08:25 INFO - Running post-action listener: _resource_record_post_action 05:08:25 INFO - [mozharness: 2017-04-10 12:08:25.367272Z] Finished read-buildbot-config step (success) 05:08:25 INFO - [mozharness: 2017-04-10 12:08:25.367400Z] Running download-and-extract step. 05:08:25 INFO - Running pre-action listener: _resource_record_pre_action 05:08:25 INFO - Running main action method: download_and_extract 05:08:25 INFO - mkdir: /builds/slave/test/build/tests 05:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:25 INFO - trying https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:08:25 INFO - Downloading https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/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 05:08:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/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 05:08:26 INFO - Downloaded 1325 bytes. 05:08:26 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:08:26 INFO - Using the following test package requirements: 05:08:26 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:08:26 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:08:26 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:08:26 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:08:26 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'jsshell-mac.zip'], 05:08:26 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:08:26 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:08:26 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:08:26 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:08:26 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:08:26 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:08:26 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:08:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:08:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:08:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:08:26 INFO - Fetch https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:08:27 INFO - Expected file size: 52581042 05:08:27 INFO - Obtained file size: 52581042 05:08:44 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 05:08:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:08:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:08:44 INFO - Fetch https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:08:46 INFO - Expected file size: 52581042 05:08:46 INFO - Obtained file size: 52581042 05:09:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:09:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:09:01 INFO - Fetch https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:09:03 INFO - Expected file size: 14405596 05:09:03 INFO - Obtained file size: 14405596 05:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:03 INFO - trying https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:09:03 INFO - Downloading https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/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 05:09:05 INFO - Downloaded 71028739 bytes. 05:09:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:09:05 INFO - mkdir: /builds/slave/test/properties 05:09:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:05 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:05 INFO - Contents: 05:09:05 INFO - build_url:https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:09:06 INFO - Running post-action listener: _resource_record_post_action 05:09:06 INFO - Running post-action listener: set_extra_try_arguments 05:09:06 INFO - [mozharness: 2017-04-10 12:09:06.029053Z] Finished download-and-extract step (success) 05:09:06 INFO - [mozharness: 2017-04-10 12:09:06.029200Z] Running populate-webroot step. 05:09:06 INFO - Running pre-action listener: _resource_record_pre_action 05:09:06 INFO - Running main action method: populate_webroot 05:09:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:06 INFO - '--tppagecycles', 05:09:06 INFO - '7'], 05:09:06 INFO - 'tests': ['tsvgm']}}, 05:09:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:09:06 INFO - 'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:09:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:09:06 INFO - 'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['damp', 'tps']}, 05:09:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:09:06 INFO - 'tests': ['damp', 'tps']}, 05:09:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['dromaeo_dom']}, 05:09:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['basic_compositor_video']}, 05:09:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:09:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['a11yr', 05:09:06 INFO - 'ts_paint', 05:09:06 INFO - 'tpaint', 05:09:06 INFO - 'sessionrestore', 05:09:06 INFO - 'sessionrestore_no_auto_restore', 05:09:06 INFO - 'tabpaint']}, 05:09:06 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:06 INFO - 'ts_paint', 05:09:06 INFO - 'tpaint', 05:09:06 INFO - 'sessionrestore', 05:09:06 INFO - 'sessionrestore_no_auto_restore', 05:09:06 INFO - 'tabpaint']}, 05:09:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:06 INFO - 'ts_paint', 05:09:06 INFO - 'tpaint', 05:09:06 INFO - 'sessionrestore', 05:09:06 INFO - 'sessionrestore_no_auto_restore', 05:09:06 INFO - 'tabpaint']}, 05:09:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:06 INFO - 'ts_paint', 05:09:06 INFO - 'tpaint', 05:09:06 INFO - 'sessionrestore', 05:09:06 INFO - 'sessionrestore_no_auto_restore', 05:09:06 INFO - 'tabpaint']}, 05:09:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['a11yr', 05:09:06 INFO - 'ts_paint', 05:09:06 INFO - 'tpaint', 05:09:06 INFO - 'sessionrestore', 05:09:06 INFO - 'sessionrestore_no_auto_restore', 05:09:06 INFO - 'tabpaint']}, 05:09:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['a11yr', 05:09:06 INFO - 'ts_paint', 05:09:06 INFO - 'tpaint', 05:09:06 INFO - 'sessionrestore', 05:09:06 INFO - 'sessionrestore_no_auto_restore', 05:09:06 INFO - 'tabpaint']}, 05:09:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['tsvgx', 05:09:06 INFO - 'tsvgr_opacity', 05:09:06 INFO - 'tart', 05:09:06 INFO - 'tscrollx', 05:09:06 INFO - 'cart', 05:09:06 INFO - 'tsvg_static']}, 05:09:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:06 INFO - 'tsvgr_opacity', 05:09:06 INFO - 'tart', 05:09:06 INFO - 'tscrollx', 05:09:06 INFO - 'cart', 05:09:06 INFO - 'tsvg_static']}, 05:09:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:09:06 INFO - 'talos_options': ['--disable-e10s'], 05:09:06 INFO - 'tests': ['tp5o']}, 05:09:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:09:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:09:06 INFO - 'talos_options': ['--disable-e10s', 05:09:06 INFO - '--xperf_path', 05:09:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:06 INFO - 'tests': ['tp5n']}, 05:09:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:09:06 INFO - 'talos_options': ['--xperf_path', 05:09:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:06 INFO - 'tests': ['tp5n']}}, 05:09:06 INFO - 'talos.zip': {'path': '', 05:09:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:06 INFO - Running post-action listener: _resource_record_post_action 05:09:06 INFO - [mozharness: 2017-04-10 12:09:06.047087Z] Finished populate-webroot step (success) 05:09:06 INFO - [mozharness: 2017-04-10 12:09:06.047219Z] Running create-virtualenv step. 05:09:06 INFO - Running pre-action listener: _resource_record_pre_action 05:09:06 INFO - Running main action method: create_virtualenv 05:09:06 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:06 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 05:09:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:06 INFO - Using real prefix '/tools/python27' 05:09:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:09 INFO - Installing pip.................done. 05:09:09 INFO - Return code: 0 05:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:09:10 INFO - Reading from file tmpfile_stdout 05:09:10 INFO - Output received: 05:09:10 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) 05:09:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:10 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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:10 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 05:09:10 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 05:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:10 INFO - 'HOME': '/Users/cltbld', 05:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:10 INFO - 'LOGNAME': 'cltbld', 05:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:10 INFO - 'MOZ_NO_REMOTE': '1', 05:09:10 INFO - 'NO_EM_RESTART': '1', 05:09:10 INFO - 'PAGER': '/bin/cat', 05:09:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:10 INFO - 'PWD': '/builds/slave/test', 05:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:10 INFO - 'SHELL': '/bin/bash', 05:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:10 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:10 INFO - 'USER': 'cltbld', 05:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:10 INFO - 'XPC_FLAGS': '0x0', 05:09:10 INFO - 'XPC_SERVICE_NAME': '0', 05:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:11 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 05:09:11 INFO - Cleaning up... 05:09:11 INFO - Return code: 0 05:09:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:11 INFO - 'HOME': '/Users/cltbld', 05:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:11 INFO - 'LOGNAME': 'cltbld', 05:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:11 INFO - 'MOZ_NO_REMOTE': '1', 05:09:11 INFO - 'NO_EM_RESTART': '1', 05:09:11 INFO - 'PAGER': '/bin/cat', 05:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:11 INFO - 'PWD': '/builds/slave/test', 05:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:11 INFO - 'SHELL': '/bin/bash', 05:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:11 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:11 INFO - 'USER': 'cltbld', 05:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:11 INFO - 'XPC_FLAGS': '0x0', 05:09:11 INFO - 'XPC_SERVICE_NAME': '0', 05:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:11 INFO - Downloading/unpacking psutil>=3.1.1 05:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:15 INFO - Installing collected packages: psutil 05:09:15 INFO - Running setup.py install for psutil 05:09:15 INFO - building 'psutil._psutil_osx' extension 05:09:15 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 INFO - building 'psutil._psutil_posix' extension 05:09:16 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 05:09:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:09:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:09:16 INFO - ^ 05:09:16 INFO - 1 warning generated. 05:09:16 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 05:09:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:16 INFO - Successfully installed psutil 05:09:16 INFO - Cleaning up... 05:09:16 INFO - Return code: 0 05:09:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:16 INFO - 'HOME': '/Users/cltbld', 05:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:16 INFO - 'LOGNAME': 'cltbld', 05:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:16 INFO - 'MOZ_NO_REMOTE': '1', 05:09:16 INFO - 'NO_EM_RESTART': '1', 05:09:16 INFO - 'PAGER': '/bin/cat', 05:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:16 INFO - 'PWD': '/builds/slave/test', 05:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:16 INFO - 'SHELL': '/bin/bash', 05:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:16 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:16 INFO - 'USER': 'cltbld', 05:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:16 INFO - 'XPC_FLAGS': '0x0', 05:09:16 INFO - 'XPC_SERVICE_NAME': '0', 05:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:09:19 INFO - Installing collected packages: mozsystemmonitor 05:09:19 INFO - Running setup.py install for mozsystemmonitor 05:09:19 INFO - Successfully installed mozsystemmonitor 05:09:19 INFO - Cleaning up... 05:09:19 INFO - Return code: 0 05:09:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:09:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:19 INFO - 'HOME': '/Users/cltbld', 05:09:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:19 INFO - 'LOGNAME': 'cltbld', 05:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:19 INFO - 'MOZ_NO_REMOTE': '1', 05:09:19 INFO - 'NO_EM_RESTART': '1', 05:09:19 INFO - 'PAGER': '/bin/cat', 05:09:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:19 INFO - 'PWD': '/builds/slave/test', 05:09:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:19 INFO - 'SHELL': '/bin/bash', 05:09:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:19 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:19 INFO - 'USER': 'cltbld', 05:09:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:19 INFO - 'XPC_FLAGS': '0x0', 05:09:19 INFO - 'XPC_SERVICE_NAME': '0', 05:09:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:20 INFO - Downloading/unpacking jsonschema==2.5.1 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:09:22 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 05:09:22 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 05:09:22 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 05:09:22 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 05:09:22 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 05:09:22 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 05:09:23 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:23 INFO - warning: no files found matching '*.txt' 05:09:23 INFO - no previously-included directories found matching 'build' 05:09:23 INFO - no previously-included directories found matching 'dist' 05:09:23 INFO - no previously-included directories found matching '.git*' 05:09:23 INFO - Installing collected packages: jsonschema, functools32 05:09:23 INFO - Running setup.py install for functools32 05:09:23 INFO - warning: no files found matching '*.txt' 05:09:23 INFO - no previously-included directories found matching 'build' 05:09:23 INFO - no previously-included directories found matching 'dist' 05:09:23 INFO - no previously-included directories found matching '.git*' 05:09:23 INFO - Successfully installed jsonschema functools32 05:09:23 INFO - Cleaning up... 05:09:23 INFO - Return code: 0 05:09:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:09:23 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')]} 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:23 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')]} 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:23 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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:23 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 05:09:23 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 05:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:23 INFO - 'HOME': '/Users/cltbld', 05:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:23 INFO - 'LOGNAME': 'cltbld', 05:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:23 INFO - 'MOZ_NO_REMOTE': '1', 05:09:23 INFO - 'NO_EM_RESTART': '1', 05:09:23 INFO - 'PAGER': '/bin/cat', 05:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:23 INFO - 'PWD': '/builds/slave/test', 05:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:23 INFO - 'SHELL': '/bin/bash', 05:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:23 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:23 INFO - 'USER': 'cltbld', 05:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:23 INFO - 'XPC_FLAGS': '0x0', 05:09:23 INFO - 'XPC_SERVICE_NAME': '0', 05:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:09:23 INFO - Cleaning up... 05:09:23 INFO - Return code: 0 05:09:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:23 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')]} 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:23 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')]} 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:23 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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:23 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 05:09:23 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 05:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:23 INFO - 'HOME': '/Users/cltbld', 05:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:23 INFO - 'LOGNAME': 'cltbld', 05:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:23 INFO - 'MOZ_NO_REMOTE': '1', 05:09:23 INFO - 'NO_EM_RESTART': '1', 05:09:23 INFO - 'PAGER': '/bin/cat', 05:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:23 INFO - 'PWD': '/builds/slave/test', 05:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:23 INFO - 'SHELL': '/bin/bash', 05:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:23 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:23 INFO - 'USER': 'cltbld', 05:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:23 INFO - 'XPC_FLAGS': '0x0', 05:09:23 INFO - 'XPC_SERVICE_NAME': '0', 05:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:24 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:26 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:28 INFO - Downloading docopt-0.6.1.tar.gz 05:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:28 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:28 INFO - Running setup.py install for blobuploader 05:09:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:28 INFO - Running setup.py install for requests 05:09:28 INFO - Running setup.py install for docopt 05:09:29 INFO - Successfully installed blobuploader requests docopt 05:09:29 INFO - Cleaning up... 05:09:29 INFO - Return code: 0 05:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:29 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')]} 05:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:29 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')]} 05:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:29 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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:29 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 05:09:29 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 05:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:29 INFO - 'HOME': '/Users/cltbld', 05:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:29 INFO - 'LOGNAME': 'cltbld', 05:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:29 INFO - 'MOZ_NO_REMOTE': '1', 05:09:29 INFO - 'NO_EM_RESTART': '1', 05:09:29 INFO - 'PAGER': '/bin/cat', 05:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:29 INFO - 'PWD': '/builds/slave/test', 05:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:29 INFO - 'SHELL': '/bin/bash', 05:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:29 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:29 INFO - 'USER': 'cltbld', 05:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:29 INFO - 'XPC_FLAGS': '0x0', 05:09:29 INFO - 'XPC_SERVICE_NAME': '0', 05:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:29 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-ZUwAQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:29 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-d31bdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:29 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-q8aIxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:29 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-8XovzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:29 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-aBzqW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:29 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Z0O14w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-_dC8N5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-HKlcnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-whCTKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-7wEdpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-V82ENx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-PmlL0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-z7BgdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-57nMz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-WHXVpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:30 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Ll1qgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:31 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-5u32KW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:31 INFO - Running setup.py install for manifestparser 05:09:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:31 INFO - Running setup.py install for mozcrash 05:09:31 INFO - Running setup.py install for mozdebug 05:09:31 INFO - Running setup.py install for mozdevice 05:09:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:31 INFO - Running setup.py install for mozfile 05:09:31 INFO - Running setup.py install for mozhttpd 05:09:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:32 INFO - Running setup.py install for mozinfo 05:09:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:32 INFO - Running setup.py install for mozInstall 05:09:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:32 INFO - Running setup.py install for mozleak 05:09:32 INFO - Running setup.py install for mozlog 05:09:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:32 INFO - Running setup.py install for moznetwork 05:09:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:32 INFO - Running setup.py install for mozprocess 05:09:32 INFO - Running setup.py install for mozprofile 05:09:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:33 INFO - Running setup.py install for mozrunner 05:09:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:33 INFO - Running setup.py install for mozscreenshot 05:09:33 INFO - Running setup.py install for moztest 05:09:33 INFO - Running setup.py install for mozversion 05:09:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:33 INFO - Cleaning up... 05:09:33 INFO - Return code: 0 05:09:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:33 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')]} 05:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:33 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')]} 05:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:33 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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:33 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 05:09:33 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 05:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:33 INFO - 'HOME': '/Users/cltbld', 05:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:33 INFO - 'LOGNAME': 'cltbld', 05:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:33 INFO - 'MOZ_NO_REMOTE': '1', 05:09:33 INFO - 'NO_EM_RESTART': '1', 05:09:33 INFO - 'PAGER': '/bin/cat', 05:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:33 INFO - 'PWD': '/builds/slave/test', 05:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:33 INFO - 'SHELL': '/bin/bash', 05:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:33 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:33 INFO - 'USER': 'cltbld', 05:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:33 INFO - 'XPC_FLAGS': '0x0', 05:09:33 INFO - 'XPC_SERVICE_NAME': '0', 05:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Etv2wK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:34 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-sjdi8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:34 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-5ImHbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:34 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-0BH0Sl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Ou9X5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:34 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-DV6Q1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:34 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-F6bzQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:34 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-kzoUVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:34 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Sxa4NH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:34 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)) 05:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:34 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-ZyLdEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:35 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)) 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:35 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-LJa8IM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:35 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)) 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:35 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-BITesZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:35 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)) 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:35 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-4xO_iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:35 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)) 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:35 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-AEpdlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:35 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)) 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:35 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-iGNLt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:35 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)) 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:35 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-APTlrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:35 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)) 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:35 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-8RjBAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:35 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)) 05:09:35 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)) 05:09:35 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)) 05:09:35 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)) 05:09:35 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:35 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:38 INFO - Downloading blessings-1.6.tar.gz 05:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:38 INFO - Installing collected packages: blessings 05:09:38 INFO - Running setup.py install for blessings 05:09:38 INFO - Successfully installed blessings 05:09:38 INFO - Cleaning up... 05:09:38 INFO - Return code: 0 05:09:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:38 INFO - Reading from file tmpfile_stdout 05:09:38 INFO - Current package versions: 05:09:38 INFO - blessings == 1.6 05:09:38 INFO - blobuploader == 1.2.4 05:09:38 INFO - docopt == 0.6.1 05:09:38 INFO - functools32 == 3.2.3-2 05:09:38 INFO - jsonschema == 2.5.1 05:09:38 INFO - manifestparser == 1.1 05:09:38 INFO - mozInstall == 1.12 05:09:38 INFO - mozcrash == 1.0 05:09:38 INFO - mozdebug == 0.1 05:09:38 INFO - mozdevice == 0.50 05:09:38 INFO - mozfile == 1.2 05:09:38 INFO - mozhttpd == 0.7 05:09:38 INFO - mozinfo == 0.9 05:09:38 INFO - mozleak == 0.1 05:09:38 INFO - mozlog == 3.4 05:09:38 INFO - moznetwork == 0.27 05:09:38 INFO - mozprocess == 0.25 05:09:38 INFO - mozprofile == 0.28 05:09:38 INFO - mozrunner == 6.13 05:09:38 INFO - mozscreenshot == 0.1 05:09:38 INFO - mozsystemmonitor == 0.3 05:09:38 INFO - moztest == 0.8 05:09:38 INFO - mozversion == 1.4 05:09:38 INFO - psutil == 3.1.1 05:09:38 INFO - requests == 1.2.3 05:09:38 INFO - wsgiref == 0.1.2 05:09:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:38 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')]} 05:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:38 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')]} 05:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:38 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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:38 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 05:09:38 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 05:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:38 INFO - 'HOME': '/Users/cltbld', 05:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:38 INFO - 'LOGNAME': 'cltbld', 05:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:38 INFO - 'MOZ_NO_REMOTE': '1', 05:09:38 INFO - 'NO_EM_RESTART': '1', 05:09:38 INFO - 'PAGER': '/bin/cat', 05:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:38 INFO - 'PWD': '/builds/slave/test', 05:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:38 INFO - 'SHELL': '/bin/bash', 05:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:38 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:38 INFO - 'USER': 'cltbld', 05:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:38 INFO - 'XPC_FLAGS': '0x0', 05:09:38 INFO - 'XPC_SERVICE_NAME': '0', 05:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 INFO - Cleaning up... 05:09:39 INFO - Return code: 0 05:09:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:39 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')]} 05:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:39 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')]} 05:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:39 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 0x10910db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109110768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd970562fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, '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.zvn5iKdG4W/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.IJVagdc5u8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:09:39 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 05:09:39 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 05:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:39 INFO - 'HOME': '/Users/cltbld', 05:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:39 INFO - 'LOGNAME': 'cltbld', 05:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:39 INFO - 'MOZ_NO_REMOTE': '1', 05:09:39 INFO - 'NO_EM_RESTART': '1', 05:09:39 INFO - 'PAGER': '/bin/cat', 05:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:39 INFO - 'PWD': '/builds/slave/test', 05:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:39 INFO - 'SHELL': '/bin/bash', 05:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:39 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:39 INFO - 'USER': 'cltbld', 05:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:39 INFO - 'XPC_FLAGS': '0x0', 05:09:39 INFO - 'XPC_SERVICE_NAME': '0', 05:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:09:39 INFO - Cleaning up... 05:09:39 INFO - Return code: 0 05:09:39 INFO - Running post-action listener: _resource_record_post_action 05:09:39 INFO - Running post-action listener: _start_resource_monitoring 05:09:39 INFO - Starting resource monitoring. 05:09:39 INFO - [mozharness: 2017-04-10 12:09:39.534808Z] Finished create-virtualenv step (success) 05:09:39 INFO - [mozharness: 2017-04-10 12:09:39.535708Z] Running install step. 05:09:39 INFO - Running pre-action listener: _resource_record_pre_action 05:09:39 INFO - Running main action method: install 05:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:39 INFO - Reading from file tmpfile_stdout 05:09:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:39 INFO - Reading from file tmpfile_stdout 05:09:39 INFO - Output received: 05:09:39 INFO - Usage: mozinstall [options] installer 05:09:39 INFO - Options: 05:09:39 INFO - -h, --help show this help message and exit 05:09:39 INFO - -d DEST, --destination=DEST 05:09:39 INFO - Directory to install application into. [default: 05:09:39 INFO - "/builds/slave/test"] 05:09:39 INFO - --app=APP Application being installed. [default: firefox] 05:09:39 INFO - mkdir: /builds/slave/test/build/application 05:09:39 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'] 05:09:39 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 05:09:59 INFO - Reading from file tmpfile_stdout 05:09:59 INFO - Output received: 05:09:59 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:09:59 INFO - Running post-action listener: _resource_record_post_action 05:09:59 INFO - [mozharness: 2017-04-10 12:09:59.320932Z] Finished install step (success) 05:09:59 INFO - [mozharness: 2017-04-10 12:09:59.321075Z] Running run-tests step. 05:09:59 INFO - Running pre-action listener: _resource_record_pre_action 05:09:59 INFO - Running main action method: run_tests 05:09:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:59 INFO - 2017-04-10 05:09:59.335 screenresolution[1625:7507] starting screenresolution argv=screenresolution get 05:09:59 INFO - 2017-04-10 05:09:59.338 screenresolution[1625:7507] Display 0: 1600x1200x32@60 05:09:59 INFO - 2017-04-10 05:09:59.344 screenresolution[1626:7509] starting screenresolution argv=screenresolution list 05:09:59 INFO - Available Modes on Display 0 05:09:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:59 INFO - Intel Iris: 05:09:59 INFO - Chipset Model: Intel Iris 05:09:59 INFO - Type: GPU 05:09:59 INFO - Bus: Built-In 05:09:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:59 INFO - Vendor: Intel (0x8086) 05:09:59 INFO - Device ID: 0x0a2e 05:09:59 INFO - Revision ID: 0x0009 05:09:59 INFO - Displays: 05:09:59 INFO - Display: 05:09:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:59 INFO - Display Serial Number: 1600x1200 60 05:09:59 INFO - Main Display: Yes 05:09:59 INFO - Mirror: Off 05:09:59 INFO - Online: Yes 05:09:59 INFO - Rotation: Supported 05:09:59 INFO - Return code: 0 05:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:59 INFO - Python 2.7.3 05:09:59 INFO - Return code: 0 05:09:59 INFO - grabbing minidump binary from tooltool 05:09: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')]} 05:09:59 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 0x108eebd30>, '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 0x108e12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ee0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:59 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 05:09:59 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 05:09:59 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 05:09:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:59 INFO - Return code: 0 05:09:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:09:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:59 INFO - ENV: RUST_BACKTRACE is now 1 05:09:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:09:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:59 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/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0287', '--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 05:09:59 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/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0287 --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 05:09:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvn5iKdG4W/Render', 05:09:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:59 INFO - 'HOME': '/Users/cltbld', 05:09:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:59 INFO - 'LOGNAME': 'cltbld', 05:09:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:59 INFO - 'MOZ_NO_REMOTE': '1', 05:09:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:59 INFO - 'NO_EM_RESTART': '1', 05:09:59 INFO - 'PAGER': '/bin/cat', 05:09:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:59 INFO - 'PWD': '/builds/slave/test', 05:09:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:59 INFO - 'RUST_BACKTRACE': '1', 05:09:59 INFO - 'SHELL': '/bin/bash', 05:09:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IJVagdc5u8/Listeners', 05:09:59 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:09:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:59 INFO - 'USER': 'cltbld', 05:09:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:59 INFO - 'XPC_FLAGS': '0x0', 05:09:59 INFO - 'XPC_SERVICE_NAME': '0', 05:09:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:59 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/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0287', '--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 05:09:59 INFO - mozversion application_buildid: 20170410030221 05:09:59 INFO - mozversion application_changeset: 731639fccc709a4dd95fed7e9dda88efb2227906 05:09:59 INFO - mozversion application_display_name: Nightly 05:09:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:59 INFO - mozversion application_name: Firefox 05:09:59 INFO - mozversion application_remotingname: firefox 05:09:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:09:59 INFO - mozversion application_vendor: Mozilla 05:09:59 INFO - mozversion application_version: 55.0a1 05:09:59 INFO - mozversion platform_buildid: 20170410030221 05:09:59 INFO - mozversion platform_changeset: 731639fccc709a4dd95fed7e9dda88efb2227906 05:09:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:09:59 INFO - mozversion platform_version: 55.0a1 05:09:59 INFO - using testdate: 1491826199 05:09:59 INFO - actual date: 1491826199 05:09:59 INFO - starting webserver on 'localhost:49241' 05:09:59 INFO - SUITE-START | Running 2 tests 05:09:59 INFO - TEST-START | dromaeo_css 05:09:59 INFO - operating with platform_type : mac_ 05:09:59 INFO - Initialising browser for dromaeo_css test... 05:09:59 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpuMvPzw/profile http://localhost:49241/getInfo.html) 05:10:03 INFO - PID 1636 | 2017-04-10 05:10:03.055 plugin-container[1639:7686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 05:10:03 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:10:03 INFO - PID 1636 | 2017-04-10 05:10:03.055 plugin-container[1639:7686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 05:10:03 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:10:03 INFO - PID 1636 | 2017-04-10 05:10:03.055 plugin-container[1639:7686] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:10:03 INFO - PID 1636 | __metrics Screen width/height:1600/1200 05:10:03 INFO - PID 1636 | colorDepth:24 05:10:03 INFO - PID 1636 | Browser inner width/height: 1024/689 05:10:03 INFO - PID 1636 | __metrics 05:10:03 ERROR - PID 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 05:10:04 INFO - TEST-INFO | 1636: exit 0 05:10:04 INFO - Browser initialized. 05:10:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:04 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpuMvPzw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:06 INFO - PID 1640 | 2017-04-10 05:10:06.922 plugin-container[1641:7814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 05:10:06 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:10:06 INFO - PID 1640 | 2017-04-10 05:10:06.922 plugin-container[1641:7814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8857, name = 'com.apple.CFPasteboardClient' 05:10:06 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:10:06 INFO - PID 1640 | 2017-04-10 05:10:06.922 plugin-container[1641:7814] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:10:16 INFO - PID 1640 | RSS: Main: 233336832 05:10:16 INFO - PID 1640 | 05:13:20 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 05:13:21 INFO - PID 1640 | RSS: Main: 251920384 05:13:21 INFO - PID 1640 | 05:15:04 INFO - PID 1640 | ************************* 05:15:04 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 05:15:04 INFO - PID 1640 | 05:15:04 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:15:04 INFO - PID 1640 | Full stack: 05:15:04 INFO - PID 1640 | ************************* 05:15:04 INFO - PID 1640 | ************************* 05:15:04 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 05:15:04 INFO - PID 1640 | 05:15:04 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:15:04 INFO - PID 1640 | Full stack: 05:15:04 INFO - PID 1640 | ************************* 05:15:04 INFO - PID 1640 | ************************* 05:15:04 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 05:15:04 INFO - PID 1640 | 05:15:04 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:15:04 INFO - PID 1640 | Full stack: 05:15:04 INFO - PID 1640 | ************************* 05:15:04 INFO - PID 1640 | ************************* 05:15:04 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 05:15:04 INFO - PID 1640 | 05:15:04 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:15:04 INFO - PID 1640 | Full stack: 05:15:04 INFO - PID 1640 | ************************* 05:16:23 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 05:16:24 INFO - PID 1640 | RSS: Main: 260882432 05:16:24 INFO - PID 1640 | 05:19:26 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 05:19:26 INFO - PID 1640 | RSS: Main: 270962688 05:19:26 INFO - PID 1640 | 05:22:28 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 05:22:29 INFO - PID 1640 | RSS: Main: 264347648 05:22:29 INFO - PID 1640 | 05:25:32 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 05:25:32 INFO - PID 1640 | RSS: Main: 275611648 05:25:32 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 05:28:34 INFO - PID 1640 | RSS: Main: 272486400 05:28:34 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | __start_tp_report 05:28:34 INFO - PID 1640 | _x_x_mozilla_page_load 05:28:34 INFO - PID 1640 | _x_x_mozilla_page_load_details 05:28:34 INFO - PID 1640 | |i|pagename|runs| 05:28:34 INFO - PID 1640 | |0;dojo.html;2861.470627933834;3556.9110772230706;3569.1790888095734;3605.567331920169;3613.94579081314;5398.838034858951;6140.692965351725;6261.217347831521;6268.435840774329;6303.590266632677;5243.711595862235;6130.122981477971;6183.59806612571;6241.625502469855;6323.525735569828;5434.456554344553;5457.808976685827;5506.201600767887;5570.053090974544;5601.411851755568;5409.26974858395;5515.862103447405;5597.8600534986745;5613.410591887854;5623.26897503326;5342.893142137174;5383.380911195223;5536.307961504812;5538.086215774392;5603.271574695284;5443.673379597237;5462.453754624546;5498.120300751901;5528.44715528444;5572.4706152915405;5323.946760532383;5379.220013098118;5392.865178370533;5393.433689462589;5397.271368365299;5982.46157845796;6119.112728654365;6192.535559832985;6239.095331176999;6259.155014073133;6441.291457939623;6573.769918052845;6645.734170633169;6669.666516674147;6694.83262918426;6796.66016699168;6806.55757375769;6806.55757375769;6812.829679258009;6822.727090916407;6349.8730025400155;6393.680315984228;6410.391012853818;6427.775027874002;6431.453326467303;6638.203415590064;6727;6911.412529935012;6930.410915072275;6977.89533157003;5970.746143309867;6363.300036995926;6548.410643042053;6552.50856185788;6585.934140658628;5486.698231597221;5693.430656934356;6518.804435866931;6637.435817955431;6659.334066593302;6644.435223006002;6646.767363142363;6657.101291325609;6666;6683.598984060972;6391.328910464427;6437.581557198767;6696.2968888267005;6703.3296670332575;6708.42978346846;4581.358609794632;5319.122344813062;5327.866803329948;5377.892442151135;5389.757460914268;4358.869233922987;4427.623651989553;4672.883177920579;4707.81168753246;4735.8342458014495;6262.968685156545;6375.394337537927;6404.775832845921;6408.10286559882;6442.8389290268115;5557.722113894289;5712.542996560265;6407.437025189868;6586.73653053882;6762.357576030269;6346.936530634727;6431.5819471734185;6441.67791610427;6456.322086180978;6456.548041637135;5985.40145985398;6001.7599296027665;6012.008018676869;6022.126791615279;6023.216981792339;6576.605403675795;6718.630475323904;6849.794506164823;6884.690188941609;7008.229094799568;6768.932310676831;6769.289224631343;6770.187577490733;6794.76218332356;6795.660216989229;5598.804041858515;6204.883121038257;6930.099087118642;6944.93055069463;6948.478864085315;6332.145160403286;6423.42189202974;6616.206055273398;6619.635920024445;6634;6454.967725161344;6730.798076057726;6778.288279730557;6787.796366109024;6794.52438329332;6340.30256671775;6524.412802847766;6529.836754080996;6532.673366331569;6534.575252608565;6330.208723909512;6496.415322620798;6522.771702990372;6564.770233041821;6566.9014964774615;4608.976955115203;6106.72519736604;6401.93598064032;6401.967990160019;6410.967945160244;6519.185101862267;6543.607383557001;6550.7707230246715;6559.770408035696;6569.868602628017;6190.907136392868;6194.690265486798;6310.7791227306825;6356.745730170742;6408.03879418091 05:28:34 INFO - PID 1640 | |1;ext.html;670.5071772247401;819.3895547816876;840.3277378097522;1268.56868664654;1283.9293838838867;36612;38092.619073809255;38152;38158.42762358567;38179.281932313075;38253.234935301276;38604;38698.61301386985;38743.22513549734;38814.8059259704;7193.748218812342;7194.4963852530345;7197.5321604095825;8093.71671991479;8241.83516329676;8073.95963020187;8437;8870.822583548324;8995.145461181177;8997.505137217451;8871.512066836322;8953.23897468716;8954.910450895506;8971.282297416192;8978.865317020218;8903.10968903111;8908.910910890905;8947.239484643813;8956;8958.328125390623;8618.525981071038;8676.479411235347;8681.131886811332;8687.391882568223;8698;37654.24691506182;37757.81121094404;37763;37849;37871.431928521095;3650.1057240975865;3660.9450858237146;3674.779513229188;3690.3172913010912;3698.260347930425;30957.22606934822;31019.689803101905;31063;31069;31155.53266701001;2794.986025069882;3150.3384289299274;3188.4420226460506;3193.5369371441243;3202.903912882617;230575;232695.836520818;233623;254224;261150.69424652946;38437;47561.76219118951;47588.57234282977;47696.04607907822;47715.76142119267;39027;42288.78855605702;47391.46953548325;49753.50246497489;49766.751166243936;13920.443182272798;14048.508302209579;14071.155730656194;14081.014328997077;14082.788758168635;11364;11462.369569673749;11548.96059354662;11597.072234221241;11609.419529023515;13440.798388024188;13579.253141077335;13589;13596.660083497794;13660.043796934318;5879.470847623649;5904.261967254093;5944.7324870380935;5963.761449542057;5977.970110149508;935.5312159909014;970.7816690053937;1037.123630532199;1051.831706926888;1087.1031399095777;18047;21290.616109952804;21689;24029.158979435637;24170.516589668106;1121.9943900280446;1130.5421304371744;1152.8443660105945;1158.9826152607718;1170.1107158559555;11851.88148118525;12399.194052386567;12416.81374779379;12422.37888105591;12484.937575312066;335181;335804;336007;336102;336827;53058.46941530613;53224.73387633114;53623;53683.731581341846;53909.452676625304;1478.9942838869456;1524.26073354423;1531.5788158256346;1532.5862017255279;1533.670260893913;23694.289171324886;23737.81310934412;23784.643230351903;23873.04507819668;23927.76072239255;37308.88073357804;37346.626533734314;37531.06172345604;37544.43683344698;37557.06107347338;268297;269188;269207;269724;270565;30272;32456.864009759545;33836.492452612736;34241;34858;43196;43811;43994.900127497065;44014.89962750829;44086.55913440953;29131.523820475497;30828.537571936886;31159.88401159866;31202.375952481278;31287.74849006014;30603.081907542808;31017.224569385948;31128.22179445532;31144.126007223862;31686;36941.89174324775;37080.443793343664;37315.81342093272;37340.6265937348;37350.8132459336;30413;30915.381692366478;31130.066098017094;31617.20956976094;31924.6807531931;19804.702929456358;23416.648750268418;23734;23807.64288535708;23965.760342396352 05:28:34 INFO - PID 1640 | |2;jquery.html;425.76157351882927;438.4081489988516;440.75758332916905;451.36132372692634;452.3531350169259;19601.607967840635;21785.2375166869;22436.551268974632;22560;22847.885760571215;21205.469863253427;22622;23591.88204058977;23690.815459227055;23692;6130.203073600425;6166.722497487613;6362.713677884506;6381.361863813605;6381.744730210786;6113.205283313163;6258.624482531062;6271.78048768291;6296.464800491964;6314.873702525947;6177.66022868742;6182.536309776762;6243.094751261065;6252.218472690914;6274.309825919146;6157.261128664567;6252;6271.560990730651;6278.937210627903;6291.370862913718;5519.199716041152;5559.138333558305;5592.273004509388;5602.271704678406;5603.691796951166;26740.46519069608;26749.46501069987;26770.5984410234;26779.866100669566;27003.594946075824;2219.83351248657;2287.2680742162515;2288.6338185890345;2289.9084036638515;2291.46150654596;17172.39896603613;17465;17642.117894105373;17806.643867122584;17966.371177008874;2151.9354419367287;2184.3337781976493;2216.357256395643;2229.2754854672135;2229.554089182154;145592;146158;147619;149368;151115;11245.493952772145;12238.877611223774;12399.690007749698;12970.286634235019;12993.805092923614;16933.407330743365;16960.406385776438;16987.830121698873;16989.40537081196;17007.40474083426;8598.269147122439;8713.825723485621;8846.203841654282;8965.686200982935;8981.955090224506;6971.372576468142;8226.259636632733;8278.627461764236;8414.15858414166;8483.363747718946;6692.899606505906;7715;7981.767384209405;8006.199380062064;8052.758417247374;1851.8611104167242;1977.7132315814415;2133.5306232628795;2142.1110239250847;2164;989.2086330935223;994.5922171909482;994.8358520844124;1002.5037606384741;1028.8662473878348;16231;20290.985450727403;21169.576608467745;21212.25757098494;21336.74422787714;942.189716843514;1024.6260115058099;1028.2596530498029;1036.512838965685;1061.4745700878116;11016.779664406668;11208.719782005515;11209.103271738242;11254.381009044417;11284.548618055185;658669;660274;663049;688053;689264;146752.26623866812;152440.9936956626;153988;154409;155897.22051389897;974.9853752193723;1075.9031687148038;1228.422641358578;1240.7704574653537;1246.7693476706838;18120.003399813137;22871.771282286965;22892.656610150527;23031.424214394774;23344.29967100922;60752;61899.452513687516;62133;62841;62854.68572657107;85267.45761525481;86756;87549;87780;88095;8740.994785599738;8755.430896991675;8775.42959707617;8903;9081.45511269326;14278.357473913906;14307.070040447337;14437.989340746048;14644.487442939446;14692.853071469148;10792.866748991242;11380.658580242605;12100.152989290666;12160.695982600506;12350.876491235333;11303.208775385645;11370;11545.249558778652;11609.825852612048;11829.17195796286;12138.75722485563;12451.564195253122;12492.937535312629;12568.811467828173;12632.621021369006;7117.430605551647;8220.547869867214;8808.779780505538;9150.725478235663;9225.169734723908;11075.169362297698;11407.429628518375;12072.517099315928;12248.510059597516;12332.876671233173 05:28:34 INFO - PID 1640 | |3;mootools.html;1205.807070868661;1447.3559266126579;1458.9854101458989;1464.5752731707805;1480.082348943655;11282.435878206097;11624.883751162486;11759.529618815259;11790.410479476024;12041.337726425065;11673.883261167386;11771.058315334774;11893.167478276524;11897.286162830214;11898.04815614751;2520.080170737686;2544.821862469628;2551.9234422967343;2558.3859873630286;2559.3217797283733;2459.053264493165;2489.029278581355;2533.0627667762956;2533.3793220660964;2540.6915438549163;2445.315311712723;2492.987535062331;2508.9086247482314;2513.899444022237;2523.2934778262024;2061.917523299066;2410.8674022928644;2435.2450740270397;2468.3829042739317;2480.1567467061104;2365.6096744037213;2447.229122826304;2450.5956517174645;2458.545169143696;2472.134752836511;11809.350485723282;11837.526498940031;11888.940555297168;11889.405529723566;11890.702732431671;6552.7378904843745;6792.252852186256;6807.0810440590385;6826.93173068268;6830.863382732367;4044.2315959967514;4309.159713855799;4370.7159034662645;4372.191144638252;4375.16871794358;1039.3140527251978;1279.8600898286888;1283.1787655900232;1283.5122653391745;1310.6330227536305;15078.54764357071;15320.157391343582;16372.181390930526;16461.58846028859;16492;4784.952150478521;5500.669959802424;5634.183043458757;5799.333076696234;5821.854453638693;12241.510339586317;12297.44661490235;12305.569305074458;12322.952549033253;12333.876661233453;6598.505112116514;6653.500987425962;6659.4006539411685;6661.000849872558;6662.86674266512;6077.665728384981;6209.5032397409;6226.782062627786;6290.905636415458;6316.747330106744;4381.846635367747;4467.620252278528;4468.927457410198;4501.279795232747;4556.498785133633;1294.9158304710352;1322.9206247624982;1323.5235315286488;1336.4587342126454;1341.9127756695784;760.3536993555433;771.7993321736388;775.1705674927886;799.9080105787909;806.3347738116078;4688.671792974459;6447.659472708824;6725.8654826903185;6811.829704257334;6816.216135144522;955.3408148377597;961.6538046303325;963.5326866469757;963.942163470194;965.971020869375;3927.587603301612;4212.199682060399;4253.383259427365;4258.659307255412;4260.467441569804;14119.576412707635;14703.92648036753;14756.63108422298;14774.113553186844;14781.48264810748;14616.415343386145;14668.633284167769;14679.48621798232;14700.309464411515;14700.926495367668;10696.465176741132;11013.559457621606;11018.779624407467;11027.44862756859;11030.558777648965;4881.755912204305;5896.6756828374855;5987.850303742442;6873.553219040747;6989.615571143636;10647.733806654585;12424.440900159334;12905.612831615066;13062.673433164247;13307.667308316983;11603.593874214363;13659.863401366256;13673.384697688827;13768.449262029406;13905.513307034205;9446.622135114794;9643.180329671775;9728.075832795874;9730.41617502952;9745.80508389814;10530.684079477627;10626.73433164177;10750;10856.2400631955;10882.455877206266;7790.649420775965;9052.275817934682;9141.908580914105;9263.583138758637;9275.53622318868;9079.636814527345;9086.727398178065;9096.954515227646;9136.862947055666;9143.03998080192;7884.881726773988;8021.037475502977;8026.3980201483955;8026.959865200637;8034.47775894565;5701.942980570308;5920.111983202381;5948.791792287249;6125.173101631222;6125.448709616156;5924.229850119457;6025.367336429612;6065.4541091302;6158;6250.593711408744 05:28:34 INFO - PID 1640 | |4;prototype.html;395.2687528073065;408.31607058177553;414.8713898691406;416.72079706596577;425.9488861336638;5781.72801983564;6149.631022138675;6256.09286653435;6278.215223097113;6282.717277722502;5189.844304670866;5239.969629156015;5736.340320863102;5816.825495235139;5840.8318336332695;3804.372278574032;4361.32089145678;4623.56076172763;4643.233866412038;4646.4888862225125;3645.817709114547;3801.33476641588;3963.98018009911;4664.667666166916;4683.648726345537;4475.798589063484;4514.706544074625;4562.954370456303;4606.470255920579;4631.143238500871;4546.363509108711;4557.270836666151;4576.313552967082;4591.334256532784;4601.401817763703;3337.7329813615097;4061.472008638858;4062.1266427717887;4071.470708807866;4078.9796051019557;5431.9185212221455;5800.114962637118;5880.882382352329;5900.881982360372;5902.409759024106;4488.281874900001;4784.569388755029;5337.572994160458;5523.944760552383;5530.861728456781;6279.116265120446;6530.738770449177;6557.672116394209;6565.212174539086;6566.474682025474;4582.473015603215;4633.675642705012;4644.930326045112;4661.347411362412;4670.579647831678;5672.744726487318;5852.151438041503;6602.339766023408;6898.551594146365;6932.6533673316135;4250.213710463574;4406.735595864258;4469.072757343945;4471;4472.373867658531;3928.5482169550296;4288.134661422291;4536.750478723636;4549.5905368517;4565.543445655474;4835.879103022453;4875.536824001714;5020.272060551272;5140.971805638887;5353.9196912046355;4707.646926480527;5706.543476521952;6050.6974651267265;6584.234621458999;6586.967065164644;1528.6102043978938;1540.1375229871308;1547.5530379095583;1549.43445642342;1549.930253138589;965.666844938495;1029.7837454134715;1033.5297439664935;1037.6368271105052;1040.427764729396;5513.531349835309;5736.770529178786;6204.7828326008375;6287.371262873676;6293.433590976834;6075.149479072933;6812.420944219698;6819.24988251292;6834;6838.3073140503575;5647.830565083054;5952.898713737973;6152.3540028297275;6200.782972596027;6249.968755155349;2791.8743656535503;2958.3787404645263;3450.5686789151355;3477.3914564951033;3517.080822916973;5875.236219291465;6770.322967703289;6905.75829845963;6918;6961.477889158334;5590.329160500684;6011.827693599794;6519.8696026079215;6590.637514936724;6960.582365058112;6617.305182955816;6965.33829286217;6975.197852246955;6981.755638552728;6991.825204369931;6795.966020169867;6808.761693340709;6815.591064536144;6829.6585170742255;7111.7866464006165;5515.338159420735;5710.914336285042;6141.570090093651;6551.672416379257;6694.765683201064;5807.82576522705;5949.672767997802;6693.364130407604;6952.687129079305;7016.158061032709;4453;4464.330350447459;4466.620337271368;4478.664100192564;4486.416765820553;5549.944500554942;5842.090811512478;6767.308172956761;6835.93164068353;6859.9314006859295;5708.457696518824;6293.811185664437;6561.671916404256;6591.472682185317;6607.57050791697;5380.3274590676165;5875.735591898289;6168.523050796556;6573.112629794917;6608.107905432705;5656.010198215378;6368.681565921778;6393.4885209184085;6397.801099450275;6425;6369.617822930639;6538.411542961157;6576.638284894377;6576.901346479903;6578.638174900427;5907.2278316505735;6314.116023756769;6384.553081284265;6432.13166222554;6434.678266086583 05:28:34 INFO - PID 1640 | |5;yui.html;835.5822088955522;854.0562678240544;857.2199298638238;874.5408660453265;885.075096524132;13859.584212473617;14391.92804035981;16294.18529073546;16568.917155414205;16596.25316860741;12816.423260953256;13232.073754837165;17180.570485737833;17191.484255472355;17249.396271130514;973.2797729680021;1006.2352612014867;1012.1497941728946;1053.6838948315512;1056.7041228456005;1017.2116609627561;1035.1511760356511;1037.2842738510403;1038.1124138861278;1040.4641609571077;1024.5543188712934;1059.9665326306829;1077.348204336377;1078.4661592511718;1091.8471414002047;993.1508560181013;1010.2322235101302;1057.27576610022;1067.4502631144967;1071.196602548089;770.4953255617579;794.2732399854126;796.9920300796975;797.079373323812;797.8364435290752;16612.25244863984;16917.83082169175;17062.06158661273;17159.485215443558;17428.564285892826;16593.751093733605;16718.08050557219;16798.41205557812;16921.98468091906;17066.65866682672;13617.931910340483;13681.931590342085;13790.65523361914;13814.723705525934;13932.164070155823;3519.4017017107153;3523.3305671922253;3551.3252482028333;3551.6093229744706;3553.0406790166767;106337.87324253471;106949.46525267401;109210;111338;120800.39599802032;13468.259245741383;13697.315134243447;13791.931040344934;13808;13808.447662093404;24772.628410573856;25372.49255014926;25417.110401135873;25428.110016149345;25818.612720809204;9035.728928132166;9070.81858362829;9150.771230719285;9165.633374665073;9167.22078623311;4723.385959825201;4737.170995075848;4776.94907120433;4787.42550893888;4825.445073816486;4250.829966801355;4337.175936572041;4343.109662519176;4353.891152721208;4369.279068953587;955.9713208603753;957.7892863570004;959.6641175588628;963.6530848894392;970.2674480766975;582.260529128005;655.2661019657918;659.5251418978328;671.4359937652298;683.9760608378683;10671.893281067245;11066.170037247239;11263.493142808593;11539.596114135966;11950;626.9749210031548;655.7770358078276;661.3320546248227;670.51722759613;680.7141000779684;5434.3207099112615;5441.945580544223;5578.30271216098;5587.497125258747;5680.091185410397;83907.16092839115;103314;104545;104979.95020049854;107238;92220;92331;93272.53363733138;94205;95953.08093838223;79263;79807.60096199482;79990.20009799827;80225;80713.59643201863;10898.673039808818;11011.174161937726;11412.48643811014;12162.59383485055;12385.380730963596;35251.298613645195;35476;36360.72769448468;38958.6104138955;40907.59092409038;52329.47670523246;68111;68842.31157688359;69080;69620.65189674222;15892.841071589137;16413;17065.658686825947;17082.487525374258;17164.485065448058;15276.54170374846;17183.14084295805;18102.5474363142;18569;18903.810961890205;13528.255945922676;13718.862811371759;13762.793558096437;13767;13908.791368129283;16692.332306707594;16850.494485165465;17255.65488690194;17474.12629368552;17496.91251543734;13903.887688984654;15022;15411.6147096319;16199.866009379703;16750.581235468722;11899.167058305835;11927.343996080299;12177.634670959886;12180.390980451119;12306.846301896952;12936.870631293566;13102.868971310165;13408.731825363244;13584.25286609246;13660.112092713942 05:28:34 INFO - PID 1640 | __end_tp_report 05:28:34 INFO - PID 1640 | __start_cc_report 05:28:34 INFO - PID 1640 | _x_x_mozilla_cycle_collect,418 05:28:34 INFO - PID 1640 | __end_cc_report 05:28:34 INFO - PID 1640 | __startTimestamp1491827314761__endTimestamp 05:28:34 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | ------- Summary: start ------- 05:28:34 INFO - PID 1640 | Number of tests: 6 05:28:34 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6108.80 Median:6393.68 stddev:752.49 (11.8%) stddev-sans-first:710.37 05:28:34 INFO - PID 1640 | Values: 2861.5 3556.9 3569.2 3605.6 3613.9 5398.8 6140.7 6261.2 6268.4 6303.6 5243.7 6130.1 6183.6 6241.6 6323.5 5434.5 5457.8 5506.2 5570.1 5601.4 5409.3 5515.9 5597.9 5613.4 5623.3 5342.9 5383.4 5536.3 5538.1 5603.3 5443.7 5462.5 5498.1 5528.4 5572.5 5323.9 5379.2 5392.9 5393.4 5397.3 5982.5 6119.1 6192.5 6239.1 6259.2 6441.3 6573.8 6645.7 6669.7 6694.8 6796.7 6806.6 6806.6 6812.8 6822.7 6349.9 6393.7 6410.4 6427.8 6431.5 6638.2 6727.0 6911.4 6930.4 6977.9 5970.7 6363.3 6548.4 6552.5 6585.9 5486.7 5693.4 6518.8 6637.4 6659.3 6644.4 6646.8 6657.1 6666.0 6683.6 6391.3 6437.6 6696.3 6703.3 6708.4 4581.4 5319.1 5327.9 5377.9 5389.8 4358.9 4427.6 4672.9 4707.8 4735.8 6263.0 6375.4 6404.8 6408.1 6442.8 5557.7 5712.5 6407.4 6586.7 6762.4 6346.9 6431.6 6441.7 6456.3 6456.5 5985.4 6001.8 6012.0 6022.1 6023.2 6576.6 6718.6 6849.8 6884.7 7008.2 6768.9 6769.3 6770.2 6794.8 6795.7 5598.8 6204.9 6930.1 6944.9 6948.5 6332.1 6423.4 6616.2 6619.6 6634.0 6455.0 6730.8 6778.3 6787.8 6794.5 6340.3 6524.4 6529.8 6532.7 6534.6 6330.2 6496.4 6522.8 6564.8 6566.9 4609.0 6106.7 6401.9 6402.0 6411.0 6519.2 6543.6 6550.8 6559.8 6569.9 6190.9 6194.7 6310.8 6356.7 6408.0 05:28:34 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:43415.41 Median:336464.50 stddev:74801.40 (22.2%) stddev-sans-first:74942.39 05:28:34 INFO - PID 1640 | Values: 670.5 819.4 840.3 1268.6 1283.9 36612.0 38092.6 38152.0 38158.4 38179.3 38253.2 38604.0 38698.6 38743.2 38814.8 7193.7 7194.5 7197.5 8093.7 8241.8 8074.0 8437.0 8870.8 8995.1 8997.5 8871.5 8953.2 8954.9 8971.3 8978.9 8903.1 8908.9 8947.2 8956.0 8958.3 8618.5 8676.5 8681.1 8687.4 8698.0 37654.2 37757.8 37763.0 37849.0 37871.4 3650.1 3660.9 3674.8 3690.3 3698.3 30957.2 31019.7 31063.0 31069.0 31155.5 2795.0 3150.3 3188.4 3193.5 3202.9 230575.0 232695.8 233623.0 254224.0 261150.7 38437.0 47561.8 47588.6 47696.0 47715.8 39027.0 42288.8 47391.5 49753.5 49766.8 13920.4 14048.5 14071.2 14081.0 14082.8 11364.0 11462.4 11549.0 11597.1 11609.4 13440.8 13579.3 13589.0 13596.7 13660.0 5879.5 5904.3 5944.7 5963.8 5978.0 935.5 970.8 1037.1 1051.8 1087.1 18047.0 21290.6 21689.0 24029.2 24170.5 1122.0 1130.5 1152.8 1159.0 1170.1 11851.9 12399.2 12416.8 12422.4 12484.9 335181.0 335804.0 336007.0 336102.0 336827.0 53058.5 53224.7 53623.0 53683.7 53909.5 1479.0 1524.3 1531.6 1532.6 1533.7 23694.3 23737.8 23784.6 23873.0 23927.8 37308.9 37346.6 37531.1 37544.4 37557.1 268297.0 269188.0 269207.0 269724.0 270565.0 30272.0 32456.9 33836.5 34241.0 34858.0 43196.0 43811.0 43994.9 44014.9 44086.6 29131.5 30828.5 31159.9 31202.4 31287.7 30603.1 31017.2 31128.2 31144.1 31686.0 36941.9 37080.4 37315.8 37340.6 37350.8 30413.0 30915.4 31130.1 31617.2 31924.7 19804.7 23416.6 23734.0 23807.6 23965.8 05:28:34 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:39952.84 Median:12580.20 stddev:112814.01 (896.8%) stddev-sans-first:113091.44 05:28:34 INFO - PID 1640 | Values: 425.8 438.4 440.8 451.4 452.4 19601.6 21785.2 22436.6 22560.0 22847.9 21205.5 22622.0 23591.9 23690.8 23692.0 6130.2 6166.7 6362.7 6381.4 6381.7 6113.2 6258.6 6271.8 6296.5 6314.9 6177.7 6182.5 6243.1 6252.2 6274.3 6157.3 6252.0 6271.6 6278.9 6291.4 5519.2 5559.1 5592.3 5602.3 5603.7 26740.5 26749.5 26770.6 26779.9 27003.6 2219.8 2287.3 2288.6 2289.9 2291.5 17172.4 17465.0 17642.1 17806.6 17966.4 2151.9 2184.3 2216.4 2229.3 2229.6 145592.0 146158.0 147619.0 149368.0 151115.0 11245.5 12238.9 12399.7 12970.3 12993.8 16933.4 16960.4 16987.8 16989.4 17007.4 8598.3 8713.8 8846.2 8965.7 8982.0 6971.4 8226.3 8278.6 8414.2 8483.4 6692.9 7715.0 7981.8 8006.2 8052.8 1851.9 1977.7 2133.5 2142.1 2164.0 989.2 994.6 994.8 1002.5 1028.9 16231.0 20291.0 21169.6 21212.3 21336.7 942.2 1024.6 1028.3 1036.5 1061.5 11016.8 11208.7 11209.1 11254.4 11284.5 658669.0 660274.0 663049.0 688053.0 689264.0 146752.3 152441.0 153988.0 154409.0 155897.2 975.0 1075.9 1228.4 1240.8 1246.8 18120.0 22871.8 22892.7 23031.4 23344.3 60752.0 61899.5 62133.0 62841.0 62854.7 85267.5 86756.0 87549.0 87780.0 88095.0 8741.0 8755.4 8775.4 8903.0 9081.5 14278.4 14307.1 14438.0 14644.5 14692.9 10792.9 11380.7 12100.2 12160.7 12350.9 11303.2 11370.0 11545.2 11609.8 11829.2 12138.8 12451.6 12492.9 12568.8 12632.6 7117.4 8220.5 8808.8 9150.7 9225.2 11075.2 11407.4 12072.5 12248.5 12332.9 05:28:34 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:7131.54 Median:2542.76 stddev:4469.91 (175.8%) stddev-sans-first:4460.26 05:28:34 INFO - PID 1640 | Values: 1205.8 1447.4 1459.0 1464.6 1480.1 11282.4 11624.9 11759.5 11790.4 12041.3 11673.9 11771.1 11893.2 11897.3 11898.0 2520.1 2544.8 2551.9 2558.4 2559.3 2459.1 2489.0 2533.1 2533.4 2540.7 2445.3 2493.0 2508.9 2513.9 2523.3 2061.9 2410.9 2435.2 2468.4 2480.2 2365.6 2447.2 2450.6 2458.5 2472.1 11809.4 11837.5 11888.9 11889.4 11890.7 6552.7 6792.3 6807.1 6826.9 6830.9 4044.2 4309.2 4370.7 4372.2 4375.2 1039.3 1279.9 1283.2 1283.5 1310.6 15078.5 15320.2 16372.2 16461.6 16492.0 4785.0 5500.7 5634.2 5799.3 5821.9 12241.5 12297.4 12305.6 12323.0 12333.9 6598.5 6653.5 6659.4 6661.0 6662.9 6077.7 6209.5 6226.8 6290.9 6316.7 4381.8 4467.6 4468.9 4501.3 4556.5 1294.9 1322.9 1323.5 1336.5 1341.9 760.4 771.8 775.2 799.9 806.3 4688.7 6447.7 6725.9 6811.8 6816.2 955.3 961.7 963.5 963.9 966.0 3927.6 4212.2 4253.4 4258.7 4260.5 14119.6 14703.9 14756.6 14774.1 14781.5 14616.4 14668.6 14679.5 14700.3 14700.9 10696.5 11013.6 11018.8 11027.4 11030.6 4881.8 5896.7 5987.9 6873.6 6989.6 10647.7 12424.4 12905.6 13062.7 13307.7 11603.6 13659.9 13673.4 13768.4 13905.5 9446.6 9643.2 9728.1 9730.4 9745.8 10530.7 10626.7 10750.0 10856.2 10882.5 7790.6 9052.3 9141.9 9263.6 9275.5 9079.6 9086.7 9097.0 9136.9 9143.0 7884.9 8021.0 8026.4 8027.0 8034.5 5701.9 5920.1 5948.8 6125.2 6125.4 5924.2 6025.4 6065.5 6158.0 6250.6 05:28:34 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5229.86 Median:5759.25 stddev:1632.13 (28.3%) stddev-sans-first:1595.86 05:28:34 INFO - PID 1640 | Values: 395.3 408.3 414.9 416.7 425.9 5781.7 6149.6 6256.1 6278.2 6282.7 5189.8 5240.0 5736.3 5816.8 5840.8 3804.4 4361.3 4623.6 4643.2 4646.5 3645.8 3801.3 3964.0 4664.7 4683.6 4475.8 4514.7 4563.0 4606.5 4631.1 4546.4 4557.3 4576.3 4591.3 4601.4 3337.7 4061.5 4062.1 4071.5 4079.0 5431.9 5800.1 5880.9 5900.9 5902.4 4488.3 4784.6 5337.6 5523.9 5530.9 6279.1 6530.7 6557.7 6565.2 6566.5 4582.5 4633.7 4644.9 4661.3 4670.6 5672.7 5852.2 6602.3 6898.6 6932.7 4250.2 4406.7 4469.1 4471.0 4472.4 3928.5 4288.1 4536.8 4549.6 4565.5 4835.9 4875.5 5020.3 5141.0 5353.9 4707.6 5706.5 6050.7 6584.2 6587.0 1528.6 1540.1 1547.6 1549.4 1549.9 965.7 1029.8 1033.5 1037.6 1040.4 5513.5 5736.8 6204.8 6287.4 6293.4 6075.1 6812.4 6819.2 6834.0 6838.3 5647.8 5952.9 6152.4 6200.8 6250.0 2791.9 2958.4 3450.6 3477.4 3517.1 5875.2 6770.3 6905.8 6918.0 6961.5 5590.3 6011.8 6519.9 6590.6 6960.6 6617.3 6965.3 6975.2 6981.8 6991.8 6796.0 6808.8 6815.6 6829.7 7111.8 5515.3 5710.9 6141.6 6551.7 6694.8 5807.8 5949.7 6693.4 6952.7 7016.2 4453.0 4464.3 4466.6 4478.7 4486.4 5549.9 5842.1 6767.3 6835.9 6859.9 5708.5 6293.8 6561.7 6591.5 6607.6 5380.3 5875.7 6168.5 6573.1 6608.1 5656.0 6368.7 6393.5 6397.8 6425.0 6369.6 6538.4 6576.6 6576.9 6578.6 5907.2 6314.1 6384.6 6432.1 6434.7 05:28:34 INFO - PID 1640 | 05:28:34 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:21401.65 Median:17172.53 stddev:29496.65 (171.8%) stddev-sans-first:29538.97 05:28:34 INFO - PID 1640 | Values: 835.6 854.1 857.2 874.5 885.1 13859.6 14391.9 16294.2 16568.9 16596.3 12816.4 13232.1 17180.6 17191.5 17249.4 973.3 1006.2 1012.1 1053.7 1056.7 1017.2 1035.2 1037.3 1038.1 1040.5 1024.6 1060.0 1077.3 1078.5 1091.8 993.2 1010.2 1057.3 1067.5 1071.2 770.5 794.3 797.0 797.1 797.8 16612.3 16917.8 17062.1 17159.5 17428.6 16593.8 16718.1 16798.4 16922.0 17066.7 13617.9 13681.9 13790.7 13814.7 13932.2 3519.4 3523.3 3551.3 3551.6 3553.0 106337.9 106949.5 109210.0 111338.0 120800.4 13468.3 13697.3 13791.9 13808.0 13808.4 24772.6 25372.5 25417.1 25428.1 25818.6 9035.7 9070.8 9150.8 9165.6 9167.2 4723.4 4737.2 4776.9 4787.4 4825.4 4250.8 4337.2 4343.1 4353.9 4369.3 956.0 957.8 959.7 963.7 970.3 582.3 655.3 659.5 671.4 684.0 10671.9 11066.2 11263.5 11539.6 11950.0 627.0 655.8 661.3 670.5 680.7 5434.3 5441.9 5578.3 5587.5 5680.1 83907.2 103314.0 104545.0 104980.0 107238.0 92220.0 92331.0 93272.5 94205.0 95953.1 79263.0 79807.6 79990.2 80225.0 80713.6 10898.7 11011.2 11412.5 12162.6 12385.4 35251.3 35476.0 36360.7 38958.6 40907.6 52329.5 68111.0 68842.3 69080.0 69620.7 15892.8 16413.0 17065.7 17082.5 17164.5 15276.5 17183.1 18102.5 18569.0 18903.8 13528.3 13718.9 13762.8 13767.0 13908.8 16692.3 16850.5 17255.7 17474.1 17496.9 13903.9 15022.0 15411.6 16199.9 16750.6 11899.2 11927.3 12177.6 12180.4 12306.8 12936.9 13102.9 13408.7 13584.3 13660.1 05:28:34 INFO - PID 1640 | -------- Summary: end -------- 05:28:34 INFO - PID 1640 | 05:28:35 INFO - TEST-INFO | 1640: exit 0 05:28:35 INFO - TEST-OK | dromaeo_css | took 1115454ms 05:28:35 INFO - TEST-START | kraken 05:28:35 INFO - operating with platform_type : mac_ 05:28:35 INFO - Initialising browser for kraken test... 05:28:35 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpPn20iM/profile http://localhost:49241/getInfo.html) 05:28:38 INFO - PID 1652 | 2017-04-10 05:28:38.559 plugin-container[1654:10435] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 05:28:38 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:38 INFO - PID 1652 | 2017-04-10 05:28:38.560 plugin-container[1654:10435] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 05:28:38 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:38 INFO - PID 1652 | 2017-04-10 05:28:38.560 plugin-container[1654:10435] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:28:38 INFO - PID 1652 | __metrics Screen width/height:1600/1200 05:28:38 INFO - PID 1652 | colorDepth:24 05:28:38 INFO - PID 1652 | Browser inner width/height: 1024/689 05:28:38 INFO - PID 1652 | __metrics 05:28:38 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 05:28:39 INFO - TEST-INFO | 1652: exit 0 05:28:39 INFO - Browser initialized. 05:28:39 INFO - Running cycle 1/1 for kraken test... 05:28:39 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpPn20iM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:42 INFO - PID 1655 | 2017-04-10 05:28:42.219 plugin-container[1656:10569] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 05:28:42 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:42 INFO - PID 1655 | 2017-04-10 05:28:42.219 plugin-container[1656:10569] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 05:28:42 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:42 INFO - PID 1655 | 2017-04-10 05:28:42.219 plugin-container[1656:10569] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:28:51 INFO - PID 1655 | RSS: Main: 234098688 05:28:51 INFO - PID 1655 | 05:28:54 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:55 INFO - PID 1655 | RSS: Main: 248688640 05:28:55 INFO - PID 1655 | 05:28:58 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 05:28:59 INFO - PID 1655 | RSS: Main: 257142784 05:28:59 INFO - PID 1655 | 05:29:02 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 05:29:03 INFO - PID 1655 | RSS: Main: 259424256 05:29:03 INFO - PID 1655 | 05:29:05 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 05:29:06 INFO - PID 1655 | RSS: Main: 261271552 05:29:06 INFO - PID 1655 | 05:29:08 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:08 INFO - PID 1655 | RSS: Main: 262180864 05:29:08 INFO - PID 1655 | 05:29:11 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:12 INFO - PID 1655 | RSS: Main: 267526144 05:29:12 INFO - PID 1655 | 05:29:15 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:16 INFO - PID 1655 | RSS: Main: 268328960 05:29:16 INFO - PID 1655 | 05:29:19 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 05:29:19 INFO - PID 1655 | RSS: Main: 267976704 05:29:19 INFO - PID 1655 | 05:29:21 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:22 INFO - PID 1655 | RSS: Main: 267784192 05:29:22 INFO - PID 1655 | 05:29:24 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:24 INFO - PID 1655 | RSS: Main: 267784192 05:29:24 INFO - PID 1655 | 05:29:26 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:27 INFO - PID 1655 | RSS: Main: 267821056 05:29:27 INFO - PID 1655 | 05:29:30 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:31 INFO - PID 1655 | RSS: Main: 268152832 05:29:31 INFO - PID 1655 | 05:29:35 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:35 INFO - PID 1655 | RSS: Main: 265404416 05:29:35 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 05:29:37 INFO - PID 1655 | RSS: Main: 268800000 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | __start_tp_report 05:29:37 INFO - PID 1655 | _x_x_mozilla_page_load 05:29:37 INFO - PID 1655 | _x_x_mozilla_page_load_details 05:29:37 INFO - PID 1655 | |i|pagename|runs| 05:29:37 INFO - PID 1655 | |0;ai-astar;147;114;183;184;155;121;145;121;123;122 05:29:37 INFO - PID 1655 | |1;audio-beat-detection;133;114;113;152;118;116;111;116;141;116 05:29:37 INFO - PID 1655 | |2;audio-dft;219;223;231;220;205;260;309;251;214;215 05:29:37 INFO - PID 1655 | |3;audio-fft;80;51;83;107;90;79;60;57;73;59 05:29:37 INFO - PID 1655 | |4;audio-oscillator;84;89;72;66;66;92;124;109;91;86 05:29:37 INFO - PID 1655 | |5;imaging-gaussian-blur;92;91;94;94;123;91;92;90;90;98 05:29:37 INFO - PID 1655 | |6;imaging-darkroom;98;100;100;98;100;99;101;98;99;99 05:29:37 INFO - PID 1655 | |7;imaging-desaturate;98;101;103;163;102;103;101;101;105;102 05:29:37 INFO - PID 1655 | |8;json-parse-financial;50;50;53;54;75;83;49;50;71;80 05:29:37 INFO - PID 1655 | |9;json-stringify-tinderbox;59;64;67;61;82;92;92;90;90;86 05:29:37 INFO - PID 1655 | |10;stanford-crypto-aes;88;88;86;85;85;87;89;87;87;100 05:29:37 INFO - PID 1655 | |11;stanford-crypto-ccm;150;155;147;146;161;185;178;179;223;183 05:29:37 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;261;206;269;204;198;220;197;199;195;201 05:29:37 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;62;108;94;74;71;71;70;77;70;74 05:29:37 INFO - PID 1655 | __end_tp_report 05:29:37 INFO - PID 1655 | __start_cc_report 05:29:37 INFO - PID 1655 | _x_x_mozilla_cycle_collect,991 05:29:37 INFO - PID 1655 | __end_cc_report 05:29:37 INFO - PID 1655 | __startTimestamp1491827377702__endTimestamp 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | ------- Summary: start ------- 05:29:37 INFO - PID 1655 | Number of tests: 14 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:141.50 Median:146.00 stddev:25.98 (17.8%) stddev-sans-first:27.47 05:29:37 INFO - PID 1655 | Values: 147.0 114.0 183.0 184.0 155.0 121.0 145.0 121.0 123.0 122.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:117.00 stddev:13.99 (12.0%) stddev-sans-first:14.37 05:29:37 INFO - PID 1655 | Values: 133.0 114.0 113.0 152.0 118.0 116.0 111.0 116.0 141.0 116.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:234.70 Median:227.00 stddev:31.16 (13.7%) stddev-sans-first:32.53 05:29:37 INFO - PID 1655 | Values: 219.0 223.0 231.0 220.0 205.0 260.0 309.0 251.0 214.0 215.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:73.90 Median:76.00 stddev:17.41 (22.9%) stddev-sans-first:18.32 05:29:37 INFO - PID 1655 | Values: 80.0 51.0 83.0 107.0 90.0 79.0 60.0 57.0 73.0 59.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:85.00 stddev:18.28 (21.5%) stddev-sans-first:19.33 05:29:37 INFO - PID 1655 | Values: 84.0 89.0 72.0 66.0 66.0 92.0 124.0 109.0 91.0 86.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:92.00 stddev:9.96 (10.8%) stddev-sans-first:10.48 05:29:37 INFO - PID 1655 | Values: 92.0 91.0 94.0 94.0 123.0 91.0 92.0 90.0 90.0 98.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:99.20 Median:98.00 stddev:1.03 (1.1%) stddev-sans-first:1.00 05:29:37 INFO - PID 1655 | Values: 98.0 100.0 100.0 98.0 100.0 99.0 101.0 98.0 99.0 99.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:107.90 Median:103.00 stddev:19.44 (18.9%) stddev-sans-first:20.29 05:29:37 INFO - PID 1655 | Values: 98.0 101.0 103.0 163.0 102.0 103.0 101.0 101.0 105.0 102.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:62.50 stddev:13.98 (22.4%) stddev-sans-first:14.19 05:29:37 INFO - PID 1655 | Values: 50.0 50.0 53.0 54.0 75.0 83.0 49.0 50.0 71.0 80.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:78.30 Median:88.00 stddev:13.85 (15.7%) stddev-sans-first:12.81 05:29:37 INFO - PID 1655 | Values: 59.0 64.0 67.0 61.0 82.0 92.0 92.0 90.0 90.0 86.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:87.00 stddev:4.34 (5.0%) stddev-sans-first:4.60 05:29:37 INFO - PID 1655 | Values: 88.0 88.0 86.0 85.0 85.0 87.0 89.0 87.0 87.0 100.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:170.70 Median:178.50 stddev:23.93 (13.4%) stddev-sans-first:24.18 05:29:37 INFO - PID 1655 | Values: 150.0 155.0 147.0 146.0 161.0 185.0 178.0 179.0 223.0 183.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.00 Median:205.00 stddev:27.33 (13.3%) stddev-sans-first:23.38 05:29:37 INFO - PID 1655 | Values: 261.0 206.0 269.0 204.0 198.0 220.0 197.0 199.0 195.0 201.0 05:29:37 INFO - PID 1655 | 05:29:37 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.10 Median:72.50 stddev:13.59 (18.7%) stddev-sans-first:13.27 05:29:37 INFO - PID 1655 | Values: 62.0 108.0 94.0 74.0 71.0 71.0 70.0 77.0 70.0 74.0 05:29:37 INFO - PID 1655 | -------- Summary: end -------- 05:29:37 INFO - PID 1655 | 05:29:38 INFO - TEST-INFO | 1655: exit 0 05:29:38 INFO - TEST-OK | kraken | took 63589ms 05:29:38 INFO - SUITE-END | took 1179s 05:29:39 INFO - Completed test suite (00:19:39) 05:29:39 INFO - javascript benchmark 05:29:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7892.383778384272, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2861.470627933834, 3556.9110772230706, 3569.1790888095734, 3605.567331920169, 3613.94579081314, 5398.838034858951, 6140.692965351725, 6261.217347831521, 6268.435840774329, 6303.590266632677, 5243.711595862235, 6130.122981477971, 6183.59806612571, 6241.625502469855, 6323.525735569828, 5434.456554344553, 5457.808976685827, 5506.201600767887, 5570.053090974544, 5601.411851755568, 5409.26974858395, 5515.862103447405, 5597.8600534986745, 5613.410591887854, 5623.26897503326, 5342.893142137174, 5383.380911195223, 5536.307961504812, 5538.086215774392, 5603.271574695284, 5443.673379597237, 5462.453754624546, 5498.120300751901, 5528.44715528444, 5572.4706152915405, 5323.946760532383, 5379.220013098118, 5392.865178370533, 5393.433689462589, 5397.271368365299, 5982.46157845796, 6119.112728654365, 6192.535559832985, 6239.095331176999, 6259.155014073133, 6441.291457939623, 6573.769918052845, 6645.734170633169, 6669.666516674147, 6694.83262918426, 6796.66016699168, 6806.55757375769, 6806.55757375769, 6812.829679258009, 6822.727090916407, 6349.8730025400155, 6393.680315984228, 6410.391012853818, 6427.775027874002, 6431.453326467303, 6638.203415590064, 6727.0, 6911.412529935012, 6930.410915072275, 6977.89533157003, 5970.746143309867, 6363.300036995926, 6548.410643042053, 6552.50856185788, 6585.934140658628, 5486.698231597221, 5693.430656934356, 6518.804435866931, 6637.435817955431, 6659.334066593302, 6644.435223006002, 6646.767363142363, 6657.101291325609, 6666.0, 6683.598984060972, 6391.328910464427, 6437.581557198767, 6696.2968888267005, 6703.3296670332575, 6708.42978346846, 4581.358609794632, 5319.122344813062, 5327.866803329948, 5377.892442151135, 5389.757460914268, 4358.869233922987, 4427.623651989553, 4672.883177920579, 4707.81168753246, 4735.8342458014495, 6262.968685156545, 6375.394337537927, 6404.775832845921, 6408.10286559882, 6442.8389290268115, 5557.722113894289, 5712.542996560265, 6407.437025189868, 6586.73653053882, 6762.357576030269, 6346.936530634727, 6431.5819471734185, 6441.67791610427, 6456.322086180978, 6456.548041637135, 5985.40145985398, 6001.7599296027665, 6012.008018676869, 6022.126791615279, 6023.216981792339, 6576.605403675795, 6718.630475323904, 6849.794506164823, 6884.690188941609, 7008.229094799568, 6768.932310676831, 6769.289224631343, 6770.187577490733, 6794.76218332356, 6795.660216989229, 5598.804041858515, 6204.883121038257, 6930.099087118642, 6944.93055069463, 6948.478864085315, 6332.145160403286, 6423.42189202974, 6616.206055273398, 6619.635920024445, 6634.0, 6454.967725161344, 6730.798076057726, 6778.288279730557, 6787.796366109024, 6794.52438329332, 6340.30256671775, 6524.412802847766, 6529.836754080996, 6532.673366331569, 6534.575252608565, 6330.208723909512, 6496.415322620798, 6522.771702990372, 6564.770233041821, 6566.9014964774615, 4608.976955115203, 6106.72519736604, 6401.93598064032, 6401.967990160019, 6410.967945160244, 6519.185101862267, 6543.607383557001, 6550.7707230246715, 6559.770408035696, 6569.868602628017, 6190.907136392868, 6194.690265486798, 6310.7791227306825, 6356.745730170742, 6408.03879418091], "value": 6058.887893943037, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.5071772247401, 819.3895547816876, 840.3277378097522, 1268.56868664654, 1283.9293838838867, 36612.0, 38092.619073809255, 38152.0, 38158.42762358567, 38179.281932313075, 38253.234935301276, 38604.0, 38698.61301386985, 38743.22513549734, 38814.8059259704, 7193.748218812342, 7194.4963852530345, 7197.5321604095825, 8093.71671991479, 8241.83516329676, 8073.95963020187, 8437.0, 8870.822583548324, 8995.145461181177, 8997.505137217451, 8871.512066836322, 8953.23897468716, 8954.910450895506, 8971.282297416192, 8978.865317020218, 8903.10968903111, 8908.910910890905, 8947.239484643813, 8956.0, 8958.328125390623, 8618.525981071038, 8676.479411235347, 8681.131886811332, 8687.391882568223, 8698.0, 37654.24691506182, 37757.81121094404, 37763.0, 37849.0, 37871.431928521095, 3650.1057240975865, 3660.9450858237146, 3674.779513229188, 3690.3172913010912, 3698.260347930425, 30957.22606934822, 31019.689803101905, 31063.0, 31069.0, 31155.53266701001, 2794.986025069882, 3150.3384289299274, 3188.4420226460506, 3193.5369371441243, 3202.903912882617, 230575.0, 232695.836520818, 233623.0, 254224.0, 261150.69424652946, 38437.0, 47561.76219118951, 47588.57234282977, 47696.04607907822, 47715.76142119267, 39027.0, 42288.78855605702, 47391.46953548325, 49753.50246497489, 49766.751166243936, 13920.443182272798, 14048.508302209579, 14071.155730656194, 14081.014328997077, 14082.788758168635, 11364.0, 11462.369569673749, 11548.96059354662, 11597.072234221241, 11609.419529023515, 13440.798388024188, 13579.253141077335, 13589.0, 13596.660083497794, 13660.043796934318, 5879.470847623649, 5904.261967254093, 5944.7324870380935, 5963.761449542057, 5977.970110149508, 935.5312159909014, 970.7816690053937, 1037.123630532199, 1051.831706926888, 1087.1031399095777, 18047.0, 21290.616109952804, 21689.0, 24029.158979435637, 24170.516589668106, 1121.9943900280446, 1130.5421304371744, 1152.8443660105945, 1158.9826152607718, 1170.1107158559555, 11851.88148118525, 12399.194052386567, 12416.81374779379, 12422.37888105591, 12484.937575312066, 335181.0, 335804.0, 336007.0, 336102.0, 336827.0, 53058.46941530613, 53224.73387633114, 53623.0, 53683.731581341846, 53909.452676625304, 1478.9942838869456, 1524.26073354423, 1531.5788158256346, 1532.5862017255279, 1533.670260893913, 23694.289171324886, 23737.81310934412, 23784.643230351903, 23873.04507819668, 23927.76072239255, 37308.88073357804, 37346.626533734314, 37531.06172345604, 37544.43683344698, 37557.06107347338, 268297.0, 269188.0, 269207.0, 269724.0, 270565.0, 30272.0, 32456.864009759545, 33836.492452612736, 34241.0, 34858.0, 43196.0, 43811.0, 43994.900127497065, 44014.89962750829, 44086.55913440953, 29131.523820475497, 30828.537571936886, 31159.88401159866, 31202.375952481278, 31287.74849006014, 30603.081907542808, 31017.224569385948, 31128.22179445532, 31144.126007223862, 31686.0, 36941.89174324775, 37080.443793343664, 37315.81342093272, 37340.6265937348, 37350.8132459336, 30413.0, 30915.381692366478, 31130.066098017094, 31617.20956976094, 31924.6807531931, 19804.702929456358, 23416.648750268418, 23734.0, 23807.64288535708, 23965.760342396352], "value": 17381.24306521714, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.76157351882927, 438.4081489988516, 440.75758332916905, 451.36132372692634, 452.3531350169259, 19601.607967840635, 21785.2375166869, 22436.551268974632, 22560.0, 22847.885760571215, 21205.469863253427, 22622.0, 23591.88204058977, 23690.815459227055, 23692.0, 6130.203073600425, 6166.722497487613, 6362.713677884506, 6381.361863813605, 6381.744730210786, 6113.205283313163, 6258.624482531062, 6271.78048768291, 6296.464800491964, 6314.873702525947, 6177.66022868742, 6182.536309776762, 6243.094751261065, 6252.218472690914, 6274.309825919146, 6157.261128664567, 6252.0, 6271.560990730651, 6278.937210627903, 6291.370862913718, 5519.199716041152, 5559.138333558305, 5592.273004509388, 5602.271704678406, 5603.691796951166, 26740.46519069608, 26749.46501069987, 26770.5984410234, 26779.866100669566, 27003.594946075824, 2219.83351248657, 2287.2680742162515, 2288.6338185890345, 2289.9084036638515, 2291.46150654596, 17172.39896603613, 17465.0, 17642.117894105373, 17806.643867122584, 17966.371177008874, 2151.9354419367287, 2184.3337781976493, 2216.357256395643, 2229.2754854672135, 2229.554089182154, 145592.0, 146158.0, 147619.0, 149368.0, 151115.0, 11245.493952772145, 12238.877611223774, 12399.690007749698, 12970.286634235019, 12993.805092923614, 16933.407330743365, 16960.406385776438, 16987.830121698873, 16989.40537081196, 17007.40474083426, 8598.269147122439, 8713.825723485621, 8846.203841654282, 8965.686200982935, 8981.955090224506, 6971.372576468142, 8226.259636632733, 8278.627461764236, 8414.15858414166, 8483.363747718946, 6692.899606505906, 7715.0, 7981.767384209405, 8006.199380062064, 8052.758417247374, 1851.8611104167242, 1977.7132315814415, 2133.5306232628795, 2142.1110239250847, 2164.0, 989.2086330935223, 994.5922171909482, 994.8358520844124, 1002.5037606384741, 1028.8662473878348, 16231.0, 20290.985450727403, 21169.576608467745, 21212.25757098494, 21336.74422787714, 942.189716843514, 1024.6260115058099, 1028.2596530498029, 1036.512838965685, 1061.4745700878116, 11016.779664406668, 11208.719782005515, 11209.103271738242, 11254.381009044417, 11284.548618055185, 658669.0, 660274.0, 663049.0, 688053.0, 689264.0, 146752.26623866812, 152440.9936956626, 153988.0, 154409.0, 155897.22051389897, 974.9853752193723, 1075.9031687148038, 1228.422641358578, 1240.7704574653537, 1246.7693476706838, 18120.003399813137, 22871.771282286965, 22892.656610150527, 23031.424214394774, 23344.29967100922, 60752.0, 61899.452513687516, 62133.0, 62841.0, 62854.68572657107, 85267.45761525481, 86756.0, 87549.0, 87780.0, 88095.0, 8740.994785599738, 8755.430896991675, 8775.42959707617, 8903.0, 9081.45511269326, 14278.357473913906, 14307.070040447337, 14437.989340746048, 14644.487442939446, 14692.853071469148, 10792.866748991242, 11380.658580242605, 12100.152989290666, 12160.695982600506, 12350.876491235333, 11303.208775385645, 11370.0, 11545.249558778652, 11609.825852612048, 11829.17195796286, 12138.75722485563, 12451.564195253122, 12492.937535312629, 12568.811467828173, 12632.621021369006, 7117.430605551647, 8220.547869867214, 8808.779780505538, 9150.725478235663, 9225.169734723908, 11075.169362297698, 11407.429628518375, 12072.517099315928, 12248.510059597516, 12332.876671233173], "value": 10689.97661954679, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1205.807070868661, 1447.3559266126579, 1458.9854101458989, 1464.5752731707805, 1480.082348943655, 11282.435878206097, 11624.883751162486, 11759.529618815259, 11790.410479476024, 12041.337726425065, 11673.883261167386, 11771.058315334774, 11893.167478276524, 11897.286162830214, 11898.04815614751, 2520.080170737686, 2544.821862469628, 2551.9234422967343, 2558.3859873630286, 2559.3217797283733, 2459.053264493165, 2489.029278581355, 2533.0627667762956, 2533.3793220660964, 2540.6915438549163, 2445.315311712723, 2492.987535062331, 2508.9086247482314, 2513.899444022237, 2523.2934778262024, 2061.917523299066, 2410.8674022928644, 2435.2450740270397, 2468.3829042739317, 2480.1567467061104, 2365.6096744037213, 2447.229122826304, 2450.5956517174645, 2458.545169143696, 2472.134752836511, 11809.350485723282, 11837.526498940031, 11888.940555297168, 11889.405529723566, 11890.702732431671, 6552.7378904843745, 6792.252852186256, 6807.0810440590385, 6826.93173068268, 6830.863382732367, 4044.2315959967514, 4309.159713855799, 4370.7159034662645, 4372.191144638252, 4375.16871794358, 1039.3140527251978, 1279.8600898286888, 1283.1787655900232, 1283.5122653391745, 1310.6330227536305, 15078.54764357071, 15320.157391343582, 16372.181390930526, 16461.58846028859, 16492.0, 4784.952150478521, 5500.669959802424, 5634.183043458757, 5799.333076696234, 5821.854453638693, 12241.510339586317, 12297.44661490235, 12305.569305074458, 12322.952549033253, 12333.876661233453, 6598.505112116514, 6653.500987425962, 6659.4006539411685, 6661.000849872558, 6662.86674266512, 6077.665728384981, 6209.5032397409, 6226.782062627786, 6290.905636415458, 6316.747330106744, 4381.846635367747, 4467.620252278528, 4468.927457410198, 4501.279795232747, 4556.498785133633, 1294.9158304710352, 1322.9206247624982, 1323.5235315286488, 1336.4587342126454, 1341.9127756695784, 760.3536993555433, 771.7993321736388, 775.1705674927886, 799.9080105787909, 806.3347738116078, 4688.671792974459, 6447.659472708824, 6725.8654826903185, 6811.829704257334, 6816.216135144522, 955.3408148377597, 961.6538046303325, 963.5326866469757, 963.942163470194, 965.971020869375, 3927.587603301612, 4212.199682060399, 4253.383259427365, 4258.659307255412, 4260.467441569804, 14119.576412707635, 14703.92648036753, 14756.63108422298, 14774.113553186844, 14781.48264810748, 14616.415343386145, 14668.633284167769, 14679.48621798232, 14700.309464411515, 14700.926495367668, 10696.465176741132, 11013.559457621606, 11018.779624407467, 11027.44862756859, 11030.558777648965, 4881.755912204305, 5896.6756828374855, 5987.850303742442, 6873.553219040747, 6989.615571143636, 10647.733806654585, 12424.440900159334, 12905.612831615066, 13062.673433164247, 13307.667308316983, 11603.593874214363, 13659.863401366256, 13673.384697688827, 13768.449262029406, 13905.513307034205, 9446.622135114794, 9643.180329671775, 9728.075832795874, 9730.41617502952, 9745.80508389814, 10530.684079477627, 10626.73433164177, 10750.0, 10856.2400631955, 10882.455877206266, 7790.649420775965, 9052.275817934682, 9141.908580914105, 9263.583138758637, 9275.53622318868, 9079.636814527345, 9086.727398178065, 9096.954515227646, 9136.862947055666, 9143.03998080192, 7884.881726773988, 8021.037475502977, 8026.3980201483955, 8026.959865200637, 8034.47775894565, 5701.942980570308, 5920.111983202381, 5948.791792287249, 6125.173101631222, 6125.448709616156, 5924.229850119457, 6025.367336429612, 6065.4541091302, 6158.0, 6250.593711408744], "value": 5402.930367201185, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.2687528073065, 408.31607058177553, 414.8713898691406, 416.72079706596577, 425.9488861336638, 5781.72801983564, 6149.631022138675, 6256.09286653435, 6278.215223097113, 6282.717277722502, 5189.844304670866, 5239.969629156015, 5736.340320863102, 5816.825495235139, 5840.8318336332695, 3804.372278574032, 4361.32089145678, 4623.56076172763, 4643.233866412038, 4646.4888862225125, 3645.817709114547, 3801.33476641588, 3963.98018009911, 4664.667666166916, 4683.648726345537, 4475.798589063484, 4514.706544074625, 4562.954370456303, 4606.470255920579, 4631.143238500871, 4546.363509108711, 4557.270836666151, 4576.313552967082, 4591.334256532784, 4601.401817763703, 3337.7329813615097, 4061.472008638858, 4062.1266427717887, 4071.470708807866, 4078.9796051019557, 5431.9185212221455, 5800.114962637118, 5880.882382352329, 5900.881982360372, 5902.409759024106, 4488.281874900001, 4784.569388755029, 5337.572994160458, 5523.944760552383, 5530.861728456781, 6279.116265120446, 6530.738770449177, 6557.672116394209, 6565.212174539086, 6566.474682025474, 4582.473015603215, 4633.675642705012, 4644.930326045112, 4661.347411362412, 4670.579647831678, 5672.744726487318, 5852.151438041503, 6602.339766023408, 6898.551594146365, 6932.6533673316135, 4250.213710463574, 4406.735595864258, 4469.072757343945, 4471.0, 4472.373867658531, 3928.5482169550296, 4288.134661422291, 4536.750478723636, 4549.5905368517, 4565.543445655474, 4835.879103022453, 4875.536824001714, 5020.272060551272, 5140.971805638887, 5353.9196912046355, 4707.646926480527, 5706.543476521952, 6050.6974651267265, 6584.234621458999, 6586.967065164644, 1528.6102043978938, 1540.1375229871308, 1547.5530379095583, 1549.43445642342, 1549.930253138589, 965.666844938495, 1029.7837454134715, 1033.5297439664935, 1037.6368271105052, 1040.427764729396, 5513.531349835309, 5736.770529178786, 6204.7828326008375, 6287.371262873676, 6293.433590976834, 6075.149479072933, 6812.420944219698, 6819.24988251292, 6834.0, 6838.3073140503575, 5647.830565083054, 5952.898713737973, 6152.3540028297275, 6200.782972596027, 6249.968755155349, 2791.8743656535503, 2958.3787404645263, 3450.5686789151355, 3477.3914564951033, 3517.080822916973, 5875.236219291465, 6770.322967703289, 6905.75829845963, 6918.0, 6961.477889158334, 5590.329160500684, 6011.827693599794, 6519.8696026079215, 6590.637514936724, 6960.582365058112, 6617.305182955816, 6965.33829286217, 6975.197852246955, 6981.755638552728, 6991.825204369931, 6795.966020169867, 6808.761693340709, 6815.591064536144, 6829.6585170742255, 7111.7866464006165, 5515.338159420735, 5710.914336285042, 6141.570090093651, 6551.672416379257, 6694.765683201064, 5807.82576522705, 5949.672767997802, 6693.364130407604, 6952.687129079305, 7016.158061032709, 4453.0, 4464.330350447459, 4466.620337271368, 4478.664100192564, 4486.416765820553, 5549.944500554942, 5842.090811512478, 6767.308172956761, 6835.93164068353, 6859.9314006859295, 5708.457696518824, 6293.811185664437, 6561.671916404256, 6591.472682185317, 6607.57050791697, 5380.3274590676165, 5875.735591898289, 6168.523050796556, 6573.112629794917, 6608.107905432705, 5656.010198215378, 6368.681565921778, 6393.4885209184085, 6397.801099450275, 6425.0, 6369.617822930639, 6538.411542961157, 6576.638284894377, 6576.901346479903, 6578.638174900427, 5907.2278316505735, 6314.116023756769, 6384.553081284265, 6432.13166222554, 6434.678266086583], "value": 4729.533089827629, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [835.5822088955522, 854.0562678240544, 857.2199298638238, 874.5408660453265, 885.075096524132, 13859.584212473617, 14391.92804035981, 16294.18529073546, 16568.917155414205, 16596.25316860741, 12816.423260953256, 13232.073754837165, 17180.570485737833, 17191.484255472355, 17249.396271130514, 973.2797729680021, 1006.2352612014867, 1012.1497941728946, 1053.6838948315512, 1056.7041228456005, 1017.2116609627561, 1035.1511760356511, 1037.2842738510403, 1038.1124138861278, 1040.4641609571077, 1024.5543188712934, 1059.9665326306829, 1077.348204336377, 1078.4661592511718, 1091.8471414002047, 993.1508560181013, 1010.2322235101302, 1057.27576610022, 1067.4502631144967, 1071.196602548089, 770.4953255617579, 794.2732399854126, 796.9920300796975, 797.079373323812, 797.8364435290752, 16612.25244863984, 16917.83082169175, 17062.06158661273, 17159.485215443558, 17428.564285892826, 16593.751093733605, 16718.08050557219, 16798.41205557812, 16921.98468091906, 17066.65866682672, 13617.931910340483, 13681.931590342085, 13790.65523361914, 13814.723705525934, 13932.164070155823, 3519.4017017107153, 3523.3305671922253, 3551.3252482028333, 3551.6093229744706, 3553.0406790166767, 106337.87324253471, 106949.46525267401, 109210.0, 111338.0, 120800.39599802032, 13468.259245741383, 13697.315134243447, 13791.931040344934, 13808.0, 13808.447662093404, 24772.628410573856, 25372.49255014926, 25417.110401135873, 25428.110016149345, 25818.612720809204, 9035.728928132166, 9070.81858362829, 9150.771230719285, 9165.633374665073, 9167.22078623311, 4723.385959825201, 4737.170995075848, 4776.94907120433, 4787.42550893888, 4825.445073816486, 4250.829966801355, 4337.175936572041, 4343.109662519176, 4353.891152721208, 4369.279068953587, 955.9713208603753, 957.7892863570004, 959.6641175588628, 963.6530848894392, 970.2674480766975, 582.260529128005, 655.2661019657918, 659.5251418978328, 671.4359937652298, 683.9760608378683, 10671.893281067245, 11066.170037247239, 11263.493142808593, 11539.596114135966, 11950.0, 626.9749210031548, 655.7770358078276, 661.3320546248227, 670.51722759613, 680.7141000779684, 5434.3207099112615, 5441.945580544223, 5578.30271216098, 5587.497125258747, 5680.091185410397, 83907.16092839115, 103314.0, 104545.0, 104979.95020049854, 107238.0, 92220.0, 92331.0, 93272.53363733138, 94205.0, 95953.08093838223, 79263.0, 79807.60096199482, 79990.20009799827, 80225.0, 80713.59643201863, 10898.673039808818, 11011.174161937726, 11412.48643811014, 12162.59383485055, 12385.380730963596, 35251.298613645195, 35476.0, 36360.72769448468, 38958.6104138955, 40907.59092409038, 52329.47670523246, 68111.0, 68842.31157688359, 69080.0, 69620.65189674222, 15892.841071589137, 16413.0, 17065.658686825947, 17082.487525374258, 17164.485065448058, 15276.54170374846, 17183.14084295805, 18102.5474363142, 18569.0, 18903.810961890205, 13528.255945922676, 13718.862811371759, 13762.793558096437, 13767.0, 13908.791368129283, 16692.332306707594, 16850.494485165465, 17255.65488690194, 17474.12629368552, 17496.91251543734, 13903.887688984654, 15022.0, 15411.6147096319, 16199.866009379703, 16750.581235468722, 11899.167058305835, 11927.343996080299, 12177.634670959886, 12180.390980451119, 12306.846301896952, 12936.870631293566, 13102.868971310165, 13408.731825363244, 13584.25286609246, 13660.112092713942], "value": 8400.787551658643, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1654.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 183.0, 184.0, 155.0, 121.0, 145.0, 121.0, 123.0, 122.0], "value": 141.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 114.0, 113.0, 152.0, 118.0, 116.0, 111.0, 116.0, 141.0, 116.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 223.0, 231.0, 220.0, 205.0, 260.0, 309.0, 251.0, 214.0, 215.0], "value": 234.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 51.0, 83.0, 107.0, 90.0, 79.0, 60.0, 57.0, 73.0, 59.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 72.0, 66.0, 66.0, 92.0, 124.0, 109.0, 91.0, 86.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 94.0, 94.0, 123.0, 91.0, 92.0, 90.0, 90.0, 98.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 100.0, 98.0, 100.0, 99.0, 101.0, 98.0, 99.0, 99.0], "value": 99.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 101.0, 103.0, 163.0, 102.0, 103.0, 101.0, 101.0, 105.0, 102.0], "value": 107.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 53.0, 54.0, 75.0, 83.0, 49.0, 50.0, 71.0, 80.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 67.0, 61.0, 82.0, 92.0, 92.0, 90.0, 90.0, 86.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 86.0, 85.0, 85.0, 87.0, 89.0, 87.0, 87.0, 100.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 155.0, 147.0, 146.0, 161.0, 185.0, 178.0, 179.0, 223.0, 183.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [261.0, 206.0, 269.0, 204.0, 198.0, 220.0, 197.0, 199.0, 195.0, 201.0], "value": 215.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 108.0, 94.0, 74.0, 71.0, 71.0, 70.0, 77.0, 70.0, 74.0], "value": 77.1, "unit": "score"}]}]} 05:29:39 INFO - Return code: 0 05:29:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:39 ERROR - # TBPL SUCCESS # 05:29:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:39 INFO - 2017-04-10 05:29:39.523 screenresolution[1660:10965] starting screenresolution argv=screenresolution get 05:29:39 INFO - 2017-04-10 05:29:39.526 screenresolution[1660:10965] Display 0: 1600x1200x32@60 05:29:39 INFO - 2017-04-10 05:29:39.532 screenresolution[1661:10968] starting screenresolution argv=screenresolution list 05:29:39 INFO - Available Modes on Display 0 05:29:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:29:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:29:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:29:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:29:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:29:39 INFO - Intel Iris: 05:29:39 INFO - Chipset Model: Intel Iris 05:29:39 INFO - Type: GPU 05:29:39 INFO - Bus: Built-In 05:29:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:39 INFO - Vendor: Intel (0x8086) 05:29:39 INFO - Device ID: 0x0a2e 05:29:39 INFO - Revision ID: 0x0009 05:29:39 INFO - Displays: 05:29:39 INFO - Display: 05:29:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:29:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:39 INFO - Display Serial Number: 1600x1200 60 05:29:39 INFO - Main Display: Yes 05:29:39 INFO - Mirror: Off 05:29:39 INFO - Online: Yes 05:29:39 INFO - Rotation: Supported 05:29:39 INFO - Return code: 0 05:29:39 INFO - Running post-action listener: _resource_record_post_action 05:29:39 INFO - [mozharness: 2017-04-10 12:29:39.769087Z] Finished run-tests step (success) 05:29:39 INFO - Running post-run listener: _resource_record_post_run 05:29:39 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 40007680; Write bytes: 523892224; Read time: 258; Write time: 1722 05:29:39 INFO - TinderboxPrint: CPU usage
54.5% 05:29:39 INFO - TinderboxPrint: I/O read bytes / time
40,007,680 / 258 05:29:39 INFO - TinderboxPrint: I/O write bytes / time
523,892,224 / 1,722 05:29:39 INFO - TinderboxPrint: CPU idle
2,183.7 (45.5%) 05:29:39 INFO - TinderboxPrint: CPU system
970.3 (20.2%) 05:29:39 INFO - TinderboxPrint: CPU user
1,643.7 (34.3%) 05:29:39 INFO - TinderboxPrint: Swap in / out
247,455,744 / 0 05:29:39 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 196344320; Write bytes: 191864832; Read time: 14914; Write time: 412 05:29:39 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 39041024; Write bytes: 315180544; Read time: 246; Write time: 1260 05:29:40 INFO - Running post-run listener: _upload_blobber_files 05:29:40 INFO - Blob upload gear active. 05:29:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:40 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:29:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:29:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:41 INFO - (blobuploader) - INFO - Done attempting. 05:29:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:29:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:29:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:42 INFO - (blobuploader) - INFO - Done attempting. 05:29:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:29:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:29:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:43 INFO - (blobuploader) - INFO - Done attempting. 05:29:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:45 INFO - (blobuploader) - INFO - Done attempting. 05:29:45 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:45 INFO - Return code: 0 05:29:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25ebf604d876ca3436b474589d0a54b8cbaa42d45a0b73aed48903b2c5998d1a47e1b6070fc1eceb0a955cb9cd2c9f0eff9a2c41dce082d37f57aa9af12377f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2ed7347f7a56428e84e397d508eacae6f1a26ddd6b7f71cec0751efeb1c57456579e000615f16347b0284bac3305e22d129090714d435ea65f501ae2cce25bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6fe2c8bf1f0eddd999f67bec3ffcaf7a452205f8e3c8b2bdec1ae89d8ad7fbb1a7d835c547bb7e88e7ca5cf0b62362648dcf90748bdd430b597e356f62cd6cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa29c2939d76b69eb65586191e3038a45587a0d0df35cd49e50024566f1a2bc762492022cf140cc523efeaad374fddad4e0404533e59e3dcfe4099a9147f12b"} 05:29:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:45 INFO - Contents: 05:29:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25ebf604d876ca3436b474589d0a54b8cbaa42d45a0b73aed48903b2c5998d1a47e1b6070fc1eceb0a955cb9cd2c9f0eff9a2c41dce082d37f57aa9af12377f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2ed7347f7a56428e84e397d508eacae6f1a26ddd6b7f71cec0751efeb1c57456579e000615f16347b0284bac3305e22d129090714d435ea65f501ae2cce25bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6fe2c8bf1f0eddd999f67bec3ffcaf7a452205f8e3c8b2bdec1ae89d8ad7fbb1a7d835c547bb7e88e7ca5cf0b62362648dcf90748bdd430b597e356f62cd6cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa29c2939d76b69eb65586191e3038a45587a0d0df35cd49e50024566f1a2bc762492022cf140cc523efeaad374fddad4e0404533e59e3dcfe4099a9147f12b"} 05:29:45 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:45 INFO - Copying logs to upload dir... 05:29:45 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.273351 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-10 05:29:45.173824) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 05:29:45.177041) ========= 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.zvn5iKdG4W/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.IJVagdc5u8/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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/25ebf604d876ca3436b474589d0a54b8cbaa42d45a0b73aed48903b2c5998d1a47e1b6070fc1eceb0a955cb9cd2c9f0eff9a2c41dce082d37f57aa9af12377f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2ed7347f7a56428e84e397d508eacae6f1a26ddd6b7f71cec0751efeb1c57456579e000615f16347b0284bac3305e22d129090714d435ea65f501ae2cce25bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6fe2c8bf1f0eddd999f67bec3ffcaf7a452205f8e3c8b2bdec1ae89d8ad7fbb1a7d835c547bb7e88e7ca5cf0b62362648dcf90748bdd430b597e356f62cd6cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa29c2939d76b69eb65586191e3038a45587a0d0df35cd49e50024566f1a2bc762492022cf140cc523efeaad374fddad4e0404533e59e3dcfe4099a9147f12b"} build_url:https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011006 build_url: 'https://queue.taskcluster.net/v1/task/bpfKz_AbSeS6zjkm3GuZ_g/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/25ebf604d876ca3436b474589d0a54b8cbaa42d45a0b73aed48903b2c5998d1a47e1b6070fc1eceb0a955cb9cd2c9f0eff9a2c41dce082d37f57aa9af12377f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2ed7347f7a56428e84e397d508eacae6f1a26ddd6b7f71cec0751efeb1c57456579e000615f16347b0284bac3305e22d129090714d435ea65f501ae2cce25bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6fe2c8bf1f0eddd999f67bec3ffcaf7a452205f8e3c8b2bdec1ae89d8ad7fbb1a7d835c547bb7e88e7ca5cf0b62362648dcf90748bdd430b597e356f62cd6cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa29c2939d76b69eb65586191e3038a45587a0d0df35cd49e50024566f1a2bc762492022cf140cc523efeaad374fddad4e0404533e59e3dcfe4099a9147f12b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 05:29:45.211027) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 05:29:45.211352) ========= 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.zvn5iKdG4W/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.IJVagdc5u8/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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.005140 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 05:29:45.260715) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 05:29:45.261046) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 05:29:45.261379) ========= ========= Total master_lag: 0.18 =========