builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0285 starttime: 1473423041.84 results: success (0) buildid: 20160909030428 builduid: 05e9f07cf13d417dac5bb0c863fbceff revision: feff79e5b1374439f17c5ea10a559acf1380a8d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:41.842824) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:41.843258) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:41.843627) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:41.869419) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:41.869795) ========= 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.RTPeEfogyk/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.RB2myVhg4u/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 --2016-09-09 05:10:41-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 78.8M=0s 2016-09-09 05:10:42 (78.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134635 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:42.027624) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:42.027935) ========= 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.RTPeEfogyk/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.RB2myVhg4u/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.029862 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:42.079554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:42.079913) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev feff79e5b1374439f17c5ea10a559acf1380a8d5 --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 feff79e5b1374439f17c5ea10a559acf1380a8d5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RTPeEfogyk/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.RB2myVhg4u/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 2016-09-09 05:10:42,160 truncating revision to first 12 chars 2016-09-09 05:10:42,160 Setting DEBUG logging. 2016-09-09 05:10:42,160 attempt 1/10 2016-09-09 05:10:42,160 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/feff79e5b137?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 05:10:42,818 unpacking tar archive at: mozilla-central-feff79e5b137/testing/mozharness/ program finished with exit code 0 elapsedTime=0.943217 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:43.045602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:43.045940) ========= script_repo_revision: feff79e5b1374439f17c5ea10a559acf1380a8d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:43.046333) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:43.046647) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 05:10:43.065141) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-09 05:10:43.065509) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RTPeEfogyk/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.RB2myVhg4u/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:10:43 INFO - MultiFileLogger online at 20160909 05:10:43 in /builds/slave/test 05:10:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:10:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:43 INFO - {'append_to_log': False, 05:10:43 INFO - 'base_work_dir': '/builds/slave/test', 05:10:43 INFO - 'blob_upload_branch': 'Firefox', 05:10:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:43 INFO - 'branch': 'Firefox', 05:10:43 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:43 INFO - 'config_files': ('talos/mac_config.py',), 05:10:43 INFO - 'default_actions': ('clobber', 05:10:43 INFO - 'read-buildbot-config', 05:10:43 INFO - 'download-and-extract', 05:10:43 INFO - 'populate-webroot', 05:10:43 INFO - 'create-virtualenv', 05:10:43 INFO - 'install', 05:10:43 INFO - 'run-tests'), 05:10:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:43 INFO - 'download_minidump_stackwalk': True, 05:10:43 INFO - 'download_symbols': 'ondemand', 05:10:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:43 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:43 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:43 INFO - 'installer_path': 'installer.exe', 05:10:43 INFO - 'log_level': 'info', 05:10:43 INFO - 'log_name': 'talos', 05:10:43 INFO - 'log_to_console': True, 05:10:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:10:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:10:43 INFO - 'opt_config_files': (), 05:10:43 INFO - 'pip_index': False, 05:10:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:43 INFO - 'cmd': ('bash', 05:10:43 INFO - '-c', 05:10:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:43 INFO - 'enabled': True, 05:10:43 INFO - 'halt_on_failure': False, 05:10:43 INFO - 'name': 'check_screen_resolution'},), 05:10:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:43 INFO - 'cmd': ('bash', 05:10:43 INFO - '-c', 05:10:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:43 INFO - 'enabled': True, 05:10:43 INFO - 'halt_on_failure': False, 05:10:43 INFO - 'name': 'check_screen_resolution'},), 05:10:43 INFO - 'run_cmd_checks_enabled': True, 05:10:43 INFO - 'sps_profile': False, 05:10:43 INFO - 'sps_profile_interval': 0, 05:10:43 INFO - 'suite': 'dromaeojs', 05:10:43 INFO - 'system_bits': '32', 05:10:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:43 INFO - 'title': 't-yosemite-r7-0285', 05:10:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:43 INFO - 'use_talos_json': True, 05:10:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:43 INFO - 'work_dir': 'build'} 05:10:43 INFO - [mozharness: 2016-09-09 12:10:43.256213Z] Running clobber step. 05:10:43 INFO - Running pre-action listener: _resource_record_pre_action 05:10:43 INFO - Running main action method: clobber 05:10:43 INFO - rmtree: /builds/slave/test/build 05:10:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:45 INFO - Running post-action listener: _resource_record_post_action 05:10:45 INFO - [mozharness: 2016-09-09 12:10:45.525376Z] Finished clobber step (success) 05:10:45 INFO - [mozharness: 2016-09-09 12:10:45.525529Z] Running read-buildbot-config step. 05:10:45 INFO - Running pre-action listener: _resource_record_pre_action 05:10:45 INFO - Running main action method: read_buildbot_config 05:10:45 INFO - Using buildbot properties: 05:10:45 INFO - { 05:10:45 INFO - "project": "", 05:10:45 INFO - "product": "firefox", 05:10:45 INFO - "script_repo_revision": "production", 05:10:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:10:45 INFO - "repository": "", 05:10:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:10:45 INFO - "buildid": "20160909030428", 05:10:45 INFO - "stage_platform": "macosx64", 05:10:45 INFO - "basedir": "/builds/slave/test", 05:10:45 INFO - "nightly_build": "True", 05:10:45 INFO - "buildnumber": 4, 05:10:45 INFO - "slavename": "t-yosemite-r7-0285", 05:10:45 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:10:45 INFO - "platform": "yosemite_r7", 05:10:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:10:45 INFO - "branch": "mozilla-central", 05:10:45 INFO - "repo_path": "mozilla-central", 05:10:45 INFO - "revision": "feff79e5b1374439f17c5ea10a559acf1380a8d5", 05:10:45 INFO - "pgo_build": "False", 05:10:45 INFO - "builduid": "05e9f07cf13d417dac5bb0c863fbceff", 05:10:45 INFO - "slavebuilddir": "test" 05:10:45 INFO - } 05:10:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:10:45 INFO - Running post-action listener: _resource_record_post_action 05:10:45 INFO - [mozharness: 2016-09-09 12:10:45.528980Z] Finished read-buildbot-config step (success) 05:10:45 INFO - [mozharness: 2016-09-09 12:10:45.529116Z] Running download-and-extract step. 05:10:45 INFO - Running pre-action listener: _resource_record_pre_action 05:10:45 INFO - Running main action method: download_and_extract 05:10:45 INFO - mkdir: /builds/slave/test/build/tests 05:10:45 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:10:45 INFO - trying https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:10:45 INFO - Downloading https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 05:10:46 INFO - Downloaded 1199 bytes. 05:10:46 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:10:46 INFO - Using the following test package requirements: 05:10:46 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:10:46 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:10:46 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:10:46 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:10:46 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:10:46 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:10:46 INFO - u'jsshell-mac.zip'], 05:10:46 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:10:46 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:10:46 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:10:46 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:10:46 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:10:46 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:10:46 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:10:46 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:10:46 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:10:46 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:10:46 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:10:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:10:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:10:47 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:10:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:10:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:10:54 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:11:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:11:02 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:11: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:11:03 INFO - trying https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:11:03 INFO - Downloading https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:11:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 05:11:06 INFO - Downloaded 111720860 bytes. 05:11:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:11:06 INFO - mkdir: /builds/slave/test/properties 05:11:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:11:06 INFO - Writing to file /builds/slave/test/properties/build_url 05:11:06 INFO - Contents: 05:11:06 INFO - build_url:https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:11:07 INFO - Running post-action listener: _resource_record_post_action 05:11:07 INFO - Running post-action listener: set_extra_try_arguments 05:11:07 INFO - [mozharness: 2016-09-09 12:11:07.311188Z] Finished download-and-extract step (success) 05:11:07 INFO - [mozharness: 2016-09-09 12:11:07.311314Z] Running populate-webroot step. 05:11:07 INFO - Running pre-action listener: _resource_record_pre_action 05:11:07 INFO - Running main action method: populate_webroot 05:11:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:11:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:11:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:11:07 INFO - '--tppagecycles', 05:11:07 INFO - '7'], 05:11:07 INFO - 'tests': ['tsvgm']}}, 05:11:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:11:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:11:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:11:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:11:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:07 INFO - 'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:07 INFO - 'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['damp', 'tps']}, 05:11:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:07 INFO - 'tests': ['damp', 'tps']}, 05:11:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['dromaeo_dom']}, 05:11:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:11:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['basic_compositor_video']}, 05:11:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:11:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['a11yr', 05:11:07 INFO - 'ts_paint', 05:11:07 INFO - 'tpaint', 05:11:07 INFO - 'sessionrestore', 05:11:07 INFO - 'sessionrestore_no_auto_restore', 05:11:07 INFO - 'tabpaint']}, 05:11:07 INFO - 'other-e10s': {'tests': ['a11yr', 05:11:07 INFO - 'ts_paint', 05:11:07 INFO - 'tpaint', 05:11:07 INFO - 'sessionrestore', 05:11:07 INFO - 'sessionrestore_no_auto_restore', 05:11:07 INFO - 'tabpaint']}, 05:11:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:11:07 INFO - 'ts_paint', 05:11:07 INFO - 'tpaint', 05:11:07 INFO - 'sessionrestore', 05:11:07 INFO - 'sessionrestore_no_auto_restore', 05:11:07 INFO - 'tabpaint']}, 05:11:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:11:07 INFO - 'ts_paint', 05:11:07 INFO - 'tpaint', 05:11:07 INFO - 'sessionrestore', 05:11:07 INFO - 'sessionrestore_no_auto_restore', 05:11:07 INFO - 'tabpaint']}, 05:11:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['a11yr', 05:11:07 INFO - 'ts_paint', 05:11:07 INFO - 'tpaint', 05:11:07 INFO - 'sessionrestore', 05:11:07 INFO - 'sessionrestore_no_auto_restore', 05:11:07 INFO - 'tabpaint']}, 05:11:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['a11yr', 05:11:07 INFO - 'ts_paint', 05:11:07 INFO - 'tpaint', 05:11:07 INFO - 'sessionrestore', 05:11:07 INFO - 'sessionrestore_no_auto_restore', 05:11:07 INFO - 'tabpaint']}, 05:11:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['tsvgx', 05:11:07 INFO - 'tsvgr_opacity', 05:11:07 INFO - 'tart', 05:11:07 INFO - 'tscrollx', 05:11:07 INFO - 'cart']}, 05:11:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:11:07 INFO - 'tsvgr_opacity', 05:11:07 INFO - 'tart', 05:11:07 INFO - 'tscrollx', 05:11:07 INFO - 'cart']}, 05:11:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:07 INFO - 'talos_options': ['--disable-e10s'], 05:11:07 INFO - 'tests': ['tp5o']}, 05:11:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:07 INFO - 'tests': ['tp5o']}, 05:11:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:07 INFO - 'talos_options': ['--disable-e10s', 05:11:07 INFO - '--xperf_path', 05:11:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:07 INFO - 'tests': ['tp5n']}, 05:11:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:07 INFO - 'talos_options': ['--xperf_path', 05:11:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:07 INFO - 'tests': ['tp5n']}}, 05:11:07 INFO - 'talos.zip': {'path': '', 05:11:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:11:07 INFO - Running post-action listener: _resource_record_post_action 05:11:07 INFO - [mozharness: 2016-09-09 12:11:07.330055Z] Finished populate-webroot step (success) 05:11:07 INFO - [mozharness: 2016-09-09 12:11:07.330187Z] Running create-virtualenv step. 05:11:07 INFO - Running pre-action listener: _resource_record_pre_action 05:11:07 INFO - Running main action method: create_virtualenv 05:11:07 INFO - Creating virtualenv /builds/slave/test/build/venv 05:11:07 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:11:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:11:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:11:07 INFO - Using real prefix '/tools/python27' 05:11:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:11:08 INFO - Installing distribute.............................................................................................................................................................................................done. 05:11:11 INFO - Installing pip.................done. 05:11:11 INFO - Return code: 0 05:11:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:11: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:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11: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', 'pip>=1.5'] in /builds/slave/test/build 05:11: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 pip>=1.5 05:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:11 INFO - 'HOME': '/Users/cltbld', 05:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:11 INFO - 'LOGNAME': 'cltbld', 05:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:11 INFO - 'MOZ_NO_REMOTE': '1', 05:11:11 INFO - 'NO_EM_RESTART': '1', 05:11:11 INFO - 'PAGER': '/bin/cat', 05:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:11 INFO - 'PWD': '/builds/slave/test', 05:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:11 INFO - 'SHELL': '/bin/bash', 05:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:11 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:11 INFO - 'USER': 'cltbld', 05:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:11 INFO - 'XPC_FLAGS': '0x0', 05:11:11 INFO - 'XPC_SERVICE_NAME': '0', 05:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11: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:11:11 INFO - Cleaning up... 05:11:11 INFO - Return code: 0 05:11:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:11: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:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11: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:11: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:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:11 INFO - 'HOME': '/Users/cltbld', 05:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:11 INFO - 'LOGNAME': 'cltbld', 05:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:11 INFO - 'MOZ_NO_REMOTE': '1', 05:11:11 INFO - 'NO_EM_RESTART': '1', 05:11:11 INFO - 'PAGER': '/bin/cat', 05:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:11 INFO - 'PWD': '/builds/slave/test', 05:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:11 INFO - 'SHELL': '/bin/bash', 05:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:11 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:11 INFO - 'USER': 'cltbld', 05:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:11 INFO - 'XPC_FLAGS': '0x0', 05:11:11 INFO - 'XPC_SERVICE_NAME': '0', 05:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:11 INFO - Downloading/unpacking psutil>=3.1.1 05:11: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:11: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:11: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:11: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:11: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:11: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:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:11:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:14 INFO - Installing collected packages: psutil 05:11:14 INFO - Running setup.py install for psutil 05:11:14 INFO - building 'psutil._psutil_osx' extension 05:11:14 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:11: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:11: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:11:15 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:11:15 INFO - building 'psutil._psutil_posix' extension 05:11:15 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:11:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:11:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:11:15 INFO - ^ 05:11:15 INFO - 1 warning generated. 05:11:15 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:11:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:15 INFO - Successfully installed psutil 05:11:15 INFO - Cleaning up... 05:11:15 INFO - Return code: 0 05:11:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:15 INFO - 'HOME': '/Users/cltbld', 05:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:15 INFO - 'LOGNAME': 'cltbld', 05:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:15 INFO - 'MOZ_NO_REMOTE': '1', 05:11:15 INFO - 'NO_EM_RESTART': '1', 05:11:15 INFO - 'PAGER': '/bin/cat', 05:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:15 INFO - 'PWD': '/builds/slave/test', 05:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:15 INFO - 'SHELL': '/bin/bash', 05:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:15 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:15 INFO - 'USER': 'cltbld', 05:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:15 INFO - 'XPC_FLAGS': '0x0', 05:11:15 INFO - 'XPC_SERVICE_NAME': '0', 05:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:11: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:11: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:11: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:11: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:11: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:11: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:11:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:11:18 INFO - Installing collected packages: mozsystemmonitor 05:11:18 INFO - Running setup.py install for mozsystemmonitor 05:11:18 INFO - Successfully installed mozsystemmonitor 05:11:18 INFO - Cleaning up... 05:11:18 INFO - Return code: 0 05:11:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:11:18 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:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:18 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:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:18 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 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11:18 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:11:18 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:11:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:18 INFO - 'HOME': '/Users/cltbld', 05:11:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:18 INFO - 'LOGNAME': 'cltbld', 05:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:18 INFO - 'MOZ_NO_REMOTE': '1', 05:11:18 INFO - 'NO_EM_RESTART': '1', 05:11:18 INFO - 'PAGER': '/bin/cat', 05:11:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:18 INFO - 'PWD': '/builds/slave/test', 05:11:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:18 INFO - 'SHELL': '/bin/bash', 05:11:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:18 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:18 INFO - 'USER': 'cltbld', 05:11:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:18 INFO - 'XPC_FLAGS': '0x0', 05:11:18 INFO - 'XPC_SERVICE_NAME': '0', 05:11:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:18 INFO - Downloading/unpacking jsonschema==2.5.1 05:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:11:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:11:21 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:11:21 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:11:21 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:11:21 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:11:21 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:11:21 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:11:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:11:21 INFO - warning: no files found matching '*.txt' 05:11:21 INFO - no previously-included directories found matching 'build' 05:11:21 INFO - no previously-included directories found matching 'dist' 05:11:21 INFO - no previously-included directories found matching '.git*' 05:11:21 INFO - Installing collected packages: jsonschema, functools32 05:11:22 INFO - Running setup.py install for functools32 05:11:22 INFO - warning: no files found matching '*.txt' 05:11:22 INFO - no previously-included directories found matching 'build' 05:11:22 INFO - no previously-included directories found matching 'dist' 05:11:22 INFO - no previously-included directories found matching '.git*' 05:11:22 INFO - Successfully installed jsonschema functools32 05:11:22 INFO - Cleaning up... 05:11:22 INFO - Return code: 0 05:11:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:11:22 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:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:22 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:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:22 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 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11:22 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:11:22 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:11:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:22 INFO - 'HOME': '/Users/cltbld', 05:11:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:22 INFO - 'LOGNAME': 'cltbld', 05:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:22 INFO - 'MOZ_NO_REMOTE': '1', 05:11:22 INFO - 'NO_EM_RESTART': '1', 05:11:22 INFO - 'PAGER': '/bin/cat', 05:11:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:22 INFO - 'PWD': '/builds/slave/test', 05:11:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:22 INFO - 'SHELL': '/bin/bash', 05:11:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:22 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:22 INFO - 'USER': 'cltbld', 05:11:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:22 INFO - 'XPC_FLAGS': '0x0', 05:11:22 INFO - 'XPC_SERVICE_NAME': '0', 05:11:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:11:22 INFO - Cleaning up... 05:11:22 INFO - Return code: 0 05:11:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:11:22 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:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:22 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:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:22 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 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11:22 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:11:22 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:11:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:22 INFO - 'HOME': '/Users/cltbld', 05:11:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:22 INFO - 'LOGNAME': 'cltbld', 05:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:22 INFO - 'MOZ_NO_REMOTE': '1', 05:11:22 INFO - 'NO_EM_RESTART': '1', 05:11:22 INFO - 'PAGER': '/bin/cat', 05:11:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:22 INFO - 'PWD': '/builds/slave/test', 05:11:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:22 INFO - 'SHELL': '/bin/bash', 05:11:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:22 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:22 INFO - 'USER': 'cltbld', 05:11:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:22 INFO - 'XPC_FLAGS': '0x0', 05:11:22 INFO - 'XPC_SERVICE_NAME': '0', 05:11:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:22 INFO - Downloading/unpacking blobuploader==1.2.4 05:11: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:11: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:11: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:11: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:11: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:11: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:11:25 INFO - Downloading blobuploader-1.2.4.tar.gz 05:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:11:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:11:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:11: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:11: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:11: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:11: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:11: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:11: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:11:26 INFO - Downloading docopt-0.6.1.tar.gz 05:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:11:26 INFO - Installing collected packages: blobuploader, requests, docopt 05:11:26 INFO - Running setup.py install for blobuploader 05:11:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:11:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:11:26 INFO - Running setup.py install for requests 05:11:27 INFO - Running setup.py install for docopt 05:11:27 INFO - Successfully installed blobuploader requests docopt 05:11:27 INFO - Cleaning up... 05:11:27 INFO - Return code: 0 05:11:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:27 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 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11:27 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:11:27 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:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:27 INFO - 'HOME': '/Users/cltbld', 05:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:27 INFO - 'LOGNAME': 'cltbld', 05:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:27 INFO - 'MOZ_NO_REMOTE': '1', 05:11:27 INFO - 'NO_EM_RESTART': '1', 05:11:27 INFO - 'PAGER': '/bin/cat', 05:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:27 INFO - 'PWD': '/builds/slave/test', 05:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:27 INFO - 'SHELL': '/bin/bash', 05:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:27 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:27 INFO - 'USER': 'cltbld', 05:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:27 INFO - 'XPC_FLAGS': '0x0', 05:11:27 INFO - 'XPC_SERVICE_NAME': '0', 05:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:27 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-gfyRpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:27 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-J6bggo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:27 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-CehxF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-VKgdqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Bp8Wks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-It11H4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-nxZPbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-SmajZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-FgIzl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-7HmjWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-nZklGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Me3OYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:28 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-MQS6q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:29 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-2ROF4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:29 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-XKkIpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:29 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Zdl3SP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:29 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-z2nUaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:11:29 INFO - Running setup.py install for manifestparser 05:11:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:11:29 INFO - Running setup.py install for mozcrash 05:11:29 INFO - Running setup.py install for mozdebug 05:11:29 INFO - Running setup.py install for mozdevice 05:11:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Running setup.py install for mozfile 05:11:30 INFO - Running setup.py install for mozhttpd 05:11:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Running setup.py install for mozinfo 05:11:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Running setup.py install for mozInstall 05:11:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Running setup.py install for mozleak 05:11:30 INFO - Running setup.py install for mozlog 05:11:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:11:30 INFO - Running setup.py install for moznetwork 05:11:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:11:31 INFO - Running setup.py install for mozprocess 05:11:31 INFO - Running setup.py install for mozprofile 05:11:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:11:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:11:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:11:31 INFO - Running setup.py install for mozrunner 05:11:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:11:31 INFO - Running setup.py install for mozscreenshot 05:11:31 INFO - Running setup.py install for moztest 05:11:31 INFO - Running setup.py install for mozversion 05:11:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:11:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:11:32 INFO - Cleaning up... 05:11:32 INFO - Return code: 0 05:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:32 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 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11:32 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:11:32 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:11:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:32 INFO - 'HOME': '/Users/cltbld', 05:11:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:32 INFO - 'LOGNAME': 'cltbld', 05:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:32 INFO - 'MOZ_NO_REMOTE': '1', 05:11:32 INFO - 'NO_EM_RESTART': '1', 05:11:32 INFO - 'PAGER': '/bin/cat', 05:11:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:32 INFO - 'PWD': '/builds/slave/test', 05:11:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:32 INFO - 'SHELL': '/bin/bash', 05:11:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:32 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:32 INFO - 'USER': 'cltbld', 05:11:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:32 INFO - 'XPC_FLAGS': '0x0', 05:11:32 INFO - 'XPC_SERVICE_NAME': '0', 05:11:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:32 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-sFRkbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:32 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:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:32 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-s7e0xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:32 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-IZ2i6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:32 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:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:32 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-7t_57y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:32 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-GYi9Lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:32 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:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:32 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-M9Ozzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:32 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:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:32 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-GiyemC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:33 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-1B78pP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:33 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Q7qJry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:33 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-qOh85x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-rOBoiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:33 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-dyZcsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-k24DXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:33 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-SVCryW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-avho9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:33 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:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:33 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-OmykQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:34 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-X48atp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:34 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:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:11:34 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:11:34 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:11:34 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:11:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:36 INFO - Downloading blessings-1.6.tar.gz 05:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:11:36 INFO - Installing collected packages: blessings 05:11:36 INFO - Running setup.py install for blessings 05:11:36 INFO - Successfully installed blessings 05:11:36 INFO - Cleaning up... 05:11:36 INFO - Return code: 0 05:11:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:37 INFO - Reading from file tmpfile_stdout 05:11:37 INFO - Current package versions: 05:11:37 INFO - blessings == 1.6 05:11:37 INFO - blobuploader == 1.2.4 05:11:37 INFO - docopt == 0.6.1 05:11:37 INFO - functools32 == 3.2.3-2 05:11:37 INFO - jsonschema == 2.5.1 05:11:37 INFO - manifestparser == 1.1 05:11:37 INFO - mozInstall == 1.12 05:11:37 INFO - mozcrash == 0.17 05:11:37 INFO - mozdebug == 0.1 05:11:37 INFO - mozdevice == 0.48 05:11:37 INFO - mozfile == 1.2 05:11:37 INFO - mozhttpd == 0.7 05:11:37 INFO - mozinfo == 0.9 05:11:37 INFO - mozleak == 0.1 05:11:37 INFO - mozlog == 3.3 05:11:37 INFO - moznetwork == 0.27 05:11:37 INFO - mozprocess == 0.23 05:11:37 INFO - mozprofile == 0.28 05:11:37 INFO - mozrunner == 6.12 05:11:37 INFO - mozscreenshot == 0.1 05:11:37 INFO - mozsystemmonitor == 0.3 05:11:37 INFO - moztest == 0.7 05:11:37 INFO - mozversion == 1.4 05:11:37 INFO - psutil == 3.1.1 05:11:37 INFO - requests == 1.2.3 05:11:37 INFO - wsgiref == 0.1.2 05:11:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:37 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 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11:37 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:11:37 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:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:37 INFO - 'HOME': '/Users/cltbld', 05:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:37 INFO - 'LOGNAME': 'cltbld', 05:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:37 INFO - 'MOZ_NO_REMOTE': '1', 05:11:37 INFO - 'NO_EM_RESTART': '1', 05:11:37 INFO - 'PAGER': '/bin/cat', 05:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:37 INFO - 'PWD': '/builds/slave/test', 05:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:37 INFO - 'SHELL': '/bin/bash', 05:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:37 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:37 INFO - 'USER': 'cltbld', 05:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:37 INFO - 'XPC_FLAGS': '0x0', 05:11:37 INFO - 'XPC_SERVICE_NAME': '0', 05:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:37 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:11:37 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:11:37 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:11:37 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:11:37 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:11:37 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:11:37 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:11:37 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:11:37 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:11:37 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:11:37 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:11:37 INFO - Cleaning up... 05:11:37 INFO - Return code: 0 05:11:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b704a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b799618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85394aa470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, '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.RTPeEfogyk/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.RB2myVhg4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:37 INFO - 'HOME': '/Users/cltbld', 05:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:37 INFO - 'LOGNAME': 'cltbld', 05:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:37 INFO - 'MOZ_NO_REMOTE': '1', 05:11:37 INFO - 'NO_EM_RESTART': '1', 05:11:37 INFO - 'PAGER': '/bin/cat', 05:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:37 INFO - 'PWD': '/builds/slave/test', 05:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:37 INFO - 'SHELL': '/bin/bash', 05:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:11:37 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:37 INFO - 'USER': 'cltbld', 05:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:37 INFO - 'XPC_FLAGS': '0x0', 05:11:37 INFO - 'XPC_SERVICE_NAME': '0', 05:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:11:37 INFO - Cleaning up... 05:11:37 INFO - Return code: 0 05:11:37 INFO - Running post-action listener: _resource_record_post_action 05:11:37 INFO - Running post-action listener: _start_resource_monitoring 05:11:37 INFO - Starting resource monitoring. 05:11:37 INFO - [mozharness: 2016-09-09 12:11:37.820035Z] Finished create-virtualenv step (success) 05:11:37 INFO - [mozharness: 2016-09-09 12:11:37.820997Z] Running install step. 05:11:37 INFO - Running pre-action listener: _resource_record_pre_action 05:11:37 INFO - Running main action method: install 05:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:38 INFO - Reading from file tmpfile_stdout 05:11:38 INFO - Detecting whether we're running mozinstall >=1.0... 05:11:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:11:38 INFO - Reading from file tmpfile_stdout 05:11:38 INFO - Output received: 05:11:38 INFO - Usage: mozinstall [options] installer 05:11:38 INFO - Options: 05:11:38 INFO - -h, --help show this help message and exit 05:11:38 INFO - -d DEST, --destination=DEST 05:11:38 INFO - Directory to install application into. [default: 05:11:38 INFO - "/builds/slave/test"] 05:11:38 INFO - --app=APP Application being installed. [default: firefox] 05:11:38 INFO - mkdir: /builds/slave/test/build/application 05:11:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:12:12 INFO - Reading from file tmpfile_stdout 05:12:12 INFO - Output received: 05:12:12 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:12:12 INFO - Running post-action listener: _resource_record_post_action 05:12:12 INFO - [mozharness: 2016-09-09 12:12:12.406142Z] Finished install step (success) 05:12:12 INFO - [mozharness: 2016-09-09 12:12:12.406280Z] Running run-tests step. 05:12:12 INFO - Running pre-action listener: _resource_record_pre_action 05:12:12 INFO - Running main action method: run_tests 05:12:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:12:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:12:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:12:12 INFO - 2016-09-09 05:12:12.418 screenresolution[1638:7362] starting screenresolution argv=screenresolution get 05:12:12 INFO - 2016-09-09 05:12:12.421 screenresolution[1638:7362] Display 0: 1600x1200x32@60 05:12:12 INFO - 2016-09-09 05:12:12.426 screenresolution[1639:7364] starting screenresolution argv=screenresolution list 05:12:12 INFO - Available Modes on Display 0 05:12:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:12:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:12:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:12:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:12:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:12:12 INFO - Intel Iris: 05:12:12 INFO - Chipset Model: Intel Iris 05:12:12 INFO - Type: GPU 05:12:12 INFO - Bus: Built-In 05:12:12 INFO - VRAM (Dynamic, Max): 1536 MB 05:12:12 INFO - Vendor: Intel (0x8086) 05:12:12 INFO - Device ID: 0x0a2e 05:12:12 INFO - Revision ID: 0x0009 05:12:12 INFO - Displays: 05:12:12 INFO - Display: 05:12:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:12:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:12:12 INFO - Display Serial Number: 1600x1200 60 05:12:12 INFO - Main Display: Yes 05:12:12 INFO - Mirror: Off 05:12:12 INFO - Online: Yes 05:12:12 INFO - Rotation: Supported 05:12:12 INFO - Return code: 0 05:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:12:12 INFO - Python 2.7.3 05:12:12 INFO - Return code: 0 05:12:12 INFO - grabbing minidump binary from tooltool 05:12:12 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:12:12 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 0x10b668d30>, '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 0x10b3cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b660b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:12:12 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:12:12 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:12:12 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:12:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:12:12 INFO - Return code: 0 05:12:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:12:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:12:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:12:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:12:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0285 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RTPeEfogyk/Render', 05:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:12 INFO - 'HOME': '/Users/cltbld', 05:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:12 INFO - 'LOGNAME': 'cltbld', 05:12:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:12:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:12 INFO - 'MOZ_NO_REMOTE': '1', 05:12:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:12:12 INFO - 'NO_EM_RESTART': '1', 05:12:12 INFO - 'PAGER': '/bin/cat', 05:12:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:12 INFO - 'PWD': '/builds/slave/test', 05:12:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:12 INFO - 'SHELL': '/bin/bash', 05:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB2myVhg4u/Listeners', 05:12:12 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 05:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:12 INFO - 'USER': 'cltbld', 05:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:12 INFO - 'XPC_FLAGS': '0x0', 05:12:12 INFO - 'XPC_SERVICE_NAME': '0', 05:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:12:13 INFO - mozversion application_buildid: 20160909030428 05:12:13 INFO - mozversion application_changeset: feff79e5b1374439f17c5ea10a559acf1380a8d5 05:12:13 INFO - mozversion application_display_name: Nightly 05:12:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:12:13 INFO - mozversion application_name: Firefox 05:12:13 INFO - mozversion application_remotingname: firefox 05:12:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:12:13 INFO - mozversion application_vendor: Mozilla 05:12:13 INFO - mozversion application_version: 51.0a1 05:12:13 INFO - mozversion platform_buildid: 20160909030428 05:12:13 INFO - mozversion platform_changeset: feff79e5b1374439f17c5ea10a559acf1380a8d5 05:12:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:12:13 INFO - mozversion platform_version: 51.0a1 05:12:13 INFO - using testdate: 1473423133 05:12:13 INFO - actual date: 1473423133 05:12:13 INFO - starting webserver on 'localhost:49271' 05:12:13 INFO - SUITE-START | Running 2 tests 05:12:13 INFO - TEST-START | dromaeo_css 05:12:13 INFO - operating with platform_type : mac_ 05:12:13 INFO - Initialising browser for dromaeo_css test... 05:12:13 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpqHm9Qk/profile http://localhost:49271/getInfo.html) 05:12:15 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 05:12:15 INFO - PROCESS | 1649 | colorDepth:24 05:12:15 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 05:12:15 INFO - PROCESS | 1649 | __metrics 05:12:15 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:12:15 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:12:16 INFO - TEST-INFO | 1649: exit 0 05:12:16 INFO - Browser initialized. 05:12:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:12:16 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpqHm9Qk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:28 INFO - PROCESS | 1652 | RSS: Main: 232599552 05:12:28 INFO - PROCESS | 1652 | 05:13:19 INFO - PROCESS | 1652 | 1473423199681 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:15:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:15:33 INFO - PROCESS | 1652 | RSS: Main: 396959744 05:15:33 INFO - PROCESS | 1652 | 05:18:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:18:36 INFO - PROCESS | 1652 | RSS: Main: 370442240 05:18:36 INFO - PROCESS | 1652 | 05:21:39 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:21:39 INFO - PROCESS | 1652 | RSS: Main: 337604608 05:21:39 INFO - PROCESS | 1652 | 05:24:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:24:42 INFO - PROCESS | 1652 | RSS: Main: 344612864 05:24:42 INFO - PROCESS | 1652 | 05:27:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:27:46 INFO - PROCESS | 1652 | RSS: Main: 329351168 05:27:46 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:30:50 INFO - PROCESS | 1652 | RSS: Main: 506056704 05:30:50 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | __start_tp_report 05:30:50 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:30:50 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:30:50 INFO - PROCESS | 1652 | |i|pagename|runs| 05:30:50 INFO - PROCESS | 1652 | |0;dojo.html;3121.3913286909055;3276.344731053789;3299.851506682199;3346.5816772903386;3354.83225838708;5462.371827239868;5554.389017208109;5577.191307260445;5618.719064046797;5635.042042852715;5580.776768929238;5634.605577609569;5637.9718101409435;5646.378898321192;5668.093105103184;5077.720725360113;5116.104681680709;5132.307138536287;5164.302819119427;5175.146100893348;5162.406323272815;5210.973945130269;5217.217417387385;5221.5822734181365;5233.476652334774;5089.821856235032;5117.46266642001;5144.691318520882;5146;5204.505571970676;5208.635395522315;5212.218167274901;5217.16525355945;5230.293910322115;5288.735563221823;5027.798888044474;5030.8018431345345;5098.872528186788;5149.0989076911765;5164.715940623264;5566.610337276392;5624.465675760796;5642.520385767215;5647.37878833328;5682.914756278659;6196.473299769525;6206.937930620681;6227.813165605039;6230.940740074198;6236.064590311445;6110.541709371771;6118.151881049805;6193.597416167935;6219.8182345354235;6222.5644204905675;5767.855803604902;5819.679917604488;5821.09772985188;5835.941640583625;5848.415158484203;6184.319724830265;6190.50475961922;6194.287656919512;6201.689915504297;6202.5658203925295;5978.850528736816;6043.939560604425;6098.32918378978;6101.145839582462;6118.969405152946;6079.0273556230795;6105.459747278077;6127.830981140938;6131.724072416752;6187.907181392283;6184.690765461709;6185.8453538661;6187.84530386735;6191.288001879751;6205.720742566596;6184.443400094013;6188.597741146811;6193.349698281706;6205.782797602063;6213.689315534205;4702.482726900038;4791.6406269529225;4805.903881922342;4819.132556139928;4827.42070951481;4347.195768782785;4374;4396.538363471789;4396.802143903532;4400.075984043387;5566.888662226733;5576.665400076008;5626.38109807929;5650.85872853182;5720.942790572041;6215.192025036717;6242.375762423812;6257.155284036688;6257.374262573797;6271.153394291712;5519.172124181405;5642.887142257214;5655.406182350876;5666.829995100154;5676.8864622707315;5271.4464981176425;5327.440618734977;5352.063388906926;5360.3299587551555;5362.195670649356;6270.278917924497;6289;6301.117843501822;6303.180586523723;6309.211348581427;6185.628862268279;6214.9689251553455;6274.121622972696;6276.6547839869245;6288.5598008138995;6213.65824879636;6260.092286618506;6277.874442511215;6284.151639528605;6305.054241863757;6098.159935307505;6101.974255744823;6105.114758360101;6109.040306147876;6120.325235636639;6025.608335458356;6166.445019948047;6195.4424101831055;6214.378562143749;6241.407066328691;5853;5894.970525147347;5902.409759024235;6007.669578173068;6028.969855150696;5324.653897496651;6006.669633170043;6081.239845019372;6097.75608975636;6112.32764395925;5829.125631155361;5845.152452894392;5846.123081537803;5854.853628659318;5857.179994800646;5831.067029275296;6010.489108425658;6048.758049678139;6051.667158306335;6057.303410107894;5762.596618236851;5833.562482813721;5833.708314584169;5836.095405212199;5838.562107842014 05:30:50 INFO - PROCESS | 1652 | |1;ext.html;457.8008566273671;469.2585714570978;469.3124572501285;522.6776256072138;530.8248278068237;30036;31512.842435787847;31515.89694360698;31543.526847097284;31934.840325798366;32032.67967320326;32502.837485812597;32551.860684876036;32590.511142332885;32652.346953060984;9525.999770024135;9826.066523680274;9835.557399916985;10020.749481262954;10039.949800250988;9790.853137202948;9888.011198880127;9900.25748068894;9906.207503399746;9913.90086099137;8796.912030879705;9860.753481162956;9897.4556399398;9900.257480688977;9932.751681207992;8842.955785221096;11868.228565143281;12078.69803254917;12116.333601651906;12331.815022774666;11908.761824763456;11985;11986.400679966055;11992.820107698391;12003.759924801543;31417.685823141932;39060.41409378862;39488.40767388492;39534.813955581376;39567.40648890269;2781.7913656475644;3252.284497410566;3269.7711160218796;3346.247094403764;4046.271671099201;25284;29961.637401294833;30621.693783062106;31599.84200079008;31631.52552711711;2897.463969165711;3167.7329068372837;3180.7614428917923;3236.4336241157703;3291.1772056985756;418966;419871;422718;422879;423733;54534.181987270225;54888.72555637196;54986;55040.17439738407;55125.89748205094;30530.54204186939;31701.84149079286;33700.32599347999;57870.421295787346;58102.709486452295;15419.614509637124;15911.920440397724;15935.043897366195;15936.840631593768;15978.520644380687;12133.817992730115;12222.266664000188;12225.327606981702;12251.693707657201;12268.877311226952;18414.907925460288;18498.890066596046;18501.629967400575;18508.722369164472;18526.907365463085;6403.583767055126;6846.7945961621235;7436.293552112541;7789.337906277917;7790.688372465152;833.6457005772522;894.608883186638;969.5782334684435;1025.270185935467;1037.7665025369383;26140;28054.877804887576;29118.417631647673;29174.41651166965;29820.956266531;1098.2531878322657;1099.378850949211;1101.2181351240708;1132.4507613807298;1174.107678164584;13407.664808379868;14799.484087705116;15262.236888155549;15277.541673749805;15643.296051677702;533080;536324;536866.3156684191;537936;538803.7538310785;55478.16782748262;56060.15909761357;56060.43939560552;56154.43845561574;56184;1219.3354621731178;1259.4647274908311;1334.0994828490536;1367.1796921846812;1394.35859504629;29022.419551609273;30281.091567253017;30318.696813031587;30513.165059019015;30937;45874.54125458836;46413.76793116148;46498.30252546147;46502.99999999865;46595.83510412267;424284;424341;424884;426017;426045;25036.873340700076;25347.112851050126;25947.870260648575;26305.210843674722;26904.32739181536;45004.32493512666;45297.09405811931;45699.77150114361;45817.54182458266;46066.078678425576;37006.25987480289;37115.81442092772;37431.625683742815;38867.222655547295;39344.40983385309;29302.853485732434;36748;37242.62757372392;37755;38211.235775283785;35094.824525878226;36488.45267321046;36792.63207367892;37334;37471.81264093662;31837.68162318347;32068.877589285195;32582.511262331558;33238.66761332356;33398.833005834815;24498.87750561236;24673.87663061673;24834.87582562148;25027;25433.61849572295 05:30:50 INFO - PROCESS | 1652 | |2;jquery.html;350.60556873517294;371.383503384382;373.91960728443377;385.33529661334194;405.693701255552;14872.47946321879;14954.700905981887;16573.502794916156;20047.899760501175;20881.582368352625;19383;19732.506687332825;19950.70073948892;20130.396088117344;20137.59724805503;1615.1359022922738;1617.7815994840664;1618.0696099742636;1619.74893891447;1835.9443320090934;3813.1229817142075;4058.756474611518;5457.781688732446;5542.722863856811;5548.833534993957;3472.2534655049158;6729.125213722234;6748.358905903931;6779.15260592426;6779.694913728869;6101.0848372744;6171.783987560414;6178.7219575119225;6754.966225168867;6771.4582833373215;5969.671668058255;5984.880302393971;5998.7600495980105;6010.158577799111;6014.2181516403025;16504;24181.87909060461;24780.38049048788;24865.875670621714;25238;1684.61253911601;2363.2201373546686;2399.316194884467;2719.836809791419;2966.3553639846805;12130.968867646261;12923.09538332317;15338.386464541405;15509.612259693486;15584.688306233926;1525.7101150781427;1768.4887459807105;1781.0871183916604;1805.4132406967788;1842.9262829486804;113219;117820;118567;118913.40543297314;119931.40034299773;11134.610288639858;11176.832347514794;11317.547298107984;11394.601188958346;11634.556743083878;11930;14373.85626143746;14381.424743010371;14382.856171438152;14383.28083595816;6620.470362371095;7988.720394786154;8181.79545511367;8276.586170691442;8284.8343033139;7840.215978402228;7908.841823163504;7934.920650793533;8066.354691624656;8077.596120193967;4347.4348334716915;4444.622207112432;4456.955430445719;4509.1658043262105;4570.268756998864;1722.5951901303183;1773.6963331951006;1830.8901465912088;1887.3488646416963;1891.1395315131583;688.7245101959156;784.4038530716697;824.5094168969454;881.5371929736812;955.0707570707272;17627.647447050986;21156;21164;21221.60575224517;21433.249836255967;906.5557876640398;928.6610387208756;929.7303781903307;957.1624828275259;964.1419136968104;10216.489175541194;10756.515956781963;10797.974192451862;10802.459877006275;10839;465289;465409;475840;475900;477454;73074.53850923058;73263;93640.12719745703;122873;125769.37115314364;935.1490143968956;959.2613687460756;959.8992105828787;960.70698436976;1026.0560284538242;18903.621927561097;19702.50743731361;19708.31020914261;19735.605287893875;19803.50491237673;55849.72075139598;56113.77724455207;56231.87536249334;56273.8745225085;57399.42600574108;77372;80796.59601701953;81100;82293.17706823094;83029.58485207535;7870.173631768582;7886.921130788619;7946.404019698662;8274.917250827655;8317.251447369767;11453.370064646524;12563.05777066742;12856.935715321364;12943.482260709467;13051.934740326618;11137.888621113685;11314.60398886035;11569.942150289531;11634.418279086181;11653.184277100861;10901.781964360827;11018.889811102106;11104.5558177672;11218.158638102012;11331.716707082389;11069.723256918642;11354.716132096764;11501.654950351503;11581.42092895335;11587.015103716278;7883.211678832071;8019.198080191934;8155.102938676622;8338.458000229966;8359.791005224917;8502.872456913276;9523.809523809623;10909.454527273447;11317.264377815416;11410.828837567276 05:30:50 INFO - PROCESS | 1652 | |3;mootools.html;1345.0719003887323;1498.5129832804341;1528.0449718925672;1731.2296028267426;1909.3508207209554;7731.613419329032;8215.096339402668;8431.70489032884;8776.473411595309;8788.69239576615;8650.826983460327;8727.956360218206;8730.60712267948;8733;8737.781555461117;2332.941676458089;2363.61000434928;2367.75138610446;2368.8815559222057;2369.7037870266217;2327.0924339507606;2360.6104992676187;2376.4058985253687;2378.9286321410386;2381.7022872140983;2337.2871274261342;2355.705536807899;2367.3134790910617;2371.6798232238684;2377.976220237793;2317.2353123469215;2339.7075365579217;2340.578695834749;2343.133040774924;2361.2916125162383;2302.320815359473;2335.94160145996;2336.158982766203;2341.0986770093637;2344.5896968030524;8741.30069594431;8770.956145219297;8827.205551500396;8844.248238899701;8846.292296616255;5184.559312458446;5378.897326048151;5382.111951527993;5417.539509141728;5423.349198096214;3500.6674365935196;3659.048647351681;3700.833462494194;3740.4763333133383;3748.3440397930253;903.5527413930081;1093.5352475198088;1094.600470828142;1118.9048930840888;1120.199057673759;11601.593944211912;11601.651950441501;11685.415729213506;11708.88291117095;11741.647750567581;4309.07354918694;4404.559544045634;4573.63410927125;4574.382458368078;4591.357209990604;9081.727548173565;9084.727458176265;9090;9091.136342047495;9109.954450227839;5178.585713143015;5180.352455943007;5188.5330320270605;5191.143461328914;5201;4296.699231053796;4356.825726970949;4405.427294451701;4527.84152554659;4836.975815120902;3688.4098544232793;3757.1546402059316;3761.7931013794505;3797.6012518685693;3812.866549670748;1151.9769604607861;1157.3229660648615;1165.1960147498198;1172.3434876469205;1177.011310499185;546.8550834028986;799.4403917257943;890.2121622364255;906.9047749986288;908.4276905549461;5010.624203184776;5628.04323265046;5660.971695141498;5739.713014349266;6112.144299798032;909.7088931541844;954.4178051388648;965.2181732796457;971.2618410008445;1012.7822518158484;3857.4213867920034;3982.0841206522664;4030.2946984277633;4081.5102187737066;4082.6121518456293;10384.532696028697;10838.837417438745;10845.51195196218;10861.83707244392;10913.618023369145;8950;10640;10644.627438039632;10765.56937722498;10768.676939691823;6522.206393241897;8229;8232.259096681328;8250.504969701837;8267.255946964802;5849.5612829037145;5900.586958912835;5901.970490147522;6077.963882099546;6265.997440409513;9554.331196816434;9993.400395976265;10216.54025568866;10240.129588985019;10288.742781430225;9238.491882946502;10086.445245511273;10205.234607404624;10231.539580719034;10236.18110551169;5875.706214689334;7082.610456424946;7178.892316615151;7308.451866109956;7450.8882366763455;8056.476329038594;8058.315043221158;8134.349252059939;8208.138145494875;8212.507249565046;7378.926210738038;7411.888821667787;7433.628318583941;7458.5151965122095;7485.70057197706;7151.892721609284;7276.96361518189;7307.451941104545;7376.37300829414;7447.255274472726;6920.757773478105;6943.132108486439;7054.153501579844;7061.399781018466;7067.293270672892;4862.781174847069;4935.580475659609;4964.3298154748645;4997.400311962588;5142.948570514247;4942.209246520683;5008.69947803133;5016.598672106295;5029.5221953914315;5115.693058416507 05:30:50 INFO - PROCESS | 1652 | |4;prototype.html;298.2454390392308;299.4011976047904;307.5033820380086;311.93761247750456;313.5813688725551;4304.461942257214;5246.370435547735;5253.4221235664045;5275.920861187075;5284.339457567804;4171.624553790159;4371.36615190798;4897.902041959159;5087.313212716283;5235.717249273924;3307.974527896348;3864.59421760716;3985.6014398560055;4033.838646454138;4054.533728621202;3993.28120938231;4005.4192142139373;4012.197560487914;4045.2201031546174;4048.7368321059184;3846.499955005845;3985.501812273466;3988.905824442162;3989.3417586098403;4048.736832105904;3521.9119522012024;3988.481497405348;4022.3162062449455;4026.134381108061;4057.736247143926;3352.9941017694596;3512.947305790415;3520.908518359317;3534.7525673202886;3551.254236610289;4101.671866250693;4861.177058603024;5121.923171152435;5138.820141295074;5159.14874045782;3989.601039895987;4368.978155109204;4447.3773671685985;4604.5165257647795;4667.509911459282;5240.956027149415;5536;5562.7774889004795;5565.2486914266465;5570.6657600544095;3797.316483033053;3883.86406475772;3888.805559722031;3890.3580909149955;3891.6692081173132;4721.409823772028;4722.929156062662;5641.548676105943;5759.280089988752;5778.566607504412;3736.831842567091;3761.9247615047548;3767.5290588676417;3802.4866643003393;3813.3326667833016;3117.1272043827767;3374.4094783412806;3491.092315997808;3655.5796083450214;3780.127103401435;4157.9605098725315;4367.432233809648;4510.639148868083;4521;4544.182047231464;4373.294415178083;5482.533984611273;5607.691576963224;5620;5635.788305514334;1149.83327417524;1288.954886578984;1298.688314804459;1315;1324.1260767893145;817.7424111405007;841.5147265077144;845.6258579807812;852.7569642651814;871.4727589808201;5076.314697515788;5100.132977393852;5113.1052065888325;5167.509086636764;5198.778287102527;4957.876053098629;5326.653767505178;5490.862728431821;5524.1989911463415;5557.77768889248;4279.486461624562;5081.923771143436;5102.678281381963;5103.617228707788;5229.50319719626;2557.373443506353;2563.846169229852;2898.6086878271585;2936.2365784895655;2939.3974235281717;4776.426828780499;5800.564957628195;5825.592208545446;5831.358550559435;5842.824715258549;5341.65279256847;5425.403205647375;5551.861203469945;5695.231143795618;5946.464818166386;4910.631702622318;5919.467247947706;5941.871044501531;5967.731452084667;6024.909626355608;4958.553730164303;5660.943390566042;5668.971655141698;5681.914771278517;5751.798687045934;4473.657902629263;5663.4991727193155;5666.461686139975;5670.999999999835;5702.429757024431;5183.611229157904;5939.732712028055;5952.494038006818;5973.790917317873;6016.398360163948;3186.840657967046;3502.2470168913806;3585.3904836178435;3719.628037196259;3786.280606684721;4728.550787675165;5633.521150702236;5677.261955945701;5686.2323586315315;5757.424257574209;4455;4521.7513036781975;4866.732329721899;5536.2249285100925;5593.748281327269;5050.924236136534;5325.775071733446;5534.584906132137;5573.832785016456;5617.634853734494;5116.513931176532;5141.408737995179;5288.444713305047;5331.226972089103;5409.486098820606;4224.683148763794;5234.4983314348865;5356.946430535801;5461.508464238238;5509.228707980806;5121.564667003197;5352.036633405869;5371.758270877742;5385.111456609616;5389.218563308376 05:30:50 INFO - PROCESS | 1652 | |5;yui.html;687.384790612402;764.1708746010578;776.0570906348788;808.1110778144039;808.4623725222727;13395.732085358288;14621.268936553182;15270.16014119223;15863.68272634548;15891.761623575641;14245.78631320531;14608.561743147697;14622.049566778178;14622.415103395879;14629.122252664822;683.8051155420706;850.5704619167327;872.9388942774009;873.6068769053919;876.9386142969963;693.9375456208941;698.4866123399297;839.8950131233596;861.577826864838;876.8816209811689;904.606496174163;905.0361365146131;906.3700727994046;907.9409838360485;908.3323757038571;903.6340282185726;904.5115637555679;904.8099899021213;905.4567259644226;907.7775944893543;531.2827682628459;551.892380985708;657.9901301480481;680.0717021265986;685.9847425809779;14783;14799.556013319618;14816.851831481763;14826.851731482762;14890.404383824634;13611;13936.303184840717;14045.367958441895;14105.2242126683;14200;12491.814587280276;12613.495460181583;12710.364481775874;13127.409266583027;13229.272390018545;4525.728456292633;4548.7725613719185;4576.496585375606;4748.620110391161;4750.311204875274;112336;113507;114401.4279928595;116895;121354.3932280333;11104.944475277573;11191.72020699489;11267.26762760418;11476.655700329004;11494.712632184095;22674.319770406913;22691.432714182276;22710;22739.658905116437;22769.316920492412;7507.662155203029;7866.724664636825;7973.880391794128;8134.267915887599;8155.632996515171;4413.7351758894565;4415.735055896656;4417.979064387214;4423.270160423494;4456.450361172478;3391.6777906098882;3560.8041557714055;3569.910752231163;3570.7143428525656;3575.4458059000895;648.8540078482304;653.3597074866657;660.6168422315046;661.0810972747788;663.4857985061519;617.2716194890073;617.3795335687605;621.6674079367517;627.4502029548635;627.8650090230623;8459.238668519863;8700.738977830675;8961.462312261285;9007;9051.502167380726;445.09645419797863;540.6539814518633;541.3314556522691;586.3432955090241;628.1896353703762;4244.0663054128045;4312.870613881589;4336.978315108404;4390.9461729185405;4500.797464114123;92883.0711692888;139430;143016;148838;151505;106031.93968060375;106332.40501392486;108893.45553272183;122357;131095;91785;94075.05924940937;95306.52346738083;103704;106745.4662726697;9346.766330841783;9562.330636855353;9654.275929305471;9695;9723.46520941355;55390.169147463625;56160.43839561552;57746;58946.11580826205;62783;87493;88666.55666721625;89488.21023579623;90852;94125;12795.104342696295;13202.811746942822;13371.732565348833;13505.987050971413;13811.516596919462;14512.1292722437;14764.48324308644;14846.331915063633;14855.777163342342;14861;13646.540212367814;13750.65623359424;13893;14089.788653170415;14542.636434089232;13480.86519134796;13689.931550342184;13733.45066197341;13886.305684715926;13923.443062277396;11017.83473247918;13333.266670332836;13479.86520134786;13585.86414135846;13717;10787;11168.329900206014;11236.775264494829;11453.885461145283;11515.17878119866;10480.371177729361;11190.234992938982;11315.130086856714;11591.594294199662;11597.362145081765 05:30:50 INFO - PROCESS | 1652 | __end_tp_report 05:30:50 INFO - PROCESS | 1652 | __start_cc_report 05:30:50 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1751 05:30:50 INFO - PROCESS | 1652 | __end_cc_report 05:30:50 INFO - PROCESS | 1652 | __startTimestamp1473424250456__endTimestamp 05:30:50 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:30:50 INFO - PROCESS | 1652 | Number of tests: 6 05:30:50 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5683.17 Median:5845.15 stddev:645.06 (11.0%) stddev-sans-first:614.94 05:30:50 INFO - PROCESS | 1652 | Values: 3121.4 3276.3 3299.9 3346.6 3354.8 5462.4 5554.4 5577.2 5618.7 5635.0 5580.8 5634.6 5638.0 5646.4 5668.1 5077.7 5116.1 5132.3 5164.3 5175.1 5162.4 5211.0 5217.2 5221.6 5233.5 5089.8 5117.5 5144.7 5146.0 5204.5 5208.6 5212.2 5217.2 5230.3 5288.7 5027.8 5030.8 5098.9 5149.1 5164.7 5566.6 5624.5 5642.5 5647.4 5682.9 6196.5 6206.9 6227.8 6230.9 6236.1 6110.5 6118.2 6193.6 6219.8 6222.6 5767.9 5819.7 5821.1 5835.9 5848.4 6184.3 6190.5 6194.3 6201.7 6202.6 5978.9 6043.9 6098.3 6101.1 6119.0 6079.0 6105.5 6127.8 6131.7 6187.9 6184.7 6185.8 6187.8 6191.3 6205.7 6184.4 6188.6 6193.3 6205.8 6213.7 4702.5 4791.6 4805.9 4819.1 4827.4 4347.2 4374.0 4396.5 4396.8 4400.1 5566.9 5576.7 5626.4 5650.9 5720.9 6215.2 6242.4 6257.2 6257.4 6271.2 5519.2 5642.9 5655.4 5666.8 5676.9 5271.4 5327.4 5352.1 5360.3 5362.2 6270.3 6289.0 6301.1 6303.2 6309.2 6185.6 6215.0 6274.1 6276.7 6288.6 6213.7 6260.1 6277.9 6284.2 6305.1 6098.2 6102.0 6105.1 6109.0 6120.3 6025.6 6166.4 6195.4 6214.4 6241.4 5853.0 5895.0 5902.4 6007.7 6029.0 5324.7 6006.7 6081.2 6097.8 6112.3 5829.1 5845.2 5846.1 5854.9 5857.2 5831.1 6010.5 6048.8 6051.7 6057.3 5762.6 5833.6 5833.7 5836.1 5838.6 05:30:50 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:59567.24 Median:17961.06 stddev:123404.46 (687.1%) stddev-sans-first:123670.84 05:30:50 INFO - PROCESS | 1652 | Values: 457.8 469.3 469.3 522.7 530.8 30036.0 31512.8 31515.9 31543.5 31934.8 32032.7 32502.8 32551.9 32590.5 32652.3 9526.0 9826.1 9835.6 10020.7 10039.9 9790.9 9888.0 9900.3 9906.2 9913.9 8796.9 9860.8 9897.5 9900.3 9932.8 8843.0 11868.2 12078.7 12116.3 12331.8 11908.8 11985.0 11986.4 11992.8 12003.8 31417.7 39060.4 39488.4 39534.8 39567.4 2781.8 3252.3 3269.8 3346.2 4046.3 25284.0 29961.6 30621.7 31599.8 31631.5 2897.5 3167.7 3180.8 3236.4 3291.2 418966.0 419871.0 422718.0 422879.0 423733.0 54534.2 54888.7 54986.0 55040.2 55125.9 30530.5 31701.8 33700.3 57870.4 58102.7 15419.6 15911.9 15935.0 15936.8 15978.5 12133.8 12222.3 12225.3 12251.7 12268.9 18414.9 18498.9 18501.6 18508.7 18526.9 6403.6 6846.8 7436.3 7789.3 7790.7 833.6 894.6 969.6 1025.3 1037.8 26140.0 28054.9 29118.4 29174.4 29821.0 1098.3 1099.4 1101.2 1132.5 1174.1 13407.7 14799.5 15262.2 15277.5 15643.3 533080.0 536324.0 536866.3 537936.0 538803.8 55478.2 56060.2 56060.4 56154.4 56184.0 1219.3 1259.5 1334.1 1367.2 1394.4 29022.4 30281.1 30318.7 30513.2 30937.0 45874.5 46413.8 46498.3 46503.0 46595.8 424284.0 424341.0 424884.0 426017.0 426045.0 25036.9 25347.1 25947.9 26305.2 26904.3 45004.3 45297.1 45699.8 45817.5 46066.1 37006.3 37115.8 37431.6 38867.2 39344.4 29302.9 36748.0 37242.6 37755.0 38211.2 35094.8 36488.5 36792.6 37334.0 37471.8 31837.7 32068.9 32582.5 33238.7 33398.8 24498.9 24673.9 24834.9 25027.0 25433.6 05:30:50 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:30638.32 Median:24481.13 stddev:79532.98 (324.9%) stddev-sans-first:79723.58 05:30:50 INFO - PROCESS | 1652 | Values: 350.6 371.4 373.9 385.3 405.7 14872.5 14954.7 16573.5 20047.9 20881.6 19383.0 19732.5 19950.7 20130.4 20137.6 1615.1 1617.8 1618.1 1619.7 1835.9 3813.1 4058.8 5457.8 5542.7 5548.8 3472.3 6729.1 6748.4 6779.2 6779.7 6101.1 6171.8 6178.7 6755.0 6771.5 5969.7 5984.9 5998.8 6010.2 6014.2 16504.0 24181.9 24780.4 24865.9 25238.0 1684.6 2363.2 2399.3 2719.8 2966.4 12131.0 12923.1 15338.4 15509.6 15584.7 1525.7 1768.5 1781.1 1805.4 1842.9 113219.0 117820.0 118567.0 118913.4 119931.4 11134.6 11176.8 11317.5 11394.6 11634.6 11930.0 14373.9 14381.4 14382.9 14383.3 6620.5 7988.7 8181.8 8276.6 8284.8 7840.2 7908.8 7934.9 8066.4 8077.6 4347.4 4444.6 4457.0 4509.2 4570.3 1722.6 1773.7 1830.9 1887.3 1891.1 688.7 784.4 824.5 881.5 955.1 17627.6 21156.0 21164.0 21221.6 21433.2 906.6 928.7 929.7 957.2 964.1 10216.5 10756.5 10798.0 10802.5 10839.0 465289.0 465409.0 475840.0 475900.0 477454.0 73074.5 73263.0 93640.1 122873.0 125769.4 935.1 959.3 959.9 960.7 1026.1 18903.6 19702.5 19708.3 19735.6 19803.5 55849.7 56113.8 56231.9 56273.9 57399.4 77372.0 80796.6 81100.0 82293.2 83029.6 7870.2 7886.9 7946.4 8274.9 8317.3 11453.4 12563.1 12856.9 12943.5 13051.9 11137.9 11314.6 11569.9 11634.4 11653.2 10901.8 11018.9 11104.6 11218.2 11331.7 11069.7 11354.7 11501.7 11581.4 11587.0 7883.2 8019.2 8155.1 8338.5 8359.8 8502.9 9523.8 10909.5 11317.3 11410.8 05:30:50 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5627.20 Median:5003.05 stddev:3152.75 (63.0%) stddev-sans-first:3145.16 05:30:50 INFO - PROCESS | 1652 | Values: 1345.1 1498.5 1528.0 1731.2 1909.4 7731.6 8215.1 8431.7 8776.5 8788.7 8650.8 8728.0 8730.6 8733.0 8737.8 2332.9 2363.6 2367.8 2368.9 2369.7 2327.1 2360.6 2376.4 2378.9 2381.7 2337.3 2355.7 2367.3 2371.7 2378.0 2317.2 2339.7 2340.6 2343.1 2361.3 2302.3 2335.9 2336.2 2341.1 2344.6 8741.3 8771.0 8827.2 8844.2 8846.3 5184.6 5378.9 5382.1 5417.5 5423.3 3500.7 3659.0 3700.8 3740.5 3748.3 903.6 1093.5 1094.6 1118.9 1120.2 11601.6 11601.7 11685.4 11708.9 11741.6 4309.1 4404.6 4573.6 4574.4 4591.4 9081.7 9084.7 9090.0 9091.1 9110.0 5178.6 5180.4 5188.5 5191.1 5201.0 4296.7 4356.8 4405.4 4527.8 4837.0 3688.4 3757.2 3761.8 3797.6 3812.9 1152.0 1157.3 1165.2 1172.3 1177.0 546.9 799.4 890.2 906.9 908.4 5010.6 5628.0 5661.0 5739.7 6112.1 909.7 954.4 965.2 971.3 1012.8 3857.4 3982.1 4030.3 4081.5 4082.6 10384.5 10838.8 10845.5 10861.8 10913.6 8950.0 10640.0 10644.6 10765.6 10768.7 6522.2 8229.0 8232.3 8250.5 8267.3 5849.6 5900.6 5902.0 6078.0 6266.0 9554.3 9993.4 10216.5 10240.1 10288.7 9238.5 10086.4 10205.2 10231.5 10236.2 5875.7 7082.6 7178.9 7308.5 7450.9 8056.5 8058.3 8134.3 8208.1 8212.5 7378.9 7411.9 7433.6 7458.5 7485.7 7151.9 7277.0 7307.5 7376.4 7447.3 6920.8 6943.1 7054.2 7061.4 7067.3 4862.8 4935.6 4964.3 4997.4 5142.9 4942.2 5008.7 5016.6 5029.5 5115.7 05:30:50 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4436.69 Median:5063.62 stddev:1378.50 (27.2%) stddev-sans-first:1346.92 05:30:50 INFO - PROCESS | 1652 | Values: 298.2 299.4 307.5 311.9 313.6 4304.5 5246.4 5253.4 5275.9 5284.3 4171.6 4371.4 4897.9 5087.3 5235.7 3308.0 3864.6 3985.6 4033.8 4054.5 3993.3 4005.4 4012.2 4045.2 4048.7 3846.5 3985.5 3988.9 3989.3 4048.7 3521.9 3988.5 4022.3 4026.1 4057.7 3353.0 3512.9 3520.9 3534.8 3551.3 4101.7 4861.2 5121.9 5138.8 5159.1 3989.6 4369.0 4447.4 4604.5 4667.5 5241.0 5536.0 5562.8 5565.2 5570.7 3797.3 3883.9 3888.8 3890.4 3891.7 4721.4 4722.9 5641.5 5759.3 5778.6 3736.8 3761.9 3767.5 3802.5 3813.3 3117.1 3374.4 3491.1 3655.6 3780.1 4158.0 4367.4 4510.6 4521.0 4544.2 4373.3 5482.5 5607.7 5620.0 5635.8 1149.8 1289.0 1298.7 1315.0 1324.1 817.7 841.5 845.6 852.8 871.5 5076.3 5100.1 5113.1 5167.5 5198.8 4957.9 5326.7 5490.9 5524.2 5557.8 4279.5 5081.9 5102.7 5103.6 5229.5 2557.4 2563.8 2898.6 2936.2 2939.4 4776.4 5800.6 5825.6 5831.4 5842.8 5341.7 5425.4 5551.9 5695.2 5946.5 4910.6 5919.5 5941.9 5967.7 6024.9 4958.6 5660.9 5669.0 5681.9 5751.8 4473.7 5663.5 5666.5 5671.0 5702.4 5183.6 5939.7 5952.5 5973.8 6016.4 3186.8 3502.2 3585.4 3719.6 3786.3 4728.6 5633.5 5677.3 5686.2 5757.4 4455.0 4521.8 4866.7 5536.2 5593.7 5050.9 5325.8 5534.6 5573.8 5617.6 5116.5 5141.4 5288.4 5331.2 5409.5 4224.7 5234.5 5356.9 5461.5 5509.2 5121.6 5352.0 5371.8 5385.1 5389.2 05:30:50 INFO - PROCESS | 1652 | 05:30:50 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:23859.24 Median:3909.76 stddev:37124.87 (949.5%) stddev-sans-first:37188.25 05:30:50 INFO - PROCESS | 1652 | Values: 687.4 764.2 776.1 808.1 808.5 13395.7 14621.3 15270.2 15863.7 15891.8 14245.8 14608.6 14622.0 14622.4 14629.1 683.8 850.6 872.9 873.6 876.9 693.9 698.5 839.9 861.6 876.9 904.6 905.0 906.4 907.9 908.3 903.6 904.5 904.8 905.5 907.8 531.3 551.9 658.0 680.1 686.0 14783.0 14799.6 14816.9 14826.9 14890.4 13611.0 13936.3 14045.4 14105.2 14200.0 12491.8 12613.5 12710.4 13127.4 13229.3 4525.7 4548.8 4576.5 4748.6 4750.3 112336.0 113507.0 114401.4 116895.0 121354.4 11104.9 11191.7 11267.3 11476.7 11494.7 22674.3 22691.4 22710.0 22739.7 22769.3 7507.7 7866.7 7973.9 8134.3 8155.6 4413.7 4415.7 4418.0 4423.3 4456.5 3391.7 3560.8 3569.9 3570.7 3575.4 648.9 653.4 660.6 661.1 663.5 617.3 617.4 621.7 627.5 627.9 8459.2 8700.7 8961.5 9007.0 9051.5 445.1 540.7 541.3 586.3 628.2 4244.1 4312.9 4337.0 4390.9 4500.8 92883.1 139430.0 143016.0 148838.0 151505.0 106031.9 106332.4 108893.5 122357.0 131095.0 91785.0 94075.1 95306.5 103704.0 106745.5 9346.8 9562.3 9654.3 9695.0 9723.5 55390.2 56160.4 57746.0 58946.1 62783.0 87493.0 88666.6 89488.2 90852.0 94125.0 12795.1 13202.8 13371.7 13506.0 13811.5 14512.1 14764.5 14846.3 14855.8 14861.0 13646.5 13750.7 13893.0 14089.8 14542.6 13480.9 13689.9 13733.5 13886.3 13923.4 11017.8 13333.3 13479.9 13585.9 13717.0 10787.0 11168.3 11236.8 11453.9 11515.2 10480.4 11190.2 11315.1 11591.6 11597.4 05:30:50 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:30:50 INFO - PROCESS | 1652 | 05:30:51 INFO - TEST-INFO | 1652: exit 0 05:30:51 INFO - TEST-OK | dromaeo_css | took 1118094ms 05:30:51 INFO - TEST-START | kraken 05:30:51 INFO - operating with platform_type : mac_ 05:30:51 INFO - Initialising browser for kraken test... 05:30:51 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpchT6Sk/profile http://localhost:49271/getInfo.html) 05:30:53 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 05:30:53 INFO - PROCESS | 1663 | colorDepth:24 05:30:53 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 05:30:53 INFO - PROCESS | 1663 | __metrics 05:30:53 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:30:53 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:30:54 INFO - TEST-INFO | 1663: exit 0 05:30:54 INFO - Browser initialized. 05:30:54 INFO - Running cycle 1/1 for kraken test... 05:30:54 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpchT6Sk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:05 INFO - PROCESS | 1665 | RSS: Main: 232767488 05:31:05 INFO - PROCESS | 1665 | 05:31:09 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:31:09 INFO - PROCESS | 1665 | RSS: Main: 293191680 05:31:09 INFO - PROCESS | 1665 | 05:31:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:31:13 INFO - PROCESS | 1665 | RSS: Main: 288763904 05:31:13 INFO - PROCESS | 1665 | 05:31:16 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:31:17 INFO - PROCESS | 1665 | RSS: Main: 379097088 05:31:17 INFO - PROCESS | 1665 | 05:31:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:31:20 INFO - PROCESS | 1665 | RSS: Main: 280170496 05:31:20 INFO - PROCESS | 1665 | 05:31:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:31:23 INFO - PROCESS | 1665 | RSS: Main: 283979776 05:31:23 INFO - PROCESS | 1665 | 05:31:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:31:26 INFO - PROCESS | 1665 | RSS: Main: 320487424 05:31:26 INFO - PROCESS | 1665 | 05:31:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:31:30 INFO - PROCESS | 1665 | RSS: Main: 322166784 05:31:30 INFO - PROCESS | 1665 | 05:31:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:31:33 INFO - PROCESS | 1665 | RSS: Main: 322416640 05:31:33 INFO - PROCESS | 1665 | 05:31:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:31:35 INFO - PROCESS | 1665 | RSS: Main: 298074112 05:31:35 INFO - PROCESS | 1665 | 05:31:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:31:38 INFO - PROCESS | 1665 | RSS: Main: 303583232 05:31:38 INFO - PROCESS | 1665 | 05:31:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:31:41 INFO - PROCESS | 1665 | RSS: Main: 309219328 05:31:41 INFO - PROCESS | 1665 | 05:31:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:31:44 INFO - PROCESS | 1665 | RSS: Main: 322134016 05:31:44 INFO - PROCESS | 1665 | 05:31:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:31:48 INFO - PROCESS | 1665 | RSS: Main: 318431232 05:31:48 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:31:50 INFO - PROCESS | 1665 | RSS: Main: 362663936 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | __start_tp_report 05:31:50 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 05:31:50 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 05:31:50 INFO - PROCESS | 1665 | |i|pagename|runs| 05:31:50 INFO - PROCESS | 1665 | |0;ai-astar;114;112;117;123;167;146;113;113;113;123 05:31:50 INFO - PROCESS | 1665 | |1;audio-beat-detection;147;110;130;161;112;160;163;136;160;111 05:31:50 INFO - PROCESS | 1665 | |2;audio-dft;188;199;264;184;184;185;185;186;181;223 05:31:50 INFO - PROCESS | 1665 | |3;audio-fft;78;74;77;165;59;59;58;59;152;64 05:31:50 INFO - PROCESS | 1665 | |4;audio-oscillator;84;84;136;70;70;100;145;98;99;109 05:31:50 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;87;84;86;85;87;84;87;85;87;84 05:31:50 INFO - PROCESS | 1665 | |6;imaging-darkroom;94;101;132;93;93;92;93;93;94;94 05:31:50 INFO - PROCESS | 1665 | |7;imaging-desaturate;84;85;84;85;90;84;83;106;136;113 05:31:50 INFO - PROCESS | 1665 | |8;json-parse-financial;55;54;57;55;56;54;54;55;54;54 05:31:50 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;65;66;66;72;80;76;72;72;72;73 05:31:50 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;88;89;83;85;85;88;85;90;85;91 05:31:50 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;153;129;148;130;121;146;146;151;199;139 05:31:50 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;169;169;168;170;170;172;169;170;172;171 05:31:50 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;68;68;67;68;69 05:31:50 INFO - PROCESS | 1665 | __end_tp_report 05:31:50 INFO - PROCESS | 1665 | __start_cc_report 05:31:50 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2112 05:31:50 INFO - PROCESS | 1665 | __end_cc_report 05:31:50 INFO - PROCESS | 1665 | __startTimestamp1473424310313__endTimestamp 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | ------- Summary: start ------- 05:31:50 INFO - PROCESS | 1665 | Number of tests: 14 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:124.10 Median:120.00 stddev:18.23 (15.2%) stddev-sans-first:18.97 05:31:50 INFO - PROCESS | 1665 | Values: 114.0 112.0 117.0 123.0 167.0 146.0 113.0 113.0 113.0 123.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:153.50 stddev:22.24 (14.5%) stddev-sans-first:23.40 05:31:50 INFO - PROCESS | 1665 | Values: 147.0 110.0 130.0 161.0 112.0 160.0 163.0 136.0 160.0 111.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:197.90 Median:187.00 stddev:26.34 (14.1%) stddev-sans-first:27.69 05:31:50 INFO - PROCESS | 1665 | Values: 188.0 199.0 264.0 184.0 184.0 185.0 185.0 186.0 181.0 223.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:84.50 Median:61.50 stddev:39.89 (64.9%) stddev-sans-first:42.24 05:31:50 INFO - PROCESS | 1665 | Values: 78.0 74.0 77.0 165.0 59.0 59.0 58.0 59.0 152.0 64.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:99.50 Median:77.00 stddev:25.20 (32.7%) stddev-sans-first:26.10 05:31:50 INFO - PROCESS | 1665 | Values: 84.0 84.0 136.0 70.0 70.0 100.0 145.0 98.0 99.0 109.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.35 (1.6%) stddev-sans-first:1.33 05:31:50 INFO - PROCESS | 1665 | Values: 87.0 84.0 86.0 85.0 87.0 84.0 87.0 85.0 87.0 84.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:93.00 stddev:12.24 (13.2%) stddev-sans-first:12.90 05:31:50 INFO - PROCESS | 1665 | Values: 94.0 101.0 132.0 93.0 93.0 92.0 93.0 93.0 94.0 94.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:84.00 stddev:17.82 (21.2%) stddev-sans-first:18.45 05:31:50 INFO - PROCESS | 1665 | Values: 84.0 85.0 84.0 85.0 90.0 84.0 83.0 106.0 136.0 113.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:1.03 (1.9%) stddev-sans-first:1.09 05:31:50 INFO - PROCESS | 1665 | Values: 55.0 54.0 57.0 55.0 56.0 54.0 54.0 55.0 54.0 54.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:4.70 (6.5%) stddev-sans-first:4.37 05:31:50 INFO - PROCESS | 1665 | Values: 65.0 66.0 66.0 72.0 80.0 76.0 72.0 72.0 72.0 73.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:88.00 stddev:2.64 (3.0%) stddev-sans-first:2.77 05:31:50 INFO - PROCESS | 1665 | Values: 88.0 89.0 83.0 85.0 85.0 88.0 85.0 90.0 85.0 91.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:147.00 stddev:21.36 (14.5%) stddev-sans-first:22.51 05:31:50 INFO - PROCESS | 1665 | Values: 153.0 129.0 148.0 130.0 121.0 146.0 146.0 151.0 199.0 139.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:170.00 stddev:1.33 (0.8%) stddev-sans-first:1.36 05:31:50 INFO - PROCESS | 1665 | Values: 169.0 169.0 168.0 170.0 170.0 172.0 169.0 170.0 172.0 171.0 05:31:50 INFO - PROCESS | 1665 | 05:31:50 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 05:31:50 INFO - PROCESS | 1665 | Values: 67.0 67.0 68.0 67.0 67.0 68.0 68.0 67.0 68.0 69.0 05:31:50 INFO - PROCESS | 1665 | -------- Summary: end -------- 05:31:50 INFO - PROCESS | 1665 | 05:31:51 INFO - TEST-INFO | 1665: exit 0 05:31:51 INFO - TEST-OK | kraken | took 60039ms 05:31:51 INFO - SUITE-END | took 1178s 05:31:51 INFO - Completed test suite (00:19:38) 05:31:51 INFO - javascript benchmark 05:31:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3121.3913286909055, 3276.344731053789, 3299.851506682199, 3346.5816772903386, 3354.83225838708, 5462.371827239868, 5554.389017208109, 5577.191307260445, 5618.719064046797, 5635.042042852715, 5580.776768929238, 5634.605577609569, 5637.9718101409435, 5646.378898321192, 5668.093105103184, 5077.720725360113, 5116.104681680709, 5132.307138536287, 5164.302819119427, 5175.146100893348, 5162.406323272815, 5210.973945130269, 5217.217417387385, 5221.5822734181365, 5233.476652334774, 5089.821856235032, 5117.46266642001, 5144.691318520882, 5146.0, 5204.505571970676, 5208.635395522315, 5212.218167274901, 5217.16525355945, 5230.293910322115, 5288.735563221823, 5027.798888044474, 5030.8018431345345, 5098.872528186788, 5149.0989076911765, 5164.715940623264, 5566.610337276392, 5624.465675760796, 5642.520385767215, 5647.37878833328, 5682.914756278659, 6196.473299769525, 6206.937930620681, 6227.813165605039, 6230.940740074198, 6236.064590311445, 6110.541709371771, 6118.151881049805, 6193.597416167935, 6219.8182345354235, 6222.5644204905675, 5767.855803604902, 5819.679917604488, 5821.09772985188, 5835.941640583625, 5848.415158484203, 6184.319724830265, 6190.50475961922, 6194.287656919512, 6201.689915504297, 6202.5658203925295, 5978.850528736816, 6043.939560604425, 6098.32918378978, 6101.145839582462, 6118.969405152946, 6079.0273556230795, 6105.459747278077, 6127.830981140938, 6131.724072416752, 6187.907181392283, 6184.690765461709, 6185.8453538661, 6187.84530386735, 6191.288001879751, 6205.720742566596, 6184.443400094013, 6188.597741146811, 6193.349698281706, 6205.782797602063, 6213.689315534205, 4702.482726900038, 4791.6406269529225, 4805.903881922342, 4819.132556139928, 4827.42070951481, 4347.195768782785, 4374.0, 4396.538363471789, 4396.802143903532, 4400.075984043387, 5566.888662226733, 5576.665400076008, 5626.38109807929, 5650.85872853182, 5720.942790572041, 6215.192025036717, 6242.375762423812, 6257.155284036688, 6257.374262573797, 6271.153394291712, 5519.172124181405, 5642.887142257214, 5655.406182350876, 5666.829995100154, 5676.8864622707315, 5271.4464981176425, 5327.440618734977, 5352.063388906926, 5360.3299587551555, 5362.195670649356, 6270.278917924497, 6289.0, 6301.117843501822, 6303.180586523723, 6309.211348581427, 6185.628862268279, 6214.9689251553455, 6274.121622972696, 6276.6547839869245, 6288.5598008138995, 6213.65824879636, 6260.092286618506, 6277.874442511215, 6284.151639528605, 6305.054241863757, 6098.159935307505, 6101.974255744823, 6105.114758360101, 6109.040306147876, 6120.325235636639, 6025.608335458356, 6166.445019948047, 6195.4424101831055, 6214.378562143749, 6241.407066328691, 5853.0, 5894.970525147347, 5902.409759024235, 6007.669578173068, 6028.969855150696, 5324.653897496651, 6006.669633170043, 6081.239845019372, 6097.75608975636, 6112.32764395925, 5829.125631155361, 5845.152452894392, 5846.123081537803, 5854.853628659318, 5857.179994800646, 5831.067029275296, 6010.489108425658, 6048.758049678139, 6051.667158306335, 6057.303410107894, 5762.596618236851, 5833.562482813721, 5833.708314584169, 5836.095405212199, 5838.562107842014], "value": 5640.114071762781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.8008566273671, 469.2585714570978, 469.3124572501285, 522.6776256072138, 530.8248278068237, 30036.0, 31512.842435787847, 31515.89694360698, 31543.526847097284, 31934.840325798366, 32032.67967320326, 32502.837485812597, 32551.860684876036, 32590.511142332885, 32652.346953060984, 9525.999770024135, 9826.066523680274, 9835.557399916985, 10020.749481262954, 10039.949800250988, 9790.853137202948, 9888.011198880127, 9900.25748068894, 9906.207503399746, 9913.90086099137, 8796.912030879705, 9860.753481162956, 9897.4556399398, 9900.257480688977, 9932.751681207992, 8842.955785221096, 11868.228565143281, 12078.69803254917, 12116.333601651906, 12331.815022774666, 11908.761824763456, 11985.0, 11986.400679966055, 11992.820107698391, 12003.759924801543, 31417.685823141932, 39060.41409378862, 39488.40767388492, 39534.813955581376, 39567.40648890269, 2781.7913656475644, 3252.284497410566, 3269.7711160218796, 3346.247094403764, 4046.271671099201, 25284.0, 29961.637401294833, 30621.693783062106, 31599.84200079008, 31631.52552711711, 2897.463969165711, 3167.7329068372837, 3180.7614428917923, 3236.4336241157703, 3291.1772056985756, 418966.0, 419871.0, 422718.0, 422879.0, 423733.0, 54534.181987270225, 54888.72555637196, 54986.0, 55040.17439738407, 55125.89748205094, 30530.54204186939, 31701.84149079286, 33700.32599347999, 57870.421295787346, 58102.709486452295, 15419.614509637124, 15911.920440397724, 15935.043897366195, 15936.840631593768, 15978.520644380687, 12133.817992730115, 12222.266664000188, 12225.327606981702, 12251.693707657201, 12268.877311226952, 18414.907925460288, 18498.890066596046, 18501.629967400575, 18508.722369164472, 18526.907365463085, 6403.583767055126, 6846.7945961621235, 7436.293552112541, 7789.337906277917, 7790.688372465152, 833.6457005772522, 894.608883186638, 969.5782334684435, 1025.270185935467, 1037.7665025369383, 26140.0, 28054.877804887576, 29118.417631647673, 29174.41651166965, 29820.956266531, 1098.2531878322657, 1099.378850949211, 1101.2181351240708, 1132.4507613807298, 1174.107678164584, 13407.664808379868, 14799.484087705116, 15262.236888155549, 15277.541673749805, 15643.296051677702, 533080.0, 536324.0, 536866.3156684191, 537936.0, 538803.7538310785, 55478.16782748262, 56060.15909761357, 56060.43939560552, 56154.43845561574, 56184.0, 1219.3354621731178, 1259.4647274908311, 1334.0994828490536, 1367.1796921846812, 1394.35859504629, 29022.419551609273, 30281.091567253017, 30318.696813031587, 30513.165059019015, 30937.0, 45874.54125458836, 46413.76793116148, 46498.30252546147, 46502.99999999865, 46595.83510412267, 424284.0, 424341.0, 424884.0, 426017.0, 426045.0, 25036.873340700076, 25347.112851050126, 25947.870260648575, 26305.210843674722, 26904.32739181536, 45004.32493512666, 45297.09405811931, 45699.77150114361, 45817.54182458266, 46066.078678425576, 37006.25987480289, 37115.81442092772, 37431.625683742815, 38867.222655547295, 39344.40983385309, 29302.853485732434, 36748.0, 37242.62757372392, 37755.0, 38211.235775283785, 35094.824525878226, 36488.45267321046, 36792.63207367892, 37334.0, 37471.81264093662, 31837.68162318347, 32068.877589285195, 32582.511262331558, 33238.66761332356, 33398.833005834815, 24498.87750561236, 24673.87663061673, 24834.87582562148, 25027.0, 25433.61849572295], "value": 18954.215379168596, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.60556873517294, 371.383503384382, 373.91960728443377, 385.33529661334194, 405.693701255552, 14872.47946321879, 14954.700905981887, 16573.502794916156, 20047.899760501175, 20881.582368352625, 19383.0, 19732.506687332825, 19950.70073948892, 20130.396088117344, 20137.59724805503, 1615.1359022922738, 1617.7815994840664, 1618.0696099742636, 1619.74893891447, 1835.9443320090934, 3813.1229817142075, 4058.756474611518, 5457.781688732446, 5542.722863856811, 5548.833534993957, 3472.2534655049158, 6729.125213722234, 6748.358905903931, 6779.15260592426, 6779.694913728869, 6101.0848372744, 6171.783987560414, 6178.7219575119225, 6754.966225168867, 6771.4582833373215, 5969.671668058255, 5984.880302393971, 5998.7600495980105, 6010.158577799111, 6014.2181516403025, 16504.0, 24181.87909060461, 24780.38049048788, 24865.875670621714, 25238.0, 1684.61253911601, 2363.2201373546686, 2399.316194884467, 2719.836809791419, 2966.3553639846805, 12130.968867646261, 12923.09538332317, 15338.386464541405, 15509.612259693486, 15584.688306233926, 1525.7101150781427, 1768.4887459807105, 1781.0871183916604, 1805.4132406967788, 1842.9262829486804, 113219.0, 117820.0, 118567.0, 118913.40543297314, 119931.40034299773, 11134.610288639858, 11176.832347514794, 11317.547298107984, 11394.601188958346, 11634.556743083878, 11930.0, 14373.85626143746, 14381.424743010371, 14382.856171438152, 14383.28083595816, 6620.470362371095, 7988.720394786154, 8181.79545511367, 8276.586170691442, 8284.8343033139, 7840.215978402228, 7908.841823163504, 7934.920650793533, 8066.354691624656, 8077.596120193967, 4347.4348334716915, 4444.622207112432, 4456.955430445719, 4509.1658043262105, 4570.268756998864, 1722.5951901303183, 1773.6963331951006, 1830.8901465912088, 1887.3488646416963, 1891.1395315131583, 688.7245101959156, 784.4038530716697, 824.5094168969454, 881.5371929736812, 955.0707570707272, 17627.647447050986, 21156.0, 21164.0, 21221.60575224517, 21433.249836255967, 906.5557876640398, 928.6610387208756, 929.7303781903307, 957.1624828275259, 964.1419136968104, 10216.489175541194, 10756.515956781963, 10797.974192451862, 10802.459877006275, 10839.0, 465289.0, 465409.0, 475840.0, 475900.0, 477454.0, 73074.53850923058, 73263.0, 93640.12719745703, 122873.0, 125769.37115314364, 935.1490143968956, 959.2613687460756, 959.8992105828787, 960.70698436976, 1026.0560284538242, 18903.621927561097, 19702.50743731361, 19708.31020914261, 19735.605287893875, 19803.50491237673, 55849.72075139598, 56113.77724455207, 56231.87536249334, 56273.8745225085, 57399.42600574108, 77372.0, 80796.59601701953, 81100.0, 82293.17706823094, 83029.58485207535, 7870.173631768582, 7886.921130788619, 7946.404019698662, 8274.917250827655, 8317.251447369767, 11453.370064646524, 12563.05777066742, 12856.935715321364, 12943.482260709467, 13051.934740326618, 11137.888621113685, 11314.60398886035, 11569.942150289531, 11634.418279086181, 11653.184277100861, 10901.781964360827, 11018.889811102106, 11104.5558177672, 11218.158638102012, 11331.716707082389, 11069.723256918642, 11354.716132096764, 11501.654950351503, 11581.42092895335, 11587.015103716278, 7883.211678832071, 8019.198080191934, 8155.102938676622, 8338.458000229966, 8359.791005224917, 8502.872456913276, 9523.809523809623, 10909.454527273447, 11317.264377815416, 11410.828837567276], "value": 9052.812948988932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.0719003887323, 1498.5129832804341, 1528.0449718925672, 1731.2296028267426, 1909.3508207209554, 7731.613419329032, 8215.096339402668, 8431.70489032884, 8776.473411595309, 8788.69239576615, 8650.826983460327, 8727.956360218206, 8730.60712267948, 8733.0, 8737.781555461117, 2332.941676458089, 2363.61000434928, 2367.75138610446, 2368.8815559222057, 2369.7037870266217, 2327.0924339507606, 2360.6104992676187, 2376.4058985253687, 2378.9286321410386, 2381.7022872140983, 2337.2871274261342, 2355.705536807899, 2367.3134790910617, 2371.6798232238684, 2377.976220237793, 2317.2353123469215, 2339.7075365579217, 2340.578695834749, 2343.133040774924, 2361.2916125162383, 2302.320815359473, 2335.94160145996, 2336.158982766203, 2341.0986770093637, 2344.5896968030524, 8741.30069594431, 8770.956145219297, 8827.205551500396, 8844.248238899701, 8846.292296616255, 5184.559312458446, 5378.897326048151, 5382.111951527993, 5417.539509141728, 5423.349198096214, 3500.6674365935196, 3659.048647351681, 3700.833462494194, 3740.4763333133383, 3748.3440397930253, 903.5527413930081, 1093.5352475198088, 1094.600470828142, 1118.9048930840888, 1120.199057673759, 11601.593944211912, 11601.651950441501, 11685.415729213506, 11708.88291117095, 11741.647750567581, 4309.07354918694, 4404.559544045634, 4573.63410927125, 4574.382458368078, 4591.357209990604, 9081.727548173565, 9084.727458176265, 9090.0, 9091.136342047495, 9109.954450227839, 5178.585713143015, 5180.352455943007, 5188.5330320270605, 5191.143461328914, 5201.0, 4296.699231053796, 4356.825726970949, 4405.427294451701, 4527.84152554659, 4836.975815120902, 3688.4098544232793, 3757.1546402059316, 3761.7931013794505, 3797.6012518685693, 3812.866549670748, 1151.9769604607861, 1157.3229660648615, 1165.1960147498198, 1172.3434876469205, 1177.011310499185, 546.8550834028986, 799.4403917257943, 890.2121622364255, 906.9047749986288, 908.4276905549461, 5010.624203184776, 5628.04323265046, 5660.971695141498, 5739.713014349266, 6112.144299798032, 909.7088931541844, 954.4178051388648, 965.2181732796457, 971.2618410008445, 1012.7822518158484, 3857.4213867920034, 3982.0841206522664, 4030.2946984277633, 4081.5102187737066, 4082.6121518456293, 10384.532696028697, 10838.837417438745, 10845.51195196218, 10861.83707244392, 10913.618023369145, 8950.0, 10640.0, 10644.627438039632, 10765.56937722498, 10768.676939691823, 6522.206393241897, 8229.0, 8232.259096681328, 8250.504969701837, 8267.255946964802, 5849.5612829037145, 5900.586958912835, 5901.970490147522, 6077.963882099546, 6265.997440409513, 9554.331196816434, 9993.400395976265, 10216.54025568866, 10240.129588985019, 10288.742781430225, 9238.491882946502, 10086.445245511273, 10205.234607404624, 10231.539580719034, 10236.18110551169, 5875.706214689334, 7082.610456424946, 7178.892316615151, 7308.451866109956, 7450.8882366763455, 8056.476329038594, 8058.315043221158, 8134.349252059939, 8208.138145494875, 8212.507249565046, 7378.926210738038, 7411.888821667787, 7433.628318583941, 7458.5151965122095, 7485.70057197706, 7151.892721609284, 7276.96361518189, 7307.451941104545, 7376.37300829414, 7447.255274472726, 6920.757773478105, 6943.132108486439, 7054.153501579844, 7061.399781018466, 7067.293270672892, 4862.781174847069, 4935.580475659609, 4964.3298154748645, 4997.400311962588, 5142.948570514247, 4942.209246520683, 5008.69947803133, 5016.598672106295, 5029.5221953914315, 5115.693058416507], "value": 4519.765104150866, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.2454390392308, 299.4011976047904, 307.5033820380086, 311.93761247750456, 313.5813688725551, 4304.461942257214, 5246.370435547735, 5253.4221235664045, 5275.920861187075, 5284.339457567804, 4171.624553790159, 4371.36615190798, 4897.902041959159, 5087.313212716283, 5235.717249273924, 3307.974527896348, 3864.59421760716, 3985.6014398560055, 4033.838646454138, 4054.533728621202, 3993.28120938231, 4005.4192142139373, 4012.197560487914, 4045.2201031546174, 4048.7368321059184, 3846.499955005845, 3985.501812273466, 3988.905824442162, 3989.3417586098403, 4048.736832105904, 3521.9119522012024, 3988.481497405348, 4022.3162062449455, 4026.134381108061, 4057.736247143926, 3352.9941017694596, 3512.947305790415, 3520.908518359317, 3534.7525673202886, 3551.254236610289, 4101.671866250693, 4861.177058603024, 5121.923171152435, 5138.820141295074, 5159.14874045782, 3989.601039895987, 4368.978155109204, 4447.3773671685985, 4604.5165257647795, 4667.509911459282, 5240.956027149415, 5536.0, 5562.7774889004795, 5565.2486914266465, 5570.6657600544095, 3797.316483033053, 3883.86406475772, 3888.805559722031, 3890.3580909149955, 3891.6692081173132, 4721.409823772028, 4722.929156062662, 5641.548676105943, 5759.280089988752, 5778.566607504412, 3736.831842567091, 3761.9247615047548, 3767.5290588676417, 3802.4866643003393, 3813.3326667833016, 3117.1272043827767, 3374.4094783412806, 3491.092315997808, 3655.5796083450214, 3780.127103401435, 4157.9605098725315, 4367.432233809648, 4510.639148868083, 4521.0, 4544.182047231464, 4373.294415178083, 5482.533984611273, 5607.691576963224, 5620.0, 5635.788305514334, 1149.83327417524, 1288.954886578984, 1298.688314804459, 1315.0, 1324.1260767893145, 817.7424111405007, 841.5147265077144, 845.6258579807812, 852.7569642651814, 871.4727589808201, 5076.314697515788, 5100.132977393852, 5113.1052065888325, 5167.509086636764, 5198.778287102527, 4957.876053098629, 5326.653767505178, 5490.862728431821, 5524.1989911463415, 5557.77768889248, 4279.486461624562, 5081.923771143436, 5102.678281381963, 5103.617228707788, 5229.50319719626, 2557.373443506353, 2563.846169229852, 2898.6086878271585, 2936.2365784895655, 2939.3974235281717, 4776.426828780499, 5800.564957628195, 5825.592208545446, 5831.358550559435, 5842.824715258549, 5341.65279256847, 5425.403205647375, 5551.861203469945, 5695.231143795618, 5946.464818166386, 4910.631702622318, 5919.467247947706, 5941.871044501531, 5967.731452084667, 6024.909626355608, 4958.553730164303, 5660.943390566042, 5668.971655141698, 5681.914771278517, 5751.798687045934, 4473.657902629263, 5663.4991727193155, 5666.461686139975, 5670.999999999835, 5702.429757024431, 5183.611229157904, 5939.732712028055, 5952.494038006818, 5973.790917317873, 6016.398360163948, 3186.840657967046, 3502.2470168913806, 3585.3904836178435, 3719.628037196259, 3786.280606684721, 4728.550787675165, 5633.521150702236, 5677.261955945701, 5686.2323586315315, 5757.424257574209, 4455.0, 4521.7513036781975, 4866.732329721899, 5536.2249285100925, 5593.748281327269, 5050.924236136534, 5325.775071733446, 5534.584906132137, 5573.832785016456, 5617.634853734494, 5116.513931176532, 5141.408737995179, 5288.444713305047, 5331.226972089103, 5409.486098820606, 4224.683148763794, 5234.4983314348865, 5356.946430535801, 5461.508464238238, 5509.228707980806, 5121.564667003197, 5352.036633405869, 5371.758270877742, 5385.111456609616, 5389.218563308376], "value": 3999.148614671545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.384790612402, 764.1708746010578, 776.0570906348788, 808.1110778144039, 808.4623725222727, 13395.732085358288, 14621.268936553182, 15270.16014119223, 15863.68272634548, 15891.761623575641, 14245.78631320531, 14608.561743147697, 14622.049566778178, 14622.415103395879, 14629.122252664822, 683.8051155420706, 850.5704619167327, 872.9388942774009, 873.6068769053919, 876.9386142969963, 693.9375456208941, 698.4866123399297, 839.8950131233596, 861.577826864838, 876.8816209811689, 904.606496174163, 905.0361365146131, 906.3700727994046, 907.9409838360485, 908.3323757038571, 903.6340282185726, 904.5115637555679, 904.8099899021213, 905.4567259644226, 907.7775944893543, 531.2827682628459, 551.892380985708, 657.9901301480481, 680.0717021265986, 685.9847425809779, 14783.0, 14799.556013319618, 14816.851831481763, 14826.851731482762, 14890.404383824634, 13611.0, 13936.303184840717, 14045.367958441895, 14105.2242126683, 14200.0, 12491.814587280276, 12613.495460181583, 12710.364481775874, 13127.409266583027, 13229.272390018545, 4525.728456292633, 4548.7725613719185, 4576.496585375606, 4748.620110391161, 4750.311204875274, 112336.0, 113507.0, 114401.4279928595, 116895.0, 121354.3932280333, 11104.944475277573, 11191.72020699489, 11267.26762760418, 11476.655700329004, 11494.712632184095, 22674.319770406913, 22691.432714182276, 22710.0, 22739.658905116437, 22769.316920492412, 7507.662155203029, 7866.724664636825, 7973.880391794128, 8134.267915887599, 8155.632996515171, 4413.7351758894565, 4415.735055896656, 4417.979064387214, 4423.270160423494, 4456.450361172478, 3391.6777906098882, 3560.8041557714055, 3569.910752231163, 3570.7143428525656, 3575.4458059000895, 648.8540078482304, 653.3597074866657, 660.6168422315046, 661.0810972747788, 663.4857985061519, 617.2716194890073, 617.3795335687605, 621.6674079367517, 627.4502029548635, 627.8650090230623, 8459.238668519863, 8700.738977830675, 8961.462312261285, 9007.0, 9051.502167380726, 445.09645419797863, 540.6539814518633, 541.3314556522691, 586.3432955090241, 628.1896353703762, 4244.0663054128045, 4312.870613881589, 4336.978315108404, 4390.9461729185405, 4500.797464114123, 92883.0711692888, 139430.0, 143016.0, 148838.0, 151505.0, 106031.93968060375, 106332.40501392486, 108893.45553272183, 122357.0, 131095.0, 91785.0, 94075.05924940937, 95306.52346738083, 103704.0, 106745.4662726697, 9346.766330841783, 9562.330636855353, 9654.275929305471, 9695.0, 9723.46520941355, 55390.169147463625, 56160.43839561552, 57746.0, 58946.11580826205, 62783.0, 87493.0, 88666.55666721625, 89488.21023579623, 90852.0, 94125.0, 12795.104342696295, 13202.811746942822, 13371.732565348833, 13505.987050971413, 13811.516596919462, 14512.1292722437, 14764.48324308644, 14846.331915063633, 14855.777163342342, 14861.0, 13646.540212367814, 13750.65623359424, 13893.0, 14089.788653170415, 14542.636434089232, 13480.86519134796, 13689.931550342184, 13733.45066197341, 13886.305684715926, 13923.443062277396, 11017.83473247918, 13333.266670332836, 13479.86520134786, 13585.86414135846, 13717.0, 10787.0, 11168.329900206014, 11236.775264494829, 11453.885461145283, 11515.17878119866, 10480.371177729361, 11190.234992938982, 11315.130086856714, 11591.594294199662, 11597.362145081765], "value": 7757.325728204341, "unit": "score"}], "name": "dromaeo_css", "value": 7168.632031120467, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 117.0, 123.0, 167.0, 146.0, 113.0, 113.0, 113.0, 123.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 130.0, 161.0, 112.0, 160.0, 163.0, 136.0, 160.0, 111.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 199.0, 264.0, 184.0, 184.0, 185.0, 185.0, 186.0, 181.0, 223.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 77.0, 165.0, 59.0, 59.0, 58.0, 59.0, 152.0, 64.0], "value": 84.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 136.0, 70.0, 70.0, 100.0, 145.0, 98.0, 99.0, 109.0], "value": 99.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 85.0, 87.0, 84.0, 87.0, 85.0, 87.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 101.0, 132.0, 93.0, 93.0, 92.0, 93.0, 93.0, 94.0, 94.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 85.0, 90.0, 84.0, 83.0, 106.0, 136.0, 113.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 57.0, 55.0, 56.0, 54.0, 54.0, 55.0, 54.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 72.0, 80.0, 76.0, 72.0, 72.0, 72.0, 73.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 83.0, 85.0, 85.0, 88.0, 85.0, 90.0, 85.0, 91.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 129.0, 148.0, 130.0, 121.0, 146.0, 146.0, 151.0, 199.0, 139.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 168.0, 170.0, 170.0, 172.0, 169.0, 170.0, 172.0, 171.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 68.0, 69.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1520.4, "alertThreshold": 2.0}]} 05:31:51 INFO - Return code: 0 05:31:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:31:51 INFO - # TBPL SUCCESS # 05:31:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:51 INFO - 2016-09-09 05:31:51.644 screenresolution[1669:9755] starting screenresolution argv=screenresolution get 05:31:51 INFO - 2016-09-09 05:31:51.647 screenresolution[1669:9755] Display 0: 1600x1200x32@60 05:31:51 INFO - 2016-09-09 05:31:51.653 screenresolution[1670:9758] starting screenresolution argv=screenresolution list 05:31:51 INFO - Available Modes on Display 0 05:31:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:51 INFO - Intel Iris: 05:31:51 INFO - Chipset Model: Intel Iris 05:31:51 INFO - Type: GPU 05:31:51 INFO - Bus: Built-In 05:31:51 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:51 INFO - Vendor: Intel (0x8086) 05:31:51 INFO - Device ID: 0x0a2e 05:31:51 INFO - Revision ID: 0x0009 05:31:51 INFO - Displays: 05:31:51 INFO - Display: 05:31:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:51 INFO - Display Serial Number: 1600x1200 60 05:31:51 INFO - Main Display: Yes 05:31:51 INFO - Mirror: Off 05:31:51 INFO - Online: Yes 05:31:51 INFO - Rotation: Supported 05:31:51 INFO - Return code: 0 05:31:51 INFO - Running post-action listener: _resource_record_post_action 05:31:51 INFO - [mozharness: 2016-09-09 12:31:51.887469Z] Finished run-tests step (success) 05:31:51 INFO - Running post-run listener: _resource_record_post_run 05:31:51 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37389312; Write bytes: 705419264; Read time: 243; Write time: 1945 05:31:51 INFO - TinderboxPrint: CPU usage
53.4% 05:31:51 INFO - TinderboxPrint: I/O read bytes / time
37,389,312 / 243 05:31:51 INFO - TinderboxPrint: I/O write bytes / time
705,419,264 / 1,945 05:31:51 INFO - TinderboxPrint: CPU idle
2,260.9 (46.6%) 05:31:51 INFO - TinderboxPrint: CPU system
968.2 (19.9%) 05:31:51 INFO - TinderboxPrint: CPU user
1,624.6 (33.5%) 05:31:51 INFO - TinderboxPrint: Swap in / out
443,101,184 / 0 05:31:51 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396530176; Write bytes: 388408832; Read time: 29587; Write time: 779 05:31:51 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35484672; Write bytes: 296178176; Read time: 225; Write time: 1112 05:31:52 INFO - Running post-run listener: _upload_blobber_files 05:31:52 INFO - Blob upload gear active. 05:31:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:31:52 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:31:52 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:31:52 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:31:52 INFO - (blobuploader) - INFO - Open directory for files ... 05:31:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:31:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:31:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:53 INFO - (blobuploader) - INFO - Done attempting. 05:31:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:31:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:31:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:55 INFO - (blobuploader) - INFO - Done attempting. 05:31:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:31:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:57 INFO - (blobuploader) - INFO - Done attempting. 05:31:57 INFO - (blobuploader) - INFO - Iteration through files over. 05:31:57 INFO - Return code: 0 05:31:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:31:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:31:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0126ff6a39b822a3174c4472db833690e15d63e4d76e089ba6d3d8ea79f165aa4e88d378d30a0d1bbd7c08269daa3c836c9d1976ca25ec2d2d1928e6b91dec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10f5b1f162b256a6d09e7c8f852d84e01fa6dfb8df7fd8c3e2f9bedb779a41aebaae19c19a737fd5c6d516d61495846add34f0027ca283dedd6f7b76148da32e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db2f977f126f61b2de6c43daba3f7f2b44369d002c8717cea329459fe6f94c57f99eacd62d265ac737ee3f1b20ede1a234095ef134c62a13029c8eb0d6c168f5"} 05:31:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:31:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:31:57 INFO - Contents: 05:31:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0126ff6a39b822a3174c4472db833690e15d63e4d76e089ba6d3d8ea79f165aa4e88d378d30a0d1bbd7c08269daa3c836c9d1976ca25ec2d2d1928e6b91dec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10f5b1f162b256a6d09e7c8f852d84e01fa6dfb8df7fd8c3e2f9bedb779a41aebaae19c19a737fd5c6d516d61495846add34f0027ca283dedd6f7b76148da32e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db2f977f126f61b2de6c43daba3f7f2b44369d002c8717cea329459fe6f94c57f99eacd62d265ac737ee3f1b20ede1a234095ef134c62a13029c8eb0d6c168f5"} 05:31:57 INFO - Running post-run listener: copy_logs_to_upload_dir 05:31:57 INFO - Copying logs to upload dir... 05:31:57 INFO - mkdir: /builds/slave/test/build/upload/logs 05:31:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.793547 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-09 05:31:57.912228) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 05:31:57.916291) ========= 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.RTPeEfogyk/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.RB2myVhg4u/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0126ff6a39b822a3174c4472db833690e15d63e4d76e089ba6d3d8ea79f165aa4e88d378d30a0d1bbd7c08269daa3c836c9d1976ca25ec2d2d1928e6b91dec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10f5b1f162b256a6d09e7c8f852d84e01fa6dfb8df7fd8c3e2f9bedb779a41aebaae19c19a737fd5c6d516d61495846add34f0027ca283dedd6f7b76148da32e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db2f977f126f61b2de6c43daba3f7f2b44369d002c8717cea329459fe6f94c57f99eacd62d265ac737ee3f1b20ede1a234095ef134c62a13029c8eb0d6c168f5"} build_url:https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011232 build_url: 'https://queue.taskcluster.net/v1/task/eIa_-9KIQW6O23og-LoYZw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0126ff6a39b822a3174c4472db833690e15d63e4d76e089ba6d3d8ea79f165aa4e88d378d30a0d1bbd7c08269daa3c836c9d1976ca25ec2d2d1928e6b91dec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10f5b1f162b256a6d09e7c8f852d84e01fa6dfb8df7fd8c3e2f9bedb779a41aebaae19c19a737fd5c6d516d61495846add34f0027ca283dedd6f7b76148da32e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db2f977f126f61b2de6c43daba3f7f2b44369d002c8717cea329459fe6f94c57f99eacd62d265ac737ee3f1b20ede1a234095ef134c62a13029c8eb0d6c168f5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 05:31:57.956120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:31:57.956698) ========= 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.RTPeEfogyk/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.RB2myVhg4u/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.005083 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:31:58.006955) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 05:31:58.007309) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 05:31:58.007745) ========= ========= Total master_lag: 0.19 =========