builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0184 starttime: 1494235268.16 results: success (0) buildid: 20170508010755 builduid: f84a6a093cc74083bbd2f5ebbcaef3c6 revision: 81977c96c6ff49e4b70f88a55f38d47f5e54a08b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:08.163155) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:08.163575) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:08.163862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:08.207950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:08.208233) ========= 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.LxBp84bKeL/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.exdD2QQduC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-08 02:21:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-05-08 02:21:08 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.517154 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:08.749521) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:08.749807) ========= 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.LxBp84bKeL/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.exdD2QQduC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.022302 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:08.799001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-08 02:21:08.799298) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 81977c96c6ff49e4b70f88a55f38d47f5e54a08b --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 81977c96c6ff49e4b70f88a55f38d47f5e54a08b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LxBp84bKeL/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.exdD2QQduC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-08 02:21:08,882 truncating revision to first 12 chars 2017-05-08 02:21:08,883 Setting DEBUG logging. 2017-05-08 02:21:08,883 attempt 1/10 2017-05-08 02:21:08,883 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/81977c96c6ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-08 02:21:10,074 unpacking tar archive at: mozilla-central-81977c96c6ff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.455572 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-08 02:21:10.280244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:10.280585) ========= script_repo_revision: 81977c96c6ff49e4b70f88a55f38d47f5e54a08b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:10.280992) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:10.281254) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 02:21:10.301943) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-08 02:21:10.302318) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LxBp84bKeL/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.exdD2QQduC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:21:10 INFO - MultiFileLogger online at 20170508 02:21:10 in /builds/slave/test 02:21:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:21:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:21:10 INFO - {'append_to_log': False, 02:21:10 INFO - 'base_work_dir': '/builds/slave/test', 02:21:10 INFO - 'blob_upload_branch': 'Firefox', 02:21:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:21:10 INFO - 'branch': 'Firefox', 02:21:10 INFO - 'buildbot_json_path': 'buildprops.json', 02:21:10 INFO - 'config_files': ('talos/mac_config.py',), 02:21:10 INFO - 'default_actions': ('clobber', 02:21:10 INFO - 'read-buildbot-config', 02:21:10 INFO - 'download-and-extract', 02:21:10 INFO - 'populate-webroot', 02:21:10 INFO - 'create-virtualenv', 02:21:10 INFO - 'install', 02:21:10 INFO - 'run-tests'), 02:21:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:21:10 INFO - 'download_minidump_stackwalk': True, 02:21:10 INFO - 'download_symbols': 'ondemand', 02:21:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:21:10 INFO - 'tooltool.py': '/tools/tooltool.py', 02:21:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:21:10 INFO - '/tools/misc-python/virtualenv.py')}, 02:21:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:21:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:21:10 INFO - 'gecko_profile': False, 02:21:10 INFO - 'gecko_profile_interval': 0, 02:21:10 INFO - 'installer_path': 'installer.exe', 02:21:10 INFO - 'log_level': 'info', 02:21:10 INFO - 'log_name': 'talos', 02:21:10 INFO - 'log_to_console': True, 02:21:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:21:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:21:10 INFO - 'opt_config_files': (), 02:21:10 INFO - 'pip_index': False, 02:21:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:21:10 INFO - 'cmd': ('bash', 02:21:10 INFO - '-c', 02:21:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:21:10 INFO - 'enabled': True, 02:21:10 INFO - 'halt_on_failure': False, 02:21:10 INFO - 'name': 'check_screen_resolution'},), 02:21:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:21:10 INFO - 'cmd': ('bash', 02:21:10 INFO - '-c', 02:21:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:21:10 INFO - 'enabled': True, 02:21:10 INFO - 'halt_on_failure': False, 02:21:10 INFO - 'name': 'check_screen_resolution'},), 02:21:10 INFO - 'run_cmd_checks_enabled': True, 02:21:10 INFO - 'suite': 'dromaeojs', 02:21:10 INFO - 'system_bits': '32', 02:21:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:21:10 INFO - 'title': 't-yosemite-r7-0184', 02:21:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:21:10 INFO - 'use_talos_json': True, 02:21:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:21:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:21:10 INFO - 'work_dir': 'build'} 02:21:10 INFO - [mozharness: 2017-05-08 09:21:10.515052Z] Running clobber step. 02:21:10 INFO - Running pre-action listener: _resource_record_pre_action 02:21:10 INFO - Running main action method: clobber 02:21:10 INFO - rmtree: /builds/slave/test/build 02:21:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:21:12 INFO - Running post-action listener: _resource_record_post_action 02:21:12 INFO - [mozharness: 2017-05-08 09:21:12.436572Z] Finished clobber step (success) 02:21:12 INFO - [mozharness: 2017-05-08 09:21:12.436798Z] Running read-buildbot-config step. 02:21:12 INFO - Running pre-action listener: _resource_record_pre_action 02:21:12 INFO - Running main action method: read_buildbot_config 02:21:12 INFO - Using buildbot properties: 02:21:12 INFO - { 02:21:12 INFO - "project": "", 02:21:12 INFO - "product": "firefox", 02:21:12 INFO - "script_repo_revision": "production", 02:21:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:21:12 INFO - "repository": "", 02:21:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:21:12 INFO - "buildid": "20170508010755", 02:21:12 INFO - "pgo_build": "False", 02:21:12 INFO - "basedir": "/builds/slave/test", 02:21:12 INFO - "buildnumber": 27, 02:21:12 INFO - "slavename": "t-yosemite-r7-0184", 02:21:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:21:12 INFO - "platform": "yosemite_r7", 02:21:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:21:12 INFO - "branch": "mozilla-central", 02:21:12 INFO - "repo_path": "mozilla-central", 02:21:12 INFO - "revision": "81977c96c6ff49e4b70f88a55f38d47f5e54a08b", 02:21:12 INFO - "stage_platform": "macosx64", 02:21:12 INFO - "builduid": "f84a6a093cc74083bbd2f5ebbcaef3c6", 02:21:12 INFO - "slavebuilddir": "test" 02:21:12 INFO - } 02:21:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:21:12 INFO - Running post-action listener: _resource_record_post_action 02:21:12 INFO - [mozharness: 2017-05-08 09:21:12.440953Z] Finished read-buildbot-config step (success) 02:21:12 INFO - [mozharness: 2017-05-08 09:21:12.441077Z] Running download-and-extract step. 02:21:12 INFO - Running pre-action listener: _resource_record_pre_action 02:21:12 INFO - Running main action method: download_and_extract 02:21:12 INFO - mkdir: /builds/slave/test/build/tests 02:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:21:12 INFO - trying https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:21:12 INFO - Downloading https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:21:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 02:21:13 INFO - Downloaded 1325 bytes. 02:21:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:21:13 INFO - Using the following test package requirements: 02:21:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:21:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:21:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:21:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:21:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'jsshell-mac.zip'], 02:21:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:21:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:21:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:21:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:21:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:21:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:21:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:21:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:21:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:21:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:21:13 INFO - Fetch https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:21:15 INFO - Content-Length response header: 52706253 02:21:15 INFO - Bytes received: 52706253 02:21:32 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:21:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:21:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:21:32 INFO - Fetch https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:21:34 INFO - Content-Length response header: 52706253 02:21:34 INFO - Bytes received: 52706253 02:21:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:21:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:21:50 INFO - Fetch https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:21:50 INFO - Content-Length response header: 14409362 02:21:50 INFO - Bytes received: 14409362 02:21:51 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')]} 02:21:51 INFO - trying https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:21:51 INFO - Downloading https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:21:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 02:21:53 INFO - Downloaded 70933568 bytes. 02:21:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:21:53 INFO - mkdir: /builds/slave/test/properties 02:21:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:21:53 INFO - Writing to file /builds/slave/test/properties/build_url 02:21:53 INFO - Contents: 02:21:53 INFO - build_url:https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:21:54 INFO - Running post-action listener: _resource_record_post_action 02:21:54 INFO - Running post-action listener: set_extra_try_arguments 02:21:54 INFO - [mozharness: 2017-05-08 09:21:54.084044Z] Finished download-and-extract step (success) 02:21:54 INFO - [mozharness: 2017-05-08 09:21:54.084172Z] Running populate-webroot step. 02:21:54 INFO - Running pre-action listener: _resource_record_pre_action 02:21:54 INFO - Running main action method: populate_webroot 02:21:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:21:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:21:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:21:54 INFO - '--tppagecycles', 02:21:54 INFO - '7'], 02:21:54 INFO - 'tests': ['tsvgm']}}, 02:21:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:21:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:21:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:21:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:21:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:21:54 INFO - 'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:21:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:21:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:21:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:21:54 INFO - 'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['damp', 'tps']}, 02:21:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:21:54 INFO - 'tests': ['damp', 'tps']}, 02:21:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['dromaeo_dom']}, 02:21:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:21:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:21:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:21:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['a11yr', 02:21:54 INFO - 'ts_paint', 02:21:54 INFO - 'tpaint', 02:21:54 INFO - 'sessionrestore', 02:21:54 INFO - 'sessionrestore_no_auto_restore', 02:21:54 INFO - 'tabpaint']}, 02:21:54 INFO - 'other-e10s': {'tests': ['a11yr', 02:21:54 INFO - 'ts_paint', 02:21:54 INFO - 'tpaint', 02:21:54 INFO - 'sessionrestore', 02:21:54 INFO - 'sessionrestore_no_auto_restore', 02:21:54 INFO - 'tabpaint']}, 02:21:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:21:54 INFO - 'ts_paint', 02:21:54 INFO - 'tpaint', 02:21:54 INFO - 'sessionrestore', 02:21:54 INFO - 'sessionrestore_no_auto_restore', 02:21:54 INFO - 'tabpaint']}, 02:21:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:21:54 INFO - 'ts_paint', 02:21:54 INFO - 'tpaint', 02:21:54 INFO - 'sessionrestore', 02:21:54 INFO - 'sessionrestore_no_auto_restore', 02:21:54 INFO - 'tabpaint']}, 02:21:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['a11yr', 02:21:54 INFO - 'ts_paint', 02:21:54 INFO - 'tpaint', 02:21:54 INFO - 'sessionrestore', 02:21:54 INFO - 'sessionrestore_no_auto_restore', 02:21:54 INFO - 'tabpaint']}, 02:21:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['a11yr', 02:21:54 INFO - 'ts_paint', 02:21:54 INFO - 'tpaint', 02:21:54 INFO - 'sessionrestore', 02:21:54 INFO - 'sessionrestore_no_auto_restore', 02:21:54 INFO - 'tabpaint']}, 02:21:54 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:21:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:21:54 INFO - 'bloom_basic_ref']}, 02:21:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['tsvgx', 02:21:54 INFO - 'tsvgr_opacity', 02:21:54 INFO - 'tart', 02:21:54 INFO - 'tscrollx', 02:21:54 INFO - 'cart', 02:21:54 INFO - 'tsvg_static']}, 02:21:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:21:54 INFO - 'tsvgr_opacity', 02:21:54 INFO - 'tart', 02:21:54 INFO - 'tscrollx', 02:21:54 INFO - 'cart', 02:21:54 INFO - 'tsvg_static']}, 02:21:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:21:54 INFO - 'talos_options': ['--disable-e10s'], 02:21:54 INFO - 'tests': ['tp5o']}, 02:21:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:21:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:21:54 INFO - 'talos_options': ['--disable-e10s', 02:21:54 INFO - '--xperf_path', 02:21:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:21:54 INFO - 'tests': ['tp5n']}, 02:21:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:21:54 INFO - 'talos_options': ['--xperf_path', 02:21:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:21:54 INFO - 'tests': ['tp5n']}}, 02:21:54 INFO - 'talos.zip': {'path': '', 02:21:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:21:54 INFO - Running post-action listener: _resource_record_post_action 02:21:54 INFO - [mozharness: 2017-05-08 09:21:54.103601Z] Finished populate-webroot step (success) 02:21:54 INFO - [mozharness: 2017-05-08 09:21:54.103729Z] Running create-virtualenv step. 02:21:54 INFO - Running pre-action listener: _resource_record_pre_action 02:21:54 INFO - Running main action method: create_virtualenv 02:21:54 INFO - Creating virtualenv /builds/slave/test/build/venv 02:21:54 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 02:21:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:21:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:21:54 INFO - Using real prefix '/tools/python27' 02:21:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:21:54 INFO - Installing distribute.............................................................................................................................................................................................done. 02:21:57 INFO - Installing pip.................done. 02:21:57 INFO - Return code: 0 02:21:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:21:58 INFO - Reading from file tmpfile_stdout 02:21:58 INFO - Output received: 02:21:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:21:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:58 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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:21:58 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 02:21:58 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 02:21:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:21:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:21:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:21:58 INFO - 'HOME': '/Users/cltbld', 02:21:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:21:58 INFO - 'LOGNAME': 'cltbld', 02:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:58 INFO - 'MOZ_NO_REMOTE': '1', 02:21:58 INFO - 'NO_EM_RESTART': '1', 02:21:58 INFO - 'PAGER': '/bin/cat', 02:21:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:58 INFO - 'PWD': '/builds/slave/test', 02:21:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:21:58 INFO - 'SHELL': '/bin/bash', 02:21:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:21:58 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:21:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:21:58 INFO - 'USER': 'cltbld', 02:21:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:21:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:58 INFO - 'XPC_FLAGS': '0x0', 02:21:58 INFO - 'XPC_SERVICE_NAME': '0', 02:21:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:58 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 02:21:58 INFO - Cleaning up... 02:21:58 INFO - Return code: 0 02:21:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:58 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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:21:58 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 02:21:58 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 02:21:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:21:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:21:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:21:58 INFO - 'HOME': '/Users/cltbld', 02:21:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:21:58 INFO - 'LOGNAME': 'cltbld', 02:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:58 INFO - 'MOZ_NO_REMOTE': '1', 02:21:58 INFO - 'NO_EM_RESTART': '1', 02:21:58 INFO - 'PAGER': '/bin/cat', 02:21:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:58 INFO - 'PWD': '/builds/slave/test', 02:21:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:21:58 INFO - 'SHELL': '/bin/bash', 02:21:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:21:58 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:21:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:21:58 INFO - 'USER': 'cltbld', 02:21:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:21:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:58 INFO - 'XPC_FLAGS': '0x0', 02:21:58 INFO - 'XPC_SERVICE_NAME': '0', 02:21:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:58 INFO - Downloading/unpacking psutil>=3.1.1 02:21:58 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 02:21:58 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 02:21:58 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 02:21:58 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 02:21:58 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 02:21:58 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 02:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:22:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:03 INFO - Installing collected packages: psutil 02:22:03 INFO - Running setup.py install for psutil 02:22:03 INFO - building 'psutil._psutil_osx' extension 02:22:03 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 02:22:03 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 02:22:03 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 02:22:04 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 02:22:04 INFO - building 'psutil._psutil_posix' extension 02:22:04 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 02:22:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:22:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:22:04 INFO - ^ 02:22:04 INFO - 1 warning generated. 02:22:04 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 02:22:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:04 INFO - Successfully installed psutil 02:22:04 INFO - Cleaning up... 02:22:04 INFO - Return code: 0 02:22:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:22:04 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')]} 02:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:04 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')]} 02:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:04 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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:22:04 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 02:22:04 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 02:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:04 INFO - 'HOME': '/Users/cltbld', 02:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:04 INFO - 'LOGNAME': 'cltbld', 02:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:04 INFO - 'MOZ_NO_REMOTE': '1', 02:22:04 INFO - 'NO_EM_RESTART': '1', 02:22:04 INFO - 'PAGER': '/bin/cat', 02:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:04 INFO - 'PWD': '/builds/slave/test', 02:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:04 INFO - 'SHELL': '/bin/bash', 02:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:04 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:04 INFO - 'USER': 'cltbld', 02:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:04 INFO - 'XPC_FLAGS': '0x0', 02:22:04 INFO - 'XPC_SERVICE_NAME': '0', 02:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 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 02:22:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:22:06 INFO - Installing collected packages: mozsystemmonitor 02:22:06 INFO - Running setup.py install for mozsystemmonitor 02:22:06 INFO - Successfully installed mozsystemmonitor 02:22:06 INFO - Cleaning up... 02:22:06 INFO - Return code: 0 02:22:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:22:06 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')]} 02:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:06 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')]} 02:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:06 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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:22:06 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 02:22:06 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 02:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:06 INFO - 'HOME': '/Users/cltbld', 02:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:06 INFO - 'LOGNAME': 'cltbld', 02:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:06 INFO - 'MOZ_NO_REMOTE': '1', 02:22:06 INFO - 'NO_EM_RESTART': '1', 02:22:06 INFO - 'PAGER': '/bin/cat', 02:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:06 INFO - 'PWD': '/builds/slave/test', 02:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:06 INFO - 'SHELL': '/bin/bash', 02:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:06 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:06 INFO - 'USER': 'cltbld', 02:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:06 INFO - 'XPC_FLAGS': '0x0', 02:22:06 INFO - 'XPC_SERVICE_NAME': '0', 02:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:06 INFO - Downloading/unpacking jsonschema==2.5.1 02:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:22:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22: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 02:22:08 INFO - Downloading functools32-3.2.3-2.tar.gz 02:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:22:08 INFO - warning: no files found matching '*.txt' 02:22:08 INFO - no previously-included directories found matching 'build' 02:22:08 INFO - no previously-included directories found matching 'dist' 02:22:08 INFO - no previously-included directories found matching '.git*' 02:22:08 INFO - Installing collected packages: jsonschema, functools32 02:22:08 INFO - Running setup.py install for functools32 02:22:08 INFO - warning: no files found matching '*.txt' 02:22:08 INFO - no previously-included directories found matching 'build' 02:22:08 INFO - no previously-included directories found matching 'dist' 02:22:08 INFO - no previously-included directories found matching '.git*' 02:22:08 INFO - Successfully installed jsonschema functools32 02:22:08 INFO - Cleaning up... 02:22:08 INFO - Return code: 0 02:22:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:22:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:22:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:08 INFO - 'HOME': '/Users/cltbld', 02:22:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:08 INFO - 'LOGNAME': 'cltbld', 02:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:08 INFO - 'MOZ_NO_REMOTE': '1', 02:22:08 INFO - 'NO_EM_RESTART': '1', 02:22:08 INFO - 'PAGER': '/bin/cat', 02:22:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:08 INFO - 'PWD': '/builds/slave/test', 02:22:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:08 INFO - 'SHELL': '/bin/bash', 02:22:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:08 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:08 INFO - 'USER': 'cltbld', 02:22:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:08 INFO - 'XPC_FLAGS': '0x0', 02:22:08 INFO - 'XPC_SERVICE_NAME': '0', 02:22:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:22:08 INFO - Cleaning up... 02:22:08 INFO - Return code: 0 02:22:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:22:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:22:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:08 INFO - 'HOME': '/Users/cltbld', 02:22:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:08 INFO - 'LOGNAME': 'cltbld', 02:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:08 INFO - 'MOZ_NO_REMOTE': '1', 02:22:08 INFO - 'NO_EM_RESTART': '1', 02:22:08 INFO - 'PAGER': '/bin/cat', 02:22:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:08 INFO - 'PWD': '/builds/slave/test', 02:22:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:08 INFO - 'SHELL': '/bin/bash', 02:22:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:08 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:08 INFO - 'USER': 'cltbld', 02:22:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:08 INFO - 'XPC_FLAGS': '0x0', 02:22:08 INFO - 'XPC_SERVICE_NAME': '0', 02:22:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:09 INFO - Downloading/unpacking blobuploader==1.2.4 02:22:09 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 02:22:09 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 02:22:09 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 02:22:09 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 02:22:09 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 02:22:09 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 02:22:10 INFO - Downloading blobuploader-1.2.4.tar.gz 02:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:22:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:22:10 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 02:22:10 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 02:22:10 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 02:22:10 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 02:22:10 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 02:22:10 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 02:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:22:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:11 INFO - Downloading docopt-0.6.1.tar.gz 02:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:22:11 INFO - Installing collected packages: blobuploader, requests, docopt 02:22:11 INFO - Running setup.py install for blobuploader 02:22:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:22:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:22:11 INFO - Running setup.py install for requests 02:22:12 INFO - Running setup.py install for docopt 02:22:12 INFO - Successfully installed blobuploader requests docopt 02:22:12 INFO - Cleaning up... 02:22:12 INFO - Return code: 0 02:22:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:12 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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:22:12 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 02:22:12 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 02:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:12 INFO - 'HOME': '/Users/cltbld', 02:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:12 INFO - 'LOGNAME': 'cltbld', 02:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:12 INFO - 'MOZ_NO_REMOTE': '1', 02:22:12 INFO - 'NO_EM_RESTART': '1', 02:22:12 INFO - 'PAGER': '/bin/cat', 02:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:12 INFO - 'PWD': '/builds/slave/test', 02:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:12 INFO - 'SHELL': '/bin/bash', 02:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:12 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:12 INFO - 'USER': 'cltbld', 02:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:12 INFO - 'XPC_FLAGS': '0x0', 02:22:12 INFO - 'XPC_SERVICE_NAME': '0', 02:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:12 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-CC7XyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:12 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-91rkPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:12 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-OEfa4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:12 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-PTA7tA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-7PaOgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-TDpQa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-j3BBIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-0eC_Dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-sFlBVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-fA1YTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-D91NUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-cT9i7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-i5tFVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:13 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-gJ5RBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:14 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-H85_wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:14 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-e02FwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:14 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-WYkK9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:22:14 INFO - Running setup.py install for manifestparser 02:22:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:22:14 INFO - Running setup.py install for mozcrash 02:22:14 INFO - Running setup.py install for mozdebug 02:22:14 INFO - Running setup.py install for mozdevice 02:22:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:22:14 INFO - Running setup.py install for mozfile 02:22:15 INFO - Running setup.py install for mozhttpd 02:22:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:22:15 INFO - Running setup.py install for mozinfo 02:22:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:22:15 INFO - Running setup.py install for mozInstall 02:22:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:22:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:22:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:22:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:22:15 INFO - Running setup.py install for mozleak 02:22:15 INFO - Running setup.py install for mozlog 02:22:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:22:15 INFO - Running setup.py install for moznetwork 02:22:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:22:16 INFO - Running setup.py install for mozprocess 02:22:16 INFO - Running setup.py install for mozprofile 02:22:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:22:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:22:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:22:16 INFO - Running setup.py install for mozrunner 02:22:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:22:16 INFO - Running setup.py install for mozscreenshot 02:22:16 INFO - Running setup.py install for moztest 02:22:16 INFO - Running setup.py install for mozversion 02:22:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:22:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:22:16 INFO - Cleaning up... 02:22:16 INFO - Return code: 0 02:22:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:16 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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:22:16 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 02:22:16 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 02:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:16 INFO - 'HOME': '/Users/cltbld', 02:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:16 INFO - 'LOGNAME': 'cltbld', 02:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:16 INFO - 'MOZ_NO_REMOTE': '1', 02:22:16 INFO - 'NO_EM_RESTART': '1', 02:22:16 INFO - 'PAGER': '/bin/cat', 02:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:16 INFO - 'PWD': '/builds/slave/test', 02:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:16 INFO - 'SHELL': '/bin/bash', 02:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:16 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:16 INFO - 'USER': 'cltbld', 02:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:16 INFO - 'XPC_FLAGS': '0x0', 02:22:16 INFO - 'XPC_SERVICE_NAME': '0', 02:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:17 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-pNeyiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:17 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)) 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:17 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-PBp6VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:17 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-UL9e6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:17 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)) 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:17 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-kbTO80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:17 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-uqMEJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:17 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)) 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:17 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-AhxAXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:17 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)) 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:17 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-MfcXI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:17 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)) 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:17 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-VXLthS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:18 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)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-H8Rszm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:18 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)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-VbsCJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-iEb9I0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:18 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)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-WmqsuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-pTd80E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:18 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)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-soNIys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-0esrPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:18 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)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-jJ5Yz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:18 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-qlPowU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:19 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)) 02:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:22:19 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)) 02:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:22:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:22:19 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 02:22:19 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 02:22:19 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 02:22:19 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 02:22:19 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 02:22:19 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 02:22:20 INFO - Downloading blessings-1.6.tar.gz 02:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:22:20 INFO - Installing collected packages: blessings 02:22:20 INFO - Running setup.py install for blessings 02:22:20 INFO - Successfully installed blessings 02:22:20 INFO - Cleaning up... 02:22:20 INFO - Return code: 0 02:22:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:22:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:20 INFO - Reading from file tmpfile_stdout 02:22:21 INFO - Current package versions: 02:22:21 INFO - blessings == 1.6 02:22:21 INFO - blobuploader == 1.2.4 02:22:21 INFO - docopt == 0.6.1 02:22:21 INFO - functools32 == 3.2.3-2 02:22:21 INFO - jsonschema == 2.5.1 02:22:21 INFO - manifestparser == 1.1 02:22:21 INFO - mozInstall == 1.12 02:22:21 INFO - mozcrash == 1.0 02:22:21 INFO - mozdebug == 0.1 02:22:21 INFO - mozdevice == 0.50 02:22:21 INFO - mozfile == 1.2 02:22:21 INFO - mozhttpd == 0.7 02:22:21 INFO - mozinfo == 0.9 02:22:21 INFO - mozleak == 0.1 02:22:21 INFO - mozlog == 3.4 02:22:21 INFO - moznetwork == 0.27 02:22:21 INFO - mozprocess == 0.25 02:22:21 INFO - mozprofile == 0.28 02:22:21 INFO - mozrunner == 6.13 02:22:21 INFO - mozscreenshot == 0.1 02:22:21 INFO - mozsystemmonitor == 0.3 02:22:21 INFO - moztest == 0.8 02:22:21 INFO - mozversion == 1.4 02:22:21 INFO - psutil == 3.1.1 02:22:21 INFO - requests == 1.2.3 02:22:21 INFO - wsgiref == 0.1.2 02:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:21 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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:22:21 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 02:22:21 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 02:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:21 INFO - 'HOME': '/Users/cltbld', 02:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:21 INFO - 'LOGNAME': 'cltbld', 02:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:21 INFO - 'MOZ_NO_REMOTE': '1', 02:22:21 INFO - 'NO_EM_RESTART': '1', 02:22:21 INFO - 'PAGER': '/bin/cat', 02:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:21 INFO - 'PWD': '/builds/slave/test', 02:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:21 INFO - 'SHELL': '/bin/bash', 02:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:21 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:21 INFO - 'USER': 'cltbld', 02:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:21 INFO - 'XPC_FLAGS': '0x0', 02:22:21 INFO - 'XPC_SERVICE_NAME': '0', 02:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 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)) 02:22:21 INFO - Cleaning up... 02:22:21 INFO - Return code: 0 02:22:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:21 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 0x1007beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea04f5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, '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.LxBp84bKeL/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.exdD2QQduC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 02:22:21 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 02:22:21 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 02:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:21 INFO - 'HOME': '/Users/cltbld', 02:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:21 INFO - 'LOGNAME': 'cltbld', 02:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:21 INFO - 'MOZ_NO_REMOTE': '1', 02:22:21 INFO - 'NO_EM_RESTART': '1', 02:22:21 INFO - 'PAGER': '/bin/cat', 02:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:21 INFO - 'PWD': '/builds/slave/test', 02:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:21 INFO - 'SHELL': '/bin/bash', 02:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:21 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:21 INFO - 'USER': 'cltbld', 02:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:21 INFO - 'XPC_FLAGS': '0x0', 02:22:21 INFO - 'XPC_SERVICE_NAME': '0', 02:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:22:21 INFO - Cleaning up... 02:22:21 INFO - Return code: 0 02:22:21 INFO - Running post-action listener: _resource_record_post_action 02:22:21 INFO - Running post-action listener: _start_resource_monitoring 02:22:21 INFO - Starting resource monitoring. 02:22:21 INFO - [mozharness: 2017-05-08 09:22:21.571250Z] Finished create-virtualenv step (success) 02:22:21 INFO - [mozharness: 2017-05-08 09:22:21.572262Z] Running install step. 02:22:21 INFO - Running pre-action listener: _resource_record_pre_action 02:22:21 INFO - Running main action method: install 02:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:21 INFO - Reading from file tmpfile_stdout 02:22:21 INFO - Detecting whether we're running mozinstall >=1.0... 02:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:22:21 INFO - Reading from file tmpfile_stdout 02:22:21 INFO - Output received: 02:22:21 INFO - Usage: mozinstall [options] installer 02:22:21 INFO - Options: 02:22:21 INFO - -h, --help show this help message and exit 02:22:21 INFO - -d DEST, --destination=DEST 02:22:21 INFO - Directory to install application into. [default: 02:22:21 INFO - "/builds/slave/test"] 02:22:21 INFO - --app=APP Application being installed. [default: firefox] 02:22:21 INFO - mkdir: /builds/slave/test/build/application 02:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:22:41 INFO - Reading from file tmpfile_stdout 02:22:41 INFO - Output received: 02:22:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:22:41 INFO - Running post-action listener: _resource_record_post_action 02:22:41 INFO - [mozharness: 2017-05-08 09:22:41.663825Z] Finished install step (success) 02:22:41 INFO - [mozharness: 2017-05-08 09:22:41.663986Z] Running run-tests step. 02:22:41 INFO - Running pre-action listener: _resource_record_pre_action 02:22:41 INFO - Running main action method: run_tests 02:22:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:22:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:22:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:22:41 INFO - 2017-05-08 02:22:41.679 screenresolution[3412:69698] starting screenresolution argv=screenresolution get 02:22:41 INFO - 2017-05-08 02:22:41.681 screenresolution[3412:69698] Display 0: 1600x1200x32@60 02:22:41 INFO - 2017-05-08 02:22:41.687 screenresolution[3413:69701] starting screenresolution argv=screenresolution list 02:22:41 INFO - Available Modes on Display 0 02:22:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:22:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:22:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:22:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:22:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:22:41 INFO - Intel Iris: 02:22:41 INFO - Chipset Model: Intel Iris 02:22:41 INFO - Type: GPU 02:22:41 INFO - Bus: Built-In 02:22:41 INFO - VRAM (Dynamic, Max): 1536 MB 02:22:41 INFO - Vendor: Intel (0x8086) 02:22:41 INFO - Device ID: 0x0a2e 02:22:41 INFO - Revision ID: 0x0009 02:22:41 INFO - Displays: 02:22:41 INFO - Display: 02:22:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:22:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:22:41 INFO - Display Serial Number: 1600x1200 60 02:22:41 INFO - Main Display: Yes 02:22:41 INFO - Mirror: Off 02:22:41 INFO - Online: Yes 02:22:41 INFO - Rotation: Supported 02:22:41 INFO - Return code: 0 02:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:22:41 INFO - Python 2.7.3 02:22:41 INFO - Return code: 0 02:22:41 INFO - grabbing minidump binary from tooltool 02:22:41 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')]} 02:22:41 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 0x1004cfd30>, '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 0x100482db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004c3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:22:41 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 02:22:41 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 02:22:41 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 02:22:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:22:42 INFO - Return code: 0 02:22:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:22:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:22:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:22:42 INFO - ENV: RUST_BACKTRACE is now 1 02:22:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:22:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0184 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxBp84bKeL/Render', 02:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:42 INFO - 'HOME': '/Users/cltbld', 02:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:42 INFO - 'LOGNAME': 'cltbld', 02:22:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:22:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:42 INFO - 'MOZ_NO_REMOTE': '1', 02:22:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:22:42 INFO - 'NO_EM_RESTART': '1', 02:22:42 INFO - 'PAGER': '/bin/cat', 02:22:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:42 INFO - 'PWD': '/builds/slave/test', 02:22:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:42 INFO - 'RUST_BACKTRACE': '1', 02:22:42 INFO - 'SHELL': '/bin/bash', 02:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.exdD2QQduC/Listeners', 02:22:42 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 02:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:42 INFO - 'USER': 'cltbld', 02:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:42 INFO - 'XPC_FLAGS': '0x0', 02:22:42 INFO - 'XPC_SERVICE_NAME': '0', 02:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:22:42 INFO - mozversion application_buildid: 20170508010755 02:22:42 INFO - mozversion application_changeset: 81977c96c6ff49e4b70f88a55f38d47f5e54a08b 02:22:42 INFO - mozversion application_display_name: Nightly 02:22:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:22:42 INFO - mozversion application_name: Firefox 02:22:42 INFO - mozversion application_remotingname: firefox 02:22:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:22:42 INFO - mozversion application_vendor: Mozilla 02:22:42 INFO - mozversion application_version: 55.0a1 02:22:42 INFO - mozversion platform_buildid: 20170508010755 02:22:42 INFO - mozversion platform_changeset: 81977c96c6ff49e4b70f88a55f38d47f5e54a08b 02:22:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:22:42 INFO - mozversion platform_version: 55.0a1 02:22:42 INFO - using testdate: 1494235362 02:22:42 INFO - actual date: 1494235362 02:22:42 INFO - starting webserver on 'localhost:64291' 02:22:42 INFO - SUITE-START | Running 2 tests 02:22:42 INFO - TEST-START | dromaeo_css 02:22:42 INFO - operating with platform_type : mac_ 02:22:42 INFO - Initialising browser for dromaeo_css test... 02:22:42 INFO - TEST-INFO | started process 3423 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpojMkFn/profile http://localhost:64291/getInfo.html) 02:22:44 INFO - PID 3423 | __metrics Screen width/height:1600/1200 02:22:44 INFO - PID 3423 | colorDepth:24 02:22:44 INFO - PID 3423 | Browser inner width/height: 1024/691 02:22:44 INFO - PID 3423 | __metrics 02:22:44 INFO - PID 3423 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:22:44 INFO - PID 3423 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:22:45 INFO - TEST-INFO | 3423: exit 0 02:22:45 INFO - Browser initialized. 02:22:45 INFO - Running cycle 1/1 for dromaeo_css test... 02:22:45 INFO - TEST-INFO | started process 3425 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpojMkFn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:22:57 INFO - PID 3425 | RSS: Main: 240332800 02:22:57 INFO - PID 3425 | 02:26:01 INFO - PID 3425 | Cycle 1(1): loaded http://localhost:64291/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64291/tests/dromaeo/cssquery-ext.html) 02:26:03 INFO - PID 3425 | RSS: Main: 457781248 02:26:03 INFO - PID 3425 | 02:29:06 INFO - PID 3425 | Cycle 1(1): loaded http://localhost:64291/tests/dromaeo/cssquery-ext.html (next: http://localhost:64291/tests/dromaeo/cssquery-jquery.html) 02:29:07 INFO - PID 3425 | RSS: Main: 367693824 02:29:07 INFO - PID 3425 | 02:32:09 INFO - PID 3425 | Cycle 1(1): loaded http://localhost:64291/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64291/tests/dromaeo/cssquery-mootools.html) 02:32:10 INFO - PID 3425 | RSS: Main: 329039872 02:32:10 INFO - PID 3425 | 02:35:12 INFO - PID 3425 | Cycle 1(1): loaded http://localhost:64291/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64291/tests/dromaeo/cssquery-prototype.html) 02:35:12 INFO - PID 3425 | RSS: Main: 360542208 02:35:12 INFO - PID 3425 | 02:38:16 INFO - PID 3425 | Cycle 1(1): loaded http://localhost:64291/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64291/tests/dromaeo/cssquery-yui.html) 02:38:17 INFO - PID 3425 | RSS: Main: 320950272 02:38:17 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | Cycle 1(1): loaded http://localhost:64291/tests/dromaeo/cssquery-yui.html (next: http://localhost:64291/tests/dromaeo/cssquery-dojo.html) 02:41:19 INFO - PID 3425 | RSS: Main: 372019200 02:41:19 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | __start_tp_report 02:41:19 INFO - PID 3425 | _x_x_mozilla_page_load 02:41:19 INFO - PID 3425 | _x_x_mozilla_page_load_details 02:41:19 INFO - PID 3425 | |i|pagename|runs| 02:41:19 INFO - PID 3425 | |0;dojo.html;2903.3902880395117;3486.5118883356317;3512.104413374591;3546.30846984838;3561.2343346180564;6039.72821223045;6115.6358679157975;6240.656763877991;6269.5611307208455;6286.151369565107;6107.34053441714;6154.076888466743;6164.506839452844;6172.567920245585;6272.341404152567;4862.951370486286;5166.380034395877;5267.920981185285;5347.652402593839;5414.485623865727;5380.515753582177;5398.514133727964;5445.019896418643;5445.1832225166145;5455.1544510600725;5237.7381130943495;5308.814191503279;5310.097283461782;5310.230016647564;5314.548263397616;5312.787488500456;5325.973370133163;5335.039692855285;5341.43914888935;5344.732763361855;4571.657125715551;5234.685918844882;5277.70972596507;5292.947070529284;5314.256004159421;6063.636181829061;6079.05774604937;6079.969600152015;6101.890146117665;6109.20580324555;6356.682165891687;6467.029945508164;6511.4465270452065;6528.477721782246;6547.869042619169;6630.668466576652;6650.700718467632;6663.9000414993325;6666.500012499034;6671.132752742161;5917.763289468416;5953.255843019622;6307.842303942438;6323.462505687022;6337.17616709834;6549.574277671936;6634.502412318999;6654.401103900672;6703.128593282842;6785.321467853274;6430.7106180222;6439.61362318253;6440.8389790255615;6456.806295811133;6470.158879345655;6608.43828274602;6620.9006864897065;6625;6628.8674226515195;6631.30371311015;6313.05304204373;6391.265004524539;6522.771702990467;6530.967345163243;6544.411003009657;6341.4609758170145;6369.426751592379;6383.680815959184;6388.073729309224;6401.583897046677;5071.163258062378;5192.896142077213;5200.479952004769;5200.7919683213;5216.061109000417;4700.482946875841;4710.788014539354;4722.244440889442;4732.716037037789;4776;6170.907436388457;6188.350223226586;6209.813705588839;6213.2233470816145;6236.407541283571;6383.680815959184;6604;6612.570182938093;6618.735250590019;6633.7346506140175;6110.114033465123;6114.357992410733;6124.203853499106;6124.816255512342;6127.050307202391;5705.62913410627;5708.1723150143;5712.885742285131;5716.142578613159;5720.856978575485;6570.8028759137305;6580.967095164494;6582.308857569982;6586.37429444202;6590.5716128451495;5382.407935127211;6355.809325720235;6613.570117942319;6656.933430665631;6657.068010478633;6800.489963252677;6801.1158549388265;6803.013563033432;6805.3875151236625;6809.319068093151;6465.3857883501;6495.188101487314;6499.025146228104;6509.804705858832;6518.57629254097;6407.839804004937;6443.871122577616;6554.410103090745;6588.110605068446;6591;6271;6368.745250189941;6405.519586030973;6407.903881441689;6408.519361047846;6383.585066970818;6388.9041664376;6398.232212134573;6400.23197216337;6401.103845461546;6250.0937364082865;6317.842053948688;6329.683515824282;6332.0818481320875;6356.713947872449;6272.5922815016875;6284.717187726472;6296.307406185408;6301.243850737941;6325.841853953688;6237.594556353823;6256.7810126645345;6274.090256912813;6283.528735344776;6336.461400780985 02:41:19 INFO - PID 3425 | |1;ext.html;756.4402342266723;802.7872613757355;1112.4271000434771;1133.1557989297974;1135.7103938495688;33643.99999999997;36833;36837;36914.81542592283;36992.26015479689;38523;38840.80579597105;38891;38984.805075974655;39144.21711565774;5968.313643930938;6044.818655440344;8156.551389673566;8298.958505207496;8303.709370172046;7409.962950185241;8396.622152003143;8412.831743365128;8414.158584141598;8435.620397082146;8432.15678432158;8475.618597163142;8481.575921203947;8489.235968762841;8530.317574594048;5978.910316345258;8438.78903027423;8453.915460845405;8458.66165353385;8460.2385785279;5962.493188079018;6002.939970600281;6019.067044608093;6032.909506357408;8126.065502467174;37170.44244336337;37465;37495.43756843649;37518.43722344167;37726.81136594327;3524.7356448266482;3549.964500355015;3570.535830342065;3573.9642603573893;3574.0528759878653;30451.695483045107;30473.847630761928;30488.54267185994;30498;30554.38891222163;3089.768267379933;3130.280035591804;3159.6998285163104;3167.9683203167906;3168.746500279972;213328;273891;278721;278892;279805.60097199585;37529.81235093861;37624.811875940446;38254.61745382566;47035.29447058228;47155.76422117867;49099.75450122727;49271.75364123157;49287;49323.75338123286;49382.0123597526;13671.589852304447;13748;13784.379702913393;13798.862011379757;13813.723725525433;11124.777504449865;11124.944375278072;11129.777404451866;11130.109591232682;11185.552577896955;12004.399780010965;12006.75986480283;12042.337671428155;12044.81932771009;12143.814113459663;4499.820007199741;4521.683482156283;5294.841154765363;5312.574994000471;5314.149736042292;1007.3955626623967;1007.5868893753526;1009.2279406254221;1042.7549525861416;1044.7179261599326;22753.08987640509;24307.149249776172;29809.85095074511;29963.95126170617;30382.392352152834;1006.8691070160833;1020.7295066807302;1082.242430298794;1095.2333366643222;1136.778328225988;12136.87863121375;12295.93852030752;12323.876761232452;12333.321667308384;12402.937985310016;294198;294517.05482945323;294532;295019;296879;51690.48309516932;51856.74071629693;51861.740691297055;51889.48110518847;52180;1451.9419223231164;1513.6821267533942;1525.7329967255687;1526.4886263101764;1533.156763779939;23272.95271712743;23345.883270584218;23378;23465.944032518237;23555;37941;38088.42867357047;38107.047323817125;38118;38288.80855595704;274205;274305;274473;274753;274814;26083.95664173412;26925.73074269232;27771.722282776915;32329.868454603977;32371;39678.20643587055;41457.79271103625;41489.58510414978;41752;42619.786901065294;27765.58351624687;28201.858990704914;28792.136235912956;29018.854905725337;29821.403571928007;29250.68371923226;29295.41409171762;29338.706612933598;29479.557806633347;29557.85221073881;27237.319067023484;34199.487007694406;35063.64936350604;35280.29439411146;35359;29814;31252.561808311173;31552.526712099796;31613.683863161074;31706.573204205404;21379.358619241448;24061.398465037815;24188.032478700654;24710;25539.6169057468 02:41:19 INFO - PID 3425 | |2;jquery.html;414.6475495828548;428.06819364140375;438.3556172426533;438.5548668101876;456.63241090921815;18925.24299028039;20752.89623551882;21301.786982130176;21529.031193596285;21545.353639390803;21041.158353665873;21476.677849832264;21634.459138521546;21686;21709.782902170973;6063.545234107449;6348.682565871688;6355.396237357444;6368.554201205918;6370.6177629342155;5864.970675146618;6061.909071363933;6125.203723515937;6143.600665956721;6159.846003849917;5923.378045305246;6092.5430592705725;6099.024156135019;6100.755969761204;6103.23709536308;6143.815685529419;6297.401746834089;6302.779402720883;6306.369363063703;6314.179156709644;5339.279197308391;5438.993786149575;5464.508169289878;5602.215689803431;5604.131359639263;23892.641610375857;27781.583276250873;27810.748516316813;27975.580366294318;28359.432811343864;2141.7001619773087;2220.3006053093222;2233.396982814631;2238.115944202031;2238.183063181927;14263.714725705428;17570.824291757046;17821.10894455272;17879.553011174823;18374.989375584337;2114.7145135406677;2115.6403411420097;2172.7615259302206;2176.1295481807406;2179.891005449721;124802.37598811947;148082;148160;148530.1175905925;149679.2516037413;11724.355160466086;12188.81716774249;12197.573084941985;12226.266424014588;12245;16295.429659961845;16323.836761632232;16331.83668163327;16368.67262654764;16419.91790041066;8681.218690317775;8911.55442227886;8940.46357218569;9045.140711632384;9087.863682044775;7158.427325813923;8505.31957443403;8516.06323304436;8709.390342675952;8739;7412.369948554433;7718.53688778675;7773.533587984739;7780.572068536285;7784.026996625422;2042.428120126367;2115.206797450956;2118.5445129297277;2123.161351266241;2123.7276114557276;898.4205187653927;929.1498279074706;942.6983365323031;974.0940924939732;974.605284859633;17902.820971790115;18978.33575824867;19341.226350946086;19442.611147776966;20838.479038023866;903.5075883643431;958.8705524754068;977.8777652793401;984.4362022947104;997.087664786712;8427.95786021066;10365.170786337076;10629.202809789296;10755.892441075646;10836.72908177286;532835;534141;538391;551132;553536;93314;117898.13629819188;124462.84131697634;132160;135448;1153.3771763247753;1209.679434949739;1235.9073069519823;1244.6266120163914;1245.4582256718356;21185.57628847445;21351;21385.25151619686;21517;21591.568168636226;55493.7225313887;57774;58494;58633.4136658628;59671;80482;81934.59032705036;83324.75012874934;83484;83626.21924698062;7255.854882902418;8294.253517183482;8577.528235947082;8744.69393571222;8789.91210087891;13574.86425135736;13587.52443664467;13745.656358591115;13851.861481385056;14096.718065638424;10310.793784124426;11306.378149201873;11472.31166130035;11612.419379031184;11733.35466549348;10694.89305106939;10708.785824283315;10725.946370268099;11012.284201526874;11102.500387482422;11570.94214528922;11669.59156429521;11753;11874.16880818369;12044.0127378985;7309.269073092648;8349.206825351583;8553.95723021381;8609.354298427517;8841.248493878093;10786.622468213574;11221.775564488502;11346.411502389506;11500.25248358854;11579.88420115788 02:41:19 INFO - PID 3425 | |3;mootools.html;1143.405429176828;1437.0515459796536;1451.7822326650994;1456.0462896802592;1466.8093147890772;11176.608818691348;11688.824667629993;11700.356480393575;11708.882911170886;11723.472443740031;11020.283681560693;11422.600208992686;11515.539378424874;11588.478518466669;11652.708682282948;2393.413613664655;2431.088341871798;2435.1111844176926;2438.5732496813075;2441.914532991346;2432.1244352033254;2457.373369790701;2459.2007597530783;2463.901443942249;2465.2604218734396;2075.9584808303825;2151.139544182324;2155.8275337972923;2408.2654790288957;2509.5357358888664;2118.5656940327194;2442.572549803786;2487.6517287579754;2495.0643508684243;2498.100683753847;2449.9510009799883;2480.131953816168;2485.72657007729;2494.4013436775226;2495.6755621769234;11211.327320360722;11290.943545282304;11309.434528273554;11324.6036388726;11328.943355283252;6411.679416029227;6661.034150048215;6665.93334066658;6670.998700389912;6675.732970681167;3470.9652903470896;3915.9804200979097;4115.279826030433;4264.9147017059795;4283.892902677427;1051.2693677893867;1223.608445297505;1282.4100913579764;1286.2796833773118;1287.2019348004296;15913.840861591352;16299.103549304784;16313.347466101342;16325.18374081303;16326.346946122141;5584.553235741212;5717.142428635655;5958.165856780054;5968.731407086605;5989.64062156272;13173.143745656502;13193.340332983313;13211.537596184087;13217.405216765268;13230.536931207362;6562.868742625121;6618.735250590019;6619.238787539398;6619.536632435779;6651.201855777338;5797.913031304533;5926.318473375531;5977.073553599199;6133.723982420802;6158.876822463615;4298.699091063596;4426.800793964279;4584.656150788637;4598.448186217675;4661.5804577587505;1299.7335546213003;1439.4386189386148;1442.4374493947369;1456.4392708807186;1461.5542259610709;778.1401551285861;790.0913948958744;798.5208874675264;802.4102284820652;804.4529719790481;6445.258795238514;6606.405423511811;6809.863802724017;6891.689873955684;6943.51395402317;923.787528868364;947.8388673925449;947.924166066713;951.833429149896;951.9190868776232;3577.928441431157;3858.5144719282716;3926.391409331558;4305.870823875289;4383.298672212494;14318.57044288673;14558.344874480674;14791.260436978324;14824;14843.554693359216;11933.343666098273;13977.58067257984;14760.704785904221;14762.778558321634;14830.480933167502;8366;8561.443506172203;8562.74311770648;8577.356698247657;8601.741947741453;6752.831179220559;6946.097007389007;7037.7184912602925;7053.753118640823;7106.893396599158;12430.502779888704;13305.600831975056;13328.933355333162;13338.93330533341;13356.465741370625;12975.286359250333;13150.868491314965;13446.529371471952;13518.459261629425;13688.384022718803;9058.954705226432;9273.67542136022;9314.394564353295;9389.10803473669;9390.624375024923;9773.609055637979;10143.34068285559;10465.529051192563;10744.73138171552;10745.785084298426;8765.868511972452;8898.065703101081;8915.598798054232;8993.865092023756;9053.68312109073;8706;8779.20987111163;8831.514266715392;8847.823043539222;8903.777405564653;7885.802854928673;8036.919630803617;8170.305524030524;8183.71357002502;8220.260176584135;5931.991561434653;5963.2844058713235;6093.390660933871;6169.259688837368;6213.2233470816145;5408;5518.310211223597;5990.071538911476;6048.971674815204;6168.414000669929 02:41:19 INFO - PID 3425 | |4;prototype.html;358.2923725641856;384.2449586562406;392.7996721671947;407.39095052895914;407.51912742963293;4825.190553542421;5673.716314184295;5722.713864306784;6005.789797357093;6012.699365031742;4625;5009.499050094989;5152.304438900747;5885.264341957255;5949.0778929265925;4570.908581828361;4586.8394606188795;4594.127115847995;4595.609373203281;4609.101225261075;3817.961820381802;4327.307630779076;4598.747068911209;4623.052274283764;4632.235681112612;3957.7031722620773;4098.610631989956;4421.9336709949375;4456.699285189791;4517.841875534357;3681.3249971263012;4494.595486406239;4534.59188673021;4542.636589072867;4555.977220113895;3423.863045478203;4080.775557344345;4108.329585769244;4118.4440100586335;4122.175564887035;4708.646851486153;5729.656220626775;5761.596688231825;5765.279340082489;5774.228370382198;4649.535046495357;5041.344625198737;5053.9241911371355;5056;5074.340335756328;5735.799247026376;6267.86892456567;6275.399773057887;6276.686165691697;6278.493161641219;3616.764910280823;3844.884653460401;4148.377743338493;4218.409422680827;4220.810063547147;6517.087607734921;6612.933870661328;6626.271110177877;6630.900536491957;6647.5014373921185;3523.3481805865995;4245.514070075449;4250.936235956463;4254.468191476066;4264.296391095497;4113.1490829126415;4198.39123327122;4250.872473825791;4292.227399068198;4311.956880431218;4906.018796240766;5016.799328026911;5019.472955339636;5041.7227052511735;5064.708499332646;5220.843374698765;5440.863978400572;5482.698451585201;6118.1434599156155;6243.188385509855;1075.8493810866485;1357.0229434806922;1457;1459.729949959261;1497.5956491747168;897.8552345759209;964.2478866484154;967.6758285974278;978.8874279457812;990.1830989433746;5462.262594549765;5802.390748971382;5832.912506312408;5857;5870.354261031283;5615.550755939515;6400.679966001774;6409.775657851953;6425.839354016187;6447.967760161169;5576.693281869536;5704.515116215085;5806.854828629318;5812.505936995402;5873.973479149687;2785.763210127162;3320.8007519548905;3322.534845121685;3339.476851921198;3339.899803005865;5206;5206.323177986913;5292.311999440049;6223.284322302902;6620;5861.384554621789;5941.435563621449;6702.664866756642;6711.295313992058;6720;5549.916751248735;6461.770689112249;6748.561343512754;6750.223724271772;6766.7969960901255;5975.518361229078;5981.760729570942;6446;6463.676816159267;6500.414962653194;5420.7289635518855;6381.617102973836;6396.456301214449;6449.3550644937;6453;6577.6053436793945;6683.331666833278;6707.990150292858;6739.92161254197;6742.797716068526;3580.8567657293415;4270.380794784676;4294.742315461082;4298.935515967326;4300.655947524128;5636.830895073154;5917.260342457193;6214.844628884314;6416.363827224028;6498.252700939453;5248.063952035973;5483.917741233805;6302.243730752339;6321.715522801393;6327.430531252209;6155.599886007395;6192.442680158807;6219.813405597839;6246.562740608114;6272.664608745109;5280.524752772269;5993.861166378374;6129.877402452015;6146.139540464428;6184.8971349741305;4685.484596694293;5515.862103447446;6037.849053773691;6287.96248618991;6316.905246421399;5650.463205995424;5725.570582206268;6059.878802423838;6117.449429551361;6137.048757442603 02:41:19 INFO - PID 3425 | |5;yui.html;763.4732034895925;830.505849019833;844.2612713875363;845.2477295207265;867.3278209387726;16455.75316370254;16673.6652669466;16910.408135715254;16921.492355229362;16954.897931634434;15395.153266570334;15597.376104955816;15797.842021579781;15802.683946321096;15861.127637979907;977.8826540815077;985.8964808695091;989.5645915797027;991.6628346362236;996.3025881882675;995.3231802374382;1006.1146191351637;1006.6527048168407;1006.8338724110549;1006.9597216111347;1008.8890222075565;1014.2089170447063;1016.0043157705453;1016.4866742295168;1050.5220124843179;870.8563087090622;1007.5566750629699;1010.1817527802467;1011.4993078426226;1012.0132870451324;719.481972979454;767.9808004799869;768.3392282636928;769.8298586149023;777.5295945952673;16552.586185345343;16933.06868122253;17117.486475405756;17479.300827966865;17741.645167096587;17078.829211707973;17167.05581193034;17217.225224864913;17473.825261747348;17544.0350780707;14106.153630782186;14193.645158871112;14237.644058898506;14568.854311456857;14761.77857332141;3587.031501494582;3589.6410358964154;3595.676389124991;3597.118705917041;3600.9099772505633;93264.53367733117;121375.39312303471;128309;131955.34022329826;134010;10308.32995855267;11017.228793984345;11220.438978051065;13130.34348282582;13326.466941322433;24103.03587856466;24861;25463.36341591438;25553.616695749577;25582.488350232892;7756.961215194001;7932.881006784903;7940.801479963047;7942.960285198653;7948.920510794934;3426.9485957710654;3923.313420151462;3940.2513522430645;3988.3419235826345;3992.161646054361;4212.620864122183;4329.523752387235;4336.0460698646875;4337.284348082531;4359.2807186813825;642.1170890026214;770.9884351734728;782.5500337306072;783.7844592737043;785.7388890830135;647.896336586144;649.7108786589922;657.7434800427837;666.3835951744617;684.6200358800913;9616.75958101053;11765.11761617882;11894.464749086312;11933.462994165284;12017.158798883995;640.955133140685;656.4354654996699;658.4501940879419;667.813012356541;679.6262055869253;5045.873853153627;5093.541581257705;5182.404023537266;5245.527902488794;5281.656692314988;103554;103813;106287.0284728633;109219.45390273156;113025;92140.07859921316;93025.53487232521;93502.06497934934;94306.05693942973;95619;80630.59684701657;81088.18911810806;81650;82488;83238;10220.386776793417;10319.845202322122;10377.481125943823;10435.16518678519;10589.311694739818;42579.7871010643;42870.78564607157;43308;43402.565974341116;43512;53938.73030634822;54332.728336358065;54743.45256547384;54854.72572637271;56046.395360463626;14199;14425.35085921115;14467.638309042359;14475;14509.419623214953;15527.922360388126;17584.472465826046;18697.25210991545;18789;18800.247990080243;16050.518484445485;16253.268602913135;16784.58038549046;17301.13494325304;17467.737983929997;16998.98006119637;17177.914110429367;17262.22319995578;17264.654706906043;17265.74101388455;16355.918220408821;16923;16942.830571694125;17016.829831701525;17095.145242737566;10917.344959302467;11548.133889958119;11881.346525941157;12066.939665301617;12167.965722913297;11923.817846007218;12310.630681079581;13739.24434156091;13820.870173335572;14008.949328800585 02:41:19 INFO - PID 3425 | __end_tp_report 02:41:19 INFO - PID 3425 | __start_cc_report 02:41:19 INFO - PID 3425 | _x_x_mozilla_cycle_collect,2210 02:41:19 INFO - PID 3425 | __end_cc_report 02:41:19 INFO - PID 3425 | __startTimestamp1494236479680__endTimestamp 02:41:19 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | ------- Summary: start ------- 02:41:19 INFO - PID 3425 | Number of tests: 6 02:41:19 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | [#0] dojo.html Cycles:165 Average:6031.52 Median:6301.24 stddev:718.85 (11.4%) stddev-sans-first:677.88 02:41:19 INFO - PID 3425 | Values: 2903.4 3486.5 3512.1 3546.3 3561.2 6039.7 6115.6 6240.7 6269.6 6286.2 6107.3 6154.1 6164.5 6172.6 6272.3 4863.0 5166.4 5267.9 5347.7 5414.5 5380.5 5398.5 5445.0 5445.2 5455.2 5237.7 5308.8 5310.1 5310.2 5314.5 5312.8 5326.0 5335.0 5341.4 5344.7 4571.7 5234.7 5277.7 5292.9 5314.3 6063.6 6079.1 6080.0 6101.9 6109.2 6356.7 6467.0 6511.4 6528.5 6547.9 6630.7 6650.7 6663.9 6666.5 6671.1 5917.8 5953.3 6307.8 6323.5 6337.2 6549.6 6634.5 6654.4 6703.1 6785.3 6430.7 6439.6 6440.8 6456.8 6470.2 6608.4 6620.9 6625.0 6628.9 6631.3 6313.1 6391.3 6522.8 6531.0 6544.4 6341.5 6369.4 6383.7 6388.1 6401.6 5071.2 5192.9 5200.5 5200.8 5216.1 4700.5 4710.8 4722.2 4732.7 4776.0 6170.9 6188.4 6209.8 6213.2 6236.4 6383.7 6604.0 6612.6 6618.7 6633.7 6110.1 6114.4 6124.2 6124.8 6127.1 5705.6 5708.2 5712.9 5716.1 5720.9 6570.8 6581.0 6582.3 6586.4 6590.6 5382.4 6355.8 6613.6 6656.9 6657.1 6800.5 6801.1 6803.0 6805.4 6809.3 6465.4 6495.2 6499.0 6509.8 6518.6 6407.8 6443.9 6554.4 6588.1 6591.0 6271.0 6368.7 6405.5 6407.9 6408.5 6383.6 6388.9 6398.2 6400.2 6401.1 6250.1 6317.8 6329.7 6332.1 6356.7 6272.6 6284.7 6296.3 6301.2 6325.8 6237.6 6256.8 6274.1 6283.5 6336.5 02:41:19 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | [#1] ext.html Cycles:180 Average:42509.94 Median:17341.40 stddev:73021.25 (421.1%) stddev-sans-first:73158.79 02:41:19 INFO - PID 3425 | Values: 756.4 802.8 1112.4 1133.2 1135.7 33644.0 36833.0 36837.0 36914.8 36992.3 38523.0 38840.8 38891.0 38984.8 39144.2 5968.3 6044.8 8156.6 8299.0 8303.7 7410.0 8396.6 8412.8 8414.2 8435.6 8432.2 8475.6 8481.6 8489.2 8530.3 5978.9 8438.8 8453.9 8458.7 8460.2 5962.5 6002.9 6019.1 6032.9 8126.1 37170.4 37465.0 37495.4 37518.4 37726.8 3524.7 3550.0 3570.5 3574.0 3574.1 30451.7 30473.8 30488.5 30498.0 30554.4 3089.8 3130.3 3159.7 3168.0 3168.7 213328.0 273891.0 278721.0 278892.0 279805.6 37529.8 37624.8 38254.6 47035.3 47155.8 49099.8 49271.8 49287.0 49323.8 49382.0 13671.6 13748.0 13784.4 13798.9 13813.7 11124.8 11124.9 11129.8 11130.1 11185.6 12004.4 12006.8 12042.3 12044.8 12143.8 4499.8 4521.7 5294.8 5312.6 5314.1 1007.4 1007.6 1009.2 1042.8 1044.7 22753.1 24307.1 29809.9 29964.0 30382.4 1006.9 1020.7 1082.2 1095.2 1136.8 12136.9 12295.9 12323.9 12333.3 12402.9 294198.0 294517.1 294532.0 295019.0 296879.0 51690.5 51856.7 51861.7 51889.5 52180.0 1451.9 1513.7 1525.7 1526.5 1533.2 23273.0 23345.9 23378.0 23465.9 23555.0 37941.0 38088.4 38107.0 38118.0 38288.8 274205.0 274305.0 274473.0 274753.0 274814.0 26084.0 26925.7 27771.7 32329.9 32371.0 39678.2 41457.8 41489.6 41752.0 42619.8 27765.6 28201.9 28792.1 29018.9 29821.4 29250.7 29295.4 29338.7 29479.6 29557.9 27237.3 34199.5 35063.6 35280.3 35359.0 29814.0 31252.6 31552.5 31613.7 31706.6 21379.4 24061.4 24188.0 24710.0 25539.6 02:41:19 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | [#2] jquery.html Cycles:180 Average:34832.33 Median:2235.76 stddev:91611.36 (4097.6%) stddev-sans-first:91831.91 02:41:19 INFO - PID 3425 | Values: 414.6 428.1 438.4 438.6 456.6 18925.2 20752.9 21301.8 21529.0 21545.4 21041.2 21476.7 21634.5 21686.0 21709.8 6063.5 6348.7 6355.4 6368.6 6370.6 5865.0 6061.9 6125.2 6143.6 6159.8 5923.4 6092.5 6099.0 6100.8 6103.2 6143.8 6297.4 6302.8 6306.4 6314.2 5339.3 5439.0 5464.5 5602.2 5604.1 23892.6 27781.6 27810.7 27975.6 28359.4 2141.7 2220.3 2233.4 2238.1 2238.2 14263.7 17570.8 17821.1 17879.6 18375.0 2114.7 2115.6 2172.8 2176.1 2179.9 124802.4 148082.0 148160.0 148530.1 149679.3 11724.4 12188.8 12197.6 12226.3 12245.0 16295.4 16323.8 16331.8 16368.7 16419.9 8681.2 8911.6 8940.5 9045.1 9087.9 7158.4 8505.3 8516.1 8709.4 8739.0 7412.4 7718.5 7773.5 7780.6 7784.0 2042.4 2115.2 2118.5 2123.2 2123.7 898.4 929.1 942.7 974.1 974.6 17902.8 18978.3 19341.2 19442.6 20838.5 903.5 958.9 977.9 984.4 997.1 8428.0 10365.2 10629.2 10755.9 10836.7 532835.0 534141.0 538391.0 551132.0 553536.0 93314.0 117898.1 124462.8 132160.0 135448.0 1153.4 1209.7 1235.9 1244.6 1245.5 21185.6 21351.0 21385.3 21517.0 21591.6 55493.7 57774.0 58494.0 58633.4 59671.0 80482.0 81934.6 83324.8 83484.0 83626.2 7255.9 8294.3 8577.5 8744.7 8789.9 13574.9 13587.5 13745.7 13851.9 14096.7 10310.8 11306.4 11472.3 11612.4 11733.4 10694.9 10708.8 10725.9 11012.3 11102.5 11570.9 11669.6 11753.0 11874.2 12044.0 7309.3 8349.2 8554.0 8609.4 8841.2 10786.6 11221.8 11346.4 11500.3 11579.9 02:41:19 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | [#3] mootools.html Cycles:180 Average:7057.55 Median:3718.22 stddev:4437.05 (119.3%) stddev-sans-first:4427.23 02:41:19 INFO - PID 3425 | Values: 1143.4 1437.1 1451.8 1456.0 1466.8 11176.6 11688.8 11700.4 11708.9 11723.5 11020.3 11422.6 11515.5 11588.5 11652.7 2393.4 2431.1 2435.1 2438.6 2441.9 2432.1 2457.4 2459.2 2463.9 2465.3 2076.0 2151.1 2155.8 2408.3 2509.5 2118.6 2442.6 2487.7 2495.1 2498.1 2450.0 2480.1 2485.7 2494.4 2495.7 11211.3 11290.9 11309.4 11324.6 11328.9 6411.7 6661.0 6665.9 6671.0 6675.7 3471.0 3916.0 4115.3 4264.9 4283.9 1051.3 1223.6 1282.4 1286.3 1287.2 15913.8 16299.1 16313.3 16325.2 16326.3 5584.6 5717.1 5958.2 5968.7 5989.6 13173.1 13193.3 13211.5 13217.4 13230.5 6562.9 6618.7 6619.2 6619.5 6651.2 5797.9 5926.3 5977.1 6133.7 6158.9 4298.7 4426.8 4584.7 4598.4 4661.6 1299.7 1439.4 1442.4 1456.4 1461.6 778.1 790.1 798.5 802.4 804.5 6445.3 6606.4 6809.9 6891.7 6943.5 923.8 947.8 947.9 951.8 951.9 3577.9 3858.5 3926.4 4305.9 4383.3 14318.6 14558.3 14791.3 14824.0 14843.6 11933.3 13977.6 14760.7 14762.8 14830.5 8366.0 8561.4 8562.7 8577.4 8601.7 6752.8 6946.1 7037.7 7053.8 7106.9 12430.5 13305.6 13328.9 13338.9 13356.5 12975.3 13150.9 13446.5 13518.5 13688.4 9059.0 9273.7 9314.4 9389.1 9390.6 9773.6 10143.3 10465.5 10744.7 10745.8 8765.9 8898.1 8915.6 8993.9 9053.7 8706.0 8779.2 8831.5 8847.8 8903.8 7885.8 8036.9 8170.3 8183.7 8220.3 5932.0 5963.3 6093.4 6169.3 6213.2 5408.0 5518.3 5990.1 6049.0 6168.4 02:41:19 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | [#4] prototype.html Cycles:180 Average:5013.78 Median:5626.19 stddev:1563.36 (27.8%) stddev-sans-first:1528.19 02:41:19 INFO - PID 3425 | Values: 358.3 384.2 392.8 407.4 407.5 4825.2 5673.7 5722.7 6005.8 6012.7 4625.0 5009.5 5152.3 5885.3 5949.1 4570.9 4586.8 4594.1 4595.6 4609.1 3818.0 4327.3 4598.7 4623.1 4632.2 3957.7 4098.6 4421.9 4456.7 4517.8 3681.3 4494.6 4534.6 4542.6 4556.0 3423.9 4080.8 4108.3 4118.4 4122.2 4708.6 5729.7 5761.6 5765.3 5774.2 4649.5 5041.3 5053.9 5056.0 5074.3 5735.8 6267.9 6275.4 6276.7 6278.5 3616.8 3844.9 4148.4 4218.4 4220.8 6517.1 6612.9 6626.3 6630.9 6647.5 3523.3 4245.5 4250.9 4254.5 4264.3 4113.1 4198.4 4250.9 4292.2 4312.0 4906.0 5016.8 5019.5 5041.7 5064.7 5220.8 5440.9 5482.7 6118.1 6243.2 1075.8 1357.0 1457.0 1459.7 1497.6 897.9 964.2 967.7 978.9 990.2 5462.3 5802.4 5832.9 5857.0 5870.4 5615.6 6400.7 6409.8 6425.8 6448.0 5576.7 5704.5 5806.9 5812.5 5874.0 2785.8 3320.8 3322.5 3339.5 3339.9 5206.0 5206.3 5292.3 6223.3 6620.0 5861.4 5941.4 6702.7 6711.3 6720.0 5549.9 6461.8 6748.6 6750.2 6766.8 5975.5 5981.8 6446.0 6463.7 6500.4 5420.7 6381.6 6396.5 6449.4 6453.0 6577.6 6683.3 6708.0 6739.9 6742.8 3580.9 4270.4 4294.7 4298.9 4300.7 5636.8 5917.3 6214.8 6416.4 6498.3 5248.1 5483.9 6302.2 6321.7 6327.4 6155.6 6192.4 6219.8 6246.6 6272.7 5280.5 5993.9 6129.9 6146.1 6184.9 4685.5 5515.9 6037.8 6288.0 6316.9 5650.5 5725.6 6059.9 6117.4 6137.0 02:41:19 INFO - PID 3425 | 02:41:19 INFO - PID 3425 | [#5] yui.html Cycles:180 Average:21749.37 Median:17564.25 stddev:30840.54 (175.6%) stddev-sans-first:30886.79 02:41:19 INFO - PID 3425 | Values: 763.5 830.5 844.3 845.2 867.3 16455.8 16673.7 16910.4 16921.5 16954.9 15395.2 15597.4 15797.8 15802.7 15861.1 977.9 985.9 989.6 991.7 996.3 995.3 1006.1 1006.7 1006.8 1007.0 1008.9 1014.2 1016.0 1016.5 1050.5 870.9 1007.6 1010.2 1011.5 1012.0 719.5 768.0 768.3 769.8 777.5 16552.6 16933.1 17117.5 17479.3 17741.6 17078.8 17167.1 17217.2 17473.8 17544.0 14106.2 14193.6 14237.6 14568.9 14761.8 3587.0 3589.6 3595.7 3597.1 3600.9 93264.5 121375.4 128309.0 131955.3 134010.0 10308.3 11017.2 11220.4 13130.3 13326.5 24103.0 24861.0 25463.4 25553.6 25582.5 7757.0 7932.9 7940.8 7943.0 7948.9 3426.9 3923.3 3940.3 3988.3 3992.2 4212.6 4329.5 4336.0 4337.3 4359.3 642.1 771.0 782.6 783.8 785.7 647.9 649.7 657.7 666.4 684.6 9616.8 11765.1 11894.5 11933.5 12017.2 641.0 656.4 658.5 667.8 679.6 5045.9 5093.5 5182.4 5245.5 5281.7 103554.0 103813.0 106287.0 109219.5 113025.0 92140.1 93025.5 93502.1 94306.1 95619.0 80630.6 81088.2 81650.0 82488.0 83238.0 10220.4 10319.8 10377.5 10435.2 10589.3 42579.8 42870.8 43308.0 43402.6 43512.0 53938.7 54332.7 54743.5 54854.7 56046.4 14199.0 14425.4 14467.6 14475.0 14509.4 15527.9 17584.5 18697.3 18789.0 18800.2 16050.5 16253.3 16784.6 17301.1 17467.7 16999.0 17177.9 17262.2 17264.7 17265.7 16355.9 16923.0 16942.8 17016.8 17095.1 10917.3 11548.1 11881.3 12066.9 12168.0 11923.8 12310.6 13739.2 13820.9 14008.9 02:41:19 INFO - PID 3425 | -------- Summary: end -------- 02:41:19 INFO - PID 3425 | 02:41:20 INFO - TEST-INFO | 3425: exit 0 02:41:20 INFO - TEST-OK | dromaeo_css | took 1118056ms 02:41:20 INFO - TEST-START | kraken 02:41:20 INFO - operating with platform_type : mac_ 02:41:20 INFO - Initialising browser for kraken test... 02:41:20 INFO - TEST-INFO | started process 3429 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpg20OoK/profile http://localhost:64291/getInfo.html) 02:41:22 INFO - PID 3429 | __metrics Screen width/height:1600/1200 02:41:22 INFO - PID 3429 | colorDepth:24 02:41:22 INFO - PID 3429 | Browser inner width/height: 1024/691 02:41:22 INFO - PID 3429 | __metrics 02:41:22 INFO - PID 3429 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:41:22 INFO - PID 3429 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:41:23 INFO - TEST-INFO | 3429: exit 0 02:41:23 INFO - Browser initialized. 02:41:23 INFO - Running cycle 1/1 for kraken test... 02:41:23 INFO - TEST-INFO | started process 3430 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpg20OoK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:41:36 INFO - PID 3430 | RSS: Main: 238252032 02:41:36 INFO - PID 3430 | 02:41:38 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64291/tests/kraken/driver.html?testName=audio-beat-detection) 02:41:39 INFO - PID 3430 | RSS: Main: 308400128 02:41:39 INFO - PID 3430 | 02:41:42 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64291/tests/kraken/driver.html?testName=audio-dft) 02:41:42 INFO - PID 3430 | RSS: Main: 292376576 02:41:42 INFO - PID 3430 | 02:41:46 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64291/tests/kraken/driver.html?testName=audio-fft) 02:41:46 INFO - PID 3430 | RSS: Main: 383102976 02:41:46 INFO - PID 3430 | 02:41:48 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64291/tests/kraken/driver.html?testName=audio-oscillator) 02:41:49 INFO - PID 3430 | RSS: Main: 286150656 02:41:49 INFO - PID 3430 | 02:41:51 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64291/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:41:51 INFO - PID 3430 | RSS: Main: 286957568 02:41:51 INFO - PID 3430 | 02:41:54 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64291/tests/kraken/driver.html?testName=imaging-darkroom) 02:41:55 INFO - PID 3430 | RSS: Main: 351105024 02:41:55 INFO - PID 3430 | 02:41:58 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64291/tests/kraken/driver.html?testName=imaging-desaturate) 02:41:58 INFO - PID 3430 | RSS: Main: 370098176 02:41:58 INFO - PID 3430 | 02:42:01 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64291/tests/kraken/driver.html?testName=json-parse-financial) 02:42:02 INFO - PID 3430 | RSS: Main: 379326464 02:42:02 INFO - PID 3430 | 02:42:03 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64291/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:42:04 INFO - PID 3430 | RSS: Main: 327880704 02:42:04 INFO - PID 3430 | 02:42:06 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64291/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:42:06 INFO - PID 3430 | RSS: Main: 333471744 02:42:06 INFO - PID 3430 | 02:42:08 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64291/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:42:09 INFO - PID 3430 | RSS: Main: 334700544 02:42:09 INFO - PID 3430 | 02:42:12 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64291/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:42:13 INFO - PID 3430 | RSS: Main: 352911360 02:42:13 INFO - PID 3430 | 02:42:16 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64291/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:42:16 INFO - PID 3430 | RSS: Main: 345669632 02:42:16 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | Cycle 1(1): loaded http://localhost:64291/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64291/tests/kraken/driver.html?testName=ai-astar) 02:42:18 INFO - PID 3430 | RSS: Main: 388333568 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | __start_tp_report 02:42:18 INFO - PID 3430 | _x_x_mozilla_page_load 02:42:18 INFO - PID 3430 | _x_x_mozilla_page_load_details 02:42:18 INFO - PID 3430 | |i|pagename|runs| 02:42:18 INFO - PID 3430 | |0;ai-astar;112;112;111;111;111;113;111;112;111;112 02:42:18 INFO - PID 3430 | |1;audio-beat-detection;145;110;108;151;105;107;109;106;134;108 02:42:18 INFO - PID 3430 | |2;audio-dft;182;184;186;188;185;184;187;184;185;181 02:42:18 INFO - PID 3430 | |3;audio-fft;83;57;57;90;62;59;61;58;82;58 02:42:18 INFO - PID 3430 | |4;audio-oscillator;89;69;88;68;68;68;87;69;67;89 02:42:18 INFO - PID 3430 | |5;imaging-gaussian-blur;87;86;86;86;87;86;87;86;86;86 02:42:18 INFO - PID 3430 | |6;imaging-darkroom;94;93;95;93;94;93;94;94;94;94 02:42:18 INFO - PID 3430 | |7;imaging-desaturate;85;85;87;88;83;86;84;84;85;85 02:42:18 INFO - PID 3430 | |8;json-parse-financial;49;49;49;47;50;49;48;48;47;48 02:42:18 INFO - PID 3430 | |9;json-stringify-tinderbox;53;53;63;71;62;61;60;58;59;59 02:42:18 INFO - PID 3430 | |10;stanford-crypto-aes;81;80;81;82;82;82;84;81;82;80 02:42:18 INFO - PID 3430 | |11;stanford-crypto-ccm;139;136;140;144;137;142;136;136;179;170 02:42:18 INFO - PID 3430 | |12;stanford-crypto-pbkdf2;182;189;216;197;183;181;184;182;185;183 02:42:18 INFO - PID 3430 | |13;stanford-crypto-sha256-iterative;68;67;66;65;68;67;68;69;68;68 02:42:18 INFO - PID 3430 | __end_tp_report 02:42:18 INFO - PID 3430 | __start_cc_report 02:42:18 INFO - PID 3430 | _x_x_mozilla_cycle_collect,2169 02:42:18 INFO - PID 3430 | __end_cc_report 02:42:18 INFO - PID 3430 | __startTimestamp1494236538859__endTimestamp 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | ------- Summary: start ------- 02:42:18 INFO - PID 3430 | Number of tests: 14 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 02:42:18 INFO - PID 3430 | Values: 112.0 112.0 111.0 111.0 111.0 113.0 111.0 112.0 111.0 112.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:109.50 stddev:17.80 (16.3%) stddev-sans-first:16.05 02:42:18 INFO - PID 3430 | Values: 145.0 110.0 108.0 151.0 105.0 107.0 109.0 106.0 134.0 108.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:2.12 (1.1%) stddev-sans-first:2.03 02:42:18 INFO - PID 3430 | Values: 182.0 184.0 186.0 188.0 185.0 184.0 187.0 184.0 185.0 181.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.50 stddev:12.89 (21.0%) stddev-sans-first:12.25 02:42:18 INFO - PID 3430 | Values: 83.0 57.0 57.0 90.0 62.0 59.0 61.0 58.0 82.0 58.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:78.00 stddev:10.40 (13.3%) stddev-sans-first:9.95 02:42:18 INFO - PID 3430 | Values: 89.0 69.0 88.0 68.0 68.0 68.0 87.0 69.0 67.0 89.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:86.00 stddev:0.48 (0.6%) stddev-sans-first:0.44 02:42:18 INFO - PID 3430 | Values: 87.0 86.0 86.0 86.0 87.0 86.0 87.0 86.0 86.0 86.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 02:42:18 INFO - PID 3430 | Values: 94.0 93.0 95.0 93.0 94.0 93.0 94.0 94.0 94.0 94.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.00 stddev:1.48 (1.7%) stddev-sans-first:1.56 02:42:18 INFO - PID 3430 | Values: 85.0 85.0 87.0 88.0 83.0 86.0 84.0 84.0 85.0 85.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#8] json-parse-financial Cycles:10 Average:48.40 Median:49.00 stddev:0.97 (2.0%) stddev-sans-first:1.00 02:42:18 INFO - PID 3430 | Values: 49.0 49.0 49.0 47.0 50.0 49.0 48.0 48.0 47.0 48.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.50 stddev:5.15 (8.5%) stddev-sans-first:4.82 02:42:18 INFO - PID 3430 | Values: 53.0 53.0 63.0 71.0 62.0 61.0 60.0 58.0 59.0 59.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:82.00 stddev:1.18 (1.4%) stddev-sans-first:1.24 02:42:18 INFO - PID 3430 | Values: 81.0 80.0 81.0 82.0 82.0 82.0 84.0 81.0 82.0 80.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:141.00 stddev:15.46 (11.0%) stddev-sans-first:16.19 02:42:18 INFO - PID 3430 | Values: 139.0 136.0 140.0 144.0 137.0 142.0 136.0 136.0 179.0 170.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:184.50 stddev:10.86 (5.9%) stddev-sans-first:11.29 02:42:18 INFO - PID 3430 | Values: 182.0 189.0 216.0 197.0 183.0 181.0 184.0 182.0 185.0 183.0 02:42:18 INFO - PID 3430 | 02:42:18 INFO - PID 3430 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.17 (1.7%) stddev-sans-first:1.22 02:42:18 INFO - PID 3430 | Values: 68.0 67.0 66.0 65.0 68.0 67.0 68.0 69.0 68.0 68.0 02:42:18 INFO - PID 3430 | -------- Summary: end -------- 02:42:18 INFO - PID 3430 | 02:42:19 INFO - TEST-INFO | 3430: exit 0 02:42:19 INFO - TEST-OK | kraken | took 59561ms 02:42:19 INFO - SUITE-END | took 1177s 02:42:20 INFO - Completed test suite (00:19:37) 02:42:20 INFO - javascript benchmark 02:42:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7684.452635284879, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2903.3902880395117, 3486.5118883356317, 3512.104413374591, 3546.30846984838, 3561.2343346180564, 6039.72821223045, 6115.6358679157975, 6240.656763877991, 6269.5611307208455, 6286.151369565107, 6107.34053441714, 6154.076888466743, 6164.506839452844, 6172.567920245585, 6272.341404152567, 4862.951370486286, 5166.380034395877, 5267.920981185285, 5347.652402593839, 5414.485623865727, 5380.515753582177, 5398.514133727964, 5445.019896418643, 5445.1832225166145, 5455.1544510600725, 5237.7381130943495, 5308.814191503279, 5310.097283461782, 5310.230016647564, 5314.548263397616, 5312.787488500456, 5325.973370133163, 5335.039692855285, 5341.43914888935, 5344.732763361855, 4571.657125715551, 5234.685918844882, 5277.70972596507, 5292.947070529284, 5314.256004159421, 6063.636181829061, 6079.05774604937, 6079.969600152015, 6101.890146117665, 6109.20580324555, 6356.682165891687, 6467.029945508164, 6511.4465270452065, 6528.477721782246, 6547.869042619169, 6630.668466576652, 6650.700718467632, 6663.9000414993325, 6666.500012499034, 6671.132752742161, 5917.763289468416, 5953.255843019622, 6307.842303942438, 6323.462505687022, 6337.17616709834, 6549.574277671936, 6634.502412318999, 6654.401103900672, 6703.128593282842, 6785.321467853274, 6430.7106180222, 6439.61362318253, 6440.8389790255615, 6456.806295811133, 6470.158879345655, 6608.43828274602, 6620.9006864897065, 6625.0, 6628.8674226515195, 6631.30371311015, 6313.05304204373, 6391.265004524539, 6522.771702990467, 6530.967345163243, 6544.411003009657, 6341.4609758170145, 6369.426751592379, 6383.680815959184, 6388.073729309224, 6401.583897046677, 5071.163258062378, 5192.896142077213, 5200.479952004769, 5200.7919683213, 5216.061109000417, 4700.482946875841, 4710.788014539354, 4722.244440889442, 4732.716037037789, 4776.0, 6170.907436388457, 6188.350223226586, 6209.813705588839, 6213.2233470816145, 6236.407541283571, 6383.680815959184, 6604.0, 6612.570182938093, 6618.735250590019, 6633.7346506140175, 6110.114033465123, 6114.357992410733, 6124.203853499106, 6124.816255512342, 6127.050307202391, 5705.62913410627, 5708.1723150143, 5712.885742285131, 5716.142578613159, 5720.856978575485, 6570.8028759137305, 6580.967095164494, 6582.308857569982, 6586.37429444202, 6590.5716128451495, 5382.407935127211, 6355.809325720235, 6613.570117942319, 6656.933430665631, 6657.068010478633, 6800.489963252677, 6801.1158549388265, 6803.013563033432, 6805.3875151236625, 6809.319068093151, 6465.3857883501, 6495.188101487314, 6499.025146228104, 6509.804705858832, 6518.57629254097, 6407.839804004937, 6443.871122577616, 6554.410103090745, 6588.110605068446, 6591.0, 6271.0, 6368.745250189941, 6405.519586030973, 6407.903881441689, 6408.519361047846, 6383.585066970818, 6388.9041664376, 6398.232212134573, 6400.23197216337, 6401.103845461546, 6250.0937364082865, 6317.842053948688, 6329.683515824282, 6332.0818481320875, 6356.713947872449, 6272.5922815016875, 6284.717187726472, 6296.307406185408, 6301.243850737941, 6325.841853953688, 6237.594556353823, 6256.7810126645345, 6274.090256912813, 6283.528735344776, 6336.461400780985], "value": 5981.777805041586, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.4402342266723, 802.7872613757355, 1112.4271000434771, 1133.1557989297974, 1135.7103938495688, 33643.99999999997, 36833.0, 36837.0, 36914.81542592283, 36992.26015479689, 38523.0, 38840.80579597105, 38891.0, 38984.805075974655, 39144.21711565774, 5968.313643930938, 6044.818655440344, 8156.551389673566, 8298.958505207496, 8303.709370172046, 7409.962950185241, 8396.622152003143, 8412.831743365128, 8414.158584141598, 8435.620397082146, 8432.15678432158, 8475.618597163142, 8481.575921203947, 8489.235968762841, 8530.317574594048, 5978.910316345258, 8438.78903027423, 8453.915460845405, 8458.66165353385, 8460.2385785279, 5962.493188079018, 6002.939970600281, 6019.067044608093, 6032.909506357408, 8126.065502467174, 37170.44244336337, 37465.0, 37495.43756843649, 37518.43722344167, 37726.81136594327, 3524.7356448266482, 3549.964500355015, 3570.535830342065, 3573.9642603573893, 3574.0528759878653, 30451.695483045107, 30473.847630761928, 30488.54267185994, 30498.0, 30554.38891222163, 3089.768267379933, 3130.280035591804, 3159.6998285163104, 3167.9683203167906, 3168.746500279972, 213328.0, 273891.0, 278721.0, 278892.0, 279805.60097199585, 37529.81235093861, 37624.811875940446, 38254.61745382566, 47035.29447058228, 47155.76422117867, 49099.75450122727, 49271.75364123157, 49287.0, 49323.75338123286, 49382.0123597526, 13671.589852304447, 13748.0, 13784.379702913393, 13798.862011379757, 13813.723725525433, 11124.777504449865, 11124.944375278072, 11129.777404451866, 11130.109591232682, 11185.552577896955, 12004.399780010965, 12006.75986480283, 12042.337671428155, 12044.81932771009, 12143.814113459663, 4499.820007199741, 4521.683482156283, 5294.841154765363, 5312.574994000471, 5314.149736042292, 1007.3955626623967, 1007.5868893753526, 1009.2279406254221, 1042.7549525861416, 1044.7179261599326, 22753.08987640509, 24307.149249776172, 29809.85095074511, 29963.95126170617, 30382.392352152834, 1006.8691070160833, 1020.7295066807302, 1082.242430298794, 1095.2333366643222, 1136.778328225988, 12136.87863121375, 12295.93852030752, 12323.876761232452, 12333.321667308384, 12402.937985310016, 294198.0, 294517.05482945323, 294532.0, 295019.0, 296879.0, 51690.48309516932, 51856.74071629693, 51861.740691297055, 51889.48110518847, 52180.0, 1451.9419223231164, 1513.6821267533942, 1525.7329967255687, 1526.4886263101764, 1533.156763779939, 23272.95271712743, 23345.883270584218, 23378.0, 23465.944032518237, 23555.0, 37941.0, 38088.42867357047, 38107.047323817125, 38118.0, 38288.80855595704, 274205.0, 274305.0, 274473.0, 274753.0, 274814.0, 26083.95664173412, 26925.73074269232, 27771.722282776915, 32329.868454603977, 32371.0, 39678.20643587055, 41457.79271103625, 41489.58510414978, 41752.0, 42619.786901065294, 27765.58351624687, 28201.858990704914, 28792.136235912956, 29018.854905725337, 29821.403571928007, 29250.68371923226, 29295.41409171762, 29338.706612933598, 29479.557806633347, 29557.85221073881, 27237.319067023484, 34199.487007694406, 35063.64936350604, 35280.29439411146, 35359.0, 29814.0, 31252.561808311173, 31552.526712099796, 31613.683863161074, 31706.573204205404, 21379.358619241448, 24061.398465037815, 24188.032478700654, 24710.0, 25539.6169057468], "value": 16781.032962342626, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.6475495828548, 428.06819364140375, 438.3556172426533, 438.5548668101876, 456.63241090921815, 18925.24299028039, 20752.89623551882, 21301.786982130176, 21529.031193596285, 21545.353639390803, 21041.158353665873, 21476.677849832264, 21634.459138521546, 21686.0, 21709.782902170973, 6063.545234107449, 6348.682565871688, 6355.396237357444, 6368.554201205918, 6370.6177629342155, 5864.970675146618, 6061.909071363933, 6125.203723515937, 6143.600665956721, 6159.846003849917, 5923.378045305246, 6092.5430592705725, 6099.024156135019, 6100.755969761204, 6103.23709536308, 6143.815685529419, 6297.401746834089, 6302.779402720883, 6306.369363063703, 6314.179156709644, 5339.279197308391, 5438.993786149575, 5464.508169289878, 5602.215689803431, 5604.131359639263, 23892.641610375857, 27781.583276250873, 27810.748516316813, 27975.580366294318, 28359.432811343864, 2141.7001619773087, 2220.3006053093222, 2233.396982814631, 2238.115944202031, 2238.183063181927, 14263.714725705428, 17570.824291757046, 17821.10894455272, 17879.553011174823, 18374.989375584337, 2114.7145135406677, 2115.6403411420097, 2172.7615259302206, 2176.1295481807406, 2179.891005449721, 124802.37598811947, 148082.0, 148160.0, 148530.1175905925, 149679.2516037413, 11724.355160466086, 12188.81716774249, 12197.573084941985, 12226.266424014588, 12245.0, 16295.429659961845, 16323.836761632232, 16331.83668163327, 16368.67262654764, 16419.91790041066, 8681.218690317775, 8911.55442227886, 8940.46357218569, 9045.140711632384, 9087.863682044775, 7158.427325813923, 8505.31957443403, 8516.06323304436, 8709.390342675952, 8739.0, 7412.369948554433, 7718.53688778675, 7773.533587984739, 7780.572068536285, 7784.026996625422, 2042.428120126367, 2115.206797450956, 2118.5445129297277, 2123.161351266241, 2123.7276114557276, 898.4205187653927, 929.1498279074706, 942.6983365323031, 974.0940924939732, 974.605284859633, 17902.820971790115, 18978.33575824867, 19341.226350946086, 19442.611147776966, 20838.479038023866, 903.5075883643431, 958.8705524754068, 977.8777652793401, 984.4362022947104, 997.087664786712, 8427.95786021066, 10365.170786337076, 10629.202809789296, 10755.892441075646, 10836.72908177286, 532835.0, 534141.0, 538391.0, 551132.0, 553536.0, 93314.0, 117898.13629819188, 124462.84131697634, 132160.0, 135448.0, 1153.3771763247753, 1209.679434949739, 1235.9073069519823, 1244.6266120163914, 1245.4582256718356, 21185.57628847445, 21351.0, 21385.25151619686, 21517.0, 21591.568168636226, 55493.7225313887, 57774.0, 58494.0, 58633.4136658628, 59671.0, 80482.0, 81934.59032705036, 83324.75012874934, 83484.0, 83626.21924698062, 7255.854882902418, 8294.253517183482, 8577.528235947082, 8744.69393571222, 8789.91210087891, 13574.86425135736, 13587.52443664467, 13745.656358591115, 13851.861481385056, 14096.718065638424, 10310.793784124426, 11306.378149201873, 11472.31166130035, 11612.419379031184, 11733.35466549348, 10694.89305106939, 10708.785824283315, 10725.946370268099, 11012.284201526874, 11102.500387482422, 11570.94214528922, 11669.59156429521, 11753.0, 11874.16880818369, 12044.0127378985, 7309.269073092648, 8349.206825351583, 8553.95723021381, 8609.354298427517, 8841.248493878093, 10786.622468213574, 11221.775564488502, 11346.411502389506, 11500.25248358854, 11579.88420115788], "value": 10282.065707374879, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1143.405429176828, 1437.0515459796536, 1451.7822326650994, 1456.0462896802592, 1466.8093147890772, 11176.608818691348, 11688.824667629993, 11700.356480393575, 11708.882911170886, 11723.472443740031, 11020.283681560693, 11422.600208992686, 11515.539378424874, 11588.478518466669, 11652.708682282948, 2393.413613664655, 2431.088341871798, 2435.1111844176926, 2438.5732496813075, 2441.914532991346, 2432.1244352033254, 2457.373369790701, 2459.2007597530783, 2463.901443942249, 2465.2604218734396, 2075.9584808303825, 2151.139544182324, 2155.8275337972923, 2408.2654790288957, 2509.5357358888664, 2118.5656940327194, 2442.572549803786, 2487.6517287579754, 2495.0643508684243, 2498.100683753847, 2449.9510009799883, 2480.131953816168, 2485.72657007729, 2494.4013436775226, 2495.6755621769234, 11211.327320360722, 11290.943545282304, 11309.434528273554, 11324.6036388726, 11328.943355283252, 6411.679416029227, 6661.034150048215, 6665.93334066658, 6670.998700389912, 6675.732970681167, 3470.9652903470896, 3915.9804200979097, 4115.279826030433, 4264.9147017059795, 4283.892902677427, 1051.2693677893867, 1223.608445297505, 1282.4100913579764, 1286.2796833773118, 1287.2019348004296, 15913.840861591352, 16299.103549304784, 16313.347466101342, 16325.18374081303, 16326.346946122141, 5584.553235741212, 5717.142428635655, 5958.165856780054, 5968.731407086605, 5989.64062156272, 13173.143745656502, 13193.340332983313, 13211.537596184087, 13217.405216765268, 13230.536931207362, 6562.868742625121, 6618.735250590019, 6619.238787539398, 6619.536632435779, 6651.201855777338, 5797.913031304533, 5926.318473375531, 5977.073553599199, 6133.723982420802, 6158.876822463615, 4298.699091063596, 4426.800793964279, 4584.656150788637, 4598.448186217675, 4661.5804577587505, 1299.7335546213003, 1439.4386189386148, 1442.4374493947369, 1456.4392708807186, 1461.5542259610709, 778.1401551285861, 790.0913948958744, 798.5208874675264, 802.4102284820652, 804.4529719790481, 6445.258795238514, 6606.405423511811, 6809.863802724017, 6891.689873955684, 6943.51395402317, 923.787528868364, 947.8388673925449, 947.924166066713, 951.833429149896, 951.9190868776232, 3577.928441431157, 3858.5144719282716, 3926.391409331558, 4305.870823875289, 4383.298672212494, 14318.57044288673, 14558.344874480674, 14791.260436978324, 14824.0, 14843.554693359216, 11933.343666098273, 13977.58067257984, 14760.704785904221, 14762.778558321634, 14830.480933167502, 8366.0, 8561.443506172203, 8562.74311770648, 8577.356698247657, 8601.741947741453, 6752.831179220559, 6946.097007389007, 7037.7184912602925, 7053.753118640823, 7106.893396599158, 12430.502779888704, 13305.600831975056, 13328.933355333162, 13338.93330533341, 13356.465741370625, 12975.286359250333, 13150.868491314965, 13446.529371471952, 13518.459261629425, 13688.384022718803, 9058.954705226432, 9273.67542136022, 9314.394564353295, 9389.10803473669, 9390.624375024923, 9773.609055637979, 10143.34068285559, 10465.529051192563, 10744.73138171552, 10745.785084298426, 8765.868511972452, 8898.065703101081, 8915.598798054232, 8993.865092023756, 9053.68312109073, 8706.0, 8779.20987111163, 8831.514266715392, 8847.823043539222, 8903.777405564653, 7885.802854928673, 8036.919630803617, 8170.305524030524, 8183.71357002502, 8220.260176584135, 5931.991561434653, 5963.2844058713235, 6093.390660933871, 6169.259688837368, 6213.2233470816145, 5408.0, 5518.310211223597, 5990.071538911476, 6048.971674815204, 6168.414000669929], "value": 5349.738801082632, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.2923725641856, 384.2449586562406, 392.7996721671947, 407.39095052895914, 407.51912742963293, 4825.190553542421, 5673.716314184295, 5722.713864306784, 6005.789797357093, 6012.699365031742, 4625.0, 5009.499050094989, 5152.304438900747, 5885.264341957255, 5949.0778929265925, 4570.908581828361, 4586.8394606188795, 4594.127115847995, 4595.609373203281, 4609.101225261075, 3817.961820381802, 4327.307630779076, 4598.747068911209, 4623.052274283764, 4632.235681112612, 3957.7031722620773, 4098.610631989956, 4421.9336709949375, 4456.699285189791, 4517.841875534357, 3681.3249971263012, 4494.595486406239, 4534.59188673021, 4542.636589072867, 4555.977220113895, 3423.863045478203, 4080.775557344345, 4108.329585769244, 4118.4440100586335, 4122.175564887035, 4708.646851486153, 5729.656220626775, 5761.596688231825, 5765.279340082489, 5774.228370382198, 4649.535046495357, 5041.344625198737, 5053.9241911371355, 5056.0, 5074.340335756328, 5735.799247026376, 6267.86892456567, 6275.399773057887, 6276.686165691697, 6278.493161641219, 3616.764910280823, 3844.884653460401, 4148.377743338493, 4218.409422680827, 4220.810063547147, 6517.087607734921, 6612.933870661328, 6626.271110177877, 6630.900536491957, 6647.5014373921185, 3523.3481805865995, 4245.514070075449, 4250.936235956463, 4254.468191476066, 4264.296391095497, 4113.1490829126415, 4198.39123327122, 4250.872473825791, 4292.227399068198, 4311.956880431218, 4906.018796240766, 5016.799328026911, 5019.472955339636, 5041.7227052511735, 5064.708499332646, 5220.843374698765, 5440.863978400572, 5482.698451585201, 6118.1434599156155, 6243.188385509855, 1075.8493810866485, 1357.0229434806922, 1457.0, 1459.729949959261, 1497.5956491747168, 897.8552345759209, 964.2478866484154, 967.6758285974278, 978.8874279457812, 990.1830989433746, 5462.262594549765, 5802.390748971382, 5832.912506312408, 5857.0, 5870.354261031283, 5615.550755939515, 6400.679966001774, 6409.775657851953, 6425.839354016187, 6447.967760161169, 5576.693281869536, 5704.515116215085, 5806.854828629318, 5812.505936995402, 5873.973479149687, 2785.763210127162, 3320.8007519548905, 3322.534845121685, 3339.476851921198, 3339.899803005865, 5206.0, 5206.323177986913, 5292.311999440049, 6223.284322302902, 6620.0, 5861.384554621789, 5941.435563621449, 6702.664866756642, 6711.295313992058, 6720.0, 5549.916751248735, 6461.770689112249, 6748.561343512754, 6750.223724271772, 6766.7969960901255, 5975.518361229078, 5981.760729570942, 6446.0, 6463.676816159267, 6500.414962653194, 5420.7289635518855, 6381.617102973836, 6396.456301214449, 6449.3550644937, 6453.0, 6577.6053436793945, 6683.331666833278, 6707.990150292858, 6739.92161254197, 6742.797716068526, 3580.8567657293415, 4270.380794784676, 4294.742315461082, 4298.935515967326, 4300.655947524128, 5636.830895073154, 5917.260342457193, 6214.844628884314, 6416.363827224028, 6498.252700939453, 5248.063952035973, 5483.917741233805, 6302.243730752339, 6321.715522801393, 6327.430531252209, 6155.599886007395, 6192.442680158807, 6219.813405597839, 6246.562740608114, 6272.664608745109, 5280.524752772269, 5993.861166378374, 6129.877402452015, 6146.139540464428, 6184.8971349741305, 4685.484596694293, 5515.862103447446, 6037.849053773691, 6287.96248618991, 6316.905246421399, 5650.463205995424, 5725.570582206268, 6059.878802423838, 6117.449429551361, 6137.048757442603], "value": 4527.803202202077, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.4732034895925, 830.505849019833, 844.2612713875363, 845.2477295207265, 867.3278209387726, 16455.75316370254, 16673.6652669466, 16910.408135715254, 16921.492355229362, 16954.897931634434, 15395.153266570334, 15597.376104955816, 15797.842021579781, 15802.683946321096, 15861.127637979907, 977.8826540815077, 985.8964808695091, 989.5645915797027, 991.6628346362236, 996.3025881882675, 995.3231802374382, 1006.1146191351637, 1006.6527048168407, 1006.8338724110549, 1006.9597216111347, 1008.8890222075565, 1014.2089170447063, 1016.0043157705453, 1016.4866742295168, 1050.5220124843179, 870.8563087090622, 1007.5566750629699, 1010.1817527802467, 1011.4993078426226, 1012.0132870451324, 719.481972979454, 767.9808004799869, 768.3392282636928, 769.8298586149023, 777.5295945952673, 16552.586185345343, 16933.06868122253, 17117.486475405756, 17479.300827966865, 17741.645167096587, 17078.829211707973, 17167.05581193034, 17217.225224864913, 17473.825261747348, 17544.0350780707, 14106.153630782186, 14193.645158871112, 14237.644058898506, 14568.854311456857, 14761.77857332141, 3587.031501494582, 3589.6410358964154, 3595.676389124991, 3597.118705917041, 3600.9099772505633, 93264.53367733117, 121375.39312303471, 128309.0, 131955.34022329826, 134010.0, 10308.32995855267, 11017.228793984345, 11220.438978051065, 13130.34348282582, 13326.466941322433, 24103.03587856466, 24861.0, 25463.36341591438, 25553.616695749577, 25582.488350232892, 7756.961215194001, 7932.881006784903, 7940.801479963047, 7942.960285198653, 7948.920510794934, 3426.9485957710654, 3923.313420151462, 3940.2513522430645, 3988.3419235826345, 3992.161646054361, 4212.620864122183, 4329.523752387235, 4336.0460698646875, 4337.284348082531, 4359.2807186813825, 642.1170890026214, 770.9884351734728, 782.5500337306072, 783.7844592737043, 785.7388890830135, 647.896336586144, 649.7108786589922, 657.7434800427837, 666.3835951744617, 684.6200358800913, 9616.75958101053, 11765.11761617882, 11894.464749086312, 11933.462994165284, 12017.158798883995, 640.955133140685, 656.4354654996699, 658.4501940879419, 667.813012356541, 679.6262055869253, 5045.873853153627, 5093.541581257705, 5182.404023537266, 5245.527902488794, 5281.656692314988, 103554.0, 103813.0, 106287.0284728633, 109219.45390273156, 113025.0, 92140.07859921316, 93025.53487232521, 93502.06497934934, 94306.05693942973, 95619.0, 80630.59684701657, 81088.18911810806, 81650.0, 82488.0, 83238.0, 10220.386776793417, 10319.845202322122, 10377.481125943823, 10435.16518678519, 10589.311694739818, 42579.7871010643, 42870.78564607157, 43308.0, 43402.565974341116, 43512.0, 53938.73030634822, 54332.728336358065, 54743.45256547384, 54854.72572637271, 56046.395360463626, 14199.0, 14425.35085921115, 14467.638309042359, 14475.0, 14509.419623214953, 15527.922360388126, 17584.472465826046, 18697.25210991545, 18789.0, 18800.247990080243, 16050.518484445485, 16253.268602913135, 16784.58038549046, 17301.13494325304, 17467.737983929997, 16998.98006119637, 17177.914110429367, 17262.22319995578, 17264.654706906043, 17265.74101388455, 16355.918220408821, 16923.0, 16942.830571694125, 17016.829831701525, 17095.145242737566, 10917.344959302467, 11548.133889958119, 11881.346525941157, 12066.939665301617, 12167.965722913297, 11923.817846007218, 12310.630681079581, 13739.24434156091, 13820.870173335572, 14008.949328800585], "value": 8235.627920428253, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1414.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 111.0, 111.0, 111.0, 113.0, 111.0, 112.0, 111.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 108.0, 151.0, 105.0, 107.0, 109.0, 106.0, 134.0, 108.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 186.0, 188.0, 185.0, 184.0, 187.0, 184.0, 185.0, 181.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 57.0, 90.0, 62.0, 59.0, 61.0, 58.0, 82.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 88.0, 68.0, 68.0, 68.0, 87.0, 69.0, 67.0, 89.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 86.0, 87.0, 86.0, 87.0, 86.0, 86.0, 86.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 88.0, 83.0, 86.0, 84.0, 84.0, 85.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 47.0, 50.0, 49.0, 48.0, 48.0, 47.0, 48.0], "value": 48.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 63.0, 71.0, 62.0, 61.0, 60.0, 58.0, 59.0, 59.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 82.0, 82.0, 82.0, 84.0, 81.0, 82.0, 80.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 140.0, 144.0, 137.0, 142.0, 136.0, 136.0, 179.0, 170.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 189.0, 216.0, 197.0, 183.0, 181.0, 184.0, 182.0, 185.0, 183.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 65.0, 68.0, 67.0, 68.0, 69.0, 68.0, 68.0], "value": 67.4, "unit": "score"}]}]} 02:42:20 INFO - Return code: 0 02:42:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:42:20 INFO - # TBPL SUCCESS # 02:42:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:20 INFO - 2017-05-08 02:42:20.215 screenresolution[3435:71886] starting screenresolution argv=screenresolution get 02:42:20 INFO - 2017-05-08 02:42:20.218 screenresolution[3435:71886] Display 0: 1600x1200x32@60 02:42:20 INFO - 2017-05-08 02:42:20.225 screenresolution[3436:71889] starting screenresolution argv=screenresolution list 02:42:20 INFO - Available Modes on Display 0 02:42:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:20 INFO - Intel Iris: 02:42:20 INFO - Chipset Model: Intel Iris 02:42:20 INFO - Type: GPU 02:42:20 INFO - Bus: Built-In 02:42:20 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:20 INFO - Vendor: Intel (0x8086) 02:42:20 INFO - Device ID: 0x0a2e 02:42:20 INFO - Revision ID: 0x0009 02:42:20 INFO - Displays: 02:42:20 INFO - Display: 02:42:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:20 INFO - Display Serial Number: 1600x1200 60 02:42:20 INFO - Main Display: Yes 02:42:20 INFO - Mirror: Off 02:42:20 INFO - Online: Yes 02:42:20 INFO - Rotation: Supported 02:42:20 INFO - Return code: 0 02:42:20 INFO - Running post-action listener: _resource_record_post_action 02:42:20 INFO - [mozharness: 2017-05-08 09:42:20.465219Z] Finished run-tests step (success) 02:42:20 INFO - Running post-run listener: _resource_record_post_run 02:42:20 INFO - Total resource usage - Wall time: 1198s; CPU: 53.0%; Read bytes: 233472; Write bytes: 534907392; Read time: 2; Write time: 2272 02:42:20 INFO - TinderboxPrint: CPU usage
53.5% 02:42:20 INFO - TinderboxPrint: I/O read bytes / time
233,472 / 2 02:42:20 INFO - TinderboxPrint: I/O write bytes / time
534,907,392 / 2,272 02:42:20 INFO - TinderboxPrint: CPU idle
2,229.6 (46.5%) 02:42:20 INFO - TinderboxPrint: CPU system
956.7 (20.0%) 02:42:20 INFO - TinderboxPrint: CPU user
1,607.3 (33.5%) 02:42:20 INFO - TinderboxPrint: Swap in / out
207,855,616 / 0 02:42:20 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209267200; Write bytes: 212542976; Read time: 16215; Write time: 495 02:42:20 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 233472; Write bytes: 320787456; Read time: 2; Write time: 1770 02:42:20 INFO - Running post-run listener: _upload_blobber_files 02:42:20 INFO - Blob upload gear active. 02:42:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:42:20 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 02:42:20 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'] 02:42:20 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 02:42:21 INFO - (blobuploader) - INFO - Open directory for files ... 02:42:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:42:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:42:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:22 INFO - (blobuploader) - INFO - Done attempting. 02:42:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:42:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:42:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:23 INFO - (blobuploader) - INFO - Done attempting. 02:42:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:42:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:42:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:24 INFO - (blobuploader) - INFO - Done attempting. 02:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:42:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:26 INFO - (blobuploader) - INFO - Done attempting. 02:42:26 INFO - (blobuploader) - INFO - Iteration through files over. 02:42:26 INFO - Return code: 0 02:42:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:42:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:42:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebc7669912ee3e334a32c038cfbb794d012703fa00d1ce469da7042064429b184eee37a4f3ea1fa53d51ecb94388c104e7b9cbc9f4d583f49b0d0e70ed11c2d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dc2dbfb82d128cf0d89a26889452694a02a86760e6b1d2a9e523c2ec723e3bf6090f778e6bc6413c148fa4671ae556b1d107a9f52e9bef91d4bccb3ba3247a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfd35d6d272f46d2deca1987bdff7fdef6977132697a9fabdeebc85d04296ff21691582c21ad327a756233776139cb8a743ef37b2642b4b886e23b3c329438f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e973cd0c8edc31821da527e1df1807a29ccf2a62faec3f526c2c35714a4cb5ed437315d1084affe163050efd96bb5e6e6db181bd77caa338f75566a3ea805252"} 02:42:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:42:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:42:26 INFO - Contents: 02:42:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebc7669912ee3e334a32c038cfbb794d012703fa00d1ce469da7042064429b184eee37a4f3ea1fa53d51ecb94388c104e7b9cbc9f4d583f49b0d0e70ed11c2d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dc2dbfb82d128cf0d89a26889452694a02a86760e6b1d2a9e523c2ec723e3bf6090f778e6bc6413c148fa4671ae556b1d107a9f52e9bef91d4bccb3ba3247a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfd35d6d272f46d2deca1987bdff7fdef6977132697a9fabdeebc85d04296ff21691582c21ad327a756233776139cb8a743ef37b2642b4b886e23b3c329438f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e973cd0c8edc31821da527e1df1807a29ccf2a62faec3f526c2c35714a4cb5ed437315d1084affe163050efd96bb5e6e6db181bd77caa338f75566a3ea805252"} 02:42:26 INFO - Running post-run listener: copy_logs_to_upload_dir 02:42:26 INFO - Copying logs to upload dir... 02:42:26 INFO - mkdir: /builds/slave/test/build/upload/logs 02:42:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.098490 ========= master_lag: 0.48 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-08 02:42:26.884865) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-08 02:42:26.892097) ========= 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.LxBp84bKeL/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.exdD2QQduC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebc7669912ee3e334a32c038cfbb794d012703fa00d1ce469da7042064429b184eee37a4f3ea1fa53d51ecb94388c104e7b9cbc9f4d583f49b0d0e70ed11c2d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dc2dbfb82d128cf0d89a26889452694a02a86760e6b1d2a9e523c2ec723e3bf6090f778e6bc6413c148fa4671ae556b1d107a9f52e9bef91d4bccb3ba3247a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfd35d6d272f46d2deca1987bdff7fdef6977132697a9fabdeebc85d04296ff21691582c21ad327a756233776139cb8a743ef37b2642b4b886e23b3c329438f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e973cd0c8edc31821da527e1df1807a29ccf2a62faec3f526c2c35714a4cb5ed437315d1084affe163050efd96bb5e6e6db181bd77caa338f75566a3ea805252"} build_url:https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/SQAmjCWrRFKuY4VjF93q9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebc7669912ee3e334a32c038cfbb794d012703fa00d1ce469da7042064429b184eee37a4f3ea1fa53d51ecb94388c104e7b9cbc9f4d583f49b0d0e70ed11c2d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dc2dbfb82d128cf0d89a26889452694a02a86760e6b1d2a9e523c2ec723e3bf6090f778e6bc6413c148fa4671ae556b1d107a9f52e9bef91d4bccb3ba3247a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfd35d6d272f46d2deca1987bdff7fdef6977132697a9fabdeebc85d04296ff21691582c21ad327a756233776139cb8a743ef37b2642b4b886e23b3c329438f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e973cd0c8edc31821da527e1df1807a29ccf2a62faec3f526c2c35714a4cb5ed437315d1084affe163050efd96bb5e6e6db181bd77caa338f75566a3ea805252"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-08 02:42:26.934724) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:42:26.935031) ========= 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.LxBp84bKeL/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.exdD2QQduC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.005217 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 02:42:26.983275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-08 02:42:26.983569) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-08 02:42:26.986906) ========= ========= Total master_lag: 0.63 =========