builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0265 starttime: 1473684992.68 results: success (0) buildid: 20160912040616 builduid: 2b400616e89747589aa2ff31ec6bb707 revision: 3d667f260feb3f7f30fca84f608b92e330aa349c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:32.682628) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:32.683579) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:32.684168) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:32.710362) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:32.711038) ========= 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.930tcDITCU/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.E6vLSAwS5j/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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-12 05:56:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-09-12 05:56:32 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130374 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:32.869151) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:32.869520) ========= 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.930tcDITCU/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.E6vLSAwS5j/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.020547 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:32.924211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-12 05:56:32.924616) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3d667f260feb3f7f30fca84f608b92e330aa349c --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 3d667f260feb3f7f30fca84f608b92e330aa349c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.930tcDITCU/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.E6vLSAwS5j/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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-12 05:56:33,010 truncating revision to first 12 chars 2016-09-12 05:56:33,011 Setting DEBUG logging. 2016-09-12 05:56:33,011 attempt 1/10 2016-09-12 05:56:33,011 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3d667f260feb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 05:56:34,897 unpacking tar archive at: mozilla-central-3d667f260feb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.138441 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-12 05:56:35.091850) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:35.092597) ========= script_repo_revision: 3d667f260feb3f7f30fca84f608b92e330aa349c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:35.093689) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:35.094495) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:56:35.116127) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-12 05:56:35.116827) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.930tcDITCU/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.E6vLSAwS5j/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:56:35 INFO - MultiFileLogger online at 20160912 05:56:35 in /builds/slave/test 05:56:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:56:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:56:35 INFO - {'append_to_log': False, 05:56:35 INFO - 'base_work_dir': '/builds/slave/test', 05:56:35 INFO - 'blob_upload_branch': 'Firefox', 05:56:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:56:35 INFO - 'branch': 'Firefox', 05:56:35 INFO - 'buildbot_json_path': 'buildprops.json', 05:56:35 INFO - 'config_files': ('talos/mac_config.py',), 05:56:35 INFO - 'default_actions': ('clobber', 05:56:35 INFO - 'read-buildbot-config', 05:56:35 INFO - 'download-and-extract', 05:56:35 INFO - 'populate-webroot', 05:56:35 INFO - 'create-virtualenv', 05:56:35 INFO - 'install', 05:56:35 INFO - 'run-tests'), 05:56:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:56:35 INFO - 'download_minidump_stackwalk': True, 05:56:35 INFO - 'download_symbols': 'ondemand', 05:56:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:56:35 INFO - 'tooltool.py': '/tools/tooltool.py', 05:56:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:56:35 INFO - '/tools/misc-python/virtualenv.py')}, 05:56:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:56:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:56:35 INFO - 'installer_path': 'installer.exe', 05:56:35 INFO - 'log_level': 'info', 05:56:35 INFO - 'log_name': 'talos', 05:56:35 INFO - 'log_to_console': True, 05:56:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:56:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:56:35 INFO - 'opt_config_files': (), 05:56:35 INFO - 'pip_index': False, 05:56:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:56:35 INFO - 'cmd': ('bash', 05:56:35 INFO - '-c', 05:56:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:56:35 INFO - 'enabled': True, 05:56:35 INFO - 'halt_on_failure': False, 05:56:35 INFO - 'name': 'check_screen_resolution'},), 05:56:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:56:35 INFO - 'cmd': ('bash', 05:56:35 INFO - '-c', 05:56:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:56:35 INFO - 'enabled': True, 05:56:35 INFO - 'halt_on_failure': False, 05:56:35 INFO - 'name': 'check_screen_resolution'},), 05:56:35 INFO - 'run_cmd_checks_enabled': True, 05:56:35 INFO - 'sps_profile': False, 05:56:35 INFO - 'sps_profile_interval': 0, 05:56:35 INFO - 'suite': 'dromaeojs-e10s', 05:56:35 INFO - 'system_bits': '32', 05:56:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:56:35 INFO - 'title': 't-yosemite-r7-0265', 05:56:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:56:35 INFO - 'use_talos_json': True, 05:56:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:56:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:56:35 INFO - 'work_dir': 'build'} 05:56:35 INFO - [mozharness: 2016-09-12 12:56:35.313162Z] Running clobber step. 05:56:35 INFO - Running pre-action listener: _resource_record_pre_action 05:56:35 INFO - Running main action method: clobber 05:56:35 INFO - rmtree: /builds/slave/test/build 05:56:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:56:36 INFO - Running post-action listener: _resource_record_post_action 05:56:36 INFO - [mozharness: 2016-09-12 12:56:36.352430Z] Finished clobber step (success) 05:56:36 INFO - [mozharness: 2016-09-12 12:56:36.352600Z] Running read-buildbot-config step. 05:56:36 INFO - Running pre-action listener: _resource_record_pre_action 05:56:36 INFO - Running main action method: read_buildbot_config 05:56:36 INFO - Using buildbot properties: 05:56:36 INFO - { 05:56:36 INFO - "project": "", 05:56:36 INFO - "product": "firefox", 05:56:36 INFO - "script_repo_revision": "production", 05:56:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:56:36 INFO - "repository": "", 05:56:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:56:36 INFO - "buildid": "20160912040616", 05:56:36 INFO - "pgo_build": "False", 05:56:36 INFO - "basedir": "/builds/slave/test", 05:56:36 INFO - "buildnumber": 55, 05:56:36 INFO - "slavename": "t-yosemite-r7-0265", 05:56:36 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:56:36 INFO - "platform": "yosemite_r7", 05:56:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:56:36 INFO - "branch": "mozilla-central", 05:56:36 INFO - "repo_path": "mozilla-central", 05:56:36 INFO - "revision": "3d667f260feb3f7f30fca84f608b92e330aa349c", 05:56:36 INFO - "stage_platform": "macosx64", 05:56:36 INFO - "builduid": "2b400616e89747589aa2ff31ec6bb707", 05:56:36 INFO - "slavebuilddir": "test" 05:56:36 INFO - } 05:56:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:56:36 INFO - Running post-action listener: _resource_record_post_action 05:56:36 INFO - [mozharness: 2016-09-12 12:56:36.355599Z] Finished read-buildbot-config step (success) 05:56:36 INFO - [mozharness: 2016-09-12 12:56:36.355708Z] Running download-and-extract step. 05:56:36 INFO - Running pre-action listener: _resource_record_pre_action 05:56:36 INFO - Running main action method: download_and_extract 05:56:36 INFO - mkdir: /builds/slave/test/build/tests 05:56:36 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:56:36 INFO - trying https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:56:36 INFO - Downloading https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/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:56:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/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:56:37 INFO - Downloaded 1199 bytes. 05:56:37 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:56:37 INFO - Using the following test package requirements: 05:56:37 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:56:37 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:56:37 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:56:37 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:56:37 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:56:37 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:56:37 INFO - u'jsshell-mac.zip'], 05:56:37 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:56:37 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:56:37 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:56:37 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:56:37 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:56:37 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:56:37 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:56:37 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:56:37 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:56:37 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:56:37 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:56:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:56:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:56:37 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:56:44 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:56:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:56:45 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:56:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:56:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:56:54 INFO - trying https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:56:54 INFO - Downloading https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:56:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/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:56:57 INFO - Downloaded 111647173 bytes. 05:56:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:56:57 INFO - mkdir: /builds/slave/test/properties 05:56:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:56:57 INFO - Writing to file /builds/slave/test/properties/build_url 05:56:57 INFO - Contents: 05:56:57 INFO - build_url:https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:56:57 INFO - Running post-action listener: _resource_record_post_action 05:56:57 INFO - Running post-action listener: set_extra_try_arguments 05:56:57 INFO - [mozharness: 2016-09-12 12:56:57.665615Z] Finished download-and-extract step (success) 05:56:57 INFO - [mozharness: 2016-09-12 12:56:57.665731Z] Running populate-webroot step. 05:56:57 INFO - Running pre-action listener: _resource_record_pre_action 05:56:57 INFO - Running main action method: populate_webroot 05:56:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:56:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:56:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:56:57 INFO - '--tppagecycles', 05:56:57 INFO - '7'], 05:56:57 INFO - 'tests': ['tsvgm']}}, 05:56:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:56:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:56:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:56:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:56:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:57 INFO - 'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:56:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:56:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:57 INFO - 'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['damp', 'tps']}, 05:56:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:57 INFO - 'tests': ['damp', 'tps']}, 05:56:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['dromaeo_dom']}, 05:56:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:56:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['basic_compositor_video']}, 05:56:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:56:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['a11yr', 05:56:57 INFO - 'ts_paint', 05:56:57 INFO - 'tpaint', 05:56:57 INFO - 'sessionrestore', 05:56:57 INFO - 'sessionrestore_no_auto_restore', 05:56:57 INFO - 'tabpaint']}, 05:56:57 INFO - 'other-e10s': {'tests': ['a11yr', 05:56:57 INFO - 'ts_paint', 05:56:57 INFO - 'tpaint', 05:56:57 INFO - 'sessionrestore', 05:56:57 INFO - 'sessionrestore_no_auto_restore', 05:56:57 INFO - 'tabpaint']}, 05:56:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:56:57 INFO - 'ts_paint', 05:56:57 INFO - 'tpaint', 05:56:57 INFO - 'sessionrestore', 05:56:57 INFO - 'sessionrestore_no_auto_restore', 05:56:57 INFO - 'tabpaint']}, 05:56:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:56:57 INFO - 'ts_paint', 05:56:57 INFO - 'tpaint', 05:56:57 INFO - 'sessionrestore', 05:56:57 INFO - 'sessionrestore_no_auto_restore', 05:56:57 INFO - 'tabpaint']}, 05:56:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['a11yr', 05:56:57 INFO - 'ts_paint', 05:56:57 INFO - 'tpaint', 05:56:57 INFO - 'sessionrestore', 05:56:57 INFO - 'sessionrestore_no_auto_restore', 05:56:57 INFO - 'tabpaint']}, 05:56:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['a11yr', 05:56:57 INFO - 'ts_paint', 05:56:57 INFO - 'tpaint', 05:56:57 INFO - 'sessionrestore', 05:56:57 INFO - 'sessionrestore_no_auto_restore', 05:56:57 INFO - 'tabpaint']}, 05:56:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['tsvgx', 05:56:57 INFO - 'tsvgr_opacity', 05:56:57 INFO - 'tart', 05:56:57 INFO - 'tscrollx', 05:56:57 INFO - 'cart']}, 05:56:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:56:57 INFO - 'tsvgr_opacity', 05:56:57 INFO - 'tart', 05:56:57 INFO - 'tscrollx', 05:56:57 INFO - 'cart']}, 05:56:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:57 INFO - 'talos_options': ['--disable-e10s'], 05:56:57 INFO - 'tests': ['tp5o']}, 05:56:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:57 INFO - 'tests': ['tp5o']}, 05:56:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:57 INFO - 'talos_options': ['--disable-e10s', 05:56:57 INFO - '--xperf_path', 05:56:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:56:57 INFO - 'tests': ['tp5n']}, 05:56:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:57 INFO - 'talos_options': ['--xperf_path', 05:56:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:56:57 INFO - 'tests': ['tp5n']}}, 05:56:57 INFO - 'talos.zip': {'path': '', 05:56:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:56:57 INFO - Running post-action listener: _resource_record_post_action 05:56:57 INFO - [mozharness: 2016-09-12 12:56:57.684479Z] Finished populate-webroot step (success) 05:56:57 INFO - [mozharness: 2016-09-12 12:56:57.684591Z] Running create-virtualenv step. 05:56:57 INFO - Running pre-action listener: _resource_record_pre_action 05:56:57 INFO - Running main action method: create_virtualenv 05:56:57 INFO - Creating virtualenv /builds/slave/test/build/venv 05:56:57 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:56:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:56:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:56:57 INFO - Using real prefix '/tools/python27' 05:56:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:56:58 INFO - Installing distribute.............................................................................................................................................................................................done. 05:57:01 INFO - Installing pip.................done. 05:57:01 INFO - Return code: 0 05:57:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:57:01 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:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:01 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:57:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:57:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:57:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:01 INFO - 'HOME': '/Users/cltbld', 05:57:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:01 INFO - 'LOGNAME': 'cltbld', 05:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:01 INFO - 'MOZ_NO_REMOTE': '1', 05:57:01 INFO - 'NO_EM_RESTART': '1', 05:57:01 INFO - 'PAGER': '/bin/cat', 05:57:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:01 INFO - 'PWD': '/builds/slave/test', 05:57:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:01 INFO - 'SHELL': '/bin/bash', 05:57:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:01 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:01 INFO - 'USER': 'cltbld', 05:57:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:01 INFO - 'XPC_FLAGS': '0x0', 05:57:01 INFO - 'XPC_SERVICE_NAME': '0', 05:57:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:01 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:57:01 INFO - Cleaning up... 05:57:01 INFO - Return code: 0 05:57:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:57:01 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:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:01 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:57:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:57:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:57:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:01 INFO - 'HOME': '/Users/cltbld', 05:57:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:01 INFO - 'LOGNAME': 'cltbld', 05:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:01 INFO - 'MOZ_NO_REMOTE': '1', 05:57:01 INFO - 'NO_EM_RESTART': '1', 05:57:01 INFO - 'PAGER': '/bin/cat', 05:57:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:01 INFO - 'PWD': '/builds/slave/test', 05:57:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:01 INFO - 'SHELL': '/bin/bash', 05:57:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:01 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:01 INFO - 'USER': 'cltbld', 05:57:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:01 INFO - 'XPC_FLAGS': '0x0', 05:57:01 INFO - 'XPC_SERVICE_NAME': '0', 05:57:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:01 INFO - Downloading/unpacking psutil>=3.1.1 05:57:01 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:57:01 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:57:01 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:57:01 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:57:01 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:57:01 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:57:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:57:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:04 INFO - Installing collected packages: psutil 05:57:04 INFO - Running setup.py install for psutil 05:57:04 INFO - building 'psutil._psutil_osx' extension 05:57:04 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:57:05 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:57:05 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:57:05 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:57:05 INFO - building 'psutil._psutil_posix' extension 05:57:05 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:57:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:57:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:57:05 INFO - ^ 05:57:05 INFO - 1 warning generated. 05:57:05 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:57:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:05 INFO - Successfully installed psutil 05:57:05 INFO - Cleaning up... 05:57:05 INFO - Return code: 0 05:57:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:57:05 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:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:05 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:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:05 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 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:05 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:57:05 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:57:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:05 INFO - 'HOME': '/Users/cltbld', 05:57:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:05 INFO - 'LOGNAME': 'cltbld', 05:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:05 INFO - 'MOZ_NO_REMOTE': '1', 05:57:05 INFO - 'NO_EM_RESTART': '1', 05:57:05 INFO - 'PAGER': '/bin/cat', 05:57:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:05 INFO - 'PWD': '/builds/slave/test', 05:57:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:05 INFO - 'SHELL': '/bin/bash', 05:57:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:05 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:05 INFO - 'USER': 'cltbld', 05:57:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:05 INFO - 'XPC_FLAGS': '0x0', 05:57:05 INFO - 'XPC_SERVICE_NAME': '0', 05:57:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:57:06 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:57:06 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:57:06 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:57:06 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:57:06 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:57:06 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:57:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:57:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:57:08 INFO - Installing collected packages: mozsystemmonitor 05:57:08 INFO - Running setup.py install for mozsystemmonitor 05:57:08 INFO - Successfully installed mozsystemmonitor 05:57:08 INFO - Cleaning up... 05:57:08 INFO - Return code: 0 05:57:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:08 INFO - 'HOME': '/Users/cltbld', 05:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:08 INFO - 'LOGNAME': 'cltbld', 05:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:08 INFO - 'MOZ_NO_REMOTE': '1', 05:57:08 INFO - 'NO_EM_RESTART': '1', 05:57:08 INFO - 'PAGER': '/bin/cat', 05:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:08 INFO - 'PWD': '/builds/slave/test', 05:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:08 INFO - 'SHELL': '/bin/bash', 05:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:08 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:08 INFO - 'USER': 'cltbld', 05:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:08 INFO - 'XPC_FLAGS': '0x0', 05:57:08 INFO - 'XPC_SERVICE_NAME': '0', 05:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:08 INFO - Downloading/unpacking jsonschema==2.5.1 05:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:57:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:57: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:57: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:57: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:57: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:57: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:57: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:57:11 INFO - Downloading functools32-3.2.3-2.tar.gz 05:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:57:11 INFO - warning: no files found matching '*.txt' 05:57:11 INFO - no previously-included directories found matching 'build' 05:57:11 INFO - no previously-included directories found matching 'dist' 05:57:11 INFO - no previously-included directories found matching '.git*' 05:57:11 INFO - Installing collected packages: jsonschema, functools32 05:57:11 INFO - Running setup.py install for functools32 05:57:12 INFO - warning: no files found matching '*.txt' 05:57:12 INFO - no previously-included directories found matching 'build' 05:57:12 INFO - no previously-included directories found matching 'dist' 05:57:12 INFO - no previously-included directories found matching '.git*' 05:57:12 INFO - Successfully installed jsonschema functools32 05:57:12 INFO - Cleaning up... 05:57:12 INFO - Return code: 0 05:57:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:57: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:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:12 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 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:12 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:57:12 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:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:12 INFO - 'HOME': '/Users/cltbld', 05:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:12 INFO - 'LOGNAME': 'cltbld', 05:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:12 INFO - 'MOZ_NO_REMOTE': '1', 05:57:12 INFO - 'NO_EM_RESTART': '1', 05:57:12 INFO - 'PAGER': '/bin/cat', 05:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:12 INFO - 'PWD': '/builds/slave/test', 05:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:12 INFO - 'SHELL': '/bin/bash', 05:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:12 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:12 INFO - 'USER': 'cltbld', 05:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:12 INFO - 'XPC_FLAGS': '0x0', 05:57:12 INFO - 'XPC_SERVICE_NAME': '0', 05:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:57:12 INFO - Cleaning up... 05:57:12 INFO - Return code: 0 05:57:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:57: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:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:12 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 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:12 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:57:12 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:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:12 INFO - 'HOME': '/Users/cltbld', 05:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:12 INFO - 'LOGNAME': 'cltbld', 05:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:12 INFO - 'MOZ_NO_REMOTE': '1', 05:57:12 INFO - 'NO_EM_RESTART': '1', 05:57:12 INFO - 'PAGER': '/bin/cat', 05:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:12 INFO - 'PWD': '/builds/slave/test', 05:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:12 INFO - 'SHELL': '/bin/bash', 05:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:12 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:12 INFO - 'USER': 'cltbld', 05:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:12 INFO - 'XPC_FLAGS': '0x0', 05:57:12 INFO - 'XPC_SERVICE_NAME': '0', 05:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:12 INFO - Downloading/unpacking blobuploader==1.2.4 05:57:12 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:57:12 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:57:12 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:57:12 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:57:12 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:57:12 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:57:15 INFO - Downloading blobuploader-1.2.4.tar.gz 05:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:57:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:57:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:16 INFO - Downloading docopt-0.6.1.tar.gz 05:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:57:16 INFO - Installing collected packages: blobuploader, requests, docopt 05:57:16 INFO - Running setup.py install for blobuploader 05:57:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:57:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:57:16 INFO - Running setup.py install for requests 05:57:17 INFO - Running setup.py install for docopt 05:57:17 INFO - Successfully installed blobuploader requests docopt 05:57:17 INFO - Cleaning up... 05:57:17 INFO - Return code: 0 05:57:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:17 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:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:17 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:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:17 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 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:17 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:57:17 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:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:17 INFO - 'HOME': '/Users/cltbld', 05:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:17 INFO - 'LOGNAME': 'cltbld', 05:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:17 INFO - 'MOZ_NO_REMOTE': '1', 05:57:17 INFO - 'NO_EM_RESTART': '1', 05:57:17 INFO - 'PAGER': '/bin/cat', 05:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:17 INFO - 'PWD': '/builds/slave/test', 05:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:17 INFO - 'SHELL': '/bin/bash', 05:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:17 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:17 INFO - 'USER': 'cltbld', 05:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:17 INFO - 'XPC_FLAGS': '0x0', 05:57:17 INFO - 'XPC_SERVICE_NAME': '0', 05:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:17 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-JXARZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:17 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-7sxj2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:17 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-4DPR1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:17 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-JN_8wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-odi11W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-yeAXam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-LygfxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-FnFG2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Va5oZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Sr6kK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-SW23aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-zu_cin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-41c36b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:18 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-x6wO1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:19 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Fs3DMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:19 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-oD6SNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:19 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-CZYVf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:57:19 INFO - Running setup.py install for manifestparser 05:57:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:57:19 INFO - Running setup.py install for mozcrash 05:57:19 INFO - Running setup.py install for mozdebug 05:57:19 INFO - Running setup.py install for mozdevice 05:57:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:57:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:57:20 INFO - Running setup.py install for mozfile 05:57:20 INFO - Running setup.py install for mozhttpd 05:57:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:57:20 INFO - Running setup.py install for mozinfo 05:57:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:57:20 INFO - Running setup.py install for mozInstall 05:57:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:57:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:57:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:57:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:57:20 INFO - Running setup.py install for mozleak 05:57:20 INFO - Running setup.py install for mozlog 05:57:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:57:20 INFO - Running setup.py install for moznetwork 05:57:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:57:21 INFO - Running setup.py install for mozprocess 05:57:21 INFO - Running setup.py install for mozprofile 05:57:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:57:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:57:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:57:21 INFO - Running setup.py install for mozrunner 05:57:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:57:21 INFO - Running setup.py install for mozscreenshot 05:57:21 INFO - Running setup.py install for moztest 05:57:21 INFO - Running setup.py install for mozversion 05:57:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:57:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:57:21 INFO - Cleaning up... 05:57:21 INFO - Return code: 0 05:57:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:21 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:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 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:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 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 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:21 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:57:21 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:57:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:21 INFO - 'HOME': '/Users/cltbld', 05:57:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:21 INFO - 'LOGNAME': 'cltbld', 05:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:21 INFO - 'MOZ_NO_REMOTE': '1', 05:57:21 INFO - 'NO_EM_RESTART': '1', 05:57:21 INFO - 'PAGER': '/bin/cat', 05:57:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:21 INFO - 'PWD': '/builds/slave/test', 05:57:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:21 INFO - 'SHELL': '/bin/bash', 05:57:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:21 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:21 INFO - 'USER': 'cltbld', 05:57:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:21 INFO - 'XPC_FLAGS': '0x0', 05:57:21 INFO - 'XPC_SERVICE_NAME': '0', 05:57:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:22 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-7l525m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:22 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:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:22 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-0YS6_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:22 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:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:22 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-0fgMPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:22 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:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:22 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-2WLtYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:22 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:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:22 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-vy2MGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:22 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:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:22 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-gFmkmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:22 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:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:22 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Nj2iU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:22 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:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:22 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-TjPO4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Gj8xFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-MrSgLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-sTR5AO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-E5SYVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-3ZX04z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-p8iRSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-_GZZ1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-ri2nUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:23 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-ojYCSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:24 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:57:24 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:57:24 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:57:24 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:57:24 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:57:24 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:57:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:26 INFO - Downloading blessings-1.6.tar.gz 05:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:57:27 INFO - Installing collected packages: blessings 05:57:27 INFO - Running setup.py install for blessings 05:57:27 INFO - Successfully installed blessings 05:57:27 INFO - Cleaning up... 05:57:27 INFO - Return code: 0 05:57:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:27 INFO - Reading from file tmpfile_stdout 05:57:27 INFO - Current package versions: 05:57:27 INFO - blessings == 1.6 05:57:27 INFO - blobuploader == 1.2.4 05:57:27 INFO - docopt == 0.6.1 05:57:27 INFO - functools32 == 3.2.3-2 05:57:27 INFO - jsonschema == 2.5.1 05:57:27 INFO - manifestparser == 1.1 05:57:27 INFO - mozInstall == 1.12 05:57:27 INFO - mozcrash == 0.17 05:57:27 INFO - mozdebug == 0.1 05:57:27 INFO - mozdevice == 0.48 05:57:27 INFO - mozfile == 1.2 05:57:27 INFO - mozhttpd == 0.7 05:57:27 INFO - mozinfo == 0.9 05:57:27 INFO - mozleak == 0.1 05:57:27 INFO - mozlog == 3.3 05:57:27 INFO - moznetwork == 0.27 05:57:27 INFO - mozprocess == 0.23 05:57:27 INFO - mozprofile == 0.28 05:57:27 INFO - mozrunner == 6.12 05:57:27 INFO - mozscreenshot == 0.1 05:57:27 INFO - mozsystemmonitor == 0.3 05:57:27 INFO - moztest == 0.7 05:57:27 INFO - mozversion == 1.4 05:57:27 INFO - psutil == 3.1.1 05:57:27 INFO - requests == 1.2.3 05:57:27 INFO - wsgiref == 0.1.2 05:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57: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:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:27 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 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:27 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:57:27 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:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:27 INFO - 'HOME': '/Users/cltbld', 05:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:27 INFO - 'LOGNAME': 'cltbld', 05:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:27 INFO - 'MOZ_NO_REMOTE': '1', 05:57:27 INFO - 'NO_EM_RESTART': '1', 05:57:27 INFO - 'PAGER': '/bin/cat', 05:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:27 INFO - 'PWD': '/builds/slave/test', 05:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:27 INFO - 'SHELL': '/bin/bash', 05:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:27 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:27 INFO - 'USER': 'cltbld', 05:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:27 INFO - 'XPC_FLAGS': '0x0', 05:57:27 INFO - 'XPC_SERVICE_NAME': '0', 05:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:27 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:57:27 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:57:27 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:57:27 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:57:27 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:57:27 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:57:27 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:57:27 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:57:27 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:57:27 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:57:27 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:57:27 INFO - Cleaning up... 05:57:27 INFO - Return code: 0 05:57:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:57: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:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10731a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd63c881d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, '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.930tcDITCU/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.E6vLSAwS5j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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:57:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:27 INFO - 'HOME': '/Users/cltbld', 05:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:27 INFO - 'LOGNAME': 'cltbld', 05:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:27 INFO - 'MOZ_NO_REMOTE': '1', 05:57:27 INFO - 'NO_EM_RESTART': '1', 05:57:27 INFO - 'PAGER': '/bin/cat', 05:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:27 INFO - 'PWD': '/builds/slave/test', 05:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:27 INFO - 'SHELL': '/bin/bash', 05:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:57:27 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:27 INFO - 'USER': 'cltbld', 05:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:27 INFO - 'XPC_FLAGS': '0x0', 05:57:27 INFO - 'XPC_SERVICE_NAME': '0', 05:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:57:28 INFO - Cleaning up... 05:57:28 INFO - Return code: 0 05:57:28 INFO - Running post-action listener: _resource_record_post_action 05:57:28 INFO - Running post-action listener: _start_resource_monitoring 05:57:28 INFO - Starting resource monitoring. 05:57:28 INFO - [mozharness: 2016-09-12 12:57:28.182314Z] Finished create-virtualenv step (success) 05:57:28 INFO - [mozharness: 2016-09-12 12:57:28.183332Z] Running install step. 05:57:28 INFO - Running pre-action listener: _resource_record_pre_action 05:57:28 INFO - Running main action method: install 05:57:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:28 INFO - Reading from file tmpfile_stdout 05:57:28 INFO - Detecting whether we're running mozinstall >=1.0... 05:57:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:57:28 INFO - Reading from file tmpfile_stdout 05:57:28 INFO - Output received: 05:57:28 INFO - Usage: mozinstall [options] installer 05:57:28 INFO - Options: 05:57:28 INFO - -h, --help show this help message and exit 05:57:28 INFO - -d DEST, --destination=DEST 05:57:28 INFO - Directory to install application into. [default: 05:57:28 INFO - "/builds/slave/test"] 05:57:28 INFO - --app=APP Application being installed. [default: firefox] 05:57:28 INFO - mkdir: /builds/slave/test/build/application 05:57:28 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:57:28 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:58:02 INFO - Reading from file tmpfile_stdout 05:58:02 INFO - Output received: 05:58:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:58:02 INFO - Running post-action listener: _resource_record_post_action 05:58:02 INFO - [mozharness: 2016-09-12 12:58:02.956451Z] Finished install step (success) 05:58:02 INFO - [mozharness: 2016-09-12 12:58:02.956576Z] Running run-tests step. 05:58:02 INFO - Running pre-action listener: _resource_record_pre_action 05:58:02 INFO - Running main action method: run_tests 05:58:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:02 INFO - 2016-09-12 05:58:02.971 screenresolution[2227:15988] starting screenresolution argv=screenresolution get 05:58:02 INFO - 2016-09-12 05:58:02.973 screenresolution[2227:15988] Display 0: 1600x1200x32@60 05:58:02 INFO - 2016-09-12 05:58:02.979 screenresolution[2228:15990] starting screenresolution argv=screenresolution list 05:58:02 INFO - Available Modes on Display 0 05:58:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:03 INFO - Intel Iris: 05:58:03 INFO - Chipset Model: Intel Iris 05:58:03 INFO - Type: GPU 05:58:03 INFO - Bus: Built-In 05:58:03 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:03 INFO - Vendor: Intel (0x8086) 05:58:03 INFO - Device ID: 0x0a2e 05:58:03 INFO - Revision ID: 0x0009 05:58:03 INFO - Displays: 05:58:03 INFO - Display: 05:58:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:03 INFO - Display Serial Number: 1600x1200 60 05:58:03 INFO - Main Display: Yes 05:58:03 INFO - Mirror: Off 05:58:03 INFO - Online: Yes 05:58:03 INFO - Rotation: Supported 05:58:03 INFO - Return code: 0 05:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:58:03 INFO - Python 2.7.3 05:58:03 INFO - Return code: 0 05:58:03 INFO - grabbing minidump binary from tooltool 05:58: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:58:03 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 0x10712ed30>, '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 0x106f54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:58:03 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:58:03 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:58:03 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:58:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:58:03 INFO - Return code: 0 05:58:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:58:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:58:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:58:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:58:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0265 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:58:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.930tcDITCU/Render', 05:58:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:03 INFO - 'HOME': '/Users/cltbld', 05:58:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:03 INFO - 'LOGNAME': 'cltbld', 05:58:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:58:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:03 INFO - 'MOZ_NO_REMOTE': '1', 05:58:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:58:03 INFO - 'NO_EM_RESTART': '1', 05:58:03 INFO - 'PAGER': '/bin/cat', 05:58:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:03 INFO - 'PWD': '/builds/slave/test', 05:58:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:58:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:03 INFO - 'SHELL': '/bin/bash', 05:58:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6vLSAwS5j/Listeners', 05:58:03 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 05:58:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:03 INFO - 'USER': 'cltbld', 05:58:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:03 INFO - 'XPC_FLAGS': '0x0', 05:58:03 INFO - 'XPC_SERVICE_NAME': '0', 05:58:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:58:03 INFO - mozversion application_buildid: 20160912040616 05:58:03 INFO - mozversion application_changeset: 3d667f260feb3f7f30fca84f608b92e330aa349c 05:58:03 INFO - mozversion application_display_name: Nightly 05:58:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:58:03 INFO - mozversion application_name: Firefox 05:58:03 INFO - mozversion application_remotingname: firefox 05:58:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:58:03 INFO - mozversion application_vendor: Mozilla 05:58:03 INFO - mozversion application_version: 51.0a1 05:58:03 INFO - mozversion platform_buildid: 20160912040616 05:58:03 INFO - mozversion platform_changeset: 3d667f260feb3f7f30fca84f608b92e330aa349c 05:58:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:58:03 INFO - mozversion platform_version: 51.0a1 05:58:03 INFO - using testdate: 1473685083 05:58:03 INFO - actual date: 1473685083 05:58:03 INFO - starting webserver on 'localhost:50221' 05:58:03 INFO - SUITE-START | Running 2 tests 05:58:03 INFO - TEST-START | dromaeo_css 05:58:03 INFO - operating with platform_type : mac_ 05:58:03 INFO - Initialising browser for dromaeo_css test... 05:58:03 INFO - TEST-INFO | started process 2238 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmparM4ga/profile http://localhost:50221/getInfo.html) 05:58:06 INFO - PROCESS | 2238 | __metrics Screen width/height:1600/1200 05:58:06 INFO - PROCESS | 2238 | colorDepth:24 05:58:06 INFO - PROCESS | 2238 | Browser inner width/height: 1024/689 05:58:06 INFO - PROCESS | 2238 | __metrics 05:58:06 ERROR - PROCESS | 2238 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 05:58:07 INFO - TEST-INFO | 2238: exit 0 05:58:07 INFO - Browser initialized. 05:58:07 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:07 INFO - TEST-INFO | started process 2241 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmparM4ga/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:19 INFO - PROCESS | 2241 | RSS: Main: 244187136 05:58:19 INFO - PROCESS | 2241 | 05:59:08 INFO - PROCESS | 2241 | 1473685148984 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] 06:01:21 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:50221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50221/tests/dromaeo/cssquery-ext.html) 06:01:23 INFO - PROCESS | 2241 | RSS: Main: 262443008 06:01:23 INFO - PROCESS | 2241 | 06:04:25 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:50221/tests/dromaeo/cssquery-ext.html (next: http://localhost:50221/tests/dromaeo/cssquery-jquery.html) 06:04:26 INFO - PROCESS | 2241 | RSS: Main: 268480512 06:04:26 INFO - PROCESS | 2241 | 06:07:27 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:50221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50221/tests/dromaeo/cssquery-mootools.html) 06:07:28 INFO - PROCESS | 2241 | RSS: Main: 275460096 06:07:28 INFO - PROCESS | 2241 | 06:10:30 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:50221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50221/tests/dromaeo/cssquery-prototype.html) 06:10:31 INFO - PROCESS | 2241 | RSS: Main: 271912960 06:10:31 INFO - PROCESS | 2241 | 06:13:33 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:50221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50221/tests/dromaeo/cssquery-yui.html) 06:13:34 INFO - PROCESS | 2241 | RSS: Main: 277368832 06:13:34 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:50221/tests/dromaeo/cssquery-yui.html (next: http://localhost:50221/tests/dromaeo/cssquery-dojo.html) 06:16:37 INFO - PROCESS | 2241 | RSS: Main: 280133632 06:16:37 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | __start_tp_report 06:16:37 INFO - PROCESS | 2241 | _x_x_mozilla_page_load 06:16:37 INFO - PROCESS | 2241 | _x_x_mozilla_page_load_details 06:16:37 INFO - PROCESS | 2241 | |i|pagename|runs| 06:16:37 INFO - PROCESS | 2241 | |0;dojo.html;3387.1362802485346;3398;3422.8117453540044;3435.536202612648;3446.775959562629;4967.602591792653;5758.654480731159;5781.826545203646;5805.564582656297;5908.645481271126;5758.625689330191;5833.095870140124;5873.353931067579;5936.198613187218;5945.04879219325;4644.233701439259;5257.447967963366;5270.49930256625;5351.7324133793345;5442.564594832404;5421.864453388677;5423.376311724143;5432.940576587567;5436.401995780461;5490.176473528963;5242.68543887368;5252.789888404459;5306.151015837448;5318.909623527187;5378.865528361783;5301.284326615896;5362.195670649434;5367.946320536784;5384.19237114436;5406.621536492447;5280.445553216895;5291;5291.735413229361;5294.841154765325;5330.227117068042;5740.586272640605;5757;5933.762649494015;5933.970330148321;5952.226210592596;6102.701334035014;6209.16176316196;6255;6261.749530018794;6271.811845644638;6318.968405157991;6324.620522768602;6329.082283068975;6340.556161068727;6351.714172862186;5124.948750512447;6006.159137720679;6016.278046634345;6019.849503762353;6021.638701677914;5157;5257.842264732064;6143.051335359052;6168.660723660152;6368.10846481493;6196.597221180699;6260.7495700171485;6277.591956522811;6289.433950944437;6304.621722696653;6107.3708791890585;6308.243010838729;6314.116023756677;6315.463185629181;6347.270063942613;6137.22377000893;6145.617236121926;6171.321154672983;6236.719347629368;6262.624242545462;6215.627062376181;6218.31598524162;6228.314885362606;6236.812895613139;6247.344028876993;4921.876953076202;4990.4011518618;4992.8002879885125;4996.4254110777;5031.924521132186;4367.890802729958;4380.561943805594;4416.205083085076;4461.888452788641;4469.642428605704;5306.151015837448;5787.797427090116;5834.183214349994;5842.824715258549;5882.49998750111;6244.094606282064;6268.373162683786;6281.057841323838;6297;6300.369963003663;5805.94194058054;5816.88366232673;5906;5952.404759524013;5988.610740301867;4643.4660014098135;5529.4470552944385;5570.61005729595;5609.971950140223;5611.466910643482;6422.165118534561;6465.418112369909;6485.189351331083;6503.25212600557;6509.958406654912;6162.137300777984;6237.251529816451;6266;6338.429541341301;6377.266614339411;6371.108044873628;6491.383318584727;6514.934850651623;6528.412442880163;6552.27924928267;6299.149614801943;6303.243610766737;6303.527235457451;6305.716242768995;6326.841828954313;6137.294211165595;6232.314445411092;6266.154069200599;6285.465735412541;6305.842353941188;6121.452973575903;6146.078088286793;6209.441150296495;6260.561760676891;6277;6184.103305020837;6237.28271248794;6248.343923887926;6250.218722659668;6255.499560035095;5857.062869940789;5985.341612422717;6082.996305609525;6106.066326226606;6129.632222066691;6204.255489341308;6237.906431403442;6246.281677606951;6261.96869015652;6276.560640755103;6155.969220153871;6161.661108639068;6169.352218017223;6173.135760993375;6193.1329613853195 06:16:37 INFO - PROCESS | 2241 | |1;ext.html;441.81443793606684;451.9186546421645;517.4282417928187;520.1988937036963;530.4006472685866;33995.49006764897;41737.79131104344;41803;41836.790816045876;42718.14563708728;41701.37447938283;42752.14495710084;43048;43093.78453107738;43143;9965.651202207924;10029.749256268615;10053.597856085784;10167.33912295702;10180.64367747129;8505.702300419487;8667.916510436195;9084.727458176265;9619.759506012371;9968.900310996905;9996.90003099967;10008.74978125549;10012.599496020186;10031.648892288771;10038.598456061749;9960.950195249014;9986.951370106108;10019.79960400788;10028.59885604575;10046.547905344203;9828.950855245677;9840.212782977345;9842.015798420172;9930.2055835533;9960.900390996143;40683.7965810172;40946.590534094576;41095.97260068492;41154.79422602868;41175.7941210295;3457.083872773692;3474.096734848932;3537.6108628050893;3798.164403831153;3859.4789703389965;30664.540031899538;31391.84304078465;31576.36847263065;31596;31637.84181079103;3271.6237632672073;3271.6401195868434;3304.1244070321145;3368.6462921393145;3413.0614081127887;409804;411658;436013;464667;466903.9999999966;32726.836365818494;57775;58189;58232;58290;33312.50031249533;34616.30767384638;34977.30045399129;35051.29897402038;35091.298174036376;14172.362243699059;14211.78682319766;14298.927580431302;14307.92846035784;14314.427422902967;11946.98450631706;12042.939785301018;12082.214656047505;12084.456199471046;12099.697507562381;7668.156502784801;7740.8451830964195;7800.219978002155;7816.022997125359;7821.452498325063;3215.260490087246;3243.2378391078078;3266.673332666762;3284.9343013139605;3313.701766840996;698.9690206944716;804.0994086623019;954.3605784124514;984.7931934293736;1119.356370087203;29930.251743706147;29932.80268789224;30385.848070759504;30744.38511229763;30776.69223307738;991.3952488981715;995.6564985080136;1017.6465301149453;1033.7105610429091;1088.0914436445498;13040.608781736562;14434.639134021523;14852.851471485363;15337.463188788339;15560.766588501181;688569;694666;695556;695801.3358931274;699278;56950.02024787338;57373.85252295014;57594;57613;57908;1290.490256348737;1291.7534579131184;1332.9800052999212;1350.3923234544495;1356.3218390804598;28027.43945121127;28062.2984425391;29059.273518161368;29176.70823291825;29540.261493462836;47171.528284718086;47235.52764472449;47857;47895;48197.7950551225;602586;603140;603900;604314;606946;25919;26173;26586.069487567842;26693.599596005686;26704.8664756675;46943.06113877635;47037;47360;48374.75812621055;48547.02905941932;38835;38874.60368974144;39046.219075618894;39262;39276.01809954774;30422.391552169276;38733.612663873;39241.01897452587;39804;40571.59428405678;36350.727724530574;41770.791146044074;41852.581474184866;42256.57743422526;42426.363604546576;29052.854735726185;36443;38822.80588597152;40381.1923761529;42238.788806055774;31769.8411507941;32113.35773284568;32113.678863212004;32565.635663619618;32733.34533309273 06:16:37 INFO - PROCESS | 2241 | |2;jquery.html;353.4221547769397;365.89937767114054;375.9755615884967;382.10015413700717;398.6452057668674;14879.851201487982;19345.516362090955;20405.48986275344;20936.89531552342;21189.258375956844;16600.418985335516;18798.43604691858;19358.516037099078;20615;21286;5832.066869300923;5901.970490147543;6014.939850601503;6105.54208434367;6122.877542449149;5903.144044113623;5912.911306330387;5931.644101353911;6052.182955301022;6067.332593414721;5744.856378590527;5891.204687367215;5912.940870591304;5925.970370148143;6043.879122417549;5999.280086389617;6042.909356359658;6047.969760151171;6051;6067.2719273687;5021.59827213822;5122.718250496221;5205.531502164823;5206.76569554371;5242.030224408497;21352;24683.62974555383;25667.871660641766;25777.871110644515;25926.96292148312;2422.9394265143337;2424.709034915804;2428.307932239321;2615.7645811877023;2872.7989040767156;15608;15636.765448518281;15753;15937.681246375008;16059.116748578823;1817.155022914338;1820.1172431370774;1858.2845604442252;1929.9131539080774;1946.6593346164364;106453;111514;113440;114282.85717142806;115682;10806.297590656739;10891.509882055327;11023.228374013903;11109.777804444027;11187.664370068747;13678.589642310746;13752.174869507662;13753.931230343784;13779.379927903268;13792.655183620489;8286.212809783061;8354.958225208835;8479.788005299793;8520.659173633108;8540.572971351408;8161.306288965386;8298.21166989135;8356.540390278471;8538.487690738575;8553.615087321086;5752.741126649311;6703.430208432241;6778.491613129035;6781.6609169541325;6810.969118742112;1488.5236724248352;1657.3426573426573;1837.660032893919;1841.0025122741974;1890.1966664167949;885.2519620920312;983.1397527163732;1005.7083445800782;1016.6390931219458;1069.5240617924949;19781.901090494455;20712.896435517727;20948.057337419854;22288.55422891533;22324.55350892973;813.5688085314792;908.8182363527188;912.8634849612275;931.7344556801279;1028.3983869436313;8780.78048048791;11034.448277586089;11439.313641181556;12037.976771974305;12096.274223546616;542270;546022;548048;584776;589133.0543347256;111793;117622;119511.40244298897;120227;124188;976.4190306767462;993.7664648807524;1007.9718686939427;1008.1380419741163;1081.1512962324537;19605.70591441158;19630.705539417206;19711.01444927719;19862.106205220512;19911.900440497706;53170;53846.461535384144;54172.45827541674;54713;55545.16682249689;73106;73861;74307.62846185951;74367.2563274382;75520;7831.882521762064;7992.960915081235;8034.799130021796;8073.798155046171;8227.547484888388;11341.355503452118;12073.57742479009;12826;12876.935615321863;13240.867591323964;10001.449920254165;10797.946010269898;11062.391568463812;11411;11567.595134170264;9305.813883722423;10864.456777160953;10869.184811139037;11063.229883218646;11089.500972456097;10400.47997600102;11137;11538.942305288756;11562.47968841421;11597.594084207012;7901.841963160589;7957.204279571996;8014.83970320602;8087.786831975251;8157.143499932659;11076.224664273423;11247.66257012291;11263.436828158723;11397.497988294412;11571.6528504145 06:16:37 INFO - PROCESS | 2241 | |3;mootools.html;1250.5560526013264;1506.5142514850493;1674.5562425957116;1866.3281218761251;1888.877222980506;7986.880196797053;8230.21812927771;8498.405111642187;9068.818623627523;9076.457002309615;8440.83118337633;8741.562921853898;8767.95616021919;8855.734327970154;8860.911390886089;2357.0807385119774;2402.3753824005707;2407.5425669122897;2411.168146989286;2411.3248290478696;2068.751749790027;2372.181597348912;2394.676718642987;2398.9880050599722;2402.147237730607;2153.203314773536;2288.107638021173;2361.7047869016374;2408.6391188978173;2416.9516609667794;2345.9296221113395;2362.2795047510676;2375.2992867104244;2388.9402764931015;2397.6043952747864;2354.2584086012853;2360.799332056777;2363.645453182019;2367.3134790910785;2385.6183010718373;8791.824163516694;8931.062238464932;8951.73144805657;8966.68616598416;8988.235999940012;5227.424983251839;5347.278117454132;5373.623846330758;5381.757820898029;5388.380336261302;3625.329314076878;3629.4555816627453;3671.7796932184156;3684.797336146511;3689.7786132832116;1071.8874518175558;1075.2741899217997;1084.0568705226424;1085.2511766880828;1093.0981939899614;11547;11630.476628551736;11657.533698652043;11684.532618695242;11685.766284674259;4497.64018878489;4603.194440972817;4753.952460475421;4787.54518320759;4800.231962885921;8087.636056377477;8207.302379297707;9948.253880958957;9969.601215951427;10030.498475076218;5323.361196656426;5328.626996110235;5336.4129945705945;5340;5342.412334643186;3957.56466788653;4053.5135783706146;4351.5430879757805;4500.909981800346;4886.267059941038;3194.217416732869;3322.684344987222;3872.5933776953016;3953.367461206193;4007.4189242559664;1080.1412876762993;1231.6982339326744;1290.2903403128241;1299.13607451044;1308.627041293227;903.6295119001178;916.5829547555846;924.3021518753299;938.1884669760744;942.9009953954736;5041.470645582161;5699.686517241592;5788.942110578924;5882.470577648032;6032.939670603326;448.7643986906835;455.79717025923145;458.90133621270917;913.5112714697605;991.4992928571025;3478.599961004517;3971.523417189898;3985.8206380712936;4043.4137050127565;4116.300228961084;10638.414887181125;10827.350358978487;10933.781324373467;10956.506957186946;10963.671089867157;8900.599473023729;10779.946100269448;10788.946055269673;10903.964123408263;10918.454077296103;8156.918430815735;8158.2657560818625;8282.71010514629;8346.040205376337;8351.1648835116;5787.276590426197;5826.912596311144;5887.116932460165;5897.233359663216;5931.08068249422;9521.285903557122;10144.188464922641;10162.085412312927;10179.643712470028;10239.948800255952;10058.647947321808;10215.540300686338;10231;10245;10284.640037598649;6876.555923256172;6886.208086070166;7411.221821708643;7463.477556570988;7564.50830696003;8293.419460637939;8321.95839020801;8380.287675547646;8448.112948140602;8454.873176902229;6284.277308109626;7479.850402991801;7539.811504712426;7600.733974311093;7653.464257501922;6233.657148856856;7313.524620899624;7341.229170936975;7407.444441666788;7424.665890034853;6885.586864788129;6900.516963812685;7002.474814389043;7006.859862802614;7010.298970102949;5031.597472202288;5069.112905241642;5076.340075790124;5108.59131269505;5119.385673719177;4953.777080031336;4969.850904472872;5085.770535709925;5201.71390573507;5213.478652134908 06:16:37 INFO - PROCESS | 2241 | |4;prototype.html;251.23624182485247;259.9363156026774;297.4957447127119;303.94073155734634;311.75298804780874;5190.610704197186;5245.082110630638;5610.719464026793;5631.239782629349;5669.149627555868;5422.2951016367915;5442.537384322337;5468.425815289397;5519.972400138004;5536.640118392312;3092.58250136231;3497.7026952708925;3770.6040865709115;3845.769253844778;3881.9223615527603;3240.0191823522414;3493.3013397320638;3872.3610604250266;3875.345388408949;4025.1748391579654;4146.232946904832;4183.602557757008;4184.849166479212;4188.643965262956;4193.874183774492;3646.781193128421;4017.6986725995375;4079.0006448420327;4211.831526738927;4223.64099051581;3144.9056528304186;3163.4780261257097;3585.515955346021;3606.6573675500786;3609.6390360963956;5301.310829592167;5357.464253574651;5359.3032905722;5407;5416.702081385522;4666.486686464486;4693.577578017995;4798.760061996921;4830.3237546743485;4837.516248375135;5657.886842263131;5660.858478538028;5740.933264024948;5769.105788602773;5858.593937455023;3969.7419667721506;3993.8402463901407;3999.2201520703475;4001.239764444746;4005.856866576397;5179.249008893689;5941.435563621449;6089.939100608982;6105.419985101453;6166.1983942087245;4023.5775243599;4042.6361627453084;4053.7973101345406;4064.7561146331313;4077.347624380085;3701.555813302367;4018.4374187613757;4081.5306239782212;4169.582342003733;4216.24107660624;4392.2094023075515;4646.3495110684535;4674.345591617176;4836.7339796310835;4856.247281671276;5318.654287471302;5434.429384914527;5827.3007239131575;5851.48910653613;5953.1367951647635;1355.302019459979;1363.1071648070529;1372.574501904393;1373.272165752153;1379.6137081617164;783.8824176373589;819.8073452738602;829.0093338460482;841.4068082002174;841.7643059943226;4384.908639409209;5356.946540471894;5401.352587460784;5508.614396992252;5589.888202235932;4770.713757174581;5627.5087101918025;6020.307664618537;6082.844259590664;6142.57002009855;5209.20282502536;5461.5357694596405;5504.284443022381;5524.558035357162;5536.640118392292;2588.831725937808;2670.626112344273;3000.6549246836457;3146.4021835851113;3158.957544010517;5927.739743141255;6192.287886893065;6230.130960711768;6238.407351301619;6319.905201421982;5438.94798375213;5471.322019673202;6142.846428839225;6278.089676996809;6296.937030629727;6203.565750397429;6213.347598502182;6223.3465486123305;6226.782062627877;6252.343503932204;5233.371995360581;5840.853978650568;5860.179574859438;6025.578209525291;6059.151718759465;5337.733113344239;5499.927514134705;5880.94119058804;5916.343423841331;5918;4718.584424672653;5403;6158.692065396623;6169.198004259418;6255.812325630239;3611.711063114997;3685.5073695153505;3873.5739068701855;3934.6065393460426;3938;5412.404635490014;5817.447342502455;5893.823185304448;6010.639361638316;6032.426919442646;4751.453582838018;5548.583856210719;5863.882722345615;5891.1457838612305;5893.793717219876;5759.568032397503;5773.624714393551;5781.739821708117;5782.479576838105;5787.94212057874;4969.925451118163;5614.883739524603;5674.453466583385;5674.687892165971;5704.486596206361;4718.97640511809;5562.467832926202;5738.1679656450415;5740.540756739534;5893.145493903446;5468.3711373191;5582.627383362136;5645.515972971879;5748.6263392881;5752.561859535116 06:16:37 INFO - PROCESS | 2241 | |5;yui.html;638.7828138432933;674.6997586074198;686.4452016183345;708.4828075504881;708.6492186367749;11296.265742726717;15082.622934426645;15280.617984550392;15512.61218469536;15750.606234844134;14344.497942572012;14429.5671129866;14582.854171458283;14607.050541714782;14673.706525869477;674.8279188806872;698.0018573439979;828.6395417993162;853.9786505337386;856.7815207122175;838.3837879158814;859.2266959736224;871.847426700325;874.0778478704964;875.0199776250608;882.3117967984982;903.0112027330096;905.2169873059789;907.1065000974036;907.795745957161;893.8569828827357;897.7755561109723;899.8425275576814;900.1088921967271;900.8198360327928;651.6253154436171;665.4643012375036;668.8695704337656;672.4989882537492;673.9326067393271;15285.923570382078;15422.768658470131;15432.845671543366;15465.304061317267;15495.92252038744;11682.41587920609;14377.496787612488;14391.2084835334;14421.855781442157;14670.926645366811;11801.763964720743;11835.467403966843;11884.881151188463;11937.462814173383;12023.939880300542;3829.617038296176;3843.331086647173;3873.709471789599;3883.4951456310782;4007.0984028593625;95456.56815147778;98883.51674724885;108810;110749.44625276822;112394;10992;11076.224664273584;11102.666919992413;11117.221794474472;11528.423578821026;21749;21793.91030448471;21827.23604673829;21851.562968740855;21936.561268774534;8078.676852925817;8180.836383272302;8210.507369557845;8215.794605134919;8334.16658334174;3762.435634654769;3954.62431069048;4565.977170114194;4649.628029757611;4723.669343146015;3523.1544429337287;3627.437747149196;3646.4712616670963;3663.816809159531;3667.431548110047;656.5043392238924;661.6493258572964;666.9933300667028;668.1225755626705;680.9182898052167;588.7792077970761;663.6620276360736;669.6685140855308;685.2325395557008;685.5783693028851;7970.840583188304;9355.95322023399;9470.715878523655;9580.808383832284;9623.518824058769;628.2806186915956;660.5277226782873;667.7863083813133;668.9732410703517;669.5045666206972;4453.398791163151;4506.211413002777;4654.394928659321;4712.787924543335;4720.881977950578;88942;98887.50556247172;120036;160785;164377;137921.6207837929;139036;140973;142223;147562;116191;117059;117454;117705;121621.99999999822;7097.645117744195;8904.510251935946;8966.237869781142;9085.454872707658;9094.181523662903;66218.67562648817;67149.66425167843;67448.66275668591;69052.65473672599;70719.64640176766;59070;60832;64207.35792642201;84092;95125.5243723777;13135.277559734306;13377.130486518345;13396.330183490982;13530.594082177551;13624.386902589606;11918.56977162746;12043.518259269882;12306.200096993667;12597.87402125967;12627.494900203888;11535.019523340274;13596.252206128758;13910.51313204033;14163.362648680628;14189.432422702976;13533.932330338286;13651.249181295125;13963.720725585634;14036.64908377258;14045.297735113409;11402.315861048364;12219.816702749287;13237.205767653972;13457.125286856322;13886.861131388558;9866;11184.516793613207;11214.271072380268;11593.768124637629;11824.23142495735;9313.527098666633;11155.944220278847;11427.028702560376;11641.825372619249;11817.172797904064 06:16:37 INFO - PROCESS | 2241 | __end_tp_report 06:16:37 INFO - PROCESS | 2241 | __start_cc_report 06:16:37 INFO - PROCESS | 2241 | _x_x_mozilla_cycle_collect,377 06:16:37 INFO - PROCESS | 2241 | __end_cc_report 06:16:37 INFO - PROCESS | 2241 | __startTimestamp1473686197690__endTimestamp 06:16:37 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | ------- Summary: start ------- 06:16:37 INFO - PROCESS | 2241 | Number of tests: 6 06:16:37 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | [#0] dojo.html Cycles:165 Average:5816.72 Median:6107.37 stddev:660.50 (10.8%) stddev-sans-first:634.43 06:16:37 INFO - PROCESS | 2241 | Values: 3387.1 3398.0 3422.8 3435.5 3446.8 4967.6 5758.7 5781.8 5805.6 5908.6 5758.6 5833.1 5873.4 5936.2 5945.0 4644.2 5257.4 5270.5 5351.7 5442.6 5421.9 5423.4 5432.9 5436.4 5490.2 5242.7 5252.8 5306.2 5318.9 5378.9 5301.3 5362.2 5367.9 5384.2 5406.6 5280.4 5291.0 5291.7 5294.8 5330.2 5740.6 5757.0 5933.8 5934.0 5952.2 6102.7 6209.2 6255.0 6261.7 6271.8 6319.0 6324.6 6329.1 6340.6 6351.7 5124.9 6006.2 6016.3 6019.8 6021.6 5157.0 5257.8 6143.1 6168.7 6368.1 6196.6 6260.7 6277.6 6289.4 6304.6 6107.4 6308.2 6314.1 6315.5 6347.3 6137.2 6145.6 6171.3 6236.7 6262.6 6215.6 6218.3 6228.3 6236.8 6247.3 4921.9 4990.4 4992.8 4996.4 5031.9 4367.9 4380.6 4416.2 4461.9 4469.6 5306.2 5787.8 5834.2 5842.8 5882.5 6244.1 6268.4 6281.1 6297.0 6300.4 5805.9 5816.9 5906.0 5952.4 5988.6 4643.5 5529.4 5570.6 5610.0 5611.5 6422.2 6465.4 6485.2 6503.3 6510.0 6162.1 6237.3 6266.0 6338.4 6377.3 6371.1 6491.4 6514.9 6528.4 6552.3 6299.1 6303.2 6303.5 6305.7 6326.8 6137.3 6232.3 6266.2 6285.5 6305.8 6121.5 6146.1 6209.4 6260.6 6277.0 6184.1 6237.3 6248.3 6250.2 6255.5 5857.1 5985.3 6083.0 6106.1 6129.6 6204.3 6237.9 6246.3 6262.0 6276.6 6156.0 6161.7 6169.4 6173.1 6193.1 06:16:37 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | [#1] ext.html Cycles:180 Average:69932.27 Median:39143.62 stddev:157917.96 (403.4%) stddev-sans-first:158274.78 06:16:37 INFO - PROCESS | 2241 | Values: 441.8 451.9 517.4 520.2 530.4 33995.5 41737.8 41803.0 41836.8 42718.1 41701.4 42752.1 43048.0 43093.8 43143.0 9965.7 10029.7 10053.6 10167.3 10180.6 8505.7 8667.9 9084.7 9619.8 9968.9 9996.9 10008.7 10012.6 10031.6 10038.6 9961.0 9987.0 10019.8 10028.6 10046.5 9829.0 9840.2 9842.0 9930.2 9960.9 40683.8 40946.6 41096.0 41154.8 41175.8 3457.1 3474.1 3537.6 3798.2 3859.5 30664.5 31391.8 31576.4 31596.0 31637.8 3271.6 3271.6 3304.1 3368.6 3413.1 409804.0 411658.0 436013.0 464667.0 466904.0 32726.8 57775.0 58189.0 58232.0 58290.0 33312.5 34616.3 34977.3 35051.3 35091.3 14172.4 14211.8 14298.9 14307.9 14314.4 11947.0 12042.9 12082.2 12084.5 12099.7 7668.2 7740.8 7800.2 7816.0 7821.5 3215.3 3243.2 3266.7 3284.9 3313.7 699.0 804.1 954.4 984.8 1119.4 29930.3 29932.8 30385.8 30744.4 30776.7 991.4 995.7 1017.6 1033.7 1088.1 13040.6 14434.6 14852.9 15337.5 15560.8 688569.0 694666.0 695556.0 695801.3 699278.0 56950.0 57373.9 57594.0 57613.0 57908.0 1290.5 1291.8 1333.0 1350.4 1356.3 28027.4 28062.3 29059.3 29176.7 29540.3 47171.5 47235.5 47857.0 47895.0 48197.8 602586.0 603140.0 603900.0 604314.0 606946.0 25919.0 26173.0 26586.1 26693.6 26704.9 46943.1 47037.0 47360.0 48374.8 48547.0 38835.0 38874.6 39046.2 39262.0 39276.0 30422.4 38733.6 39241.0 39804.0 40571.6 36350.7 41770.8 41852.6 42256.6 42426.4 29052.9 36443.0 38822.8 40381.2 42238.8 31769.8 32113.4 32113.7 32565.6 32733.3 06:16:37 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | [#2] jquery.html Cycles:180 Average:33669.08 Median:12373.75 stddev:93760.64 (757.7%) stddev-sans-first:93990.30 06:16:37 INFO - PROCESS | 2241 | Values: 353.4 365.9 376.0 382.1 398.6 14879.9 19345.5 20405.5 20936.9 21189.3 16600.4 18798.4 19358.5 20615.0 21286.0 5832.1 5902.0 6014.9 6105.5 6122.9 5903.1 5912.9 5931.6 6052.2 6067.3 5744.9 5891.2 5912.9 5926.0 6043.9 5999.3 6042.9 6048.0 6051.0 6067.3 5021.6 5122.7 5205.5 5206.8 5242.0 21352.0 24683.6 25667.9 25777.9 25927.0 2422.9 2424.7 2428.3 2615.8 2872.8 15608.0 15636.8 15753.0 15937.7 16059.1 1817.2 1820.1 1858.3 1929.9 1946.7 106453.0 111514.0 113440.0 114282.9 115682.0 10806.3 10891.5 11023.2 11109.8 11187.7 13678.6 13752.2 13753.9 13779.4 13792.7 8286.2 8355.0 8479.8 8520.7 8540.6 8161.3 8298.2 8356.5 8538.5 8553.6 5752.7 6703.4 6778.5 6781.7 6811.0 1488.5 1657.3 1837.7 1841.0 1890.2 885.3 983.1 1005.7 1016.6 1069.5 19781.9 20712.9 20948.1 22288.6 22324.6 813.6 908.8 912.9 931.7 1028.4 8780.8 11034.4 11439.3 12038.0 12096.3 542270.0 546022.0 548048.0 584776.0 589133.1 111793.0 117622.0 119511.4 120227.0 124188.0 976.4 993.8 1008.0 1008.1 1081.2 19605.7 19630.7 19711.0 19862.1 19911.9 53170.0 53846.5 54172.5 54713.0 55545.2 73106.0 73861.0 74307.6 74367.3 75520.0 7831.9 7993.0 8034.8 8073.8 8227.5 11341.4 12073.6 12826.0 12876.9 13240.9 10001.4 10797.9 11062.4 11411.0 11567.6 9305.8 10864.5 10869.2 11063.2 11089.5 10400.5 11137.0 11538.9 11562.5 11597.6 7901.8 7957.2 8014.8 8087.8 8157.1 11076.2 11247.7 11263.4 11397.5 11571.7 06:16:37 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | [#3] mootools.html Cycles:180 Average:5667.06 Median:4843.25 stddev:3200.37 (66.1%) stddev-sans-first:3192.14 06:16:37 INFO - PROCESS | 2241 | Values: 1250.6 1506.5 1674.6 1866.3 1888.9 7986.9 8230.2 8498.4 9068.8 9076.5 8440.8 8741.6 8768.0 8855.7 8860.9 2357.1 2402.4 2407.5 2411.2 2411.3 2068.8 2372.2 2394.7 2399.0 2402.1 2153.2 2288.1 2361.7 2408.6 2417.0 2345.9 2362.3 2375.3 2388.9 2397.6 2354.3 2360.8 2363.6 2367.3 2385.6 8791.8 8931.1 8951.7 8966.7 8988.2 5227.4 5347.3 5373.6 5381.8 5388.4 3625.3 3629.5 3671.8 3684.8 3689.8 1071.9 1075.3 1084.1 1085.3 1093.1 11547.0 11630.5 11657.5 11684.5 11685.8 4497.6 4603.2 4754.0 4787.5 4800.2 8087.6 8207.3 9948.3 9969.6 10030.5 5323.4 5328.6 5336.4 5340.0 5342.4 3957.6 4053.5 4351.5 4500.9 4886.3 3194.2 3322.7 3872.6 3953.4 4007.4 1080.1 1231.7 1290.3 1299.1 1308.6 903.6 916.6 924.3 938.2 942.9 5041.5 5699.7 5788.9 5882.5 6032.9 448.8 455.8 458.9 913.5 991.5 3478.6 3971.5 3985.8 4043.4 4116.3 10638.4 10827.4 10933.8 10956.5 10963.7 8900.6 10779.9 10788.9 10904.0 10918.5 8156.9 8158.3 8282.7 8346.0 8351.2 5787.3 5826.9 5887.1 5897.2 5931.1 9521.3 10144.2 10162.1 10179.6 10239.9 10058.6 10215.5 10231.0 10245.0 10284.6 6876.6 6886.2 7411.2 7463.5 7564.5 8293.4 8322.0 8380.3 8448.1 8454.9 6284.3 7479.9 7539.8 7600.7 7653.5 6233.7 7313.5 7341.2 7407.4 7424.7 6885.6 6900.5 7002.5 7006.9 7010.3 5031.6 5069.1 5076.3 5108.6 5119.4 4953.8 4969.9 5085.8 5201.7 5213.5 06:16:37 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | [#4] prototype.html Cycles:180 Average:4700.58 Median:5414.55 stddev:1491.62 (27.5%) stddev-sans-first:1457.94 06:16:37 INFO - PROCESS | 2241 | Values: 251.2 259.9 297.5 303.9 311.8 5190.6 5245.1 5610.7 5631.2 5669.1 5422.3 5442.5 5468.4 5520.0 5536.6 3092.6 3497.7 3770.6 3845.8 3881.9 3240.0 3493.3 3872.4 3875.3 4025.2 4146.2 4183.6 4184.8 4188.6 4193.9 3646.8 4017.7 4079.0 4211.8 4223.6 3144.9 3163.5 3585.5 3606.7 3609.6 5301.3 5357.5 5359.3 5407.0 5416.7 4666.5 4693.6 4798.8 4830.3 4837.5 5657.9 5660.9 5740.9 5769.1 5858.6 3969.7 3993.8 3999.2 4001.2 4005.9 5179.2 5941.4 6089.9 6105.4 6166.2 4023.6 4042.6 4053.8 4064.8 4077.3 3701.6 4018.4 4081.5 4169.6 4216.2 4392.2 4646.3 4674.3 4836.7 4856.2 5318.7 5434.4 5827.3 5851.5 5953.1 1355.3 1363.1 1372.6 1373.3 1379.6 783.9 819.8 829.0 841.4 841.8 4384.9 5356.9 5401.4 5508.6 5589.9 4770.7 5627.5 6020.3 6082.8 6142.6 5209.2 5461.5 5504.3 5524.6 5536.6 2588.8 2670.6 3000.7 3146.4 3159.0 5927.7 6192.3 6230.1 6238.4 6319.9 5438.9 5471.3 6142.8 6278.1 6296.9 6203.6 6213.3 6223.3 6226.8 6252.3 5233.4 5840.9 5860.2 6025.6 6059.2 5337.7 5499.9 5880.9 5916.3 5918.0 4718.6 5403.0 6158.7 6169.2 6255.8 3611.7 3685.5 3873.6 3934.6 3938.0 5412.4 5817.4 5893.8 6010.6 6032.4 4751.5 5548.6 5863.9 5891.1 5893.8 5759.6 5773.6 5781.7 5782.5 5787.9 4969.9 5614.9 5674.5 5674.7 5704.5 4719.0 5562.5 5738.2 5740.5 5893.1 5468.4 5582.6 5645.5 5748.6 5752.6 06:16:37 INFO - PROCESS | 2241 | 06:16:37 INFO - PROCESS | 2241 | [#5] yui.html Cycles:180 Average:24246.74 Median:3836.47 stddev:38866.94 (1013.1%) stddev-sans-first:38935.55 06:16:37 INFO - PROCESS | 2241 | Values: 638.8 674.7 686.4 708.5 708.6 11296.3 15082.6 15280.6 15512.6 15750.6 14344.5 14429.6 14582.9 14607.1 14673.7 674.8 698.0 828.6 854.0 856.8 838.4 859.2 871.8 874.1 875.0 882.3 903.0 905.2 907.1 907.8 893.9 897.8 899.8 900.1 900.8 651.6 665.5 668.9 672.5 673.9 15285.9 15422.8 15432.8 15465.3 15495.9 11682.4 14377.5 14391.2 14421.9 14670.9 11801.8 11835.5 11884.9 11937.5 12023.9 3829.6 3843.3 3873.7 3883.5 4007.1 95456.6 98883.5 108810.0 110749.4 112394.0 10992.0 11076.2 11102.7 11117.2 11528.4 21749.0 21793.9 21827.2 21851.6 21936.6 8078.7 8180.8 8210.5 8215.8 8334.2 3762.4 3954.6 4566.0 4649.6 4723.7 3523.2 3627.4 3646.5 3663.8 3667.4 656.5 661.6 667.0 668.1 680.9 588.8 663.7 669.7 685.2 685.6 7970.8 9356.0 9470.7 9580.8 9623.5 628.3 660.5 667.8 669.0 669.5 4453.4 4506.2 4654.4 4712.8 4720.9 88942.0 98887.5 120036.0 160785.0 164377.0 137921.6 139036.0 140973.0 142223.0 147562.0 116191.0 117059.0 117454.0 117705.0 121622.0 7097.6 8904.5 8966.2 9085.5 9094.2 66218.7 67149.7 67448.7 69052.7 70719.6 59070.0 60832.0 64207.4 84092.0 95125.5 13135.3 13377.1 13396.3 13530.6 13624.4 11918.6 12043.5 12306.2 12597.9 12627.5 11535.0 13596.3 13910.5 14163.4 14189.4 13533.9 13651.2 13963.7 14036.6 14045.3 11402.3 12219.8 13237.2 13457.1 13886.9 9866.0 11184.5 11214.3 11593.8 11824.2 9313.5 11155.9 11427.0 11641.8 11817.2 06:16:37 INFO - PROCESS | 2241 | -------- Summary: end -------- 06:16:37 INFO - PROCESS | 2241 | 06:16:38 INFO - TEST-INFO | 2241: exit 0 06:16:38 INFO - TEST-OK | dromaeo_css | took 1114741ms 06:16:38 INFO - TEST-START | kraken 06:16:38 INFO - operating with platform_type : mac_ 06:16:38 INFO - Initialising browser for kraken test... 06:16:38 INFO - TEST-INFO | started process 2246 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpvmggM9/profile http://localhost:50221/getInfo.html) 06:16:41 INFO - PROCESS | 2246 | __metrics Screen width/height:1600/1200 06:16:41 INFO - PROCESS | 2246 | colorDepth:24 06:16:41 INFO - PROCESS | 2246 | Browser inner width/height: 1024/689 06:16:41 INFO - PROCESS | 2246 | __metrics 06:16:41 ERROR - PROCESS | 2246 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 06:16:41 INFO - TEST-INFO | 2246: exit 0 06:16:41 INFO - Browser initialized. 06:16:41 INFO - Running cycle 1/1 for kraken test... 06:16:41 INFO - TEST-INFO | started process 2248 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpvmggM9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:53 INFO - PROCESS | 2248 | RSS: Main: 244211712 06:16:53 INFO - PROCESS | 2248 | 06:16:56 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50221/tests/kraken/driver.html?testName=audio-beat-detection) 06:16:57 INFO - PROCESS | 2248 | RSS: Main: 256880640 06:16:57 INFO - PROCESS | 2248 | 06:17:00 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50221/tests/kraken/driver.html?testName=audio-dft) 06:17:00 INFO - PROCESS | 2248 | RSS: Main: 265523200 06:17:00 INFO - PROCESS | 2248 | 06:17:04 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50221/tests/kraken/driver.html?testName=audio-fft) 06:17:05 INFO - PROCESS | 2248 | RSS: Main: 262258688 06:17:05 INFO - PROCESS | 2248 | 06:17:06 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50221/tests/kraken/driver.html?testName=audio-oscillator) 06:17:07 INFO - PROCESS | 2248 | RSS: Main: 263503872 06:17:07 INFO - PROCESS | 2248 | 06:17:09 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:10 INFO - PROCESS | 2248 | RSS: Main: 263901184 06:17:10 INFO - PROCESS | 2248 | 06:17:12 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50221/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:13 INFO - PROCESS | 2248 | RSS: Main: 264843264 06:17:13 INFO - PROCESS | 2248 | 06:17:16 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50221/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:17 INFO - PROCESS | 2248 | RSS: Main: 264970240 06:17:17 INFO - PROCESS | 2248 | 06:17:20 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50221/tests/kraken/driver.html?testName=json-parse-financial) 06:17:20 INFO - PROCESS | 2248 | RSS: Main: 265355264 06:17:20 INFO - PROCESS | 2248 | 06:17:22 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:23 INFO - PROCESS | 2248 | RSS: Main: 264253440 06:17:23 INFO - PROCESS | 2248 | 06:17:25 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50221/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:25 INFO - PROCESS | 2248 | RSS: Main: 265699328 06:17:25 INFO - PROCESS | 2248 | 06:17:27 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:28 INFO - PROCESS | 2248 | RSS: Main: 266031104 06:17:28 INFO - PROCESS | 2248 | 06:17:31 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:17:32 INFO - PROCESS | 2248 | RSS: Main: 266211328 06:17:32 INFO - PROCESS | 2248 | 06:17:35 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:17:36 INFO - PROCESS | 2248 | RSS: Main: 266215424 06:17:36 INFO - PROCESS | 2248 | 06:17:37 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:50221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50221/tests/kraken/driver.html?testName=ai-astar) 06:17:38 INFO - PROCESS | 2248 | RSS: Main: 267997184 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | __start_tp_report 06:17:38 INFO - PROCESS | 2248 | _x_x_mozilla_page_load 06:17:38 INFO - PROCESS | 2248 | _x_x_mozilla_page_load_details 06:17:38 INFO - PROCESS | 2248 | |i|pagename|runs| 06:17:38 INFO - PROCESS | 2248 | |0;ai-astar;107;114;118;112;117;117;118;126;128;133 06:17:38 INFO - PROCESS | 2248 | |1;audio-beat-detection;148;112;136;160;114;137;137;113;160;135 06:17:38 INFO - PROCESS | 2248 | |2;audio-dft;204;207;211;267;247;199;198;196;199;224 06:17:38 INFO - PROCESS | 2248 | |3;audio-fft;67;88;76;148;58;59;58;61;137;59 06:17:38 INFO - PROCESS | 2248 | |4;audio-oscillator;70;75;131;70;70;74;112;69;72;111 06:17:38 INFO - PROCESS | 2248 | |5;imaging-gaussian-blur;90;88;89;88;91;86;90;88;91;87 06:17:38 INFO - PROCESS | 2248 | |6;imaging-darkroom;96;124;149;151;136;95;94;137;95;119 06:17:38 INFO - PROCESS | 2248 | |7;imaging-desaturate;93;92;128;118;90;91;89;125;144;96 06:17:38 INFO - PROCESS | 2248 | |8;json-parse-financial;54;57;58;58;57;56;58;57;55;58 06:17:38 INFO - PROCESS | 2248 | |9;json-stringify-tinderbox;68;67;66;75;80;111;107;99;75;72 06:17:38 INFO - PROCESS | 2248 | |10;stanford-crypto-aes;90;86;88;86;86;90;85;87;92;78 06:17:38 INFO - PROCESS | 2248 | |11;stanford-crypto-ccm;162;155;145;167;153;155;139;158;245;156 06:17:38 INFO - PROCESS | 2248 | |12;stanford-crypto-pbkdf2;224;172;172;173;150;230;181;162;175;178 06:17:38 INFO - PROCESS | 2248 | |13;stanford-crypto-sha256-iterative;66;59;66;89;88;84;69;68;66;68 06:17:38 INFO - PROCESS | 2248 | __end_tp_report 06:17:38 INFO - PROCESS | 2248 | __start_cc_report 06:17:38 INFO - PROCESS | 2248 | _x_x_mozilla_cycle_collect,961 06:17:38 INFO - PROCESS | 2248 | __end_cc_report 06:17:38 INFO - PROCESS | 2248 | __startTimestamp1473686258104__endTimestamp 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | ------- Summary: start ------- 06:17:38 INFO - PROCESS | 2248 | Number of tests: 14 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#0] ai-astar Cycles:10 Average:119.00 Median:118.00 stddev:7.85 (6.6%) stddev-sans-first:7.02 06:17:38 INFO - PROCESS | 2248 | Values: 107.0 114.0 118.0 112.0 117.0 117.0 118.0 126.0 128.0 133.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:137.00 stddev:17.89 (13.1%) stddev-sans-first:18.37 06:17:38 INFO - PROCESS | 2248 | Values: 148.0 112.0 136.0 160.0 114.0 137.0 137.0 113.0 160.0 135.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#2] audio-dft Cycles:10 Average:215.20 Median:209.00 stddev:23.97 (11.5%) stddev-sans-first:25.08 06:17:38 INFO - PROCESS | 2248 | Values: 204.0 207.0 211.0 267.0 247.0 199.0 198.0 196.0 199.0 224.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#3] audio-fft Cycles:10 Average:81.10 Median:60.00 stddev:33.86 (56.4%) stddev-sans-first:35.53 06:17:38 INFO - PROCESS | 2248 | Values: 67.0 88.0 76.0 148.0 58.0 59.0 58.0 61.0 137.0 59.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:70.00 stddev:23.19 (33.1%) stddev-sans-first:23.92 06:17:38 INFO - PROCESS | 2248 | Values: 70.0 75.0 131.0 70.0 70.0 74.0 112.0 69.0 72.0 111.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:89.50 stddev:1.69 (1.9%) stddev-sans-first:1.73 06:17:38 INFO - PROCESS | 2248 | Values: 90.0 88.0 89.0 88.0 91.0 86.0 90.0 88.0 91.0 87.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#6] imaging-darkroom Cycles:10 Average:119.60 Median:122.50 stddev:23.25 (19.0%) stddev-sans-first:23.04 06:17:38 INFO - PROCESS | 2248 | Values: 96.0 124.0 149.0 151.0 136.0 95.0 94.0 137.0 95.0 119.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#7] imaging-desaturate Cycles:10 Average:106.60 Median:90.50 stddev:20.18 (22.3%) stddev-sans-first:20.79 06:17:38 INFO - PROCESS | 2248 | Values: 93.0 92.0 128.0 118.0 90.0 91.0 89.0 125.0 144.0 96.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.50 stddev:1.40 (2.4%) stddev-sans-first:1.05 06:17:38 INFO - PROCESS | 2248 | Values: 54.0 57.0 58.0 58.0 57.0 56.0 58.0 57.0 55.0 58.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:73.50 stddev:17.11 (23.3%) stddev-sans-first:17.38 06:17:38 INFO - PROCESS | 2248 | Values: 68.0 67.0 66.0 75.0 80.0 111.0 107.0 99.0 75.0 72.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:87.50 stddev:3.82 (4.4%) stddev-sans-first:3.88 06:17:38 INFO - PROCESS | 2248 | Values: 90.0 86.0 88.0 86.0 86.0 90.0 85.0 87.0 92.0 78.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#11] stanford-crypto-ccm Cycles:10 Average:163.50 Median:157.00 stddev:29.70 (18.9%) stddev-sans-first:31.50 06:17:38 INFO - PROCESS | 2248 | Values: 162.0 155.0 145.0 167.0 153.0 155.0 139.0 158.0 245.0 156.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.70 Median:176.50 stddev:25.47 (14.4%) stddev-sans-first:21.94 06:17:38 INFO - PROCESS | 2248 | Values: 224.0 172.0 172.0 173.0 150.0 230.0 181.0 162.0 175.0 178.0 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - PROCESS | 2248 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:68.50 stddev:10.57 (15.4%) stddev-sans-first:10.97 06:17:38 INFO - PROCESS | 2248 | Values: 66.0 59.0 66.0 89.0 88.0 84.0 69.0 68.0 66.0 68.0 06:17:38 INFO - PROCESS | 2248 | -------- Summary: end -------- 06:17:38 INFO - PROCESS | 2248 | 06:17:38 INFO - TEST-INFO | 2248: exit 0 06:17:39 INFO - TEST-OK | kraken | took 60704ms 06:17:39 INFO - SUITE-END | took 1175s 06:17:39 INFO - Completed test suite (00:19:35) 06:17:39 INFO - javascript benchmark 06:17:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7326.05004995268, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3387.1362802485346, 3398.0, 3422.8117453540044, 3435.536202612648, 3446.775959562629, 4967.602591792653, 5758.654480731159, 5781.826545203646, 5805.564582656297, 5908.645481271126, 5758.625689330191, 5833.095870140124, 5873.353931067579, 5936.198613187218, 5945.04879219325, 4644.233701439259, 5257.447967963366, 5270.49930256625, 5351.7324133793345, 5442.564594832404, 5421.864453388677, 5423.376311724143, 5432.940576587567, 5436.401995780461, 5490.176473528963, 5242.68543887368, 5252.789888404459, 5306.151015837448, 5318.909623527187, 5378.865528361783, 5301.284326615896, 5362.195670649434, 5367.946320536784, 5384.19237114436, 5406.621536492447, 5280.445553216895, 5291.0, 5291.735413229361, 5294.841154765325, 5330.227117068042, 5740.586272640605, 5757.0, 5933.762649494015, 5933.970330148321, 5952.226210592596, 6102.701334035014, 6209.16176316196, 6255.0, 6261.749530018794, 6271.811845644638, 6318.968405157991, 6324.620522768602, 6329.082283068975, 6340.556161068727, 6351.714172862186, 5124.948750512447, 6006.159137720679, 6016.278046634345, 6019.849503762353, 6021.638701677914, 5157.0, 5257.842264732064, 6143.051335359052, 6168.660723660152, 6368.10846481493, 6196.597221180699, 6260.7495700171485, 6277.591956522811, 6289.433950944437, 6304.621722696653, 6107.3708791890585, 6308.243010838729, 6314.116023756677, 6315.463185629181, 6347.270063942613, 6137.22377000893, 6145.617236121926, 6171.321154672983, 6236.719347629368, 6262.624242545462, 6215.627062376181, 6218.31598524162, 6228.314885362606, 6236.812895613139, 6247.344028876993, 4921.876953076202, 4990.4011518618, 4992.8002879885125, 4996.4254110777, 5031.924521132186, 4367.890802729958, 4380.561943805594, 4416.205083085076, 4461.888452788641, 4469.642428605704, 5306.151015837448, 5787.797427090116, 5834.183214349994, 5842.824715258549, 5882.49998750111, 6244.094606282064, 6268.373162683786, 6281.057841323838, 6297.0, 6300.369963003663, 5805.94194058054, 5816.88366232673, 5906.0, 5952.404759524013, 5988.610740301867, 4643.4660014098135, 5529.4470552944385, 5570.61005729595, 5609.971950140223, 5611.466910643482, 6422.165118534561, 6465.418112369909, 6485.189351331083, 6503.25212600557, 6509.958406654912, 6162.137300777984, 6237.251529816451, 6266.0, 6338.429541341301, 6377.266614339411, 6371.108044873628, 6491.383318584727, 6514.934850651623, 6528.412442880163, 6552.27924928267, 6299.149614801943, 6303.243610766737, 6303.527235457451, 6305.716242768995, 6326.841828954313, 6137.294211165595, 6232.314445411092, 6266.154069200599, 6285.465735412541, 6305.842353941188, 6121.452973575903, 6146.078088286793, 6209.441150296495, 6260.561760676891, 6277.0, 6184.103305020837, 6237.28271248794, 6248.343923887926, 6250.218722659668, 6255.499560035095, 5857.062869940789, 5985.341612422717, 6082.996305609525, 6106.066326226606, 6129.632222066691, 6204.255489341308, 6237.906431403442, 6246.281677606951, 6261.96869015652, 6276.560640755103, 6155.969220153871, 6161.661108639068, 6169.352218017223, 6173.135760993375, 6193.1329613853195], "value": 5774.822838322467, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.81443793606684, 451.9186546421645, 517.4282417928187, 520.1988937036963, 530.4006472685866, 33995.49006764897, 41737.79131104344, 41803.0, 41836.790816045876, 42718.14563708728, 41701.37447938283, 42752.14495710084, 43048.0, 43093.78453107738, 43143.0, 9965.651202207924, 10029.749256268615, 10053.597856085784, 10167.33912295702, 10180.64367747129, 8505.702300419487, 8667.916510436195, 9084.727458176265, 9619.759506012371, 9968.900310996905, 9996.90003099967, 10008.74978125549, 10012.599496020186, 10031.648892288771, 10038.598456061749, 9960.950195249014, 9986.951370106108, 10019.79960400788, 10028.59885604575, 10046.547905344203, 9828.950855245677, 9840.212782977345, 9842.015798420172, 9930.2055835533, 9960.900390996143, 40683.7965810172, 40946.590534094576, 41095.97260068492, 41154.79422602868, 41175.7941210295, 3457.083872773692, 3474.096734848932, 3537.6108628050893, 3798.164403831153, 3859.4789703389965, 30664.540031899538, 31391.84304078465, 31576.36847263065, 31596.0, 31637.84181079103, 3271.6237632672073, 3271.6401195868434, 3304.1244070321145, 3368.6462921393145, 3413.0614081127887, 409804.0, 411658.0, 436013.0, 464667.0, 466903.9999999966, 32726.836365818494, 57775.0, 58189.0, 58232.0, 58290.0, 33312.50031249533, 34616.30767384638, 34977.30045399129, 35051.29897402038, 35091.298174036376, 14172.362243699059, 14211.78682319766, 14298.927580431302, 14307.92846035784, 14314.427422902967, 11946.98450631706, 12042.939785301018, 12082.214656047505, 12084.456199471046, 12099.697507562381, 7668.156502784801, 7740.8451830964195, 7800.219978002155, 7816.022997125359, 7821.452498325063, 3215.260490087246, 3243.2378391078078, 3266.673332666762, 3284.9343013139605, 3313.701766840996, 698.9690206944716, 804.0994086623019, 954.3605784124514, 984.7931934293736, 1119.356370087203, 29930.251743706147, 29932.80268789224, 30385.848070759504, 30744.38511229763, 30776.69223307738, 991.3952488981715, 995.6564985080136, 1017.6465301149453, 1033.7105610429091, 1088.0914436445498, 13040.608781736562, 14434.639134021523, 14852.851471485363, 15337.463188788339, 15560.766588501181, 688569.0, 694666.0, 695556.0, 695801.3358931274, 699278.0, 56950.02024787338, 57373.85252295014, 57594.0, 57613.0, 57908.0, 1290.490256348737, 1291.7534579131184, 1332.9800052999212, 1350.3923234544495, 1356.3218390804598, 28027.43945121127, 28062.2984425391, 29059.273518161368, 29176.70823291825, 29540.261493462836, 47171.528284718086, 47235.52764472449, 47857.0, 47895.0, 48197.7950551225, 602586.0, 603140.0, 603900.0, 604314.0, 606946.0, 25919.0, 26173.0, 26586.069487567842, 26693.599596005686, 26704.8664756675, 46943.06113877635, 47037.0, 47360.0, 48374.75812621055, 48547.02905941932, 38835.0, 38874.60368974144, 39046.219075618894, 39262.0, 39276.01809954774, 30422.391552169276, 38733.612663873, 39241.01897452587, 39804.0, 40571.59428405678, 36350.727724530574, 41770.791146044074, 41852.581474184866, 42256.57743422526, 42426.363604546576, 29052.854735726185, 36443.0, 38822.80588597152, 40381.1923761529, 42238.788806055774, 31769.8411507941, 32113.35773284568, 32113.678863212004, 32565.635663619618, 32733.34533309273], "value": 18778.44092053574, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.4221547769397, 365.89937767114054, 375.9755615884967, 382.10015413700717, 398.6452057668674, 14879.851201487982, 19345.516362090955, 20405.48986275344, 20936.89531552342, 21189.258375956844, 16600.418985335516, 18798.43604691858, 19358.516037099078, 20615.0, 21286.0, 5832.066869300923, 5901.970490147543, 6014.939850601503, 6105.54208434367, 6122.877542449149, 5903.144044113623, 5912.911306330387, 5931.644101353911, 6052.182955301022, 6067.332593414721, 5744.856378590527, 5891.204687367215, 5912.940870591304, 5925.970370148143, 6043.879122417549, 5999.280086389617, 6042.909356359658, 6047.969760151171, 6051.0, 6067.2719273687, 5021.59827213822, 5122.718250496221, 5205.531502164823, 5206.76569554371, 5242.030224408497, 21352.0, 24683.62974555383, 25667.871660641766, 25777.871110644515, 25926.96292148312, 2422.9394265143337, 2424.709034915804, 2428.307932239321, 2615.7645811877023, 2872.7989040767156, 15608.0, 15636.765448518281, 15753.0, 15937.681246375008, 16059.116748578823, 1817.155022914338, 1820.1172431370774, 1858.2845604442252, 1929.9131539080774, 1946.6593346164364, 106453.0, 111514.0, 113440.0, 114282.85717142806, 115682.0, 10806.297590656739, 10891.509882055327, 11023.228374013903, 11109.777804444027, 11187.664370068747, 13678.589642310746, 13752.174869507662, 13753.931230343784, 13779.379927903268, 13792.655183620489, 8286.212809783061, 8354.958225208835, 8479.788005299793, 8520.659173633108, 8540.572971351408, 8161.306288965386, 8298.21166989135, 8356.540390278471, 8538.487690738575, 8553.615087321086, 5752.741126649311, 6703.430208432241, 6778.491613129035, 6781.6609169541325, 6810.969118742112, 1488.5236724248352, 1657.3426573426573, 1837.660032893919, 1841.0025122741974, 1890.1966664167949, 885.2519620920312, 983.1397527163732, 1005.7083445800782, 1016.6390931219458, 1069.5240617924949, 19781.901090494455, 20712.896435517727, 20948.057337419854, 22288.55422891533, 22324.55350892973, 813.5688085314792, 908.8182363527188, 912.8634849612275, 931.7344556801279, 1028.3983869436313, 8780.78048048791, 11034.448277586089, 11439.313641181556, 12037.976771974305, 12096.274223546616, 542270.0, 546022.0, 548048.0, 584776.0, 589133.0543347256, 111793.0, 117622.0, 119511.40244298897, 120227.0, 124188.0, 976.4190306767462, 993.7664648807524, 1007.9718686939427, 1008.1380419741163, 1081.1512962324537, 19605.70591441158, 19630.705539417206, 19711.01444927719, 19862.106205220512, 19911.900440497706, 53170.0, 53846.461535384144, 54172.45827541674, 54713.0, 55545.16682249689, 73106.0, 73861.0, 74307.62846185951, 74367.2563274382, 75520.0, 7831.882521762064, 7992.960915081235, 8034.799130021796, 8073.798155046171, 8227.547484888388, 11341.355503452118, 12073.57742479009, 12826.0, 12876.935615321863, 13240.867591323964, 10001.449920254165, 10797.946010269898, 11062.391568463812, 11411.0, 11567.595134170264, 9305.813883722423, 10864.456777160953, 10869.184811139037, 11063.229883218646, 11089.500972456097, 10400.47997600102, 11137.0, 11538.942305288756, 11562.47968841421, 11597.594084207012, 7901.841963160589, 7957.204279571996, 8014.83970320602, 8087.786831975251, 8157.143499932659, 11076.224664273423, 11247.66257012291, 11263.436828158723, 11397.497988294412, 11571.6528504145], "value": 9695.331358463702, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.5560526013264, 1506.5142514850493, 1674.5562425957116, 1866.3281218761251, 1888.877222980506, 7986.880196797053, 8230.21812927771, 8498.405111642187, 9068.818623627523, 9076.457002309615, 8440.83118337633, 8741.562921853898, 8767.95616021919, 8855.734327970154, 8860.911390886089, 2357.0807385119774, 2402.3753824005707, 2407.5425669122897, 2411.168146989286, 2411.3248290478696, 2068.751749790027, 2372.181597348912, 2394.676718642987, 2398.9880050599722, 2402.147237730607, 2153.203314773536, 2288.107638021173, 2361.7047869016374, 2408.6391188978173, 2416.9516609667794, 2345.9296221113395, 2362.2795047510676, 2375.2992867104244, 2388.9402764931015, 2397.6043952747864, 2354.2584086012853, 2360.799332056777, 2363.645453182019, 2367.3134790910785, 2385.6183010718373, 8791.824163516694, 8931.062238464932, 8951.73144805657, 8966.68616598416, 8988.235999940012, 5227.424983251839, 5347.278117454132, 5373.623846330758, 5381.757820898029, 5388.380336261302, 3625.329314076878, 3629.4555816627453, 3671.7796932184156, 3684.797336146511, 3689.7786132832116, 1071.8874518175558, 1075.2741899217997, 1084.0568705226424, 1085.2511766880828, 1093.0981939899614, 11547.0, 11630.476628551736, 11657.533698652043, 11684.532618695242, 11685.766284674259, 4497.64018878489, 4603.194440972817, 4753.952460475421, 4787.54518320759, 4800.231962885921, 8087.636056377477, 8207.302379297707, 9948.253880958957, 9969.601215951427, 10030.498475076218, 5323.361196656426, 5328.626996110235, 5336.4129945705945, 5340.0, 5342.412334643186, 3957.56466788653, 4053.5135783706146, 4351.5430879757805, 4500.909981800346, 4886.267059941038, 3194.217416732869, 3322.684344987222, 3872.5933776953016, 3953.367461206193, 4007.4189242559664, 1080.1412876762993, 1231.6982339326744, 1290.2903403128241, 1299.13607451044, 1308.627041293227, 903.6295119001178, 916.5829547555846, 924.3021518753299, 938.1884669760744, 942.9009953954736, 5041.470645582161, 5699.686517241592, 5788.942110578924, 5882.470577648032, 6032.939670603326, 448.7643986906835, 455.79717025923145, 458.90133621270917, 913.5112714697605, 991.4992928571025, 3478.599961004517, 3971.523417189898, 3985.8206380712936, 4043.4137050127565, 4116.300228961084, 10638.414887181125, 10827.350358978487, 10933.781324373467, 10956.506957186946, 10963.671089867157, 8900.599473023729, 10779.946100269448, 10788.946055269673, 10903.964123408263, 10918.454077296103, 8156.918430815735, 8158.2657560818625, 8282.71010514629, 8346.040205376337, 8351.1648835116, 5787.276590426197, 5826.912596311144, 5887.116932460165, 5897.233359663216, 5931.08068249422, 9521.285903557122, 10144.188464922641, 10162.085412312927, 10179.643712470028, 10239.948800255952, 10058.647947321808, 10215.540300686338, 10231.0, 10245.0, 10284.640037598649, 6876.555923256172, 6886.208086070166, 7411.221821708643, 7463.477556570988, 7564.50830696003, 8293.419460637939, 8321.95839020801, 8380.287675547646, 8448.112948140602, 8454.873176902229, 6284.277308109626, 7479.850402991801, 7539.811504712426, 7600.733974311093, 7653.464257501922, 6233.657148856856, 7313.524620899624, 7341.229170936975, 7407.444441666788, 7424.665890034853, 6885.586864788129, 6900.516963812685, 7002.474814389043, 7006.859862802614, 7010.298970102949, 5031.597472202288, 5069.112905241642, 5076.340075790124, 5108.59131269505, 5119.385673719177, 4953.777080031336, 4969.850904472872, 5085.770535709925, 5201.71390573507, 5213.478652134908], "value": 4522.515211111284, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.23624182485247, 259.9363156026774, 297.4957447127119, 303.94073155734634, 311.75298804780874, 5190.610704197186, 5245.082110630638, 5610.719464026793, 5631.239782629349, 5669.149627555868, 5422.2951016367915, 5442.537384322337, 5468.425815289397, 5519.972400138004, 5536.640118392312, 3092.58250136231, 3497.7026952708925, 3770.6040865709115, 3845.769253844778, 3881.9223615527603, 3240.0191823522414, 3493.3013397320638, 3872.3610604250266, 3875.345388408949, 4025.1748391579654, 4146.232946904832, 4183.602557757008, 4184.849166479212, 4188.643965262956, 4193.874183774492, 3646.781193128421, 4017.6986725995375, 4079.0006448420327, 4211.831526738927, 4223.64099051581, 3144.9056528304186, 3163.4780261257097, 3585.515955346021, 3606.6573675500786, 3609.6390360963956, 5301.310829592167, 5357.464253574651, 5359.3032905722, 5407.0, 5416.702081385522, 4666.486686464486, 4693.577578017995, 4798.760061996921, 4830.3237546743485, 4837.516248375135, 5657.886842263131, 5660.858478538028, 5740.933264024948, 5769.105788602773, 5858.593937455023, 3969.7419667721506, 3993.8402463901407, 3999.2201520703475, 4001.239764444746, 4005.856866576397, 5179.249008893689, 5941.435563621449, 6089.939100608982, 6105.419985101453, 6166.1983942087245, 4023.5775243599, 4042.6361627453084, 4053.7973101345406, 4064.7561146331313, 4077.347624380085, 3701.555813302367, 4018.4374187613757, 4081.5306239782212, 4169.582342003733, 4216.24107660624, 4392.2094023075515, 4646.3495110684535, 4674.345591617176, 4836.7339796310835, 4856.247281671276, 5318.654287471302, 5434.429384914527, 5827.3007239131575, 5851.48910653613, 5953.1367951647635, 1355.302019459979, 1363.1071648070529, 1372.574501904393, 1373.272165752153, 1379.6137081617164, 783.8824176373589, 819.8073452738602, 829.0093338460482, 841.4068082002174, 841.7643059943226, 4384.908639409209, 5356.946540471894, 5401.352587460784, 5508.614396992252, 5589.888202235932, 4770.713757174581, 5627.5087101918025, 6020.307664618537, 6082.844259590664, 6142.57002009855, 5209.20282502536, 5461.5357694596405, 5504.284443022381, 5524.558035357162, 5536.640118392292, 2588.831725937808, 2670.626112344273, 3000.6549246836457, 3146.4021835851113, 3158.957544010517, 5927.739743141255, 6192.287886893065, 6230.130960711768, 6238.407351301619, 6319.905201421982, 5438.94798375213, 5471.322019673202, 6142.846428839225, 6278.089676996809, 6296.937030629727, 6203.565750397429, 6213.347598502182, 6223.3465486123305, 6226.782062627877, 6252.343503932204, 5233.371995360581, 5840.853978650568, 5860.179574859438, 6025.578209525291, 6059.151718759465, 5337.733113344239, 5499.927514134705, 5880.94119058804, 5916.343423841331, 5918.0, 4718.584424672653, 5403.0, 6158.692065396623, 6169.198004259418, 6255.812325630239, 3611.711063114997, 3685.5073695153505, 3873.5739068701855, 3934.6065393460426, 3938.0, 5412.404635490014, 5817.447342502455, 5893.823185304448, 6010.639361638316, 6032.426919442646, 4751.453582838018, 5548.583856210719, 5863.882722345615, 5891.1457838612305, 5893.793717219876, 5759.568032397503, 5773.624714393551, 5781.739821708117, 5782.479576838105, 5787.94212057874, 4969.925451118163, 5614.883739524603, 5674.453466583385, 5674.687892165971, 5704.486596206361, 4718.97640511809, 5562.467832926202, 5738.1679656450415, 5740.540756739534, 5893.145493903446, 5468.3711373191, 5582.627383362136, 5645.515972971879, 5748.6263392881, 5752.561859535116], "value": 4205.640916031924, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.7828138432933, 674.6997586074198, 686.4452016183345, 708.4828075504881, 708.6492186367749, 11296.265742726717, 15082.622934426645, 15280.617984550392, 15512.61218469536, 15750.606234844134, 14344.497942572012, 14429.5671129866, 14582.854171458283, 14607.050541714782, 14673.706525869477, 674.8279188806872, 698.0018573439979, 828.6395417993162, 853.9786505337386, 856.7815207122175, 838.3837879158814, 859.2266959736224, 871.847426700325, 874.0778478704964, 875.0199776250608, 882.3117967984982, 903.0112027330096, 905.2169873059789, 907.1065000974036, 907.795745957161, 893.8569828827357, 897.7755561109723, 899.8425275576814, 900.1088921967271, 900.8198360327928, 651.6253154436171, 665.4643012375036, 668.8695704337656, 672.4989882537492, 673.9326067393271, 15285.923570382078, 15422.768658470131, 15432.845671543366, 15465.304061317267, 15495.92252038744, 11682.41587920609, 14377.496787612488, 14391.2084835334, 14421.855781442157, 14670.926645366811, 11801.763964720743, 11835.467403966843, 11884.881151188463, 11937.462814173383, 12023.939880300542, 3829.617038296176, 3843.331086647173, 3873.709471789599, 3883.4951456310782, 4007.0984028593625, 95456.56815147778, 98883.51674724885, 108810.0, 110749.44625276822, 112394.0, 10992.0, 11076.224664273584, 11102.666919992413, 11117.221794474472, 11528.423578821026, 21749.0, 21793.91030448471, 21827.23604673829, 21851.562968740855, 21936.561268774534, 8078.676852925817, 8180.836383272302, 8210.507369557845, 8215.794605134919, 8334.16658334174, 3762.435634654769, 3954.62431069048, 4565.977170114194, 4649.628029757611, 4723.669343146015, 3523.1544429337287, 3627.437747149196, 3646.4712616670963, 3663.816809159531, 3667.431548110047, 656.5043392238924, 661.6493258572964, 666.9933300667028, 668.1225755626705, 680.9182898052167, 588.7792077970761, 663.6620276360736, 669.6685140855308, 685.2325395557008, 685.5783693028851, 7970.840583188304, 9355.95322023399, 9470.715878523655, 9580.808383832284, 9623.518824058769, 628.2806186915956, 660.5277226782873, 667.7863083813133, 668.9732410703517, 669.5045666206972, 4453.398791163151, 4506.211413002777, 4654.394928659321, 4712.787924543335, 4720.881977950578, 88942.0, 98887.50556247172, 120036.0, 160785.0, 164377.0, 137921.6207837929, 139036.0, 140973.0, 142223.0, 147562.0, 116191.0, 117059.0, 117454.0, 117705.0, 121621.99999999822, 7097.645117744195, 8904.510251935946, 8966.237869781142, 9085.454872707658, 9094.181523662903, 66218.67562648817, 67149.66425167843, 67448.66275668591, 69052.65473672599, 70719.64640176766, 59070.0, 60832.0, 64207.35792642201, 84092.0, 95125.5243723777, 13135.277559734306, 13377.130486518345, 13396.330183490982, 13530.594082177551, 13624.386902589606, 11918.56977162746, 12043.518259269882, 12306.200096993667, 12597.87402125967, 12627.494900203888, 11535.019523340274, 13596.252206128758, 13910.51313204033, 14163.362648680628, 14189.432422702976, 13533.932330338286, 13651.249181295125, 13963.720725585634, 14036.64908377258, 14045.297735113409, 11402.315861048364, 12219.816702749287, 13237.205767653972, 13457.125286856322, 13886.861131388558, 9866.0, 11184.516793613207, 11214.271072380268, 11593.768124637629, 11824.23142495735, 9313.527098666633, 11155.944220278847, 11427.028702560376, 11641.825372619249, 11817.172797904064], "value": 7730.423823172457, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 114.0, 118.0, 112.0, 117.0, 117.0, 118.0, 126.0, 128.0, 133.0], "value": 119.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 136.0, 160.0, 114.0, 137.0, 137.0, 113.0, 160.0, 135.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 207.0, 211.0, 267.0, 247.0, 199.0, 198.0, 196.0, 199.0, 224.0], "value": 215.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 88.0, 76.0, 148.0, 58.0, 59.0, 58.0, 61.0, 137.0, 59.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 131.0, 70.0, 70.0, 74.0, 112.0, 69.0, 72.0, 111.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 88.0, 91.0, 86.0, 90.0, 88.0, 91.0, 87.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 124.0, 149.0, 151.0, 136.0, 95.0, 94.0, 137.0, 95.0, 119.0], "value": 119.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 128.0, 118.0, 90.0, 91.0, 89.0, 125.0, 144.0, 96.0], "value": 106.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 58.0, 58.0, 57.0, 56.0, 58.0, 57.0, 55.0, 58.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 75.0, 80.0, 111.0, 107.0, 99.0, 75.0, 72.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 88.0, 86.0, 86.0, 90.0, 85.0, 87.0, 92.0, 78.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 155.0, 145.0, 167.0, 153.0, 155.0, 139.0, 158.0, 245.0, 156.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 172.0, 172.0, 173.0, 150.0, 230.0, 181.0, 162.0, 175.0, 178.0], "value": 181.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 59.0, 66.0, 89.0, 88.0, 84.0, 69.0, 68.0, 66.0, 68.0], "value": 72.3, "unit": "score"}]}]} 06:17:39 INFO - Return code: 0 06:17:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:17:39 ERROR - # TBPL SUCCESS # 06:17:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:17:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:17:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:17:39 INFO - 2016-09-12 06:17:39.444 screenresolution[2253:18718] starting screenresolution argv=screenresolution get 06:17:39 INFO - 2016-09-12 06:17:39.447 screenresolution[2253:18718] Display 0: 1600x1200x32@60 06:17:39 INFO - 2016-09-12 06:17:39.453 screenresolution[2254:18721] starting screenresolution argv=screenresolution list 06:17:39 INFO - Available Modes on Display 0 06:17:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:17:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:17:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:17:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:17:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:17:39 INFO - Intel Iris: 06:17:39 INFO - Chipset Model: Intel Iris 06:17:39 INFO - Type: GPU 06:17:39 INFO - Bus: Built-In 06:17:39 INFO - VRAM (Dynamic, Max): 1536 MB 06:17:39 INFO - Vendor: Intel (0x8086) 06:17:39 INFO - Device ID: 0x0a2e 06:17:39 INFO - Revision ID: 0x0009 06:17:39 INFO - Displays: 06:17:39 INFO - Display: 06:17:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:17:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:17:39 INFO - Display Serial Number: 1600x1200 60 06:17:39 INFO - Main Display: Yes 06:17:39 INFO - Mirror: Off 06:17:39 INFO - Online: Yes 06:17:39 INFO - Rotation: Supported 06:17:39 INFO - Return code: 0 06:17:39 INFO - Running post-action listener: _resource_record_post_action 06:17:39 INFO - [mozharness: 2016-09-12 13:17:39.696290Z] Finished run-tests step (success) 06:17:39 INFO - Running post-run listener: _resource_record_post_run 06:17:39 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 4096; Write bytes: 704997376; Read time: 0; Write time: 2016 06:17:39 INFO - TinderboxPrint: CPU usage
54.5% 06:17:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 0 06:17:39 INFO - TinderboxPrint: I/O write bytes / time
704,997,376 / 2,016 06:17:39 INFO - TinderboxPrint: CPU idle
2,202.8 (45.5%) 06:17:39 INFO - TinderboxPrint: CPU system
983.0 (20.3%) 06:17:39 INFO - TinderboxPrint: CPU user
1,659.9 (34.3%) 06:17:39 INFO - TinderboxPrint: Swap in / out
405,274,624 / 0 06:17:39 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392120832; Write bytes: 386390016; Read time: 29509; Write time: 823 06:17:39 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 4096; Write bytes: 296550400; Read time: 0; Write time: 1141 06:17:40 INFO - Running post-run listener: _upload_blobber_files 06:17:40 INFO - Blob upload gear active. 06:17:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:17:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:17:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:17:40 INFO - (blobuploader) - INFO - Open directory for files ... 06:17:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:17:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:17:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:41 INFO - (blobuploader) - INFO - Done attempting. 06:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:17:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:42 INFO - (blobuploader) - INFO - Done attempting. 06:17:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:17:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:17:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:44 INFO - (blobuploader) - INFO - Done attempting. 06:17:44 INFO - (blobuploader) - INFO - Iteration through files over. 06:17:44 INFO - Return code: 0 06:17:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:17:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:17:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/396d877fd90f792e54699a3ec88ce365b94dc0f9f6b68fd143a718573e26a808acbc9f70efa12cd527125bd797f49de90f451a17a6e2cb4dbf384b7a9a7b89f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/672f895dbeae11b3a4ebd5dd91e4a7fd0fde21459eaa73cec1409ed54d744986cc2e7509d712bd3f88e4af83815ee2063c47baa3e00e7e686011839a8e6d0a0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ff2909b9e6ad54e024d3b84f0a341e805e137c24720c29dcb4ac2679560218fec377eb4acf1918f53b99d0478825a3c13386ee01990d774deee22d1f2945bc6"} 06:17:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:17:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:17:44 INFO - Contents: 06:17:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/396d877fd90f792e54699a3ec88ce365b94dc0f9f6b68fd143a718573e26a808acbc9f70efa12cd527125bd797f49de90f451a17a6e2cb4dbf384b7a9a7b89f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/672f895dbeae11b3a4ebd5dd91e4a7fd0fde21459eaa73cec1409ed54d744986cc2e7509d712bd3f88e4af83815ee2063c47baa3e00e7e686011839a8e6d0a0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ff2909b9e6ad54e024d3b84f0a341e805e137c24720c29dcb4ac2679560218fec377eb4acf1918f53b99d0478825a3c13386ee01990d774deee22d1f2945bc6"} 06:17:44 INFO - Running post-run listener: copy_logs_to_upload_dir 06:17:44 INFO - Copying logs to upload dir... 06:17:44 INFO - mkdir: /builds/slave/test/build/upload/logs 06:17:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.897554 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-12 06:17:45.053665) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 06:17:45.057077) ========= 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.930tcDITCU/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.E6vLSAwS5j/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/396d877fd90f792e54699a3ec88ce365b94dc0f9f6b68fd143a718573e26a808acbc9f70efa12cd527125bd797f49de90f451a17a6e2cb4dbf384b7a9a7b89f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/672f895dbeae11b3a4ebd5dd91e4a7fd0fde21459eaa73cec1409ed54d744986cc2e7509d712bd3f88e4af83815ee2063c47baa3e00e7e686011839a8e6d0a0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ff2909b9e6ad54e024d3b84f0a341e805e137c24720c29dcb4ac2679560218fec377eb4acf1918f53b99d0478825a3c13386ee01990d774deee22d1f2945bc6"} build_url:https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011240 build_url: 'https://queue.taskcluster.net/v1/task/dTGa4fR2TMWCmd0laLAR5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/396d877fd90f792e54699a3ec88ce365b94dc0f9f6b68fd143a718573e26a808acbc9f70efa12cd527125bd797f49de90f451a17a6e2cb4dbf384b7a9a7b89f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/672f895dbeae11b3a4ebd5dd91e4a7fd0fde21459eaa73cec1409ed54d744986cc2e7509d712bd3f88e4af83815ee2063c47baa3e00e7e686011839a8e6d0a0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ff2909b9e6ad54e024d3b84f0a341e805e137c24720c29dcb4ac2679560218fec377eb4acf1918f53b99d0478825a3c13386ee01990d774deee22d1f2945bc6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 06:17:45.090339) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:17:45.090737) ========= 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.930tcDITCU/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.E6vLSAwS5j/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.005257 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:17:45.139611) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 06:17:45.139931) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 06:17:45.140253) ========= ========= Total master_lag: 0.20 =========