builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0244 starttime: 1473518595.23 results: success (0) buildid: 20160910055116 builduid: 8dc36a18819545d4a68100449d4a7b60 revision: 6138e059bacb345b7943be495a1ae0779737dfe0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:15.233151) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:15.233553) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:15.233849) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:15.262537) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:15.262805) ========= 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.N7n6XR5sqh/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.s7587poQbE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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-10 07:43:15-- 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% 13.2M=0.001s 2016-09-10 07:43:15 (13.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.658965 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:15.945017) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:15.945328) ========= 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.N7n6XR5sqh/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.s7587poQbE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.026142 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:16.005091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 07:43:16.005442) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6138e059bacb345b7943be495a1ae0779737dfe0 --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 6138e059bacb345b7943be495a1ae0779737dfe0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N7n6XR5sqh/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.s7587poQbE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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-10 07:43:16,095 truncating revision to first 12 chars 2016-09-10 07:43:16,095 Setting DEBUG logging. 2016-09-10 07:43:16,095 attempt 1/10 2016-09-10 07:43:16,095 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6138e059bacb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-10 07:43:16,834 unpacking tar archive at: mozilla-central-6138e059bacb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.020069 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 07:43:17.057579) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:17.057907) ========= script_repo_revision: 6138e059bacb345b7943be495a1ae0779737dfe0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:17.058365) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:17.058665) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 07:43:17.080455) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2016-09-10 07:43:17.083779) ========= /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.N7n6XR5sqh/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.s7587poQbE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:43:17 INFO - MultiFileLogger online at 20160910 07:43:17 in /builds/slave/test 07:43:17 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 07:43:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:43:17 INFO - {'append_to_log': False, 07:43:17 INFO - 'base_work_dir': '/builds/slave/test', 07:43:17 INFO - 'blob_upload_branch': 'Firefox', 07:43:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:43:17 INFO - 'branch': 'Firefox', 07:43:17 INFO - 'buildbot_json_path': 'buildprops.json', 07:43:17 INFO - 'config_files': ('talos/mac_config.py',), 07:43:17 INFO - 'default_actions': ('clobber', 07:43:17 INFO - 'read-buildbot-config', 07:43:17 INFO - 'download-and-extract', 07:43:17 INFO - 'populate-webroot', 07:43:17 INFO - 'create-virtualenv', 07:43:17 INFO - 'install', 07:43:17 INFO - 'run-tests'), 07:43:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:43:17 INFO - 'download_minidump_stackwalk': True, 07:43:17 INFO - 'download_symbols': 'ondemand', 07:43:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:43:17 INFO - 'tooltool.py': '/tools/tooltool.py', 07:43:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:43:17 INFO - '/tools/misc-python/virtualenv.py')}, 07:43:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:43:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:43:17 INFO - 'installer_path': 'installer.exe', 07:43:17 INFO - 'log_level': 'info', 07:43:17 INFO - 'log_name': 'talos', 07:43:17 INFO - 'log_to_console': True, 07:43:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:43:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:43:17 INFO - 'opt_config_files': (), 07:43:17 INFO - 'pip_index': False, 07:43:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:43:17 INFO - 'cmd': ('bash', 07:43:17 INFO - '-c', 07:43:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:43:17 INFO - 'enabled': True, 07:43:17 INFO - 'halt_on_failure': False, 07:43:17 INFO - 'name': 'check_screen_resolution'},), 07:43:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:43:17 INFO - 'cmd': ('bash', 07:43:17 INFO - '-c', 07:43:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:43:17 INFO - 'enabled': True, 07:43:17 INFO - 'halt_on_failure': False, 07:43:17 INFO - 'name': 'check_screen_resolution'},), 07:43:17 INFO - 'run_cmd_checks_enabled': True, 07:43:17 INFO - 'sps_profile': False, 07:43:17 INFO - 'sps_profile_interval': 0, 07:43:17 INFO - 'suite': 'dromaeojs-e10s', 07:43:17 INFO - 'system_bits': '32', 07:43:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:43:17 INFO - 'title': 't-yosemite-r7-0244', 07:43:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:43:17 INFO - 'use_talos_json': True, 07:43:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:43:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:43:17 INFO - 'work_dir': 'build'} 07:43:17 INFO - [mozharness: 2016-09-10 14:43:17.283876Z] Running clobber step. 07:43:17 INFO - Running pre-action listener: _resource_record_pre_action 07:43:17 INFO - Running main action method: clobber 07:43:17 INFO - rmtree: /builds/slave/test/build 07:43:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:43:19 INFO - Running post-action listener: _resource_record_post_action 07:43:19 INFO - [mozharness: 2016-09-10 14:43:19.039898Z] Finished clobber step (success) 07:43:19 INFO - [mozharness: 2016-09-10 14:43:19.040069Z] Running read-buildbot-config step. 07:43:19 INFO - Running pre-action listener: _resource_record_pre_action 07:43:19 INFO - Running main action method: read_buildbot_config 07:43:19 INFO - Using buildbot properties: 07:43:19 INFO - { 07:43:19 INFO - "project": "", 07:43:19 INFO - "product": "firefox", 07:43:19 INFO - "script_repo_revision": "production", 07:43:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:43:19 INFO - "repository": "", 07:43:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:43:19 INFO - "buildid": "20160910055116", 07:43:19 INFO - "pgo_build": "False", 07:43:19 INFO - "basedir": "/builds/slave/test", 07:43:19 INFO - "buildnumber": 81, 07:43:19 INFO - "slavename": "t-yosemite-r7-0244", 07:43:19 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:43:19 INFO - "platform": "yosemite_r7", 07:43:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:43:19 INFO - "branch": "mozilla-central", 07:43:19 INFO - "repo_path": "mozilla-central", 07:43:19 INFO - "revision": "6138e059bacb345b7943be495a1ae0779737dfe0", 07:43:19 INFO - "stage_platform": "macosx64", 07:43:19 INFO - "builduid": "8dc36a18819545d4a68100449d4a7b60", 07:43:19 INFO - "slavebuilddir": "test" 07:43:19 INFO - } 07:43:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 07:43:19 INFO - Running post-action listener: _resource_record_post_action 07:43:19 INFO - [mozharness: 2016-09-10 14:43:19.043381Z] Finished read-buildbot-config step (success) 07:43:19 INFO - [mozharness: 2016-09-10 14:43:19.043495Z] Running download-and-extract step. 07:43:19 INFO - Running pre-action listener: _resource_record_pre_action 07:43:19 INFO - Running main action method: download_and_extract 07:43:19 INFO - mkdir: /builds/slave/test/build/tests 07:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:19 INFO - trying https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 07:43:19 INFO - Downloading https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/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 07:43:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/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 07:43:20 INFO - Downloaded 1199 bytes. 07:43:20 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:43:20 INFO - Using the following test package requirements: 07:43:20 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:43:20 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:43:20 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 07:43:20 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:43:20 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 07:43:20 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:43:20 INFO - u'jsshell-mac.zip'], 07:43:20 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:43:20 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 07:43:20 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:43:20 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:43:20 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 07:43:20 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:43:20 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 07:43:20 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:43:20 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 07:43:20 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:43:20 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 07:43:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:43:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:43:21 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:43:28 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 07:43:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:43:28 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:43:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:43:36 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:37 INFO - trying https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:43:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 07:43:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/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 07:43:40 INFO - Downloaded 111663424 bytes. 07:43:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:43:40 INFO - mkdir: /builds/slave/test/properties 07:43:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:43:40 INFO - Writing to file /builds/slave/test/properties/build_url 07:43:40 INFO - Contents: 07:43:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:43:40 INFO - Running post-action listener: _resource_record_post_action 07:43:40 INFO - Running post-action listener: set_extra_try_arguments 07:43:40 INFO - [mozharness: 2016-09-10 14:43:40.965830Z] Finished download-and-extract step (success) 07:43:40 INFO - [mozharness: 2016-09-10 14:43:40.965968Z] Running populate-webroot step. 07:43:40 INFO - Running pre-action listener: _resource_record_pre_action 07:43:40 INFO - Running main action method: populate_webroot 07:43:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:43:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:43:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:43:40 INFO - '--tppagecycles', 07:43:40 INFO - '7'], 07:43:40 INFO - 'tests': ['tsvgm']}}, 07:43:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:43:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:43:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:43:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:43:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:40 INFO - 'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:40 INFO - 'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['damp', 'tps']}, 07:43:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:40 INFO - 'tests': ['damp', 'tps']}, 07:43:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['dromaeo_dom']}, 07:43:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:43:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['basic_compositor_video']}, 07:43:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:43:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['a11yr', 07:43:40 INFO - 'ts_paint', 07:43:40 INFO - 'tpaint', 07:43:40 INFO - 'sessionrestore', 07:43:40 INFO - 'sessionrestore_no_auto_restore', 07:43:40 INFO - 'tabpaint']}, 07:43:40 INFO - 'other-e10s': {'tests': ['a11yr', 07:43:40 INFO - 'ts_paint', 07:43:40 INFO - 'tpaint', 07:43:40 INFO - 'sessionrestore', 07:43:40 INFO - 'sessionrestore_no_auto_restore', 07:43:40 INFO - 'tabpaint']}, 07:43:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:43:40 INFO - 'ts_paint', 07:43:40 INFO - 'tpaint', 07:43:40 INFO - 'sessionrestore', 07:43:40 INFO - 'sessionrestore_no_auto_restore', 07:43:40 INFO - 'tabpaint']}, 07:43:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:43:40 INFO - 'ts_paint', 07:43:40 INFO - 'tpaint', 07:43:40 INFO - 'sessionrestore', 07:43:40 INFO - 'sessionrestore_no_auto_restore', 07:43:40 INFO - 'tabpaint']}, 07:43:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['a11yr', 07:43:40 INFO - 'ts_paint', 07:43:40 INFO - 'tpaint', 07:43:40 INFO - 'sessionrestore', 07:43:40 INFO - 'sessionrestore_no_auto_restore', 07:43:40 INFO - 'tabpaint']}, 07:43:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['a11yr', 07:43:40 INFO - 'ts_paint', 07:43:40 INFO - 'tpaint', 07:43:40 INFO - 'sessionrestore', 07:43:40 INFO - 'sessionrestore_no_auto_restore', 07:43:40 INFO - 'tabpaint']}, 07:43:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['tsvgx', 07:43:40 INFO - 'tsvgr_opacity', 07:43:40 INFO - 'tart', 07:43:40 INFO - 'tscrollx', 07:43:40 INFO - 'cart']}, 07:43:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:43:40 INFO - 'tsvgr_opacity', 07:43:40 INFO - 'tart', 07:43:40 INFO - 'tscrollx', 07:43:40 INFO - 'cart']}, 07:43:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:40 INFO - 'talos_options': ['--disable-e10s'], 07:43:40 INFO - 'tests': ['tp5o']}, 07:43:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:40 INFO - 'tests': ['tp5o']}, 07:43:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:40 INFO - 'talos_options': ['--disable-e10s', 07:43:40 INFO - '--xperf_path', 07:43:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:40 INFO - 'tests': ['tp5n']}, 07:43:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:40 INFO - 'talos_options': ['--xperf_path', 07:43:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:40 INFO - 'tests': ['tp5n']}}, 07:43:40 INFO - 'talos.zip': {'path': '', 07:43:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:43:40 INFO - Running post-action listener: _resource_record_post_action 07:43:40 INFO - [mozharness: 2016-09-10 14:43:40.984801Z] Finished populate-webroot step (success) 07:43:40 INFO - [mozharness: 2016-09-10 14:43:40.984908Z] Running create-virtualenv step. 07:43:40 INFO - Running pre-action listener: _resource_record_pre_action 07:43:40 INFO - Running main action method: create_virtualenv 07:43:40 INFO - Creating virtualenv /builds/slave/test/build/venv 07:43:40 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 07:43:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:43:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:43:41 INFO - Using real prefix '/tools/python27' 07:43:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:43:41 INFO - Installing distribute.............................................................................................................................................................................................done. 07:43:44 INFO - Installing pip.................done. 07:43:44 INFO - Return code: 0 07:43:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:43:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:43:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:43:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:44 INFO - 'HOME': '/Users/cltbld', 07:43:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:44 INFO - 'LOGNAME': 'cltbld', 07:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:44 INFO - 'MOZ_NO_REMOTE': '1', 07:43:44 INFO - 'NO_EM_RESTART': '1', 07:43:44 INFO - 'PAGER': '/bin/cat', 07:43:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:44 INFO - 'PWD': '/builds/slave/test', 07:43:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:44 INFO - 'SHELL': '/bin/bash', 07:43:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:43:44 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:43:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:44 INFO - 'USER': 'cltbld', 07:43:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:44 INFO - 'XPC_FLAGS': '0x0', 07:43:44 INFO - 'XPC_SERVICE_NAME': '0', 07:43:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:44 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 07:43:44 INFO - Cleaning up... 07:43:44 INFO - Return code: 0 07:43:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:43:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:43:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:43:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:44 INFO - 'HOME': '/Users/cltbld', 07:43:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:44 INFO - 'LOGNAME': 'cltbld', 07:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:44 INFO - 'MOZ_NO_REMOTE': '1', 07:43:44 INFO - 'NO_EM_RESTART': '1', 07:43:44 INFO - 'PAGER': '/bin/cat', 07:43:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:44 INFO - 'PWD': '/builds/slave/test', 07:43:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:44 INFO - 'SHELL': '/bin/bash', 07:43:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:43:44 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:43:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:44 INFO - 'USER': 'cltbld', 07:43:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:44 INFO - 'XPC_FLAGS': '0x0', 07:43:44 INFO - 'XPC_SERVICE_NAME': '0', 07:43:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:45 INFO - Downloading/unpacking psutil>=3.1.1 07:43:45 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 07:43:45 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 07:43:45 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 07:43:45 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 07:43:45 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 07:43:45 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 07:43:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:43:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:48 INFO - Installing collected packages: psutil 07:43:48 INFO - Running setup.py install for psutil 07:43:48 INFO - building 'psutil._psutil_osx' extension 07:43:48 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 07:43:49 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 07:43:49 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 07:43:49 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 07:43:49 INFO - building 'psutil._psutil_posix' extension 07:43:49 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 07:43:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:43:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:43:49 INFO - ^ 07:43:49 INFO - 1 warning generated. 07:43:49 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 07:43:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:49 INFO - Successfully installed psutil 07:43:49 INFO - Cleaning up... 07:43:49 INFO - Return code: 0 07:43:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:49 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 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:43:49 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 07:43:49 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 07:43:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:43:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:49 INFO - 'HOME': '/Users/cltbld', 07:43:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:49 INFO - 'LOGNAME': 'cltbld', 07:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:49 INFO - 'MOZ_NO_REMOTE': '1', 07:43:49 INFO - 'NO_EM_RESTART': '1', 07:43:49 INFO - 'PAGER': '/bin/cat', 07:43:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:49 INFO - 'PWD': '/builds/slave/test', 07:43:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:49 INFO - 'SHELL': '/bin/bash', 07:43:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:43:49 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:43:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:49 INFO - 'USER': 'cltbld', 07:43:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:49 INFO - 'XPC_FLAGS': '0x0', 07:43:49 INFO - 'XPC_SERVICE_NAME': '0', 07:43:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:43:49 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 07:43:49 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 07:43:49 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 07:43:49 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 07:43:49 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 07:43:49 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 07:43:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:43:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:43:52 INFO - Installing collected packages: mozsystemmonitor 07:43:52 INFO - Running setup.py install for mozsystemmonitor 07:43:52 INFO - Successfully installed mozsystemmonitor 07:43:52 INFO - Cleaning up... 07:43:52 INFO - Return code: 0 07:43:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:52 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 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:43:52 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 07:43:52 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 07:43:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:43:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:52 INFO - 'HOME': '/Users/cltbld', 07:43:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:52 INFO - 'LOGNAME': 'cltbld', 07:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:52 INFO - 'MOZ_NO_REMOTE': '1', 07:43:52 INFO - 'NO_EM_RESTART': '1', 07:43:52 INFO - 'PAGER': '/bin/cat', 07:43:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:52 INFO - 'PWD': '/builds/slave/test', 07:43:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:52 INFO - 'SHELL': '/bin/bash', 07:43:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:43:52 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:43:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:52 INFO - 'USER': 'cltbld', 07:43:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:52 INFO - 'XPC_FLAGS': '0x0', 07:43:52 INFO - 'XPC_SERVICE_NAME': '0', 07:43:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:52 INFO - Downloading/unpacking jsonschema==2.5.1 07:43:52 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 07:43:52 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 07:43:52 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 07:43:52 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 07:43:52 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 07:43:52 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 07:43:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:43:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:43:55 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 07:43:55 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 07:43:55 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 07:43:55 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 07:43:55 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 07:43:55 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 07:43:55 INFO - Downloading functools32-3.2.3-2.tar.gz 07:43:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:43:55 INFO - warning: no files found matching '*.txt' 07:43:55 INFO - no previously-included directories found matching 'build' 07:43:55 INFO - no previously-included directories found matching 'dist' 07:43:55 INFO - no previously-included directories found matching '.git*' 07:43:55 INFO - Installing collected packages: jsonschema, functools32 07:43:55 INFO - Running setup.py install for functools32 07:43:56 INFO - warning: no files found matching '*.txt' 07:43:56 INFO - no previously-included directories found matching 'build' 07:43:56 INFO - no previously-included directories found matching 'dist' 07:43:56 INFO - no previously-included directories found matching '.git*' 07:43:56 INFO - Successfully installed jsonschema functools32 07:43:56 INFO - Cleaning up... 07:43:56 INFO - Return code: 0 07:43:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:43:56 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')]} 07:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:56 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')]} 07:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:56 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 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:43:56 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 07:43:56 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 07:43:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:43:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:56 INFO - 'HOME': '/Users/cltbld', 07:43:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:56 INFO - 'LOGNAME': 'cltbld', 07:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:56 INFO - 'MOZ_NO_REMOTE': '1', 07:43:56 INFO - 'NO_EM_RESTART': '1', 07:43:56 INFO - 'PAGER': '/bin/cat', 07:43:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:56 INFO - 'PWD': '/builds/slave/test', 07:43:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:56 INFO - 'SHELL': '/bin/bash', 07:43:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:43:56 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:43:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:56 INFO - 'USER': 'cltbld', 07:43:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:56 INFO - 'XPC_FLAGS': '0x0', 07:43:56 INFO - 'XPC_SERVICE_NAME': '0', 07:43:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:43:56 INFO - Cleaning up... 07:43:56 INFO - Return code: 0 07:43:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:43:56 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')]} 07:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:56 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')]} 07:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:56 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 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:43:56 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 07:43:56 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 07:43:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:43:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:56 INFO - 'HOME': '/Users/cltbld', 07:43:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:56 INFO - 'LOGNAME': 'cltbld', 07:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:56 INFO - 'MOZ_NO_REMOTE': '1', 07:43:56 INFO - 'NO_EM_RESTART': '1', 07:43:56 INFO - 'PAGER': '/bin/cat', 07:43:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:56 INFO - 'PWD': '/builds/slave/test', 07:43:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:56 INFO - 'SHELL': '/bin/bash', 07:43:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:43:56 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:43:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:56 INFO - 'USER': 'cltbld', 07:43:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:56 INFO - 'XPC_FLAGS': '0x0', 07:43:56 INFO - 'XPC_SERVICE_NAME': '0', 07:43:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:56 INFO - Downloading/unpacking blobuploader==1.2.4 07:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:59 INFO - Downloading blobuploader-1.2.4.tar.gz 07:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:43:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:43:59 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 07:43:59 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 07:43:59 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 07:43:59 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 07:43:59 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 07:43:59 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 07:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:43:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:43:59 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 07:43:59 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 07:43:59 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 07:43:59 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 07:43:59 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 07:43:59 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 07:44:00 INFO - Downloading docopt-0.6.1.tar.gz 07:44:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:44:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:44:00 INFO - Running setup.py install for blobuploader 07:44:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:44:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:44:00 INFO - Running setup.py install for requests 07:44:01 INFO - Running setup.py install for docopt 07:44:01 INFO - Successfully installed blobuploader requests docopt 07:44:01 INFO - Cleaning up... 07:44:01 INFO - Return code: 0 07:44:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:01 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 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:44:01 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 07:44:01 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 07:44:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:44:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:01 INFO - 'HOME': '/Users/cltbld', 07:44:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:01 INFO - 'LOGNAME': 'cltbld', 07:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:01 INFO - 'MOZ_NO_REMOTE': '1', 07:44:01 INFO - 'NO_EM_RESTART': '1', 07:44:01 INFO - 'PAGER': '/bin/cat', 07:44:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:01 INFO - 'PWD': '/builds/slave/test', 07:44:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:01 INFO - 'SHELL': '/bin/bash', 07:44:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:44:01 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:44:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:01 INFO - 'USER': 'cltbld', 07:44:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:01 INFO - 'XPC_FLAGS': '0x0', 07:44:01 INFO - 'XPC_SERVICE_NAME': '0', 07:44:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:44:01 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-5weezn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:44:01 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-bfCi88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:44:01 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-8onp1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:44:01 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-_25nTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:44:01 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-6aDtut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-1dR5wS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-uqUlJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-prsZwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Fd3YR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-g4kqH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-MZ14Nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Fe3HRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-GSZNx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-xYRsDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:02 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Q7FNBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:44:03 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-AXzNDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:44:03 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-dDKWfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:44:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:44:03 INFO - Running setup.py install for manifestparser 07:44:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:44:03 INFO - Running setup.py install for mozcrash 07:44:03 INFO - Running setup.py install for mozdebug 07:44:03 INFO - Running setup.py install for mozdevice 07:44:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:44:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:44:03 INFO - Running setup.py install for mozfile 07:44:04 INFO - Running setup.py install for mozhttpd 07:44:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:44:04 INFO - Running setup.py install for mozinfo 07:44:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:44:04 INFO - Running setup.py install for mozInstall 07:44:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:44:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:44:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:44:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:44:04 INFO - Running setup.py install for mozleak 07:44:04 INFO - Running setup.py install for mozlog 07:44:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:44:04 INFO - Running setup.py install for moznetwork 07:44:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:44:04 INFO - Running setup.py install for mozprocess 07:44:05 INFO - Running setup.py install for mozprofile 07:44:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:44:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:44:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:44:05 INFO - Running setup.py install for mozrunner 07:44:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:44:05 INFO - Running setup.py install for mozscreenshot 07:44:05 INFO - Running setup.py install for moztest 07:44:05 INFO - Running setup.py install for mozversion 07:44:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:44:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:44:05 INFO - Cleaning up... 07:44:05 INFO - Return code: 0 07:44:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:05 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 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:44:05 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 07:44:05 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 07:44:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:44:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:05 INFO - 'HOME': '/Users/cltbld', 07:44:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:05 INFO - 'LOGNAME': 'cltbld', 07:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:05 INFO - 'MOZ_NO_REMOTE': '1', 07:44:05 INFO - 'NO_EM_RESTART': '1', 07:44:05 INFO - 'PAGER': '/bin/cat', 07:44:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:05 INFO - 'PWD': '/builds/slave/test', 07:44:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:05 INFO - 'SHELL': '/bin/bash', 07:44:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:44:05 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:44:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:05 INFO - 'USER': 'cltbld', 07:44:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:05 INFO - 'XPC_FLAGS': '0x0', 07:44:05 INFO - 'XPC_SERVICE_NAME': '0', 07:44:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-CeILDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:44:06 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)) 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-y8dmE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:44:06 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)) 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-piUfR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:44:06 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)) 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-61I2qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:44:06 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)) 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Y6MQYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:44:06 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)) 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-IAi_pB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:44:06 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)) 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-1jkj5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:44:06 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)) 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-iykoXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:44:06 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)) 07:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:44:06 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-EXdjr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:44:07 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)) 07:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:44:07 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-dEunUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:44:07 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)) 07:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:44:07 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-ZfbI9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:44:07 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)) 07:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:44:07 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-qclcnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:44:07 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)) 07:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:44:07 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-7o_IME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:44:07 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)) 07:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:44:07 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-owC2SA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:44:07 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)) 07:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:07 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-OkZMDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:07 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)) 07:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:44:07 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-TtBjWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:44:07 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)) 07:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:44:07 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-NYcr9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:44:07 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)) 07:44:07 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)) 07:44:07 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)) 07:44:07 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)) 07:44:07 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)) 07:44:07 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)) 07:44:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:44:07 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 07:44:07 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 07:44:07 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 07:44:07 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 07:44:07 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 07:44:07 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 07:44:10 INFO - Downloading blessings-1.6.tar.gz 07:44:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:44:10 INFO - Installing collected packages: blessings 07:44:10 INFO - Running setup.py install for blessings 07:44:10 INFO - Successfully installed blessings 07:44:10 INFO - Cleaning up... 07:44:10 INFO - Return code: 0 07:44:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:44:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:44:11 INFO - Reading from file tmpfile_stdout 07:44:11 INFO - Current package versions: 07:44:11 INFO - blessings == 1.6 07:44:11 INFO - blobuploader == 1.2.4 07:44:11 INFO - docopt == 0.6.1 07:44:11 INFO - functools32 == 3.2.3-2 07:44:11 INFO - jsonschema == 2.5.1 07:44:11 INFO - manifestparser == 1.1 07:44:11 INFO - mozInstall == 1.12 07:44:11 INFO - mozcrash == 0.17 07:44:11 INFO - mozdebug == 0.1 07:44:11 INFO - mozdevice == 0.48 07:44:11 INFO - mozfile == 1.2 07:44:11 INFO - mozhttpd == 0.7 07:44:11 INFO - mozinfo == 0.9 07:44:11 INFO - mozleak == 0.1 07:44:11 INFO - mozlog == 3.3 07:44:11 INFO - moznetwork == 0.27 07:44:11 INFO - mozprocess == 0.23 07:44:11 INFO - mozprofile == 0.28 07:44:11 INFO - mozrunner == 6.12 07:44:11 INFO - mozscreenshot == 0.1 07:44:11 INFO - mozsystemmonitor == 0.3 07:44:11 INFO - moztest == 0.7 07:44:11 INFO - mozversion == 1.4 07:44:11 INFO - psutil == 3.1.1 07:44:11 INFO - requests == 1.2.3 07:44:11 INFO - wsgiref == 0.1.2 07:44:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:11 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 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:44:11 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 07:44:11 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 07:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:11 INFO - 'HOME': '/Users/cltbld', 07:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:11 INFO - 'LOGNAME': 'cltbld', 07:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:11 INFO - 'MOZ_NO_REMOTE': '1', 07:44:11 INFO - 'NO_EM_RESTART': '1', 07:44:11 INFO - 'PAGER': '/bin/cat', 07:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:11 INFO - 'PWD': '/builds/slave/test', 07:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:11 INFO - 'SHELL': '/bin/bash', 07:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:44:11 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:11 INFO - 'USER': 'cltbld', 07:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:11 INFO - 'XPC_FLAGS': '0x0', 07:44:11 INFO - 'XPC_SERVICE_NAME': '0', 07:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 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)) 07:44:11 INFO - Cleaning up... 07:44:11 INFO - Return code: 0 07:44:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aba1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6db468b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, '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.N7n6XR5sqh/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.s7587poQbE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 07:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:11 INFO - 'HOME': '/Users/cltbld', 07:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:11 INFO - 'LOGNAME': 'cltbld', 07:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:11 INFO - 'MOZ_NO_REMOTE': '1', 07:44:11 INFO - 'NO_EM_RESTART': '1', 07:44:11 INFO - 'PAGER': '/bin/cat', 07:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:11 INFO - 'PWD': '/builds/slave/test', 07:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:11 INFO - 'SHELL': '/bin/bash', 07:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:44:11 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:11 INFO - 'USER': 'cltbld', 07:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:11 INFO - 'XPC_FLAGS': '0x0', 07:44:11 INFO - 'XPC_SERVICE_NAME': '0', 07:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:44:11 INFO - Cleaning up... 07:44:11 INFO - Return code: 0 07:44:11 INFO - Running post-action listener: _resource_record_post_action 07:44:11 INFO - Running post-action listener: _start_resource_monitoring 07:44:11 INFO - Starting resource monitoring. 07:44:11 INFO - [mozharness: 2016-09-10 14:44:11.610134Z] Finished create-virtualenv step (success) 07:44:11 INFO - [mozharness: 2016-09-10 14:44:11.611122Z] Running install step. 07:44:11 INFO - Running pre-action listener: _resource_record_pre_action 07:44:11 INFO - Running main action method: install 07:44:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:44:11 INFO - Reading from file tmpfile_stdout 07:44:11 INFO - Detecting whether we're running mozinstall >=1.0... 07:44:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:44:11 INFO - Reading from file tmpfile_stdout 07:44:11 INFO - Output received: 07:44:11 INFO - Usage: mozinstall [options] installer 07:44:11 INFO - Options: 07:44:11 INFO - -h, --help show this help message and exit 07:44:11 INFO - -d DEST, --destination=DEST 07:44:11 INFO - Directory to install application into. [default: 07:44:11 INFO - "/builds/slave/test"] 07:44:11 INFO - --app=APP Application being installed. [default: firefox] 07:44:11 INFO - mkdir: /builds/slave/test/build/application 07:44:11 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'] 07:44:11 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 07:44:45 INFO - Reading from file tmpfile_stdout 07:44:45 INFO - Output received: 07:44:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:44:45 INFO - Running post-action listener: _resource_record_post_action 07:44:45 INFO - [mozharness: 2016-09-10 14:44:45.873957Z] Finished install step (success) 07:44:45 INFO - [mozharness: 2016-09-10 14:44:45.874122Z] Running run-tests step. 07:44:45 INFO - Running pre-action listener: _resource_record_pre_action 07:44:45 INFO - Running main action method: run_tests 07:44:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:44:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:44:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:44:45 INFO - 2016-09-10 07:44:45.888 screenresolution[1659:13190] starting screenresolution argv=screenresolution get 07:44:45 INFO - 2016-09-10 07:44:45.891 screenresolution[1659:13190] Display 0: 1600x1200x32@60 07:44:45 INFO - 2016-09-10 07:44:45.896 screenresolution[1660:13192] starting screenresolution argv=screenresolution list 07:44:45 INFO - Available Modes on Display 0 07:44:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:44:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:44:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:44:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:44:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:44:46 INFO - Intel Iris: 07:44:46 INFO - Chipset Model: Intel Iris 07:44:46 INFO - Type: GPU 07:44:46 INFO - Bus: Built-In 07:44:46 INFO - VRAM (Dynamic, Max): 1536 MB 07:44:46 INFO - Vendor: Intel (0x8086) 07:44:46 INFO - Device ID: 0x0a2e 07:44:46 INFO - Revision ID: 0x0009 07:44:46 INFO - Displays: 07:44:46 INFO - Display: 07:44:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:44:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:44:46 INFO - Display Serial Number: 1600x1200 60 07:44:46 INFO - Main Display: Yes 07:44:46 INFO - Mirror: Off 07:44:46 INFO - Online: Yes 07:44:46 INFO - Rotation: Supported 07:44:46 INFO - Return code: 0 07:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:44:46 INFO - Python 2.7.3 07:44:46 INFO - Return code: 0 07:44:46 INFO - grabbing minidump binary from tooltool 07:44:46 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')]} 07:44:46 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 0x10aa3fd30>, '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 0x10a865db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa37b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:44:46 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 07:44:46 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 07:44:46 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 07:44:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:44:46 INFO - Return code: 0 07:44:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:44:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:44:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:44:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:44:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:44:46 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/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 07:44:46 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/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0244 --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 07:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N7n6XR5sqh/Render', 07:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:46 INFO - 'HOME': '/Users/cltbld', 07:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:46 INFO - 'LOGNAME': 'cltbld', 07:44:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:44:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:46 INFO - 'MOZ_NO_REMOTE': '1', 07:44:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:44:46 INFO - 'NO_EM_RESTART': '1', 07:44:46 INFO - 'PAGER': '/bin/cat', 07:44:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:46 INFO - 'PWD': '/builds/slave/test', 07:44:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:46 INFO - 'SHELL': '/bin/bash', 07:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7587poQbE/Listeners', 07:44:46 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 07:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:46 INFO - 'USER': 'cltbld', 07:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:46 INFO - 'XPC_FLAGS': '0x0', 07:44:46 INFO - 'XPC_SERVICE_NAME': '0', 07:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:46 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/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 07:44:46 INFO - mozversion application_buildid: 20160910055116 07:44:46 INFO - mozversion application_changeset: 6138e059bacb345b7943be495a1ae0779737dfe0 07:44:46 INFO - mozversion application_display_name: Nightly 07:44:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:44:46 INFO - mozversion application_name: Firefox 07:44:46 INFO - mozversion application_remotingname: firefox 07:44:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:44:46 INFO - mozversion application_vendor: Mozilla 07:44:46 INFO - mozversion application_version: 51.0a1 07:44:46 INFO - mozversion platform_buildid: 20160910055116 07:44:46 INFO - mozversion platform_changeset: 6138e059bacb345b7943be495a1ae0779737dfe0 07:44:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:44:46 INFO - mozversion platform_version: 51.0a1 07:44:46 INFO - using testdate: 1473518686 07:44:46 INFO - actual date: 1473518686 07:44:46 INFO - starting webserver on 'localhost:49277' 07:44:46 INFO - SUITE-START | Running 2 tests 07:44:46 INFO - TEST-START | dromaeo_css 07:44:46 INFO - operating with platform_type : mac_ 07:44:46 INFO - Initialising browser for dromaeo_css test... 07:44:46 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpnCSjpe/profile http://localhost:49277/getInfo.html) 07:44:49 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 07:44:49 INFO - PROCESS | 1670 | colorDepth:24 07:44:49 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 07:44:49 INFO - PROCESS | 1670 | __metrics 07:44:49 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 07:44:50 INFO - TEST-INFO | 1670: exit 0 07:44:50 INFO - Browser initialized. 07:44:50 INFO - Running cycle 1/1 for dromaeo_css test... 07:44:50 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpnCSjpe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:45:02 INFO - PROCESS | 1674 | RSS: Main: 244428800 07:45:02 INFO - PROCESS | 1674 | 07:45:51 INFO - PROCESS | 1674 | 1473518751855 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] 07:48:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 07:48:05 INFO - PROCESS | 1674 | RSS: Main: 261586944 07:48:05 INFO - PROCESS | 1674 | 07:51:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 07:51:08 INFO - PROCESS | 1674 | RSS: Main: 269471744 07:51:08 INFO - PROCESS | 1674 | 07:54:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 07:54:10 INFO - PROCESS | 1674 | RSS: Main: 271163392 07:54:10 INFO - PROCESS | 1674 | 07:57:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 07:57:13 INFO - PROCESS | 1674 | RSS: Main: 268353536 07:57:13 INFO - PROCESS | 1674 | 08:00:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 08:00:16 INFO - PROCESS | 1674 | RSS: Main: 273559552 08:00:16 INFO - PROCESS | 1674 | 08:03:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 08:03:19 INFO - PROCESS | 1674 | RSS: Main: 276516864 08:03:19 INFO - PROCESS | 1674 | 08:03:19 INFO - PROCESS | 1674 | __start_tp_report 08:03:19 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 08:03:19 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 08:03:19 INFO - PROCESS | 1674 | |i|pagename|runs| 08:03:19 INFO - PROCESS | 1674 | |0;dojo.html;3369;3378.645242249564;3410.7100896423804;3416.8291585420725;3420.4185288500953;5576.247206627104;5642.548596112311;5716.599838011341;5792.928308262969;5812.389699081599;5635.098384258519;5714.714264286779;5729.598928075036;5793.768249270024;5802.999999999989;5121.00140472608;5219.347581552306;5235.397929238129;5289.444608316129;5305.310309659739;5187.351581052369;5307.840764777063;5310.92033619496;5342.973285133588;5376.274202982606;4253.553376895412;5145.176771716545;5181.326427564431;5234.659747116425;5301.549368303698;5222.190560463135;5229;5246.711430871301;5256.106461901486;5263.6841789492755;5168.198929165947;5195.480451954774;5203.739813009334;5260.421353651095;5272.92090618641;4594.724316541018;4614.838480653195;4927.6550641455115;5337.172738225581;5757.136429535562;6219.502439804804;6254.687265636746;6257.624542527417;6258.374162583751;6267.905981410283;6214.844628884269;6254.405831446051;6259.342769009279;6268.905966410507;6272.686365681698;5756.5682573807135;5820.126980952891;5842.532597392198;5886.852828679232;5922.703864806743;4573.153966516205;5280.894382112413;6119.418655227746;6283.120363149163;6290.182276304143;4675.275332323496;5009.423916249605;6025.15647809307;6177.62934223948;6203.565750397429;6124.075808605183;6181.196444462191;6183.783567575113;6254.34329395416;6263.217097862767;5498.625343664084;6078.240219972504;6108.389161083945;6131.957816600026;6232.750689972441;6234.812955611339;6235.220597425322;6242.188515492957;6247.3440288769025;6292.024736165901;4869.123557759566;4891.095147397743;4928.556429921253;4959.603231741452;4978.800847966113;4340.544242854518;4376.671749618791;4394.538573449806;4435.733855968588;4476.888077798081;5464.863378415572;5781.508571771363;5811.651300921958;5830.970845145747;5845.766169353264;6147.231596050494;6214.844628884223;6227.972384556588;6229.034499652471;6245.156903817927;4719.9764001180465;5756.107803290497;5760.36635970043;5829.387914268941;5875.030619947661;5434.510894019557;5438.809641662523;5442.102053161263;5442.2380866678695;5453.400125986138;6373.235211774617;6384.265809431882;6405.551611387158;6415.101885736094;6449;6148.415900489446;6200.131981522681;6203.7828675996125;6217.813465596039;6244.5316601256;6334.271558770801;6367.108604795425;6375.234971803413;6375.840603984938;6388.648624325707;6134.969325153345;6143.911070235962;6183.567150299616;6234.189555357774;6235.407636274546;6189.226346706661;6211.782587609412;6224.62652240867;6230.59501132425;6245.968770156302;5987.31145918225;6069.180660610761;6125.091147189775;6148.569600127948;6152.200213972334;6052.848678783065;6127.632342059491;6164.321924588202;6174.166487524307;6177.84555386119;4612.7001744886475;5544.500994910478;5854.385289544536;5885.55858310637;5965.970170149121;4557.680962332737;5910.408959104056;6083.330833608211;6108.9954560370215;6119.319765820928;5045.142325804549;6008.309044459943;6075;6099.451049405575;6124.081387791689 08:03:19 INFO - PROCESS | 1674 | |1;ext.html;444.10956033153525;506.1041955738343;527.3697930972487;531.5986430245164;533.0032838591833;36703.348349324275;40629.187416251654;40755;40797.980050498714;40874.7956260219;42010.78994605023;42499.36250956238;42542.57457425425;42570.935726606775;42625.72122836312;8442.366822488308;9805.999999999982;9975.102240798327;9978.50107494626;10000.349977251492;9604.279679024066;9723.611055557769;9726.610935562569;9732.902670973306;9740.561674724619;9707.077827606367;9735.464549449778;9742.902570974271;9751.17115045222;9753.609855605768;9706.70879873605;9706.757331066745;9734.17259532944;9734.513274336327;9740.831100267942;9673.274504412197;9737.415755054719;9741.366811157252;9748.26887983397;9764.755881102958;39049.023774405585;39174.60825391738;39179.804100979316;39294.803525982185;39359.606403936166;3202.1674364665355;3296.587926509186;3386.7290616750847;3436.1409647588102;3583.395476012926;30525.38949221025;30707.846460767778;31152.221194470094;31511.36977260442;32111;3221.7261532769744;3269.264415506492;3272.7381809455187;3349.7822641528464;3368.747343949189;430015.8499207515;507164;508867.9999999963;510516;511869;32990.340193196;61022.69488652617;61357.69321153365;61392.69303653543;61682;33412;33678;33688.49467257993;33980.66019339775;34137.65862341395;14255.215963122128;14355.425782968565;14391.78412323816;14392.640183995483;14411.495597654031;12048.698782530508;12191.268523888595;12198.512059517698;12229.816552751716;12247.020238380908;7460.403167746567;7551.169371369145;7628.084629844454;7632.427567932427;7632.618369081523;2792.8184667996516;3205.34290470453;3230.660780618048;3247.610286765556;3252.902412927616;933.8606899582498;949.2643201518878;961.1013702188476;969.4522594733953;1004.5379125602159;29345.7065429343;29385.559216611768;29526.852365738465;29598.408031839244;30358;1061.830107182847;1061.8531985455688;1081.5673730507704;1118.4799068433217;1214.8359971403925;11344.773104537862;14993.850061499244;15172.620684482976;15525;15616;686545;692703;693857;695631;700435;55834.44165558292;55948.16077758837;55978;56297.87404251974;56318.71840640771;1129.8587676540433;1209.056935590241;1229.8339724137127;1344.6503908983539;1394.574654730317;21841.672374914073;22301.330960071224;22383.77616223817;24497.632535511624;29169.854150729112;43231.78384108059;43503.56496434995;43523.78238108789;43594;43796.68609941707;393304;397228;398776;399796;400883;21818.454538636663;25881.83531741114;25965.350866228495;26257.21228363152;26273.868630656725;36746.448803267434;38448.6155138445;42963;44680.55319446894;47704.52295477001;37901;37914.8104259477;38283.42574861435;38659.6134038656;39034.414483782195;37900.104994750705;38030.239395211385;38946.4158037624;39213;39267;37193.256134877694;37198.6280137206;37262.25475490421;37290;37685.811570943064;31055;32287;34003.964024979374;34489.827550862086;34620;24944.37639059038;25019.87490062611;25117;25402;25694.486110278063 08:03:19 INFO - PROCESS | 1674 | |2;jquery.html;372.136642988267;375.89662842718246;380.65360521925055;381.36312358361533;396.74410005546423;19036.524086897818;21326.36020919371;21415.036323365428;21538.56922861542;21648;18606;20247.595048099065;20453.693194602092;20753.377398678065;21159.894200529016;4960.429550601672;5796.24648795656;6099.756009759604;6127.142200091991;6169.660668663222;3898.7465814722095;4069.491313585802;6099.176611157503;6129.295131059918;6131.3562075982045;5821.592488525805;6095.695215239242;6143.7235324410285;6186.319504854462;6190.473809726179;6132.570720049598;6159.168512250856;6588.505862060365;6759.695813688347;6770.086038384854;5916.674582897939;5925.525957923356;5932.881342373172;5958;5959.731812068468;24091.759082409128;24969;25182;25926.87036564824;26210.606840897402;2142.914283428661;2180.4439867833603;2211.7124773779465;2609.856457894815;2622.593498007812;9491.620335186653;9963.45201013944;10252.589896404135;10418.635347762793;10508.94745526275;1859.6001859600115;1892.81071892811;1894.1665667106429;1911.751472308605;1969.940901772949;116573.41713291463;125915;126577.36711316477;126727.73272267343;128411.35794321062;11107.278026928223;11499.827502587468;11578.826317605242;11601.651950441501;11794.174407791372;14336.354864031144;14435.927820361041;14460.855391445952;14490.85509144895;14525.636859078608;8280.213379728915;8537.615807288686;8555.786105347292;8641.092685268082;8685.395560842306;7031.613261270577;8506.404551681322;8508.702195423131;8572.957135214285;8658.39391242607;4403.779811009436;4451.933221001687;4498.280275156025;4519.751413672214;4541.023679908835;1583.5011971229026;1865.8134186581506;1934.4293433436887;1936.5061909213061;1939.767227932657;812.9309008734206;852.2500199824239;918.3892711347013;942.7124726958343;944.5088553952044;16202.513924582281;20415.387538373874;21663.891680541496;21730.456738581346;21744;841.9621117049747;919.6045700348914;931.3108299858056;937.920276776468;1020.6121673763922;6948;9316.906830931604;9464.526773661428;11327.490262938187;11350.921662442055;619221;623014;624788;637799;640245;79888;84427.5778621103;114021;143681.56318436682;151292.5238083353;978.2467500024941;1025.5590096258532;1027.0602398805158;1044.7649278912336;1046.9528871200814;18134.365297214536;18846.717299240798;18900.338488152847;18973.9564323958;19344.226230951168;55387;55495.722521387135;56798.71600642135;57322.999999998334;57930.420695792505;83032;87534.62326883757;88587.11412886047;89352.10647893438;90272.54863725639;7973.601319933864;8128.715494957648;8169.428140030141;8257.339412847077;8360.331173506227;12330.150477428457;12988.870111299144;13160.736785264433;13450.12574182675;13461.259630720404;11078.390688512209;11190.720231994264;11544.59593914209;11635.76728465409;11639.825402619137;10834.295770774874;11066.004059634672;11277.32336059839;11471;11481.655550333504;11256.549738010388;11457.369844658624;11676.94161529187;11781.998530125036;11816.291022538331;8034.919650803417;8058.435909486514;8243.958780206061;8261.463004904663;8374;8270.2556769891;11127.888721112686;11210.215284929976;11252.437378131224;11632.651020469399 08:03:20 INFO - PROCESS | 1674 | |3;mootools.html;1507.710907174366;1548.8993215440996;1688.5187721499376;1824.616830465602;1900.2399040383837;7961.880571791427;8166.305864001552;8479.448835825668;9026.097390260971;9048.547572621375;7190.56856588605;7238.4209263258945;7643.656035478403;8644.135586441353;8729.039805621376;2419.745926677691;2422.0553983946274;2423.164008417093;2428.150147448392;2434.257551446808;2387.546366190418;2388.773066558674;2422.200673777658;2427.647991041298;2436.3300092474533;2399.208261273784;2411.517696460715;2420.8184386171106;2431.185552839801;2439.9146029888957;2386.5107652931283;2387.940301492459;2403.495265994143;2404.386881345263;2408.385861605297;2370.8221883358815;2390.1156572068444;2398.616221404567;2399.2682231919257;2404.6152615581423;8881.689140880102;8921.643134274622;8976.955115224448;8984.820303593891;9024.458532488072;5243.737813109367;5394.78420863169;5413.675179489243;5421.918671219935;5432.864178395532;3491.3017396520545;3626.256617393428;3682.5212722345923;3687.8524859005606;3690.095926498025;1042.1923009667491;1076.8707755069363;1081.291753901196;1082.9620963266248;1095.769888323453;11467.139964502612;11524.654260372203;11573.132015098901;11590.594329198437;11627.244229125165;4507.4365704286965;4628.028114096078;4795.664303498791;4812.494688057774;4856.538628830255;8241.711540096068;9837.114659680663;9971.202303815677;9971.202303815677;9988.850167247498;5375.5162035417;5392.892142157135;5400.486953739467;5406.378266499324;5415.431379705153;4445.177642136216;4601.838935637236;4791;4906.803727850917;4945.159322915041;3880.409032296745;3927.803609819555;3990.5809889961715;4025.959740402617;4046.4825690366115;1147.6270212181075;1286.922784632925;1292.011611117496;1305.764962306794;1307.0523870194033;761.2539711083169;783.6669415498391;904.7285814255697;916.2624087609538;925.2089463508603;5775.306963164448;5842.941570584325;5918.2898052234;5972.611780234271;6159.599626024294;952.0907533305676;955.2119501411363;960.1358777100666;973.5132433783108;994.6220436234184;3299.5875515560556;3935.35066713989;3960.5643379228263;3963.8810835674976;4074;9723.027697230362;10988.945055274833;11017.669469915916;11048.226624136394;11051.447427628586;10815.405152716676;10838.53679753239;10918.781624367468;10930.617428389969;10994;8048.275655191061;8099.392545559107;8263.297619702273;8320.875186872201;8362.874556881652;5398.4871437213405;5455.099908515051;5659;5787.450192231731;6015.969920150371;9619.5671194795;10153.289269751047;10206;10230.130438912776;10250;10151.137153341753;10276.537555810157;10329;10350.430726310125;10357.9482102589;6974.407175390149;6976.895346569907;7287.744928927462;7417.147028091838;7603;7604.201558836243;8298.63905118428;8371.455855369624;8544.359173061921;8546.529940853312;6384.904226436514;7496.25037496246;7544.811379715332;7625.351845093229;7784.338331241682;7266.346028857428;7441.92558074434;7521.623918804147;7571.734989275349;7596.8480630388185;5709.8287051388525;6992.615406152507;7018.087648605649;7087.220405755466;7094.787156385317;4248.468941382327;5052.141136006814;5099;5171.81898633546;5173.249878767633;4334.28484300087;5036.597072234285;5098.343038512424;5257.4216836147225;5293.338332708411 08:03:20 INFO - PROCESS | 1674 | |4;prototype.html;257.6071490976261;301.3355551010023;316.6342873980553;318.19179284617076;321.7201035099464;4195.979020104895;4638.958858102433;5060.810709483273;5116;5676.914846277304;4811.518848115186;5283.2867562879;5408.972955135228;5562.3881373048935;5582.91625625616;3342.632310445849;3495.689116581282;3619.7104231661538;4053.43251944728;4218.51487078986;3425.8629654813776;3625.29306785177;4130.938035929448;4224.61978421942;4247.554006829285;4102.9127281270485;4158.610732253219;4192.182524407742;4210.578942105795;4224.97913991496;3530.75284730069;4153.127843152926;4212.515560710527;4213.9578604213875;4250;3143.883921207984;3628.909277268063;3635.8182090895616;3636.781793092423;3637.908627411766;4476.753778542179;4535.274356103041;5355.678659280456;5452.700101494416;5460.91808622867;3993.4009898515164;4091.529474110486;4814.376164990089;4824.421069471686;4844.394450693663;5634.71826408682;5880.882382352372;5893.911591326134;5973.611715238496;6004.879902401927;3308.3052558962645;3739.626037396266;3932.2135572885536;3966.7421617594764;3976.284268831609;5400.91898621521;6238.220222472191;6282;6345.016522439029;6345.555811093225;3422.7946323220685;4019.718619696652;4022.4055484213104;4030.2141082488633;4045.9480535060507;3151.19644490658;3637.199816040467;3860.4869568692916;4075.7962101894786;4101.85643502476;4712.811487540528;4715.81136754526;4760.976195119002;4785.401824771903;4827.493113223131;5823.563232757476;5974.312954010257;6007.15899774032;6069.3020302665755;6078.449995251182;1180.38030034231;1283.433133732535;1317;1333.9066265361525;1376.348381941674;717.4045542199962;816.3754727633366;834.5326617094447;839.7396806989852;845.3237410071918;3693.5567731872347;4340.958170039231;4549.406838102316;4589.586937175603;4709.434867815816;4754.671729789162;5152.819651312186;5154.664946778447;5167.147420675622;5171.3277273954145;4312.460942382202;4589.682210483751;4631.067912672993;4698.436187657503;4719.5044520325555;2218.744844342889;2317.9221661927286;2574.086199399224;2584.1988983415204;2607.035396903158;4449.933251001237;4535.818567257273;5083.7203953782155;5287.286216360742;5336.519713225828;4305.48334199898;4906.803727850917;5183.922241166386;5231.947680523146;5243.501867322599;4420.049689316812;4786;4914.877128071756;5260.658057226421;5274.97138058075;4413.2276851550205;4884.483172116071;4922.655414120977;4993.40343021634;5188.636795424284;4749.775467836672;5004.445954181677;5034.370703662043;5057.519535644108;5060.873478163076;4292.892677682958;4766.9036121691515;4894.804207831789;5002.449730529712;5236.921446178234;3226.435373809621;3458.4120699480645;3501.4418583729994;3503.8962726740997;3533.1980689848874;4459.777011149495;5049.9495005049475;5135.974320128375;5167.560757335518;5185.766640501262;5323.3878104016985;5651.57613179005;5926.436988486087;5983.34183239852;6007.429294217042;4623.69945953512;5275.393329767126;6023.5783495156675;6038.245219347582;6089.786857459968;5071.898562028665;5633.774649013993;5734.9390362783015;5886.823395298148;5894.145348924468;5221.921671175011;5901.763929442946;5958.285005799332;6029.698515074141;6054.848628784315;5304.814331498379;5843.211166492469;5864.475236438642;5874.882502349844;5921.624673935988 08:03:20 INFO - PROCESS | 1674 | |5;yui.html;641.7785863876962;696.1506961506961;730.2843213650619;759.5936174146833;795.363709032774;15222.314995825187;15916.442924497645;15938;15956.48413400728;15993.440229591966;14289.214093224868;14425.711485770305;14447.566573002827;14449.277536123178;14474.927625361885;618.1994317359008;657.7763560389467;828.6271177969923;840.1388576708861;842.8831797867849;866.8049688820028;868.7610907000562;868.7654333329997;871.1288711288711;873.8907636545432;919.8528235482324;927.9768005799875;928.5960607135918;935.2658163341778;935.8081593273397;909.5315912305135;918.9081091890757;928.6471140966423;928.8188803183382;931.1340453378356;664.6776313487954;692.9480288978297;696.216756149332;696.8780463418932;697.8255436140964;15470.381184752596;15528.301226444837;15530.068195908281;15548;15588.142652154127;12321.322327271997;14842.406303747837;14877.404903803836;14890.776638350433;14954.925225373912;12011.339376334297;12179.51281948721;12396.13227074105;12400.503979840796;12557.686057848536;3753.651725796908;3760.9811950940343;3936.626020528045;3985.342418500944;3995.620416060499;120625.79374206233;121842.92027699502;129083;131301.34349328288;132160;11302.717432064099;11305.604303849326;11396.14528910335;11468.885311146782;11877.524899003944;24216.031358745804;24245.87877060639;24269.150579729623;24311.392215194406;24325;7603.809904752315;7936.285734283827;7941.761747147595;7960.363170946311;8210.137935516685;4316.223079845658;4387.122575484916;4429.623482004002;4431.091626216617;4435.1129774044675;3531;3657.743957922973;3660.4875317455576;3670.4127339625534;3906.1797022625;724.6920058974914;738.65283316841;740.8999785028952;747.8354761952362;761.6686741267566;601.9127226552125;640.7761176153524;659.515256286629;662.5031226580065;664.2129077043719;9429.913010571145;9497.667581634747;9510.90489095114;9597.808043839224;9670.178034866974;622.8474023864092;639.67376637915;646.1277275677892;694.9791506254719;702.6100514214558;4263.445752052276;4396.890077748018;4645.326427668037;4676.55572720591;4681.157391669532;111765;121397;163190;168524.15737921232;175139;144512;147994.52005480023;148059;149484;150126;82113.58943205245;120113.39943300227;122677.38661306637;124301.75698243083;126768;6847.828804279933;8905;8957.776055598662;8959.148880856308;9118.22495087925;62845.74308513896;63291;65887.34112658812;66270.33729662641;68618;88452.5577372109;94186.52906735422;95076;95293.04706952842;96947.0305296938;11166.441677916235;12036.398180091135;12273.386330683608;12365.690857728268;12376.81434778461;12778.552750653682;13133.540326088541;13260.270685112411;13539.932300338436;13847.519676006872;10340.741481463023;11499.252548584314;11738.178327517333;12516.31160286193;12726.427310770852;11946.104042196695;12128.514859405877;12329;12372.876271237172;12815.807762883378;10339.534720937425;10621.946890265499;11882.524699011943;12007.159498834997;12183.086268530073;7920.5247685139075;8556.828863422572;9206.723798286062;9689.854652180082;10592.364458132537;7666.693332266647;9577.369626453792;10552.203666028356;10567.313124646873;10696.518656660313 08:03:20 INFO - PROCESS | 1674 | __end_tp_report 08:03:20 INFO - PROCESS | 1674 | __start_cc_report 08:03:20 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,375 08:03:20 INFO - PROCESS | 1674 | __end_cc_report 08:03:20 INFO - PROCESS | 1674 | __startTimestamp1473519799990__endTimestamp 08:03:20 INFO - PROCESS | 1674 | 08:03:20 INFO - PROCESS | 1674 | ------- Summary: start ------- 08:03:20 INFO - PROCESS | 1674 | Number of tests: 6 08:03:20 INFO - PROCESS | 1674 | 08:03:20 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5672.55 Median:5875.03 stddev:683.43 (11.6%) stddev-sans-first:661.21 08:03:20 INFO - PROCESS | 1674 | Values: 3369.0 3378.6 3410.7 3416.8 3420.4 5576.2 5642.5 5716.6 5792.9 5812.4 5635.1 5714.7 5729.6 5793.8 5803.0 5121.0 5219.3 5235.4 5289.4 5305.3 5187.4 5307.8 5310.9 5343.0 5376.3 4253.6 5145.2 5181.3 5234.7 5301.5 5222.2 5229.0 5246.7 5256.1 5263.7 5168.2 5195.5 5203.7 5260.4 5272.9 4594.7 4614.8 4927.7 5337.2 5757.1 6219.5 6254.7 6257.6 6258.4 6267.9 6214.8 6254.4 6259.3 6268.9 6272.7 5756.6 5820.1 5842.5 5886.9 5922.7 4573.2 5280.9 6119.4 6283.1 6290.2 4675.3 5009.4 6025.2 6177.6 6203.6 6124.1 6181.2 6183.8 6254.3 6263.2 5498.6 6078.2 6108.4 6132.0 6232.8 6234.8 6235.2 6242.2 6247.3 6292.0 4869.1 4891.1 4928.6 4959.6 4978.8 4340.5 4376.7 4394.5 4435.7 4476.9 5464.9 5781.5 5811.7 5831.0 5845.8 6147.2 6214.8 6228.0 6229.0 6245.2 4720.0 5756.1 5760.4 5829.4 5875.0 5434.5 5438.8 5442.1 5442.2 5453.4 6373.2 6384.3 6405.6 6415.1 6449.0 6148.4 6200.1 6203.8 6217.8 6244.5 6334.3 6367.1 6375.2 6375.8 6388.6 6135.0 6143.9 6183.6 6234.2 6235.4 6189.2 6211.8 6224.6 6230.6 6246.0 5987.3 6069.2 6125.1 6148.6 6152.2 6052.8 6127.6 6164.3 6174.2 6177.8 4612.7 5544.5 5854.4 5885.6 5966.0 4557.7 5910.4 6083.3 6109.0 6119.3 5045.1 6008.3 6075.0 6099.5 6124.1 08:03:20 INFO - PROCESS | 1674 | 08:03:20 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:64782.83 Median:38554.11 stddev:145697.13 (377.9%) stddev-sans-first:146025.77 08:03:20 INFO - PROCESS | 1674 | Values: 444.1 506.1 527.4 531.6 533.0 36703.3 40629.2 40755.0 40798.0 40874.8 42010.8 42499.4 42542.6 42570.9 42625.7 8442.4 9806.0 9975.1 9978.5 10000.3 9604.3 9723.6 9726.6 9732.9 9740.6 9707.1 9735.5 9742.9 9751.2 9753.6 9706.7 9706.8 9734.2 9734.5 9740.8 9673.3 9737.4 9741.4 9748.3 9764.8 39049.0 39174.6 39179.8 39294.8 39359.6 3202.2 3296.6 3386.7 3436.1 3583.4 30525.4 30707.8 31152.2 31511.4 32111.0 3221.7 3269.3 3272.7 3349.8 3368.7 430015.8 507164.0 508868.0 510516.0 511869.0 32990.3 61022.7 61357.7 61392.7 61682.0 33412.0 33678.0 33688.5 33980.7 34137.7 14255.2 14355.4 14391.8 14392.6 14411.5 12048.7 12191.3 12198.5 12229.8 12247.0 7460.4 7551.2 7628.1 7632.4 7632.6 2792.8 3205.3 3230.7 3247.6 3252.9 933.9 949.3 961.1 969.5 1004.5 29345.7 29385.6 29526.9 29598.4 30358.0 1061.8 1061.9 1081.6 1118.5 1214.8 11344.8 14993.9 15172.6 15525.0 15616.0 686545.0 692703.0 693857.0 695631.0 700435.0 55834.4 55948.2 55978.0 56297.9 56318.7 1129.9 1209.1 1229.8 1344.7 1394.6 21841.7 22301.3 22383.8 24497.6 29169.9 43231.8 43503.6 43523.8 43594.0 43796.7 393304.0 397228.0 398776.0 399796.0 400883.0 21818.5 25881.8 25965.4 26257.2 26273.9 36746.4 38448.6 42963.0 44680.6 47704.5 37901.0 37914.8 38283.4 38659.6 39034.4 37900.1 38030.2 38946.4 39213.0 39267.0 37193.3 37198.6 37262.3 37290.0 37685.8 31055.0 32287.0 34004.0 34489.8 34620.0 24944.4 25019.9 25117.0 25402.0 25694.5 08:03:20 INFO - PROCESS | 1674 | 08:03:20 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:36082.05 Median:14416.60 stddev:104821.61 (727.1%) stddev-sans-first:105081.37 08:03:20 INFO - PROCESS | 1674 | Values: 372.1 375.9 380.7 381.4 396.7 19036.5 21326.4 21415.0 21538.6 21648.0 18606.0 20247.6 20453.7 20753.4 21159.9 4960.4 5796.2 6099.8 6127.1 6169.7 3898.7 4069.5 6099.2 6129.3 6131.4 5821.6 6095.7 6143.7 6186.3 6190.5 6132.6 6159.2 6588.5 6759.7 6770.1 5916.7 5925.5 5932.9 5958.0 5959.7 24091.8 24969.0 25182.0 25926.9 26210.6 2142.9 2180.4 2211.7 2609.9 2622.6 9491.6 9963.5 10252.6 10418.6 10508.9 1859.6 1892.8 1894.2 1911.8 1969.9 116573.4 125915.0 126577.4 126727.7 128411.4 11107.3 11499.8 11578.8 11601.7 11794.2 14336.4 14435.9 14460.9 14490.9 14525.6 8280.2 8537.6 8555.8 8641.1 8685.4 7031.6 8506.4 8508.7 8573.0 8658.4 4403.8 4451.9 4498.3 4519.8 4541.0 1583.5 1865.8 1934.4 1936.5 1939.8 812.9 852.3 918.4 942.7 944.5 16202.5 20415.4 21663.9 21730.5 21744.0 842.0 919.6 931.3 937.9 1020.6 6948.0 9316.9 9464.5 11327.5 11350.9 619221.0 623014.0 624788.0 637799.0 640245.0 79888.0 84427.6 114021.0 143681.6 151292.5 978.2 1025.6 1027.1 1044.8 1047.0 18134.4 18846.7 18900.3 18974.0 19344.2 55387.0 55495.7 56798.7 57323.0 57930.4 83032.0 87534.6 88587.1 89352.1 90272.5 7973.6 8128.7 8169.4 8257.3 8360.3 12330.2 12988.9 13160.7 13450.1 13461.3 11078.4 11190.7 11544.6 11635.8 11639.8 10834.3 11066.0 11277.3 11471.0 11481.7 11256.5 11457.4 11676.9 11782.0 11816.3 8034.9 8058.4 8244.0 8261.5 8374.0 8270.3 11127.9 11210.2 11252.4 11632.7 08:03:20 INFO - PROCESS | 1674 | 08:03:20 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5697.77 Median:5044.37 stddev:3180.53 (63.1%) stddev-sans-first:3173.86 08:03:20 INFO - PROCESS | 1674 | Values: 1507.7 1548.9 1688.5 1824.6 1900.2 7961.9 8166.3 8479.4 9026.1 9048.5 7190.6 7238.4 7643.7 8644.1 8729.0 2419.7 2422.1 2423.2 2428.2 2434.3 2387.5 2388.8 2422.2 2427.6 2436.3 2399.2 2411.5 2420.8 2431.2 2439.9 2386.5 2387.9 2403.5 2404.4 2408.4 2370.8 2390.1 2398.6 2399.3 2404.6 8881.7 8921.6 8977.0 8984.8 9024.5 5243.7 5394.8 5413.7 5421.9 5432.9 3491.3 3626.3 3682.5 3687.9 3690.1 1042.2 1076.9 1081.3 1083.0 1095.8 11467.1 11524.7 11573.1 11590.6 11627.2 4507.4 4628.0 4795.7 4812.5 4856.5 8241.7 9837.1 9971.2 9971.2 9988.9 5375.5 5392.9 5400.5 5406.4 5415.4 4445.2 4601.8 4791.0 4906.8 4945.2 3880.4 3927.8 3990.6 4026.0 4046.5 1147.6 1286.9 1292.0 1305.8 1307.1 761.3 783.7 904.7 916.3 925.2 5775.3 5842.9 5918.3 5972.6 6159.6 952.1 955.2 960.1 973.5 994.6 3299.6 3935.4 3960.6 3963.9 4074.0 9723.0 10988.9 11017.7 11048.2 11051.4 10815.4 10838.5 10918.8 10930.6 10994.0 8048.3 8099.4 8263.3 8320.9 8362.9 5398.5 5455.1 5659.0 5787.5 6016.0 9619.6 10153.3 10206.0 10230.1 10250.0 10151.1 10276.5 10329.0 10350.4 10357.9 6974.4 6976.9 7287.7 7417.1 7603.0 7604.2 8298.6 8371.5 8544.4 8546.5 6384.9 7496.3 7544.8 7625.4 7784.3 7266.3 7441.9 7521.6 7571.7 7596.8 5709.8 6992.6 7018.1 7087.2 7094.8 4248.5 5052.1 5099.0 5171.8 5173.2 4334.3 5036.6 5098.3 5257.4 5293.3 08:03:20 INFO - PROCESS | 1674 | 08:03:20 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4417.97 Median:4785.70 stddev:1400.22 (29.3%) stddev-sans-first:1368.88 08:03:20 INFO - PROCESS | 1674 | Values: 257.6 301.3 316.6 318.2 321.7 4196.0 4639.0 5060.8 5116.0 5676.9 4811.5 5283.3 5409.0 5562.4 5582.9 3342.6 3495.7 3619.7 4053.4 4218.5 3425.9 3625.3 4130.9 4224.6 4247.6 4102.9 4158.6 4192.2 4210.6 4225.0 3530.8 4153.1 4212.5 4214.0 4250.0 3143.9 3628.9 3635.8 3636.8 3637.9 4476.8 4535.3 5355.7 5452.7 5460.9 3993.4 4091.5 4814.4 4824.4 4844.4 5634.7 5880.9 5893.9 5973.6 6004.9 3308.3 3739.6 3932.2 3966.7 3976.3 5400.9 6238.2 6282.0 6345.0 6345.6 3422.8 4019.7 4022.4 4030.2 4045.9 3151.2 3637.2 3860.5 4075.8 4101.9 4712.8 4715.8 4761.0 4785.4 4827.5 5823.6 5974.3 6007.2 6069.3 6078.4 1180.4 1283.4 1317.0 1333.9 1376.3 717.4 816.4 834.5 839.7 845.3 3693.6 4341.0 4549.4 4589.6 4709.4 4754.7 5152.8 5154.7 5167.1 5171.3 4312.5 4589.7 4631.1 4698.4 4719.5 2218.7 2317.9 2574.1 2584.2 2607.0 4449.9 4535.8 5083.7 5287.3 5336.5 4305.5 4906.8 5183.9 5231.9 5243.5 4420.0 4786.0 4914.9 5260.7 5275.0 4413.2 4884.5 4922.7 4993.4 5188.6 4749.8 5004.4 5034.4 5057.5 5060.9 4292.9 4766.9 4894.8 5002.4 5236.9 3226.4 3458.4 3501.4 3503.9 3533.2 4459.8 5049.9 5136.0 5167.6 5185.8 5323.4 5651.6 5926.4 5983.3 6007.4 4623.7 5275.4 6023.6 6038.2 6089.8 5071.9 5633.8 5734.9 5886.8 5894.1 5221.9 5901.8 5958.3 6029.7 6054.8 5304.8 5843.2 5864.5 5874.9 5921.6 08:03:20 INFO - PROCESS | 1674 | 08:03:20 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:25975.64 Median:3960.98 stddev:42951.88 (1084.4%) stddev-sans-first:43030.26 08:03:20 INFO - PROCESS | 1674 | Values: 641.8 696.2 730.3 759.6 795.4 15222.3 15916.4 15938.0 15956.5 15993.4 14289.2 14425.7 14447.6 14449.3 14474.9 618.2 657.8 828.6 840.1 842.9 866.8 868.8 868.8 871.1 873.9 919.9 928.0 928.6 935.3 935.8 909.5 918.9 928.6 928.8 931.1 664.7 692.9 696.2 696.9 697.8 15470.4 15528.3 15530.1 15548.0 15588.1 12321.3 14842.4 14877.4 14890.8 14954.9 12011.3 12179.5 12396.1 12400.5 12557.7 3753.7 3761.0 3936.6 3985.3 3995.6 120625.8 121842.9 129083.0 131301.3 132160.0 11302.7 11305.6 11396.1 11468.9 11877.5 24216.0 24245.9 24269.2 24311.4 24325.0 7603.8 7936.3 7941.8 7960.4 8210.1 4316.2 4387.1 4429.6 4431.1 4435.1 3531.0 3657.7 3660.5 3670.4 3906.2 724.7 738.7 740.9 747.8 761.7 601.9 640.8 659.5 662.5 664.2 9429.9 9497.7 9510.9 9597.8 9670.2 622.8 639.7 646.1 695.0 702.6 4263.4 4396.9 4645.3 4676.6 4681.2 111765.0 121397.0 163190.0 168524.2 175139.0 144512.0 147994.5 148059.0 149484.0 150126.0 82113.6 120113.4 122677.4 124301.8 126768.0 6847.8 8905.0 8957.8 8959.1 9118.2 62845.7 63291.0 65887.3 66270.3 68618.0 88452.6 94186.5 95076.0 95293.0 96947.0 11166.4 12036.4 12273.4 12365.7 12376.8 12778.6 13133.5 13260.3 13539.9 13847.5 10340.7 11499.3 11738.2 12516.3 12726.4 11946.1 12128.5 12329.0 12372.9 12815.8 10339.5 10621.9 11882.5 12007.2 12183.1 7920.5 8556.8 9206.7 9689.9 10592.4 7666.7 9577.4 10552.2 10567.3 10696.5 08:03:20 INFO - PROCESS | 1674 | -------- Summary: end -------- 08:03:20 INFO - PROCESS | 1674 | 08:03:20 INFO - TEST-INFO | 1674: exit 0 08:03:20 INFO - TEST-OK | dromaeo_css | took 1114121ms 08:03:20 INFO - TEST-START | kraken 08:03:20 INFO - operating with platform_type : mac_ 08:03:20 INFO - Initialising browser for kraken test... 08:03:20 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpiAAaMj/profile http://localhost:49277/getInfo.html) 08:03:23 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 08:03:23 INFO - PROCESS | 1681 | colorDepth:24 08:03:23 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 08:03:23 INFO - PROCESS | 1681 | __metrics 08:03:23 ERROR - PROCESS | 1681 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 08:03:23 INFO - TEST-INFO | 1681: exit 0 08:03:23 INFO - Browser initialized. 08:03:23 INFO - Running cycle 1/1 for kraken test... 08:03:23 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpiAAaMj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:35 INFO - PROCESS | 1683 | RSS: Main: 244576256 08:03:35 INFO - PROCESS | 1683 | 08:03:38 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 08:03:39 INFO - PROCESS | 1683 | RSS: Main: 257138688 08:03:39 INFO - PROCESS | 1683 | 08:03:42 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 08:03:43 INFO - PROCESS | 1683 | RSS: Main: 265760768 08:03:43 INFO - PROCESS | 1683 | 08:03:46 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 08:03:47 INFO - PROCESS | 1683 | RSS: Main: 262451200 08:03:47 INFO - PROCESS | 1683 | 08:03:49 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 08:03:49 INFO - PROCESS | 1683 | RSS: Main: 263651328 08:03:49 INFO - PROCESS | 1683 | 08:03:51 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:03:52 INFO - PROCESS | 1683 | RSS: Main: 265437184 08:03:52 INFO - PROCESS | 1683 | 08:03:55 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 08:03:55 INFO - PROCESS | 1683 | RSS: Main: 265674752 08:03:55 INFO - PROCESS | 1683 | 08:03:58 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 08:03:59 INFO - PROCESS | 1683 | RSS: Main: 266022912 08:03:59 INFO - PROCESS | 1683 | 08:04:02 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 08:04:03 INFO - PROCESS | 1683 | RSS: Main: 266424320 08:04:03 INFO - PROCESS | 1683 | 08:04:04 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:04:05 INFO - PROCESS | 1683 | RSS: Main: 265850880 08:04:05 INFO - PROCESS | 1683 | 08:04:07 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:04:08 INFO - PROCESS | 1683 | RSS: Main: 265912320 08:04:08 INFO - PROCESS | 1683 | 08:04:10 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:04:10 INFO - PROCESS | 1683 | RSS: Main: 265895936 08:04:10 INFO - PROCESS | 1683 | 08:04:13 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:04:14 INFO - PROCESS | 1683 | RSS: Main: 266317824 08:04:14 INFO - PROCESS | 1683 | 08:04:17 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:04:18 INFO - PROCESS | 1683 | RSS: Main: 266219520 08:04:18 INFO - PROCESS | 1683 | 08:04:19 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 08:04:20 INFO - PROCESS | 1683 | RSS: Main: 267964416 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | __start_tp_report 08:04:20 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 08:04:20 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 08:04:20 INFO - PROCESS | 1683 | |i|pagename|runs| 08:04:20 INFO - PROCESS | 1683 | |0;ai-astar;113;116;108;116;127;116;98;116;117;118 08:04:20 INFO - PROCESS | 1683 | |1;audio-beat-detection;144;112;129;157;109;134;133;144;203;139 08:04:20 INFO - PROCESS | 1683 | |2;audio-dft;205;214;208;196;195;196;196;199;200;190 08:04:20 INFO - PROCESS | 1683 | |3;audio-fft;72;84;77;140;63;60;70;87;156;60 08:04:20 INFO - PROCESS | 1683 | |4;audio-oscillator;69;70;116;73;96;74;105;69;71;112 08:04:20 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;137;110;88;104;113;86;108;131;90;85 08:04:20 INFO - PROCESS | 1683 | |6;imaging-darkroom;96;151;119;95;95;96;96;99;95;89 08:04:20 INFO - PROCESS | 1683 | |7;imaging-desaturate;91;110;153;154;90;90;88;90;75;146 08:04:20 INFO - PROCESS | 1683 | |8;json-parse-financial;57;58;58;57;68;80;90;91;91;83 08:04:20 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;68;67;72;68;74;73;70;67;70;70 08:04:20 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;90;88;88;87;83;71;105;87;89;86 08:04:20 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;161;148;139;154;165;157;154;136;180;178 08:04:20 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;234;170;172;169;169;172;173;168;180;169 08:04:20 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;71;67;68;70;70;74;83;90;90;78 08:04:20 INFO - PROCESS | 1683 | __end_tp_report 08:04:20 INFO - PROCESS | 1683 | __start_cc_report 08:04:20 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,932 08:04:20 INFO - PROCESS | 1683 | __end_cc_report 08:04:20 INFO - PROCESS | 1683 | __startTimestamp1473519860192__endTimestamp 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | ------- Summary: start ------- 08:04:20 INFO - PROCESS | 1683 | Number of tests: 14 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:114.50 Median:116.50 stddev:7.46 (6.4%) stddev-sans-first:7.89 08:04:20 INFO - PROCESS | 1683 | Values: 113.0 116.0 108.0 116.0 127.0 116.0 98.0 116.0 117.0 118.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:141.50 stddev:26.33 (18.6%) stddev-sans-first:27.90 08:04:20 INFO - PROCESS | 1683 | Values: 144.0 112.0 129.0 157.0 109.0 134.0 133.0 144.0 203.0 139.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:199.90 Median:199.50 stddev:7.14 (3.6%) stddev-sans-first:7.33 08:04:20 INFO - PROCESS | 1683 | Values: 205.0 214.0 208.0 196.0 195.0 196.0 196.0 199.0 200.0 190.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:86.90 Median:71.00 stddev:33.71 (47.5%) stddev-sans-first:35.32 08:04:20 INFO - PROCESS | 1683 | Values: 72.0 84.0 77.0 140.0 63.0 60.0 70.0 87.0 156.0 60.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:70.50 stddev:19.46 (27.6%) stddev-sans-first:19.70 08:04:20 INFO - PROCESS | 1683 | Values: 69.0 70.0 116.0 73.0 96.0 74.0 105.0 69.0 71.0 112.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:111.00 stddev:18.48 (16.6%) stddev-sans-first:15.61 08:04:20 INFO - PROCESS | 1683 | Values: 137.0 110.0 88.0 104.0 113.0 86.0 108.0 131.0 90.0 85.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:95.50 stddev:18.59 (19.5%) stddev-sans-first:19.54 08:04:20 INFO - PROCESS | 1683 | Values: 96.0 151.0 119.0 95.0 95.0 96.0 96.0 99.0 95.0 89.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:108.70 Median:89.00 stddev:30.43 (34.2%) stddev-sans-first:31.60 08:04:20 INFO - PROCESS | 1683 | Values: 91.0 110.0 153.0 154.0 90.0 90.0 88.0 90.0 75.0 146.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:73.30 Median:81.50 stddev:15.17 (18.6%) stddev-sans-first:14.90 08:04:20 INFO - PROCESS | 1683 | Values: 57.0 58.0 58.0 57.0 68.0 80.0 90.0 91.0 91.0 83.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:2.47 (3.5%) stddev-sans-first:2.52 08:04:20 INFO - PROCESS | 1683 | Values: 68.0 67.0 72.0 68.0 74.0 73.0 70.0 67.0 70.0 70.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.50 stddev:8.24 (9.4%) stddev-sans-first:8.68 08:04:20 INFO - PROCESS | 1683 | Values: 90.0 88.0 88.0 87.0 83.0 71.0 105.0 87.0 89.0 86.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:157.20 Median:159.00 stddev:14.58 (9.2%) stddev-sans-first:15.40 08:04:20 INFO - PROCESS | 1683 | Values: 161.0 148.0 139.0 154.0 165.0 157.0 154.0 136.0 180.0 178.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:172.00 stddev:20.12 (11.7%) stddev-sans-first:3.67 08:04:20 INFO - PROCESS | 1683 | Values: 234.0 170.0 172.0 169.0 169.0 172.0 173.0 168.0 180.0 169.0 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:76.00 stddev:8.76 (11.5%) stddev-sans-first:9.10 08:04:20 INFO - PROCESS | 1683 | Values: 71.0 67.0 68.0 70.0 70.0 74.0 83.0 90.0 90.0 78.0 08:04:20 INFO - PROCESS | 1683 | -------- Summary: end -------- 08:04:20 INFO - PROCESS | 1683 | 08:04:20 INFO - TEST-INFO | 1683: exit 0 08:04:21 INFO - TEST-OK | kraken | took 60416ms 08:04:21 INFO - SUITE-END | took 1174s 08:04:21 INFO - Completed test suite (00:19:34) 08:04:21 INFO - javascript benchmark 08:04:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7205.577745522889, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3369.0, 3378.645242249564, 3410.7100896423804, 3416.8291585420725, 3420.4185288500953, 5576.247206627104, 5642.548596112311, 5716.599838011341, 5792.928308262969, 5812.389699081599, 5635.098384258519, 5714.714264286779, 5729.598928075036, 5793.768249270024, 5802.999999999989, 5121.00140472608, 5219.347581552306, 5235.397929238129, 5289.444608316129, 5305.310309659739, 5187.351581052369, 5307.840764777063, 5310.92033619496, 5342.973285133588, 5376.274202982606, 4253.553376895412, 5145.176771716545, 5181.326427564431, 5234.659747116425, 5301.549368303698, 5222.190560463135, 5229.0, 5246.711430871301, 5256.106461901486, 5263.6841789492755, 5168.198929165947, 5195.480451954774, 5203.739813009334, 5260.421353651095, 5272.92090618641, 4594.724316541018, 4614.838480653195, 4927.6550641455115, 5337.172738225581, 5757.136429535562, 6219.502439804804, 6254.687265636746, 6257.624542527417, 6258.374162583751, 6267.905981410283, 6214.844628884269, 6254.405831446051, 6259.342769009279, 6268.905966410507, 6272.686365681698, 5756.5682573807135, 5820.126980952891, 5842.532597392198, 5886.852828679232, 5922.703864806743, 4573.153966516205, 5280.894382112413, 6119.418655227746, 6283.120363149163, 6290.182276304143, 4675.275332323496, 5009.423916249605, 6025.15647809307, 6177.62934223948, 6203.565750397429, 6124.075808605183, 6181.196444462191, 6183.783567575113, 6254.34329395416, 6263.217097862767, 5498.625343664084, 6078.240219972504, 6108.389161083945, 6131.957816600026, 6232.750689972441, 6234.812955611339, 6235.220597425322, 6242.188515492957, 6247.3440288769025, 6292.024736165901, 4869.123557759566, 4891.095147397743, 4928.556429921253, 4959.603231741452, 4978.800847966113, 4340.544242854518, 4376.671749618791, 4394.538573449806, 4435.733855968588, 4476.888077798081, 5464.863378415572, 5781.508571771363, 5811.651300921958, 5830.970845145747, 5845.766169353264, 6147.231596050494, 6214.844628884223, 6227.972384556588, 6229.034499652471, 6245.156903817927, 4719.9764001180465, 5756.107803290497, 5760.36635970043, 5829.387914268941, 5875.030619947661, 5434.510894019557, 5438.809641662523, 5442.102053161263, 5442.2380866678695, 5453.400125986138, 6373.235211774617, 6384.265809431882, 6405.551611387158, 6415.101885736094, 6449.0, 6148.415900489446, 6200.131981522681, 6203.7828675996125, 6217.813465596039, 6244.5316601256, 6334.271558770801, 6367.108604795425, 6375.234971803413, 6375.840603984938, 6388.648624325707, 6134.969325153345, 6143.911070235962, 6183.567150299616, 6234.189555357774, 6235.407636274546, 6189.226346706661, 6211.782587609412, 6224.62652240867, 6230.59501132425, 6245.968770156302, 5987.31145918225, 6069.180660610761, 6125.091147189775, 6148.569600127948, 6152.200213972334, 6052.848678783065, 6127.632342059491, 6164.321924588202, 6174.166487524307, 6177.84555386119, 4612.7001744886475, 5544.500994910478, 5854.385289544536, 5885.55858310637, 5965.970170149121, 4557.680962332737, 5910.408959104056, 6083.330833608211, 6108.9954560370215, 6119.319765820928, 5045.142325804549, 6008.309044459943, 6075.0, 6099.451049405575, 6124.081387791689], "value": 5632.167774792906, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.10956033153525, 506.1041955738343, 527.3697930972487, 531.5986430245164, 533.0032838591833, 36703.348349324275, 40629.187416251654, 40755.0, 40797.980050498714, 40874.7956260219, 42010.78994605023, 42499.36250956238, 42542.57457425425, 42570.935726606775, 42625.72122836312, 8442.366822488308, 9805.999999999982, 9975.102240798327, 9978.50107494626, 10000.349977251492, 9604.279679024066, 9723.611055557769, 9726.610935562569, 9732.902670973306, 9740.561674724619, 9707.077827606367, 9735.464549449778, 9742.902570974271, 9751.17115045222, 9753.609855605768, 9706.70879873605, 9706.757331066745, 9734.17259532944, 9734.513274336327, 9740.831100267942, 9673.274504412197, 9737.415755054719, 9741.366811157252, 9748.26887983397, 9764.755881102958, 39049.023774405585, 39174.60825391738, 39179.804100979316, 39294.803525982185, 39359.606403936166, 3202.1674364665355, 3296.587926509186, 3386.7290616750847, 3436.1409647588102, 3583.395476012926, 30525.38949221025, 30707.846460767778, 31152.221194470094, 31511.36977260442, 32111.0, 3221.7261532769744, 3269.264415506492, 3272.7381809455187, 3349.7822641528464, 3368.747343949189, 430015.8499207515, 507164.0, 508867.9999999963, 510516.0, 511869.0, 32990.340193196, 61022.69488652617, 61357.69321153365, 61392.69303653543, 61682.0, 33412.0, 33678.0, 33688.49467257993, 33980.66019339775, 34137.65862341395, 14255.215963122128, 14355.425782968565, 14391.78412323816, 14392.640183995483, 14411.495597654031, 12048.698782530508, 12191.268523888595, 12198.512059517698, 12229.816552751716, 12247.020238380908, 7460.403167746567, 7551.169371369145, 7628.084629844454, 7632.427567932427, 7632.618369081523, 2792.8184667996516, 3205.34290470453, 3230.660780618048, 3247.610286765556, 3252.902412927616, 933.8606899582498, 949.2643201518878, 961.1013702188476, 969.4522594733953, 1004.5379125602159, 29345.7065429343, 29385.559216611768, 29526.852365738465, 29598.408031839244, 30358.0, 1061.830107182847, 1061.8531985455688, 1081.5673730507704, 1118.4799068433217, 1214.8359971403925, 11344.773104537862, 14993.850061499244, 15172.620684482976, 15525.0, 15616.0, 686545.0, 692703.0, 693857.0, 695631.0, 700435.0, 55834.44165558292, 55948.16077758837, 55978.0, 56297.87404251974, 56318.71840640771, 1129.8587676540433, 1209.056935590241, 1229.8339724137127, 1344.6503908983539, 1394.574654730317, 21841.672374914073, 22301.330960071224, 22383.77616223817, 24497.632535511624, 29169.854150729112, 43231.78384108059, 43503.56496434995, 43523.78238108789, 43594.0, 43796.68609941707, 393304.0, 397228.0, 398776.0, 399796.0, 400883.0, 21818.454538636663, 25881.83531741114, 25965.350866228495, 26257.21228363152, 26273.868630656725, 36746.448803267434, 38448.6155138445, 42963.0, 44680.55319446894, 47704.52295477001, 37901.0, 37914.8104259477, 38283.42574861435, 38659.6134038656, 39034.414483782195, 37900.104994750705, 38030.239395211385, 38946.4158037624, 39213.0, 39267.0, 37193.256134877694, 37198.6280137206, 37262.25475490421, 37290.0, 37685.811570943064, 31055.0, 32287.0, 34003.964024979374, 34489.827550862086, 34620.0, 24944.37639059038, 25019.87490062611, 25117.0, 25402.0, 25694.486110278063], "value": 18085.182851084825, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.136642988267, 375.89662842718246, 380.65360521925055, 381.36312358361533, 396.74410005546423, 19036.524086897818, 21326.36020919371, 21415.036323365428, 21538.56922861542, 21648.0, 18606.0, 20247.595048099065, 20453.693194602092, 20753.377398678065, 21159.894200529016, 4960.429550601672, 5796.24648795656, 6099.756009759604, 6127.142200091991, 6169.660668663222, 3898.7465814722095, 4069.491313585802, 6099.176611157503, 6129.295131059918, 6131.3562075982045, 5821.592488525805, 6095.695215239242, 6143.7235324410285, 6186.319504854462, 6190.473809726179, 6132.570720049598, 6159.168512250856, 6588.505862060365, 6759.695813688347, 6770.086038384854, 5916.674582897939, 5925.525957923356, 5932.881342373172, 5958.0, 5959.731812068468, 24091.759082409128, 24969.0, 25182.0, 25926.87036564824, 26210.606840897402, 2142.914283428661, 2180.4439867833603, 2211.7124773779465, 2609.856457894815, 2622.593498007812, 9491.620335186653, 9963.45201013944, 10252.589896404135, 10418.635347762793, 10508.94745526275, 1859.6001859600115, 1892.81071892811, 1894.1665667106429, 1911.751472308605, 1969.940901772949, 116573.41713291463, 125915.0, 126577.36711316477, 126727.73272267343, 128411.35794321062, 11107.278026928223, 11499.827502587468, 11578.826317605242, 11601.651950441501, 11794.174407791372, 14336.354864031144, 14435.927820361041, 14460.855391445952, 14490.85509144895, 14525.636859078608, 8280.213379728915, 8537.615807288686, 8555.786105347292, 8641.092685268082, 8685.395560842306, 7031.613261270577, 8506.404551681322, 8508.702195423131, 8572.957135214285, 8658.39391242607, 4403.779811009436, 4451.933221001687, 4498.280275156025, 4519.751413672214, 4541.023679908835, 1583.5011971229026, 1865.8134186581506, 1934.4293433436887, 1936.5061909213061, 1939.767227932657, 812.9309008734206, 852.2500199824239, 918.3892711347013, 942.7124726958343, 944.5088553952044, 16202.513924582281, 20415.387538373874, 21663.891680541496, 21730.456738581346, 21744.0, 841.9621117049747, 919.6045700348914, 931.3108299858056, 937.920276776468, 1020.6121673763922, 6948.0, 9316.906830931604, 9464.526773661428, 11327.490262938187, 11350.921662442055, 619221.0, 623014.0, 624788.0, 637799.0, 640245.0, 79888.0, 84427.5778621103, 114021.0, 143681.56318436682, 151292.5238083353, 978.2467500024941, 1025.5590096258532, 1027.0602398805158, 1044.7649278912336, 1046.9528871200814, 18134.365297214536, 18846.717299240798, 18900.338488152847, 18973.9564323958, 19344.226230951168, 55387.0, 55495.722521387135, 56798.71600642135, 57322.999999998334, 57930.420695792505, 83032.0, 87534.62326883757, 88587.11412886047, 89352.10647893438, 90272.54863725639, 7973.601319933864, 8128.715494957648, 8169.428140030141, 8257.339412847077, 8360.331173506227, 12330.150477428457, 12988.870111299144, 13160.736785264433, 13450.12574182675, 13461.259630720404, 11078.390688512209, 11190.720231994264, 11544.59593914209, 11635.76728465409, 11639.825402619137, 10834.295770774874, 11066.004059634672, 11277.32336059839, 11471.0, 11481.655550333504, 11256.549738010388, 11457.369844658624, 11676.94161529187, 11781.998530125036, 11816.291022538331, 8034.919650803417, 8058.435909486514, 8243.958780206061, 8261.463004904663, 8374.0, 8270.2556769891, 11127.888721112686, 11210.215284929976, 11252.437378131224, 11632.651020469399], "value": 9631.361755641488, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1507.710907174366, 1548.8993215440996, 1688.5187721499376, 1824.616830465602, 1900.2399040383837, 7961.880571791427, 8166.305864001552, 8479.448835825668, 9026.097390260971, 9048.547572621375, 7190.56856588605, 7238.4209263258945, 7643.656035478403, 8644.135586441353, 8729.039805621376, 2419.745926677691, 2422.0553983946274, 2423.164008417093, 2428.150147448392, 2434.257551446808, 2387.546366190418, 2388.773066558674, 2422.200673777658, 2427.647991041298, 2436.3300092474533, 2399.208261273784, 2411.517696460715, 2420.8184386171106, 2431.185552839801, 2439.9146029888957, 2386.5107652931283, 2387.940301492459, 2403.495265994143, 2404.386881345263, 2408.385861605297, 2370.8221883358815, 2390.1156572068444, 2398.616221404567, 2399.2682231919257, 2404.6152615581423, 8881.689140880102, 8921.643134274622, 8976.955115224448, 8984.820303593891, 9024.458532488072, 5243.737813109367, 5394.78420863169, 5413.675179489243, 5421.918671219935, 5432.864178395532, 3491.3017396520545, 3626.256617393428, 3682.5212722345923, 3687.8524859005606, 3690.095926498025, 1042.1923009667491, 1076.8707755069363, 1081.291753901196, 1082.9620963266248, 1095.769888323453, 11467.139964502612, 11524.654260372203, 11573.132015098901, 11590.594329198437, 11627.244229125165, 4507.4365704286965, 4628.028114096078, 4795.664303498791, 4812.494688057774, 4856.538628830255, 8241.711540096068, 9837.114659680663, 9971.202303815677, 9971.202303815677, 9988.850167247498, 5375.5162035417, 5392.892142157135, 5400.486953739467, 5406.378266499324, 5415.431379705153, 4445.177642136216, 4601.838935637236, 4791.0, 4906.803727850917, 4945.159322915041, 3880.409032296745, 3927.803609819555, 3990.5809889961715, 4025.959740402617, 4046.4825690366115, 1147.6270212181075, 1286.922784632925, 1292.011611117496, 1305.764962306794, 1307.0523870194033, 761.2539711083169, 783.6669415498391, 904.7285814255697, 916.2624087609538, 925.2089463508603, 5775.306963164448, 5842.941570584325, 5918.2898052234, 5972.611780234271, 6159.599626024294, 952.0907533305676, 955.2119501411363, 960.1358777100666, 973.5132433783108, 994.6220436234184, 3299.5875515560556, 3935.35066713989, 3960.5643379228263, 3963.8810835674976, 4074.0, 9723.027697230362, 10988.945055274833, 11017.669469915916, 11048.226624136394, 11051.447427628586, 10815.405152716676, 10838.53679753239, 10918.781624367468, 10930.617428389969, 10994.0, 8048.275655191061, 8099.392545559107, 8263.297619702273, 8320.875186872201, 8362.874556881652, 5398.4871437213405, 5455.099908515051, 5659.0, 5787.450192231731, 6015.969920150371, 9619.5671194795, 10153.289269751047, 10206.0, 10230.130438912776, 10250.0, 10151.137153341753, 10276.537555810157, 10329.0, 10350.430726310125, 10357.9482102589, 6974.407175390149, 6976.895346569907, 7287.744928927462, 7417.147028091838, 7603.0, 7604.201558836243, 8298.63905118428, 8371.455855369624, 8544.359173061921, 8546.529940853312, 6384.904226436514, 7496.25037496246, 7544.811379715332, 7625.351845093229, 7784.338331241682, 7266.346028857428, 7441.92558074434, 7521.623918804147, 7571.734989275349, 7596.8480630388185, 5709.8287051388525, 6992.615406152507, 7018.087648605649, 7087.220405755466, 7094.787156385317, 4248.468941382327, 5052.141136006814, 5099.0, 5171.81898633546, 5173.249878767633, 4334.28484300087, 5036.597072234285, 5098.343038512424, 5257.4216836147225, 5293.338332708411], "value": 4596.9229274009585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.6071490976261, 301.3355551010023, 316.6342873980553, 318.19179284617076, 321.7201035099464, 4195.979020104895, 4638.958858102433, 5060.810709483273, 5116.0, 5676.914846277304, 4811.518848115186, 5283.2867562879, 5408.972955135228, 5562.3881373048935, 5582.91625625616, 3342.632310445849, 3495.689116581282, 3619.7104231661538, 4053.43251944728, 4218.51487078986, 3425.8629654813776, 3625.29306785177, 4130.938035929448, 4224.61978421942, 4247.554006829285, 4102.9127281270485, 4158.610732253219, 4192.182524407742, 4210.578942105795, 4224.97913991496, 3530.75284730069, 4153.127843152926, 4212.515560710527, 4213.9578604213875, 4250.0, 3143.883921207984, 3628.909277268063, 3635.8182090895616, 3636.781793092423, 3637.908627411766, 4476.753778542179, 4535.274356103041, 5355.678659280456, 5452.700101494416, 5460.91808622867, 3993.4009898515164, 4091.529474110486, 4814.376164990089, 4824.421069471686, 4844.394450693663, 5634.71826408682, 5880.882382352372, 5893.911591326134, 5973.611715238496, 6004.879902401927, 3308.3052558962645, 3739.626037396266, 3932.2135572885536, 3966.7421617594764, 3976.284268831609, 5400.91898621521, 6238.220222472191, 6282.0, 6345.016522439029, 6345.555811093225, 3422.7946323220685, 4019.718619696652, 4022.4055484213104, 4030.2141082488633, 4045.9480535060507, 3151.19644490658, 3637.199816040467, 3860.4869568692916, 4075.7962101894786, 4101.85643502476, 4712.811487540528, 4715.81136754526, 4760.976195119002, 4785.401824771903, 4827.493113223131, 5823.563232757476, 5974.312954010257, 6007.15899774032, 6069.3020302665755, 6078.449995251182, 1180.38030034231, 1283.433133732535, 1317.0, 1333.9066265361525, 1376.348381941674, 717.4045542199962, 816.3754727633366, 834.5326617094447, 839.7396806989852, 845.3237410071918, 3693.5567731872347, 4340.958170039231, 4549.406838102316, 4589.586937175603, 4709.434867815816, 4754.671729789162, 5152.819651312186, 5154.664946778447, 5167.147420675622, 5171.3277273954145, 4312.460942382202, 4589.682210483751, 4631.067912672993, 4698.436187657503, 4719.5044520325555, 2218.744844342889, 2317.9221661927286, 2574.086199399224, 2584.1988983415204, 2607.035396903158, 4449.933251001237, 4535.818567257273, 5083.7203953782155, 5287.286216360742, 5336.519713225828, 4305.48334199898, 4906.803727850917, 5183.922241166386, 5231.947680523146, 5243.501867322599, 4420.049689316812, 4786.0, 4914.877128071756, 5260.658057226421, 5274.97138058075, 4413.2276851550205, 4884.483172116071, 4922.655414120977, 4993.40343021634, 5188.636795424284, 4749.775467836672, 5004.445954181677, 5034.370703662043, 5057.519535644108, 5060.873478163076, 4292.892677682958, 4766.9036121691515, 4894.804207831789, 5002.449730529712, 5236.921446178234, 3226.435373809621, 3458.4120699480645, 3501.4418583729994, 3503.8962726740997, 3533.1980689848874, 4459.777011149495, 5049.9495005049475, 5135.974320128375, 5167.560757335518, 5185.766640501262, 5323.3878104016985, 5651.57613179005, 5926.436988486087, 5983.34183239852, 6007.429294217042, 4623.69945953512, 5275.393329767126, 6023.5783495156675, 6038.245219347582, 6089.786857459968, 5071.898562028665, 5633.774649013993, 5734.9390362783015, 5886.823395298148, 5894.145348924468, 5221.921671175011, 5901.763929442946, 5958.285005799332, 6029.698515074141, 6054.848628784315, 5304.814331498379, 5843.211166492469, 5864.475236438642, 5874.882502349844, 5921.624673935988], "value": 3972.679537432821, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.7785863876962, 696.1506961506961, 730.2843213650619, 759.5936174146833, 795.363709032774, 15222.314995825187, 15916.442924497645, 15938.0, 15956.48413400728, 15993.440229591966, 14289.214093224868, 14425.711485770305, 14447.566573002827, 14449.277536123178, 14474.927625361885, 618.1994317359008, 657.7763560389467, 828.6271177969923, 840.1388576708861, 842.8831797867849, 866.8049688820028, 868.7610907000562, 868.7654333329997, 871.1288711288711, 873.8907636545432, 919.8528235482324, 927.9768005799875, 928.5960607135918, 935.2658163341778, 935.8081593273397, 909.5315912305135, 918.9081091890757, 928.6471140966423, 928.8188803183382, 931.1340453378356, 664.6776313487954, 692.9480288978297, 696.216756149332, 696.8780463418932, 697.8255436140964, 15470.381184752596, 15528.301226444837, 15530.068195908281, 15548.0, 15588.142652154127, 12321.322327271997, 14842.406303747837, 14877.404903803836, 14890.776638350433, 14954.925225373912, 12011.339376334297, 12179.51281948721, 12396.13227074105, 12400.503979840796, 12557.686057848536, 3753.651725796908, 3760.9811950940343, 3936.626020528045, 3985.342418500944, 3995.620416060499, 120625.79374206233, 121842.92027699502, 129083.0, 131301.34349328288, 132160.0, 11302.717432064099, 11305.604303849326, 11396.14528910335, 11468.885311146782, 11877.524899003944, 24216.031358745804, 24245.87877060639, 24269.150579729623, 24311.392215194406, 24325.0, 7603.809904752315, 7936.285734283827, 7941.761747147595, 7960.363170946311, 8210.137935516685, 4316.223079845658, 4387.122575484916, 4429.623482004002, 4431.091626216617, 4435.1129774044675, 3531.0, 3657.743957922973, 3660.4875317455576, 3670.4127339625534, 3906.1797022625, 724.6920058974914, 738.65283316841, 740.8999785028952, 747.8354761952362, 761.6686741267566, 601.9127226552125, 640.7761176153524, 659.515256286629, 662.5031226580065, 664.2129077043719, 9429.913010571145, 9497.667581634747, 9510.90489095114, 9597.808043839224, 9670.178034866974, 622.8474023864092, 639.67376637915, 646.1277275677892, 694.9791506254719, 702.6100514214558, 4263.445752052276, 4396.890077748018, 4645.326427668037, 4676.55572720591, 4681.157391669532, 111765.0, 121397.0, 163190.0, 168524.15737921232, 175139.0, 144512.0, 147994.52005480023, 148059.0, 149484.0, 150126.0, 82113.58943205245, 120113.39943300227, 122677.38661306637, 124301.75698243083, 126768.0, 6847.828804279933, 8905.0, 8957.776055598662, 8959.148880856308, 9118.22495087925, 62845.74308513896, 63291.0, 65887.34112658812, 66270.33729662641, 68618.0, 88452.5577372109, 94186.52906735422, 95076.0, 95293.04706952842, 96947.0305296938, 11166.441677916235, 12036.398180091135, 12273.386330683608, 12365.690857728268, 12376.81434778461, 12778.552750653682, 13133.540326088541, 13260.270685112411, 13539.932300338436, 13847.519676006872, 10340.741481463023, 11499.252548584314, 11738.178327517333, 12516.31160286193, 12726.427310770852, 11946.104042196695, 12128.514859405877, 12329.0, 12372.876271237172, 12815.807762883378, 10339.534720937425, 10621.946890265499, 11882.524699011943, 12007.159498834997, 12183.086268530073, 7920.5247685139075, 8556.828863422572, 9206.723798286062, 9689.854652180082, 10592.364458132537, 7666.693332266647, 9577.369626453792, 10552.203666028356, 10567.313124646873, 10696.518656660313], "value": 7811.45523928631, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 108.0, 116.0, 127.0, 116.0, 98.0, 116.0, 117.0, 118.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 129.0, 157.0, 109.0, 134.0, 133.0, 144.0, 203.0, 139.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 214.0, 208.0, 196.0, 195.0, 196.0, 196.0, 199.0, 200.0, 190.0], "value": 199.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 84.0, 77.0, 140.0, 63.0, 60.0, 70.0, 87.0, 156.0, 60.0], "value": 86.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 116.0, 73.0, 96.0, 74.0, 105.0, 69.0, 71.0, 112.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 110.0, 88.0, 104.0, 113.0, 86.0, 108.0, 131.0, 90.0, 85.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 151.0, 119.0, 95.0, 95.0, 96.0, 96.0, 99.0, 95.0, 89.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 110.0, 153.0, 154.0, 90.0, 90.0, 88.0, 90.0, 75.0, 146.0], "value": 108.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 57.0, 68.0, 80.0, 90.0, 91.0, 91.0, 83.0], "value": 73.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 68.0, 74.0, 73.0, 70.0, 67.0, 70.0, 70.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 88.0, 87.0, 83.0, 71.0, 105.0, 87.0, 89.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 148.0, 139.0, 154.0, 165.0, 157.0, 154.0, 136.0, 180.0, 178.0], "value": 157.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [234.0, 170.0, 172.0, 169.0, 169.0, 172.0, 173.0, 168.0, 180.0, 169.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 68.0, 70.0, 70.0, 74.0, 83.0, 90.0, 90.0, 78.0], "value": 76.1, "unit": "score"}]}]} 08:04:21 INFO - Return code: 0 08:04:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:04:21 ERROR - # TBPL SUCCESS # 08:04:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:04:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:04:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:04:21 INFO - 2016-09-10 08:04:21.524 screenresolution[1688:15913] starting screenresolution argv=screenresolution get 08:04:21 INFO - 2016-09-10 08:04:21.526 screenresolution[1688:15913] Display 0: 1600x1200x32@60 08:04:21 INFO - 2016-09-10 08:04:21.532 screenresolution[1689:15916] starting screenresolution argv=screenresolution list 08:04:21 INFO - Available Modes on Display 0 08:04:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:04:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:04:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:04:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:04:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:04:21 INFO - Intel Iris: 08:04:21 INFO - Chipset Model: Intel Iris 08:04:21 INFO - Type: GPU 08:04:21 INFO - Bus: Built-In 08:04:21 INFO - VRAM (Dynamic, Max): 1536 MB 08:04:21 INFO - Vendor: Intel (0x8086) 08:04:21 INFO - Device ID: 0x0a2e 08:04:21 INFO - Revision ID: 0x0009 08:04:21 INFO - Displays: 08:04:21 INFO - Display: 08:04:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:04:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:04:21 INFO - Display Serial Number: 1600x1200 60 08:04:21 INFO - Main Display: Yes 08:04:21 INFO - Mirror: Off 08:04:21 INFO - Online: Yes 08:04:21 INFO - Rotation: Supported 08:04:21 INFO - Return code: 0 08:04:21 INFO - Running post-action listener: _resource_record_post_action 08:04:21 INFO - [mozharness: 2016-09-10 15:04:21.767845Z] Finished run-tests step (success) 08:04:21 INFO - Running post-run listener: _resource_record_post_run 08:04:21 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 31902720; Write bytes: 708115968; Read time: 193; Write time: 1993 08:04:21 INFO - TinderboxPrint: CPU usage
54.5% 08:04:21 INFO - TinderboxPrint: I/O read bytes / time
31,902,720 / 193 08:04:21 INFO - TinderboxPrint: I/O write bytes / time
708,115,968 / 1,993 08:04:21 INFO - TinderboxPrint: CPU idle
2,198.7 (45.4%) 08:04:21 INFO - TinderboxPrint: CPU system
972.7 (20.1%) 08:04:21 INFO - TinderboxPrint: CPU user
1,666.3 (34.4%) 08:04:21 INFO - TinderboxPrint: Swap in / out
433,897,472 / 0 08:04:21 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402815488; Write bytes: 397870080; Read time: 29741; Write time: 809 08:04:21 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 29101056; Write bytes: 297433600; Read time: 172; Write time: 1147 08:04:22 INFO - Running post-run listener: _upload_blobber_files 08:04:22 INFO - Blob upload gear active. 08:04:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:04:22 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 08:04:22 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'] 08:04:22 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 08:04:22 INFO - (blobuploader) - INFO - Open directory for files ... 08:04:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:04:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:04:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:23 INFO - (blobuploader) - INFO - Done attempting. 08:04:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:04:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:04:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:24 INFO - (blobuploader) - INFO - Done attempting. 08:04:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:04:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:04:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:26 INFO - (blobuploader) - INFO - Done attempting. 08:04:26 INFO - (blobuploader) - INFO - Iteration through files over. 08:04:26 INFO - Return code: 0 08:04:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:04:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:04:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b02bce43423e56047e72abae59ca8b3dbd77ba564a0b68e0321db93678e426d3dd70dcb4ef6f2f5e04d0b4bf2aed71f2ef6405834df02c7f3907d1e9d5213a84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c5bacec565d532c3c8ee675aa198e9139c80be2be569c599b13733119a61813f5149c0d5df7a6fd35507ba437edacc87c79c5f9bca5e0e6683378268d604e83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c9be57a7290ef68c4e4dd5f1aa6dba8110a8cd0c83406a9969662018286c573663cd51a89aa798745ff6af442378157b50adac6e911686980345e8d6e317b6a"} 08:04:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:04:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:04:26 INFO - Contents: 08:04:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b02bce43423e56047e72abae59ca8b3dbd77ba564a0b68e0321db93678e426d3dd70dcb4ef6f2f5e04d0b4bf2aed71f2ef6405834df02c7f3907d1e9d5213a84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c5bacec565d532c3c8ee675aa198e9139c80be2be569c599b13733119a61813f5149c0d5df7a6fd35507ba437edacc87c79c5f9bca5e0e6683378268d604e83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c9be57a7290ef68c4e4dd5f1aa6dba8110a8cd0c83406a9969662018286c573663cd51a89aa798745ff6af442378157b50adac6e911686980345e8d6e317b6a"} 08:04:26 INFO - Running post-run listener: copy_logs_to_upload_dir 08:04:26 INFO - Copying logs to upload dir... 08:04:26 INFO - mkdir: /builds/slave/test/build/upload/logs 08:04:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.825493 ========= master_lag: 70.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2016-09-10 08:05:37.225075) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 08:05:37.228932) ========= 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.N7n6XR5sqh/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.s7587poQbE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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/b02bce43423e56047e72abae59ca8b3dbd77ba564a0b68e0321db93678e426d3dd70dcb4ef6f2f5e04d0b4bf2aed71f2ef6405834df02c7f3907d1e9d5213a84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c5bacec565d532c3c8ee675aa198e9139c80be2be569c599b13733119a61813f5149c0d5df7a6fd35507ba437edacc87c79c5f9bca5e0e6683378268d604e83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c9be57a7290ef68c4e4dd5f1aa6dba8110a8cd0c83406a9969662018286c573663cd51a89aa798745ff6af442378157b50adac6e911686980345e8d6e317b6a"} build_url:https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/Fh5G9KnzQOOPx4TisN0Elw/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/b02bce43423e56047e72abae59ca8b3dbd77ba564a0b68e0321db93678e426d3dd70dcb4ef6f2f5e04d0b4bf2aed71f2ef6405834df02c7f3907d1e9d5213a84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c5bacec565d532c3c8ee675aa198e9139c80be2be569c599b13733119a61813f5149c0d5df7a6fd35507ba437edacc87c79c5f9bca5e0e6683378268d604e83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c9be57a7290ef68c4e4dd5f1aa6dba8110a8cd0c83406a9969662018286c573663cd51a89aa798745ff6af442378157b50adac6e911686980345e8d6e317b6a"}' ========= master_lag: 0.86 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 08:05:38.101542) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-09-10 08:05:38.101904) ========= 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.N7n6XR5sqh/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.s7587poQbE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.005279 ========= master_lag: 5.54 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-09-10 08:05:43.642622) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 08:05:43.642930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 08:05:44.592924) ========= ========= Total master_lag: 76.80 =========