builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0281 starttime: 1484165003.66 results: success (0) buildid: 20170111094815 builduid: 42b22ddd90724e46ab1b8ecc7c864633 revision: 46c114f308065118917e7ae164eb4a646a113df1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:23.662545) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:23.662956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:23.663228) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:23.784073) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:23.784404) ========= 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.0GJYw8c6g0/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.7AXVzYTajr/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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-01-11 12:03:23-- 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% 5.80M=0.002s 2017-01-11 12:03:24 (5.80 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.439625 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:24.256328) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:24.256750) ========= 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.0GJYw8c6g0/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.7AXVzYTajr/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.019889 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:24.305894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 12:03:24.306192) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 46c114f308065118917e7ae164eb4a646a113df1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 46c114f308065118917e7ae164eb4a646a113df1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0GJYw8c6g0/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.7AXVzYTajr/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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-01-11 12:03:24,389 truncating revision to first 12 chars 2017-01-11 12:03:24,389 Setting DEBUG logging. 2017-01-11 12:03:24,389 attempt 1/10 2017-01-11 12:03:24,389 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/46c114f30806?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 12:03:25,195 unpacking tar archive at: graphics-46c114f30806/testing/mozharness/ program finished with exit code 0 elapsedTime=1.066394 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 12:03:25.395680) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:25.396032) ========= script_repo_revision: 46c114f308065118917e7ae164eb4a646a113df1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:25.396494) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:25.396771) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 12:03:25.419175) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-11 12:03:25.419447) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0GJYw8c6g0/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.7AXVzYTajr/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:03:25 INFO - MultiFileLogger online at 20170111 12:03:25 in /builds/slave/test 12:03:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 12:03:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:03:25 INFO - {'append_to_log': False, 12:03:25 INFO - 'base_work_dir': '/builds/slave/test', 12:03:25 INFO - 'blob_upload_branch': 'Graphics', 12:03:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:03:25 INFO - 'branch': 'Graphics', 12:03:25 INFO - 'buildbot_json_path': 'buildprops.json', 12:03:25 INFO - 'config_files': ('talos/mac_config.py',), 12:03:25 INFO - 'default_actions': ('clobber', 12:03:25 INFO - 'read-buildbot-config', 12:03:25 INFO - 'download-and-extract', 12:03:25 INFO - 'populate-webroot', 12:03:25 INFO - 'create-virtualenv', 12:03:25 INFO - 'install', 12:03:25 INFO - 'run-tests'), 12:03:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:03:25 INFO - 'download_minidump_stackwalk': True, 12:03:25 INFO - 'download_symbols': 'ondemand', 12:03:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:03:25 INFO - 'tooltool.py': '/tools/tooltool.py', 12:03:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:03:25 INFO - '/tools/misc-python/virtualenv.py')}, 12:03:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:03:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:03:25 INFO - 'installer_path': 'installer.exe', 12:03:25 INFO - 'log_level': 'info', 12:03:25 INFO - 'log_name': 'talos', 12:03:25 INFO - 'log_to_console': True, 12:03:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:03:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:03:25 INFO - 'opt_config_files': (), 12:03:25 INFO - 'pip_index': False, 12:03:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:03:25 INFO - 'cmd': ('bash', 12:03:25 INFO - '-c', 12:03:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:03:25 INFO - 'enabled': True, 12:03:25 INFO - 'halt_on_failure': False, 12:03:25 INFO - 'name': 'check_screen_resolution'},), 12:03:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:03:25 INFO - 'cmd': ('bash', 12:03:25 INFO - '-c', 12:03:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:03:25 INFO - 'enabled': True, 12:03:25 INFO - 'halt_on_failure': False, 12:03:25 INFO - 'name': 'check_screen_resolution'},), 12:03:25 INFO - 'run_cmd_checks_enabled': True, 12:03:25 INFO - 'sps_profile': False, 12:03:25 INFO - 'sps_profile_interval': 0, 12:03:25 INFO - 'suite': 'dromaeojs-e10s', 12:03:25 INFO - 'system_bits': '32', 12:03:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:03:25 INFO - 'title': 't-yosemite-r7-0281', 12:03:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:03:25 INFO - 'use_talos_json': True, 12:03:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:03:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:03:25 INFO - 'work_dir': 'build'} 12:03:25 INFO - [mozharness: 2017-01-11 20:03:25.624583Z] Running clobber step. 12:03:25 INFO - Running pre-action listener: _resource_record_pre_action 12:03:25 INFO - Running main action method: clobber 12:03:25 INFO - rmtree: /builds/slave/test/build 12:03:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:03:26 INFO - Running post-action listener: _resource_record_post_action 12:03:26 INFO - [mozharness: 2017-01-11 20:03:26.617009Z] Finished clobber step (success) 12:03:26 INFO - [mozharness: 2017-01-11 20:03:26.617140Z] Running read-buildbot-config step. 12:03:26 INFO - Running pre-action listener: _resource_record_pre_action 12:03:26 INFO - Running main action method: read_buildbot_config 12:03:26 INFO - Using buildbot properties: 12:03:26 INFO - { 12:03:26 INFO - "project": "", 12:03:26 INFO - "product": "firefox", 12:03:26 INFO - "script_repo_revision": "default", 12:03:26 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 12:03:26 INFO - "repository": "", 12:03:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 12:03:26 INFO - "buildid": "20170111094815", 12:03:26 INFO - "pgo_build": "False", 12:03:26 INFO - "basedir": "/builds/slave/test", 12:03:26 INFO - "buildnumber": 17, 12:03:26 INFO - "slavename": "t-yosemite-r7-0281", 12:03:26 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:03:26 INFO - "platform": "yosemite_r7", 12:03:26 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 12:03:26 INFO - "branch": "graphics", 12:03:26 INFO - "repo_path": "projects/graphics", 12:03:26 INFO - "revision": "46c114f308065118917e7ae164eb4a646a113df1", 12:03:26 INFO - "stage_platform": "macosx64", 12:03:26 INFO - "builduid": "42b22ddd90724e46ab1b8ecc7c864633", 12:03:26 INFO - "slavebuilddir": "test" 12:03:26 INFO - } 12:03:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:03:26 INFO - Running post-action listener: _resource_record_post_action 12:03:26 INFO - [mozharness: 2017-01-11 20:03:26.620570Z] Finished read-buildbot-config step (success) 12:03:26 INFO - [mozharness: 2017-01-11 20:03:26.620663Z] Running download-and-extract step. 12:03:26 INFO - Running pre-action listener: _resource_record_pre_action 12:03:26 INFO - Running main action method: download_and_extract 12:03:26 INFO - mkdir: /builds/slave/test/build/tests 12:03:26 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')]} 12:03:26 INFO - trying https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:03:26 INFO - Downloading https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:03:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 12:03:28 INFO - Downloaded 1199 bytes. 12:03:28 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:03:28 INFO - Using the following test package requirements: 12:03:28 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:03:28 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:03:28 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:03:28 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:03:28 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:03:28 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:03:28 INFO - u'jsshell-mac.zip'], 12:03:28 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:03:28 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:03:28 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:03:28 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:03:28 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:03:28 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:03:28 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:03:28 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:03:28 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:03:28 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:03:28 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:03:28 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:03:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:03:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:03:28 INFO - Fetch https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:03:29 INFO - Expected file size: 16311453 12:03:29 INFO - Obtained file size: 16311453 12:03:35 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:03:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:03:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:03:35 INFO - Fetch https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:03:36 INFO - Expected file size: 16311453 12:03:36 INFO - Obtained file size: 16311453 12:03:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:03:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:03:43 INFO - Fetch https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:03:44 INFO - Expected file size: 14403529 12:03:44 INFO - Obtained file size: 14403529 12:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:44 INFO - trying https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:03:44 INFO - Downloading https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:03:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 12:03:46 INFO - Downloaded 69922684 bytes. 12:03:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:03:46 INFO - mkdir: /builds/slave/test/properties 12:03:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:03:46 INFO - Writing to file /builds/slave/test/properties/build_url 12:03:46 INFO - Contents: 12:03:46 INFO - build_url:https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:03:47 INFO - Running post-action listener: _resource_record_post_action 12:03:47 INFO - Running post-action listener: set_extra_try_arguments 12:03:47 INFO - [mozharness: 2017-01-11 20:03:47.167277Z] Finished download-and-extract step (success) 12:03:47 INFO - [mozharness: 2017-01-11 20:03:47.167426Z] Running populate-webroot step. 12:03:47 INFO - Running pre-action listener: _resource_record_pre_action 12:03:47 INFO - Running main action method: populate_webroot 12:03:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:03:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:03:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:03:47 INFO - '--tppagecycles', 12:03:47 INFO - '7'], 12:03:47 INFO - 'tests': ['tsvgm']}}, 12:03:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:03:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:03:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:03:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:03:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:03:47 INFO - 'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:03:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:03:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:03:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:03:47 INFO - 'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['damp', 'tps']}, 12:03:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:03:47 INFO - 'tests': ['damp', 'tps']}, 12:03:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['dromaeo_dom']}, 12:03:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:03:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['basic_compositor_video']}, 12:03:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:03:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['a11yr', 12:03:47 INFO - 'ts_paint', 12:03:47 INFO - 'tpaint', 12:03:47 INFO - 'sessionrestore', 12:03:47 INFO - 'sessionrestore_no_auto_restore', 12:03:47 INFO - 'tabpaint']}, 12:03:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:03:47 INFO - 'ts_paint', 12:03:47 INFO - 'tpaint', 12:03:47 INFO - 'sessionrestore', 12:03:47 INFO - 'sessionrestore_no_auto_restore', 12:03:47 INFO - 'tabpaint']}, 12:03:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:03:47 INFO - 'ts_paint', 12:03:47 INFO - 'tpaint', 12:03:47 INFO - 'sessionrestore', 12:03:47 INFO - 'sessionrestore_no_auto_restore', 12:03:47 INFO - 'tabpaint']}, 12:03:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:03:47 INFO - 'ts_paint', 12:03:47 INFO - 'tpaint', 12:03:47 INFO - 'sessionrestore', 12:03:47 INFO - 'sessionrestore_no_auto_restore', 12:03:47 INFO - 'tabpaint']}, 12:03:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['a11yr', 12:03:47 INFO - 'ts_paint', 12:03:47 INFO - 'tpaint', 12:03:47 INFO - 'sessionrestore', 12:03:47 INFO - 'sessionrestore_no_auto_restore', 12:03:47 INFO - 'tabpaint']}, 12:03:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['a11yr', 12:03:47 INFO - 'ts_paint', 12:03:47 INFO - 'tpaint', 12:03:47 INFO - 'sessionrestore', 12:03:47 INFO - 'sessionrestore_no_auto_restore', 12:03:47 INFO - 'tabpaint']}, 12:03:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['tsvgx', 12:03:47 INFO - 'tsvgr_opacity', 12:03:47 INFO - 'tart', 12:03:47 INFO - 'tscrollx', 12:03:47 INFO - 'cart', 12:03:47 INFO - 'tsvg_static']}, 12:03:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:03:47 INFO - 'tsvgr_opacity', 12:03:47 INFO - 'tart', 12:03:47 INFO - 'tscrollx', 12:03:47 INFO - 'cart', 12:03:47 INFO - 'tsvg_static']}, 12:03:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:03:47 INFO - 'talos_options': ['--disable-e10s'], 12:03:47 INFO - 'tests': ['tp5o']}, 12:03:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:03:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:03:47 INFO - 'talos_options': ['--disable-e10s', 12:03:47 INFO - '--xperf_path', 12:03:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:03:47 INFO - 'tests': ['tp5n']}, 12:03:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:03:47 INFO - 'talos_options': ['--xperf_path', 12:03:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:03:47 INFO - 'tests': ['tp5n']}}, 12:03:47 INFO - 'talos.zip': {'path': '', 12:03:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:03:47 INFO - Running post-action listener: _resource_record_post_action 12:03:47 INFO - [mozharness: 2017-01-11 20:03:47.185962Z] Finished populate-webroot step (success) 12:03:47 INFO - [mozharness: 2017-01-11 20:03:47.186074Z] Running create-virtualenv step. 12:03:47 INFO - Running pre-action listener: _resource_record_pre_action 12:03:47 INFO - Running main action method: create_virtualenv 12:03:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:03:47 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 12:03:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:03:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:03:47 INFO - Using real prefix '/tools/python27' 12:03:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:03:48 INFO - Installing distribute.............................................................................................................................................................................................done. 12:03:50 INFO - Installing pip.................done. 12:03:50 INFO - Return code: 0 12:03:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:03:51 INFO - Reading from file tmpfile_stdout 12:03:51 INFO - Output received: 12:03:51 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) 12:03:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:03: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')]} 12:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03: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')]} 12:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:51 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 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:03:51 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 12:03:51 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 12:03:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:03:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:51 INFO - 'HOME': '/Users/cltbld', 12:03:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:51 INFO - 'LOGNAME': 'cltbld', 12:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:51 INFO - 'MOZ_NO_REMOTE': '1', 12:03:51 INFO - 'NO_EM_RESTART': '1', 12:03:51 INFO - 'PAGER': '/bin/cat', 12:03:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:51 INFO - 'PWD': '/builds/slave/test', 12:03:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:51 INFO - 'SHELL': '/bin/bash', 12:03:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:03:51 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:03:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:51 INFO - 'USER': 'cltbld', 12:03:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:51 INFO - 'XPC_FLAGS': '0x0', 12:03:51 INFO - 'XPC_SERVICE_NAME': '0', 12:03:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:51 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 12:03:51 INFO - Cleaning up... 12:03:51 INFO - Return code: 0 12:03:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:03: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')]} 12:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03: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')]} 12:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:51 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 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:03:51 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 12:03:51 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 12:03:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:03:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:51 INFO - 'HOME': '/Users/cltbld', 12:03:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:51 INFO - 'LOGNAME': 'cltbld', 12:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:51 INFO - 'MOZ_NO_REMOTE': '1', 12:03:51 INFO - 'NO_EM_RESTART': '1', 12:03:51 INFO - 'PAGER': '/bin/cat', 12:03:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:51 INFO - 'PWD': '/builds/slave/test', 12:03:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:51 INFO - 'SHELL': '/bin/bash', 12:03:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:03:51 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:03:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:51 INFO - 'USER': 'cltbld', 12:03:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:51 INFO - 'XPC_FLAGS': '0x0', 12:03:51 INFO - 'XPC_SERVICE_NAME': '0', 12:03:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:51 INFO - Downloading/unpacking psutil>=3.1.1 12:03:51 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 12:03:51 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 12:03:51 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 12:03:51 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 12:03:51 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 12:03:51 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 12:03:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:03:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:55 INFO - Installing collected packages: psutil 12:03:55 INFO - Running setup.py install for psutil 12:03:55 INFO - building 'psutil._psutil_osx' extension 12:03:55 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 12:03:56 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 12:03:56 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 12:03:56 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 12:03:56 INFO - building 'psutil._psutil_posix' extension 12:03:56 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 12:03:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:03:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:03:56 INFO - ^ 12:03:56 INFO - 1 warning generated. 12:03:56 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 12:03:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:56 INFO - Successfully installed psutil 12:03:56 INFO - Cleaning up... 12:03:56 INFO - Return code: 0 12:03:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:03:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:03:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:03:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:03:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:03:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:56 INFO - 'HOME': '/Users/cltbld', 12:03:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:56 INFO - 'LOGNAME': 'cltbld', 12:03:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:56 INFO - 'MOZ_NO_REMOTE': '1', 12:03:56 INFO - 'NO_EM_RESTART': '1', 12:03:56 INFO - 'PAGER': '/bin/cat', 12:03:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:56 INFO - 'PWD': '/builds/slave/test', 12:03:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:56 INFO - 'SHELL': '/bin/bash', 12:03:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:03:56 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:03:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:56 INFO - 'USER': 'cltbld', 12:03:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:56 INFO - 'XPC_FLAGS': '0x0', 12:03:56 INFO - 'XPC_SERVICE_NAME': '0', 12:03:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:03:57 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 12:03:57 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 12:03:57 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 12:03:57 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 12:03:57 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 12:03:57 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 12:03:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:03:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:03:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:03:59 INFO - Installing collected packages: mozsystemmonitor 12:03:59 INFO - Running setup.py install for mozsystemmonitor 12:03:59 INFO - Successfully installed mozsystemmonitor 12:03:59 INFO - Cleaning up... 12:03:59 INFO - Return code: 0 12:03:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:03:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:59 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 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:03:59 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 12:03:59 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 12:03:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:03:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:59 INFO - 'HOME': '/Users/cltbld', 12:03:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:59 INFO - 'LOGNAME': 'cltbld', 12:03:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:59 INFO - 'MOZ_NO_REMOTE': '1', 12:03:59 INFO - 'NO_EM_RESTART': '1', 12:03:59 INFO - 'PAGER': '/bin/cat', 12:03:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:59 INFO - 'PWD': '/builds/slave/test', 12:03:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:59 INFO - 'SHELL': '/bin/bash', 12:03:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:03:59 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:03:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:59 INFO - 'USER': 'cltbld', 12:03:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:59 INFO - 'XPC_FLAGS': '0x0', 12:03:59 INFO - 'XPC_SERVICE_NAME': '0', 12:03:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:00 INFO - Downloading/unpacking jsonschema==2.5.1 12:04:00 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 12:04:00 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 12:04:00 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 12:04:00 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 12:04:00 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 12:04:00 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 12:04:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:04:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:04:02 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 12:04:02 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 12:04:02 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 12:04:02 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 12:04:02 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 12:04:02 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 12:04:03 INFO - Downloading functools32-3.2.3-2.tar.gz 12:04:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:04:03 INFO - warning: no files found matching '*.txt' 12:04:03 INFO - no previously-included directories found matching 'build' 12:04:03 INFO - no previously-included directories found matching 'dist' 12:04:03 INFO - no previously-included directories found matching '.git*' 12:04:03 INFO - Installing collected packages: jsonschema, functools32 12:04:03 INFO - Running setup.py install for functools32 12:04:03 INFO - warning: no files found matching '*.txt' 12:04:03 INFO - no previously-included directories found matching 'build' 12:04:03 INFO - no previously-included directories found matching 'dist' 12:04:03 INFO - no previously-included directories found matching '.git*' 12:04:03 INFO - Successfully installed jsonschema functools32 12:04:03 INFO - Cleaning up... 12:04:03 INFO - Return code: 0 12:04:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:03 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 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:04:03 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 12:04:03 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 12:04:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:04:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:03 INFO - 'HOME': '/Users/cltbld', 12:04:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:03 INFO - 'LOGNAME': 'cltbld', 12:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:03 INFO - 'MOZ_NO_REMOTE': '1', 12:04:03 INFO - 'NO_EM_RESTART': '1', 12:04:03 INFO - 'PAGER': '/bin/cat', 12:04:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:03 INFO - 'PWD': '/builds/slave/test', 12:04:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:03 INFO - 'SHELL': '/bin/bash', 12:04:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:04:03 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:04:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:03 INFO - 'USER': 'cltbld', 12:04:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:03 INFO - 'XPC_FLAGS': '0x0', 12:04:03 INFO - 'XPC_SERVICE_NAME': '0', 12:04:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:04:03 INFO - Cleaning up... 12:04:03 INFO - Return code: 0 12:04:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:03 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 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:04:03 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 12:04:03 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 12:04:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:04:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:03 INFO - 'HOME': '/Users/cltbld', 12:04:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:03 INFO - 'LOGNAME': 'cltbld', 12:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:03 INFO - 'MOZ_NO_REMOTE': '1', 12:04:03 INFO - 'NO_EM_RESTART': '1', 12:04:03 INFO - 'PAGER': '/bin/cat', 12:04:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:03 INFO - 'PWD': '/builds/slave/test', 12:04:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:03 INFO - 'SHELL': '/bin/bash', 12:04:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:04:03 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:04:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:03 INFO - 'USER': 'cltbld', 12:04:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:03 INFO - 'XPC_FLAGS': '0x0', 12:04:03 INFO - 'XPC_SERVICE_NAME': '0', 12:04:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:04 INFO - Downloading/unpacking blobuploader==1.2.4 12:04: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 12:04: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 12:04: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 12:04: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 12:04: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 12:04: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 12:04:06 INFO - Downloading blobuploader-1.2.4.tar.gz 12:04:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:04:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:04: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 12:04: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 12:04: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 12:04: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 12:04: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 12:04: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 12:04:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:04:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:04: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 12:04: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 12:04: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 12:04: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 12:04: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 12:04: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 12:04:07 INFO - Downloading docopt-0.6.1.tar.gz 12:04:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:04:07 INFO - Installing collected packages: blobuploader, requests, docopt 12:04:07 INFO - Running setup.py install for blobuploader 12:04:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:04:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:04:08 INFO - Running setup.py install for requests 12:04:08 INFO - Running setup.py install for docopt 12:04:08 INFO - Successfully installed blobuploader requests docopt 12:04:08 INFO - Cleaning up... 12:04:08 INFO - Return code: 0 12:04:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:04: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')]} 12:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04: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')]} 12:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:08 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 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:04:08 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 12:04:08 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 12:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:08 INFO - 'HOME': '/Users/cltbld', 12:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:08 INFO - 'LOGNAME': 'cltbld', 12:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:08 INFO - 'MOZ_NO_REMOTE': '1', 12:04:08 INFO - 'NO_EM_RESTART': '1', 12:04:08 INFO - 'PAGER': '/bin/cat', 12:04:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:08 INFO - 'PWD': '/builds/slave/test', 12:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:08 INFO - 'SHELL': '/bin/bash', 12:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:04:08 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:08 INFO - 'USER': 'cltbld', 12:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:08 INFO - 'XPC_FLAGS': '0x0', 12:04:08 INFO - 'XPC_SERVICE_NAME': '0', 12:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:04:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-OiZOwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:04:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-q5scWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:04:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-rIA_Sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:04:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-TSfsuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:04:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Kx47Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:04:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-D1I7sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:04:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-cdxzJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:04:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-psgj7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-FRdL8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-kokVvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-yoEy_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-LksfQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-9ENt1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-9qdsau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-4muyNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-GRL9Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:04:10 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-rSkzaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:04:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:04:10 INFO - Running setup.py install for manifestparser 12:04:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:04:11 INFO - Running setup.py install for mozcrash 12:04:11 INFO - Running setup.py install for mozdebug 12:04:11 INFO - Running setup.py install for mozdevice 12:04:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:04:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:04:11 INFO - Running setup.py install for mozfile 12:04:11 INFO - Running setup.py install for mozhttpd 12:04:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:04:11 INFO - Running setup.py install for mozinfo 12:04:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:04:11 INFO - Running setup.py install for mozInstall 12:04:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Running setup.py install for mozleak 12:04:12 INFO - Running setup.py install for mozlog 12:04:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Running setup.py install for moznetwork 12:04:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Running setup.py install for mozprocess 12:04:12 INFO - Running setup.py install for mozprofile 12:04:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:04:12 INFO - Running setup.py install for mozrunner 12:04:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:04:13 INFO - Running setup.py install for mozscreenshot 12:04:13 INFO - Running setup.py install for moztest 12:04:13 INFO - Running setup.py install for mozversion 12:04:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:04:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:04:13 INFO - Cleaning up... 12:04:13 INFO - Return code: 0 12:04:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:04:13 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')]} 12:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:13 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')]} 12:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:13 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 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:04:13 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 12:04:13 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 12:04:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:04:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:13 INFO - 'HOME': '/Users/cltbld', 12:04:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:13 INFO - 'LOGNAME': 'cltbld', 12:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:13 INFO - 'MOZ_NO_REMOTE': '1', 12:04:13 INFO - 'NO_EM_RESTART': '1', 12:04:13 INFO - 'PAGER': '/bin/cat', 12:04:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:13 INFO - 'PWD': '/builds/slave/test', 12:04:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:13 INFO - 'SHELL': '/bin/bash', 12:04:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:04:13 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:04:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:13 INFO - 'USER': 'cltbld', 12:04:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:13 INFO - 'XPC_FLAGS': '0x0', 12:04:13 INFO - 'XPC_SERVICE_NAME': '0', 12:04:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:04:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-6Qsedm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:04:13 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)) 12:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:04:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-K2MhFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-luFvFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-M_gGOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:04:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-OL_Sqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Mm5S2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-FmE82q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-hjZCGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Ftherl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-o6ylsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-rop0BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:04:14 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)) 12:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:04:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-WP687j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 12:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:04:15 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-0yHBGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:04:15 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)) 12:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:04:15 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-FT9Syf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:04:15 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)) 12:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:04:15 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-1ANf_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:04:15 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)) 12:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:04:15 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-JAnuah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:04:15 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)) 12:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:04:15 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-kfi_id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:04:15 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)) 12:04:15 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)) 12:04:15 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)) 12:04:15 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)) 12:04:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:04:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:04:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:18 INFO - Downloading blessings-1.6.tar.gz 12:04:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:04:18 INFO - Installing collected packages: blessings 12:04:18 INFO - Running setup.py install for blessings 12:04:18 INFO - Successfully installed blessings 12:04:18 INFO - Cleaning up... 12:04:18 INFO - Return code: 0 12:04:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:04:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:04:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:04:18 INFO - Reading from file tmpfile_stdout 12:04:18 INFO - Current package versions: 12:04:18 INFO - blessings == 1.6 12:04:18 INFO - blobuploader == 1.2.4 12:04:18 INFO - docopt == 0.6.1 12:04:18 INFO - functools32 == 3.2.3-2 12:04:18 INFO - jsonschema == 2.5.1 12:04:18 INFO - manifestparser == 1.1 12:04:18 INFO - mozInstall == 1.12 12:04:18 INFO - mozcrash == 1.0 12:04:18 INFO - mozdebug == 0.1 12:04:18 INFO - mozdevice == 0.49 12:04:18 INFO - mozfile == 1.2 12:04:18 INFO - mozhttpd == 0.7 12:04:18 INFO - mozinfo == 0.9 12:04:18 INFO - mozleak == 0.1 12:04:18 INFO - mozlog == 3.4 12:04:18 INFO - moznetwork == 0.27 12:04:18 INFO - mozprocess == 0.24 12:04:18 INFO - mozprofile == 0.28 12:04:18 INFO - mozrunner == 6.13 12:04:18 INFO - mozscreenshot == 0.1 12:04:18 INFO - mozsystemmonitor == 0.3 12:04:18 INFO - moztest == 0.8 12:04:18 INFO - mozversion == 1.4 12:04:18 INFO - psutil == 3.1.1 12:04:18 INFO - requests == 1.2.3 12:04:18 INFO - wsgiref == 0.1.2 12:04:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:18 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 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:04:18 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 12:04:18 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 12:04:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:04:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:18 INFO - 'HOME': '/Users/cltbld', 12:04:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:18 INFO - 'LOGNAME': 'cltbld', 12:04:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:18 INFO - 'MOZ_NO_REMOTE': '1', 12:04:18 INFO - 'NO_EM_RESTART': '1', 12:04:18 INFO - 'PAGER': '/bin/cat', 12:04:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:18 INFO - 'PWD': '/builds/slave/test', 12:04:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:18 INFO - 'SHELL': '/bin/bash', 12:04:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:04:18 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:04:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:18 INFO - 'USER': 'cltbld', 12:04:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:18 INFO - 'XPC_FLAGS': '0x0', 12:04:18 INFO - 'XPC_SERVICE_NAME': '0', 12:04:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 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)) 12:04:19 INFO - Cleaning up... 12:04:19 INFO - Return code: 0 12:04:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:04:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:04:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:04:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:04:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4ca4bd6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, '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.0GJYw8c6g0/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.7AXVzYTajr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 12:04:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:04:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:04:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:04:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:19 INFO - 'HOME': '/Users/cltbld', 12:04:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:19 INFO - 'LOGNAME': 'cltbld', 12:04:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:19 INFO - 'MOZ_NO_REMOTE': '1', 12:04:19 INFO - 'NO_EM_RESTART': '1', 12:04:19 INFO - 'PAGER': '/bin/cat', 12:04:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:19 INFO - 'PWD': '/builds/slave/test', 12:04:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:19 INFO - 'SHELL': '/bin/bash', 12:04:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:04:19 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:04:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:19 INFO - 'USER': 'cltbld', 12:04:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:19 INFO - 'XPC_FLAGS': '0x0', 12:04:19 INFO - 'XPC_SERVICE_NAME': '0', 12:04:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:04:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:04:19 INFO - Cleaning up... 12:04:19 INFO - Return code: 0 12:04:19 INFO - Running post-action listener: _resource_record_post_action 12:04:19 INFO - Running post-action listener: _start_resource_monitoring 12:04:19 INFO - Starting resource monitoring. 12:04:19 INFO - [mozharness: 2017-01-11 20:04:19.473303Z] Finished create-virtualenv step (success) 12:04:19 INFO - [mozharness: 2017-01-11 20:04:19.474346Z] Running install step. 12:04:19 INFO - Running pre-action listener: _resource_record_pre_action 12:04:19 INFO - Running main action method: install 12:04:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:04:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:04:19 INFO - Reading from file tmpfile_stdout 12:04:19 INFO - Detecting whether we're running mozinstall >=1.0... 12:04:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:04:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:04:19 INFO - Reading from file tmpfile_stdout 12:04:19 INFO - Output received: 12:04:19 INFO - Usage: mozinstall [options] installer 12:04:19 INFO - Options: 12:04:19 INFO - -h, --help show this help message and exit 12:04:19 INFO - -d DEST, --destination=DEST 12:04:19 INFO - Directory to install application into. [default: 12:04:19 INFO - "/builds/slave/test"] 12:04:19 INFO - --app=APP Application being installed. [default: firefox] 12:04:19 INFO - mkdir: /builds/slave/test/build/application 12:04:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:04:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:04:41 INFO - Reading from file tmpfile_stdout 12:04:41 INFO - Output received: 12:04:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:04:41 INFO - Running post-action listener: _resource_record_post_action 12:04:41 INFO - [mozharness: 2017-01-11 20:04:41.707471Z] Finished install step (success) 12:04:41 INFO - [mozharness: 2017-01-11 20:04:41.707629Z] Running run-tests step. 12:04:41 INFO - Running pre-action listener: _resource_record_pre_action 12:04:41 INFO - Running main action method: run_tests 12:04:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:04:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:04:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:04:41 INFO - 2017-01-11 12:04:41.722 screenresolution[2554:25407] starting screenresolution argv=screenresolution get 12:04:41 INFO - 2017-01-11 12:04:41.724 screenresolution[2554:25407] Display 0: 1600x1200x32@60 12:04:41 INFO - 2017-01-11 12:04:41.730 screenresolution[2555:25409] starting screenresolution argv=screenresolution list 12:04:41 INFO - Available Modes on Display 0 12:04:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:04:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:04:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:04:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:04:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:04:41 INFO - Intel Iris: 12:04:41 INFO - Chipset Model: Intel Iris 12:04:41 INFO - Type: GPU 12:04:41 INFO - Bus: Built-In 12:04:41 INFO - VRAM (Dynamic, Max): 1536 MB 12:04:41 INFO - Vendor: Intel (0x8086) 12:04:41 INFO - Device ID: 0x0a2e 12:04:41 INFO - Revision ID: 0x0009 12:04:41 INFO - Displays: 12:04:41 INFO - Display: 12:04:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:04:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:04:41 INFO - Display Serial Number: 1600x1200 60 12:04:41 INFO - Main Display: Yes 12:04:41 INFO - Mirror: Off 12:04:41 INFO - Online: Yes 12:04:41 INFO - Rotation: Supported 12:04:41 INFO - Return code: 0 12:04:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:04:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:04:41 INFO - Python 2.7.3 12:04:41 INFO - Return code: 0 12:04:41 INFO - grabbing minidump binary from tooltool 12:04: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')]} 12:04: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 0x10e5b2d30>, '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 0x10e4d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:04: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 12:04: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 12:04: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 12:04:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:04:42 INFO - Return code: 0 12:04:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:04:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:04:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:04:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:04:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:04:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:04:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0281 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:04:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0GJYw8c6g0/Render', 12:04:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:42 INFO - 'HOME': '/Users/cltbld', 12:04:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:42 INFO - 'LOGNAME': 'cltbld', 12:04:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:04:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:42 INFO - 'MOZ_NO_REMOTE': '1', 12:04:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:04:42 INFO - 'NO_EM_RESTART': '1', 12:04:42 INFO - 'PAGER': '/bin/cat', 12:04:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:42 INFO - 'PWD': '/builds/slave/test', 12:04:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:04:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:42 INFO - 'SHELL': '/bin/bash', 12:04:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AXVzYTajr/Listeners', 12:04:42 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:04:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:42 INFO - 'USER': 'cltbld', 12:04:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:42 INFO - 'XPC_FLAGS': '0x0', 12:04:42 INFO - 'XPC_SERVICE_NAME': '0', 12:04:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:04:42 INFO - mozversion application_buildid: 20170111094815 12:04:42 INFO - mozversion application_changeset: 46c114f308065118917e7ae164eb4a646a113df1 12:04:42 INFO - mozversion application_display_name: Nightly 12:04:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:04:42 INFO - mozversion application_name: Firefox 12:04:42 INFO - mozversion application_remotingname: firefox 12:04:42 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:04:42 INFO - mozversion application_vendor: Mozilla 12:04:42 INFO - mozversion application_version: 53.0a1 12:04:42 INFO - mozversion platform_buildid: 20170111094815 12:04:42 INFO - mozversion platform_changeset: 46c114f308065118917e7ae164eb4a646a113df1 12:04:42 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:04:42 INFO - mozversion platform_version: 53.0a1 12:04:42 INFO - using testdate: 1484165082 12:04:42 INFO - actual date: 1484165082 12:04:42 INFO - starting webserver on 'localhost:52962' 12:04:42 INFO - SUITE-START | Running 2 tests 12:04:42 INFO - TEST-START | dromaeo_css 12:04:42 INFO - operating with platform_type : mac_ 12:04:42 INFO - Initialising browser for dromaeo_css test... 12:04:42 INFO - TEST-INFO | started process 2565 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp8REGHz/profile http://localhost:52962/getInfo.html) 12:04:45 INFO - PROCESS | 2565 | 2017-01-11 12:04:45.014 plugin-container[2567:25571] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494b, name = 'com.apple.tsm.portname' 12:04:45 INFO - PROCESS | 2565 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:04:45 INFO - PROCESS | 2565 | 2017-01-11 12:04:45.017 plugin-container[2567:25571] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 12:04:45 INFO - PROCESS | 2565 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:04:45 INFO - PROCESS | 2565 | 2017-01-11 12:04:45.017 plugin-container[2567:25571] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:04:45 INFO - PROCESS | 2565 | __metrics Screen width/height:1600/1200 12:04:45 INFO - PROCESS | 2565 | colorDepth:24 12:04:45 INFO - PROCESS | 2565 | Browser inner width/height: 1024/689 12:04:45 INFO - PROCESS | 2565 | __metrics 12:04:45 ERROR - PROCESS | 2565 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 12:04:45 INFO - TEST-INFO | 2565: exit 0 12:04:45 INFO - Browser initialized. 12:04:45 INFO - Running cycle 1/1 for dromaeo_css test... 12:04:45 INFO - TEST-INFO | started process 2568 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp8REGHz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:04:48 INFO - PROCESS | 2568 | 2017-01-11 12:04:48.235 plugin-container[2569:25691] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 12:04:48 INFO - PROCESS | 2568 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:04:48 INFO - PROCESS | 2568 | 2017-01-11 12:04:48.239 plugin-container[2569:25691] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 12:04:48 INFO - PROCESS | 2568 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:04:48 INFO - PROCESS | 2568 | 2017-01-11 12:04:48.239 plugin-container[2569:25691] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:04:57 INFO - PROCESS | 2568 | RSS: Main: 233172992 12:04:57 INFO - PROCESS | 2568 | 12:08:01 INFO - PROCESS | 2568 | Cycle 1(1): loaded http://localhost:52962/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52962/tests/dromaeo/cssquery-ext.html) 12:08:02 INFO - PROCESS | 2568 | RSS: Main: 252116992 12:08:02 INFO - PROCESS | 2568 | 12:09:46 INFO - PROCESS | 2568 | ************************* 12:09:46 INFO - PROCESS | 2568 | A coding exception was thrown and uncaught in a Task. 12:09:46 INFO - PROCESS | 2568 | 12:09:46 INFO - PROCESS | 2568 | Full message: ReferenceError: fetch is not defined 12:09:46 INFO - PROCESS | 2568 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 12:09:46 INFO - PROCESS | 2568 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 12:09:46 INFO - PROCESS | 2568 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 12:09:46 INFO - PROCESS | 2568 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:09:46 INFO - PROCESS | 2568 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:09:46 INFO - PROCESS | 2568 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:09:46 INFO - PROCESS | 2568 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 12:09:46 INFO - PROCESS | 2568 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:09:46 INFO - PROCESS | 2568 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:09:46 INFO - PROCESS | 2568 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:09:46 INFO - PROCESS | 2568 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 12:09:46 INFO - PROCESS | 2568 | 12:09:46 INFO - PROCESS | 2568 | ************************* 12:11:05 INFO - PROCESS | 2568 | Cycle 1(1): loaded http://localhost:52962/tests/dromaeo/cssquery-ext.html (next: http://localhost:52962/tests/dromaeo/cssquery-jquery.html) 12:11:05 INFO - PROCESS | 2568 | RSS: Main: 262270976 12:11:05 INFO - PROCESS | 2568 | 12:14:07 INFO - PROCESS | 2568 | Cycle 1(1): loaded http://localhost:52962/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52962/tests/dromaeo/cssquery-mootools.html) 12:14:08 INFO - PROCESS | 2568 | RSS: Main: 270671872 12:14:08 INFO - PROCESS | 2568 | 12:17:10 INFO - PROCESS | 2568 | Cycle 1(1): loaded http://localhost:52962/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52962/tests/dromaeo/cssquery-prototype.html) 12:17:11 INFO - PROCESS | 2568 | RSS: Main: 266862592 12:17:11 INFO - PROCESS | 2568 | 12:20:13 INFO - PROCESS | 2568 | Cycle 1(1): loaded http://localhost:52962/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52962/tests/dromaeo/cssquery-yui.html) 12:20:14 INFO - PROCESS | 2568 | RSS: Main: 271314944 12:20:14 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | Cycle 1(1): loaded http://localhost:52962/tests/dromaeo/cssquery-yui.html (next: http://localhost:52962/tests/dromaeo/cssquery-dojo.html) 12:23:17 INFO - PROCESS | 2568 | RSS: Main: 273915904 12:23:17 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | __start_tp_report 12:23:17 INFO - PROCESS | 2568 | _x_x_mozilla_page_load 12:23:17 INFO - PROCESS | 2568 | _x_x_mozilla_page_load_details 12:23:17 INFO - PROCESS | 2568 | |i|pagename|runs| 12:23:17 INFO - PROCESS | 2568 | |0;dojo.html;3318.3197444523876;3325.7339412846973;3378.577677790276;3392.609849867266;3397.6602339766027;5511.173324001396;5606.691631960241;5611.186377975191;5616.522595579376;5620.662760234389;5586.050371436855;5695.45893140152;5700.800471983482;5744.827655170341;5809.128630705396;4690.748440748443;5117.33474648297;5147.22791581262;5217.660852044605;5242.528172464477;5202.947970520303;5287.39194992575;5303.628745987782;5317.787288508455;5322.148456247001;5100.795968161269;5271.156614941629;5276.657017293883;5293.761780719877;5303.8408847734245;5154.793808247666;5211.635185537014;5211.973940130313;5212.687238765686;5224.947750522484;5110.872228194287;5157.71632560209;5178.818741344073;5181.119209734316;5186.403563590197;4447.355133505656;5363.115086010804;5586.944130558683;5782.219400380979;5801.7389217485315;5248.845254044104;6213.440790328892;6248.812535623939;6262.1546091278005;6277.717502712343;5931.436513531251;6024.885396201673;6277.937220627827;6294.0558916162945;6316.115743795872;5649.0114230009995;5660.150977353389;5692.5161361284345;5750.079987202028;5755.740991655385;6160.414760597736;6193.411625895533;6235.75056997715;6245.531585131133;6311.274203466568;5081.618878584047;6038.547108966845;6192.628442293477;6205.937940620626;6209.441150296495;6120.142398366953;6263.96868015657;6281.089242059968;6293.96853015732;6307.684615769193;6141.293751218578;6149.477294430024;6162.815115546541;6197.783077592353;6271.717772700239;6251.718672659741;6255.468285195719;6256.31180570146;6287.339829317947;6319.873602527924;4867.805287788519;4981.601471882241;4987.600991920638;4998.550130488274;5016.698998060055;4379.343098535245;4407.4050003249795;4411.536788637147;4424.491183513873;4466.9553304466535;5023.824166154167;5902;5911.142884281755;5916.911246331309;5973.551983601247;6269.247690277196;6284.340144284875;6284.622922624657;6289.905651415233;6304.936950630435;5692.402297758758;5742.454466825645;5775.595708300462;5916.290045194604;5936.821895343147;5399.082156033483;5403.945960540423;5424.267723857308;5440.401555828856;5504.862378440571;6323.08315294289;6334.049892516159;6338.524610654219;6343.1436756038265;6350.396712312323;6132.99804414626;6246.476941112023;6277.340879207617;6286.057091436322;6288.24541055058;6168.1056246844855;6214.316425193135;6242.563020588696;6248.312685604489;6323.810285691437;4908.288298196813;6249.25008998923;6250.531210159347;6259.467945224706;6266.091416744638;6260.874767547833;6263.3736626337;6296.874062518816;6299.811005669837;6322.810315690536;5724.3703192647945;5838.854028649148;5871.236739223874;5902.1441890926435;5951.613145145551;5957.136215248678;6079.148919151234;6089.725962331794;6135.693215339126;6206.658633775005;4826.420829500483;5053.292539044463;5160.767765450639;5885.352611212677;5967.46292833647;6111.318268824492;6134;6160.106784516131;6160.630362178104;6167.167432396569;4868.537488938545;6039.909401358896;6057.091436284592;6070.2412198475195;6077.817665470043 12:23:17 INFO - PROCESS | 2568 | |1;ext.html;413.86135644559073;456.6529437627402;458.77749291593574;497.1995087908467;512.1523877981939;38542.807285963565;40440.393394099076;40505.79747101261;40521.98695032618;40541.98645033876;42299;42476;42653.7867310663;42672.35991460123;42674.93312667185;8437.409381343308;8688.782780430476;9208.171264586186;9427.34008619397;9458.527073646324;11141.554337826477;11426.657200284004;11470.827937580902;11562.884371156264;11619.12856535759;11329.150313726503;11361.659150225507;11373.374464404455;11377.2035957483;11395;11317.151213659008;11400.543978240861;11412.42937853104;11415.372154531538;11417.60038398652;11247.775044499145;11256.212065155443;11331.546738130464;11344.546218151265;11354.545818167346;39123.02192445183;40037.19925601501;40240.396394054114;40263.99340016494;40311.596884031074;3498.7900725956524;4006.859759908389;4021.557628660845;4056.1076563156353;4180.686448516343;30470.390592188254;30983;31012.689873101204;31015.689843101507;31320.53019204714;2544.8091393145405;3195.984020079908;3285.6878596533293;3293.4236508610898;3293.9176520587175;461796;461923;462948;465897.67051164864;466944;59739.402605974254;59990;60222.09666855001;60273;60320.69839650774;57210.71394643;64401.355986439536;64415;65022.024669629995;66822;16014.039157650579;16232.269547870374;16253.918730406273;16256;16264.942778719345;11256.549738010552;11535.942320288512;12229.14395992272;12288.447019884126;12613.936930315473;17306.48080557585;17345.47963561095;17458.214380352914;17466.825331746775;17508.03705796168;5785.392533783976;5832.854178645568;6880.097431443631;6932.306769323028;6938.757143499954;753.3445902065238;865.9610317535726;877.622622272429;896.3367108339781;898.389095415124;25186.622200667003;28813.56779648307;28893.855530722212;30005.249868753457;30015;999.0409207161061;1059.3855563772993;1091.8143915534379;1097.91765617579;1199.6401079676061;13658.385372658255;15475.458358957383;15629.921850390903;15663.060216387054;15978.201089945456;559234;562107;563706;564886;565835;56891.715541422855;59827;60054;60216.397836021955;60247;1394.5188909826093;1438.6475313548249;1455.9417623294948;1456.5411895253173;1466.5453709350136;29148.7085129146;29171.41657166802;29294.26764330909;29510.262243444085;29660.40679186361;50764.492355077455;51216.20743273968;51232.97534049224;51245.743771280904;51455.48544514507;408768;413162;414026;414881;417312;23819.52360952806;24322;24369.878150609133;24477.51044979126;39972.80013599913;34743.65256347404;41128;42489.575104248564;42991.78504107585;45113;30342.84828575843;30954.69045309518;35395.46906796349;35581.466278006366;36318.636813632584;34903;35201.64798352086;35981;36204.818975906004;36295.27409451743;34787.65212347844;34816.82591587026;35356;35460.46809297914;35472.11319717028;31201.53197701991;31397.05808825739;31475.527867081553;31579.526307105873;31642.525362119126;21669.241576544744;23499.727358350407;23589.646155307342;23908.76091239135;24181.274561763174 12:23:17 INFO - PROCESS | 2568 | |2;jquery.html;382.32901258291696;387.47690617666143;387.9507074395251;388.67157252121956;415.14894466344003;14327.64180895478;19741.60516789667;19896.50258743532;21206.681899771516;21266.57466850664;20616.072276747545;20939.68590471144;21390.358289251308;21618.56762864742;21631.242906498275;5346.599005074625;5715.9428405716035;5733.512651424634;5776.960147173507;5787.247657804501;5543.390227075019;5650.74571644275;5712.542996560265;5758.42415758425;5764.394738552454;5675.801346952858;5714.599978001542;5716.628419152742;5729;5730.255066841305;5468.562514998791;5656.717164141776;5719.227039617711;5738.7991420300295;5744.999999999979;5035.370578677665;5043.773030213649;5048.192289233705;5048.949510504922;5071.985602879439;24920.750792492207;25206.49587008268;25521.361965950815;25637.487250255075;25775.742242577522;3081.3837232553356;3113.844307784602;3132.342208136294;3138.8116712997294;3203.19920019995;15286.235688215545;15938.760918586231;15989.520314390587;16112;16257.674846503121;1553.689262147575;1805.2869116698962;1811.529002459357;1826.3881599664128;1830.8535317174594;116118.41940790242;122992;125554.99999999908;133046;140060;11531.1351648625;11679.824802627967;11837.585684501;11901.51231096113;11963.700907477209;13102.606921792361;13991.020628555903;14487.348069336906;15224;15558.9222053889;7703.8844417333785;7782.49413788102;8291.419600628018;8589.26991205742;8871.201591856763;8517.57412129391;8531.786705332417;8535;8671.956640216758;8693.782655433664;4043.2722110020477;4692.1084993851055;4838.806447742121;4870.295396611158;4884.389451318585;1760.8063113057553;2002.229141780425;2059.1763294682246;2062.2060506705025;2114.238874005357;958.6357184269933;1010.8685870836745;1015.6800607808517;1017.6590842067842;1024.9077583017565;16813.91593042027;21072.683909741365;21448.24931127403;21506.354809355744;21751.56496870054;1037.6990672704983;1043.7767555107723;1075.9515821788038;1081.3349789879167;1084.6312253833576;10722.195835312226;11309.943450282695;11345.149113816331;11352.091832653368;11396.202265841312;646285;658607;667827;669946;680262;126925.749826261;146946.53053469543;147921;152044;153814.46185538225;833.741540122564;1000.0349662575504;1017.6743442098606;1018.2821111116793;1020.9183265338755;18355.908220458812;19046;19048.333308334142;19139.330123445612;19158.329458469445;44408.77795611001;58184.709076454346;59548.40451595602;60221;61159.694201528706;79487.80768288365;83863.5806820962;86989;89161.8670506345;91957;8150.144234855255;8315.00219973607;8436;8502.82994340122;8522.616482258189;12496.06279529057;12930.288834114213;13360.465581376637;13415.128016678884;13420.664483387993;9270.119338662817;9531.952340238255;11061.723456913642;11581.13141514373;11661;8968.86546701787;11278.830817537908;11432.113701239536;11562.595309164475;11617.8962998515;9188;11720.296782193096;11815.113866459878;11828.526858925547;11959.282443053444;7387.482876198615;8175.773633954716;8351.624176912175;8482.872756908775;8593.355498337774;5912.615679980788;7470.589117598584;10981.450927453436;11410.201285909907;11762.470688818852 12:23:17 INFO - PROCESS | 2568 | |3;mootools.html;1420.0769499825112;1421.6354218420258;1442.430240055179;1457.2859298943515;1475.3729664892421;6617.86764264715;7474.215207403226;7727.188645192244;8410.116937721543;8417.537035463054;7917.327027202695;8226.17738226177;8240.423170378075;8316.168383161696;8322.459040162385;2291.5875142474347;2320.907163713458;2332.6151185054446;2336.848104873178;2347.94130146746;2141.821997901156;2297.357389466531;2318.6637937499136;2331.743508214095;2334.949272827226;2135.370065830576;2284.7029886114783;2298.609236429811;2309.399556115414;2311.4799170186657;2175.7497887742875;2198.6152423325852;2198.7471440784357;2203.4821816873023;2227.2984010036785;2303.7465878753324;2329.7320808107115;2330.9650355244685;2334.8015418689433;2348.3659411958674;7966;8095.150009249003;8115.634796434175;8116.228958248957;8119.5940202990205;5032.119379108642;5181.740912954338;5183.092958732244;5187.481251874782;5191.636585439021;2803.215099772067;3200.519922011694;3257.364813861298;3375.3586818504405;3382.069930769034;1246.0841281657974;1261.344101067449;1703.8839560088163;1736.3401907275154;1813.3743858368841;10620.203484738675;10649.25455218135;10751.978562036673;10763.300385474997;10796.838047429217;3881.922361552753;4497.2129877271345;4706.835260765857;4711.929321060187;4735.526447355306;8552.44409113418;8666.090060543553;8700.564971751388;8720.607572659246;8721.651133954698;5087.31321271631;5113.948860511422;5118.181091025418;5120.411152717411;5124.948750512522;4225.57744225575;4745.691530050535;4775.594074503637;4817.494163112893;4818.084563932842;3379.6312493440196;3581.1405262737276;3670.15586415126;3708.9258214835554;3759.6992240620684;995.3331268050424;1129.824877144044;1162.442027826648;1168.5851522709486;1175.9118066145074;915.4598786715736;918.9494577798151;940.6707652321633;945.2910317262053;950.8304785114348;5499.065158922993;5681;5737.282839645044;5898.498627616615;5959.255093113361;946.0066929723763;949.8242825077383;967.5141945170898;980.3725615606018;1006.3056491021172;3678.301122786662;3942.1918506706056;3963.207358528306;3964.273582832129;3996.980015099964;9706.126448619658;9978.351407158512;10019.348742331726;10037.648682296085;10042.497875106215;9321.580528876217;9682.75793105178;9707.66023189185;9722.2708296878;9980.051895069957;6420.357964203636;6493.870122597522;7454.925450745532;7675.961620191975;7719.610470115433;5469.480399362054;5488.4785945335125;5501.339839219159;5701.315842098975;5814.76740930358;9043.819123617359;9171.862422063541;9190.724278271662;9234.353595248269;9238.30712696564;7068.939659051183;7434.479586428898;8279.668813247403;8720.73837784842;9355.251579873458;7060.117485314336;7262.164851041986;7428;7584.4311676625575;7755.573443460664;6557.377049180321;7672.462927595015;7794.142644309008;7826;7827.139014708264;5907.5273978080795;6009.338972713085;6955.061066756126;7026.578405295699;7063.364297213276;6792.592444453348;6835.555688880207;6852.417544508772;6927.168739751262;6935.514513983972;6056.57603967718;6080.452759251689;6108.694565271808;6173.536984726074;6270.968645156745;4817.78319975595;4924.285978533164;4937.185364414975;4937.851864444073;5048.444671086251;4888.853334399973;4975.4278258000795;5015.899682006412;5022.221555658879;5041.42023667283 12:23:17 INFO - PROCESS | 2568 | |4;prototype.html;358.6753987641184;362.8947605194494;367.3057920530197;368.7529355331927;374.2328227134374;4445.533219012002;5375.3280839895015;5435.33115147276;5460.453954604547;5487.7530511127;4728.692634978724;5264.886818371857;5408.486193811592;5411.4577345456555;5418.2956215692;3153.6057992750907;3321.651226621206;3875.7831000269707;4071.2671719090554;4217.599328063829;3421.9981310933135;3587.7488575799703;4142;4227.725197862144;4285.100128972919;4204.264253755596;4214.346776249671;4227.746335219881;4227.978860105695;4244.596763307481;3725.217704282104;3882.20481253497;3938.7199160273026;4063.8824323311032;4201.033762234703;3411.822921092219;3434.6737059979264;3718.851245950159;3740.3454395480953;3752.1182522107283;4164.68764842638;4575.542445755431;5203.152880727363;5276.947230527684;5285.889963107752;4253.787310634487;4658.767061646904;4704.200285951397;4897.9959108383055;4948.851534453972;5746.25298711169;5755.510781583571;5819.73811178498;5848.970755146197;5922.58541902067;4089.8364065437345;4161.146964872194;4168.416421700934;4176.519700234481;4187.848341706055;5340.49265319794;5564.749586268627;5739.8565035874435;5873.735681894325;6153.230846144232;4075.4950742361543;4177.352510360899;4192.245395828719;4193.958060419357;4197.265478541243;3830.004198908304;4075.959240407558;4259.169461954951;4272.786360681954;4296.002358903125;4314.417553630283;4682.2508398656055;4884.242942343943;4947.158982972904;4955.157623204064;5553.472794981412;5579.238277052885;5650.802221922213;5738.8278351649515;5754.079347304411;1228.834107395508;1296.949470928551;1326.4428939845288;1383.2945197949118;1397.6296281485415;790.3440144679902;880.7053631162181;905.6694306578183;927.7495076329488;973.8441849304078;4171;4414.962483816301;4468.503051477726;4804.927926081112;5095.299491269633;4972.950270497321;4978.203487442064;5143.759431558095;5311.575073994071;5655.1799989001365;4286.349755344153;4490.955090449053;4898.4121905371585;4899.779509922062;4989.981061457838;2632.5919482480244;2788.549954023896;2908.5491748778973;2932.5601159826197;2945.6612489563972;5625.174881080904;5838.5037271831525;5862.391235900069;5870.683064068788;5936.970315148396;5002.247864528639;5568.972155139199;5575.275214222125;5626.634268772516;5838.86142202275;5103.213875143744;5874.882502349929;5881.411858814085;6072.696365181723;6077.259737404831;4822.590079843252;5153.948460515347;5430.723779911638;5893.9115913260475;5920.259967504062;4866.1484240257105;5513.42109078557;5617.415988645269;5791.739371728367;5831.1836342912875;6008.5493587981955;6046.455818976313;6069.575129740876;6090.543209259412;6116.3883611638485;3440.8451619676675;4144.461220041375;4180.289350810431;4188.308929026798;4197.580241975778;5286.273177409994;5551.361593416647;5664.915026274527;5816.651000939788;5841.824745257649;4782.593479554138;4837.782299796448;4926.704397736147;5007.474215207497;5368.919466208088;5478.220492200068;5571.804986825442;5660.632058916157;5690.829275121753;5736.365135936238;4992.375953005874;5153.484651534816;5387.895481426376;5558.805441809517;5593.384727680033;4660.720356778605;5207.81772637971;5450.427705091067;5666.9716651416475;5694.886102278014;4676.275177347517;5247.344081989751;5288.259643649969;5523.6409633373705;5546.362168350692 12:23:17 INFO - PROCESS | 2568 | |5;yui.html;711.2887112887113;722.32823474169;747.2676776758776;826.6099094193931;847.4322204123239;12095.213811102272;14492.130472171677;14925.253737313118;14961.925190374033;14967;14561.417543298281;14601.707965840676;14624.195669238188;14651.340689668963;14666.266686665651;681.7681988124037;703.9753608623699;826.5949684654536;852.9488230706162;863.8272345530887;853.7524118005771;858.5449711652834;867.6789587852472;872.0451106038877;872.2803686958254;922.7047344849651;925.6528801699362;927.3276874266171;928.8792456980583;931.6878845586702;742.8440027594212;809.2918696140877;920.5305294299922;926.4163576946514;927.7495076329353;689.6517258784282;692.435664933083;693.4972145194744;695.8573492434039;697.104221075915;12701.301428421433;15270.770938435933;15578.532644020697;15794.842051579451;15886.04683718969;13841.100328478617;14516.637084072983;14622.195779232137;14627.926860365737;14776.772803877277;11724.296542207494;11864.644060678194;11872.168948173632;12041.277523348626;12103.757924841542;3700.9259814803822;3726.2547490502006;3739.1773809761808;3746.138388170709;3760.736748427611;116636;116678;118941.40529297471;119212.01969950733;119679;11122.165837562216;11161.995420412202;11248;11266.943665281786;11642.76714465706;23385.649215261783;23444.531109377716;23451.537588553296;23540.64689029666;23612.881935590554;6614.710131524403;6849.212340580797;7724.6137693115115;7758.883616745753;7915.960420197862;4169.437125988014;4215.620594146541;4256.402321855473;4264.189803937304;4328.982689068066;2987.4473222453917;3493.703035242033;3573.7677050992124;3597.352476554245;3597.6042635310096;601.4977493792645;618.6442795392667;633.189517417706;639.9536757401589;655.2759201082735;610.7587502936316;612.3881644784996;701.5650296816007;707.3315716647821;712.2948281201573;10091.49542522871;10232.795344093076;10353.8859534345;10388.272820902615;10644.946775266075;676.9864602707918;684.2370756606448;701.1516065560729;724.5290561135304;727.0185249912576;5042.268871013755;5058.3929928408825;5068.56917162038;5073.873153171126;5088.6946783193125;82253.58873205596;103263;104438.47780761047;106195;106627;93229;93667.53166234125;94836.52581737048;95270;95721;81956.59021704854;81974.9999999988;83750;83906;84090.57954710188;9779.804403912025;9799.069088436587;9837.508124593885;9845.655402060893;9852;44020.55979440165;51840.74079629578;52529.949401011;52598.47401526089;55278;58407.66369345179;71374;71886;72586.63706681432;73820.63089684518;10112.84830727525;11846.229995050295;12011.339376334385;12220.75558488843;12322.445489953154;12760.29818359999;12974.870251297367;13078.346082696018;13577.728445431234;13613.455461781814;12207.328596927255;12260.26438413698;12492.25046497213;12578.622641320775;12670.366481675695;11918.40407979615;12000.03999680006;12090.758184836079;12100.376087399236;12410.069244806857;11734.823977640513;11876;12101.81847272274;12248.571300004458;12515;9925.305228634215;10431.530581123716;10523.684289471328;10538;10962.287451315638;8853.380263381501;9829.754256143367;9840.950795246265;9865.112139907444;10049.447280399649 12:23:17 INFO - PROCESS | 2568 | __end_tp_report 12:23:17 INFO - PROCESS | 2568 | __start_cc_report 12:23:17 INFO - PROCESS | 2568 | _x_x_mozilla_cycle_collect,380 12:23:17 INFO - PROCESS | 2568 | __end_cc_report 12:23:17 INFO - PROCESS | 2568 | __startTimestamp1484166197487__endTimestamp 12:23:17 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | ------- Summary: start ------- 12:23:17 INFO - PROCESS | 2568 | Number of tests: 6 12:23:17 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | [#0] dojo.html Cycles:165 Average:5694.49 Median:5911.14 stddev:665.58 (11.3%) stddev-sans-first:640.99 12:23:17 INFO - PROCESS | 2568 | Values: 3318.3 3325.7 3378.6 3392.6 3397.7 5511.2 5606.7 5611.2 5616.5 5620.7 5586.1 5695.5 5700.8 5744.8 5809.1 4690.7 5117.3 5147.2 5217.7 5242.5 5202.9 5287.4 5303.6 5317.8 5322.1 5100.8 5271.2 5276.7 5293.8 5303.8 5154.8 5211.6 5212.0 5212.7 5224.9 5110.9 5157.7 5178.8 5181.1 5186.4 4447.4 5363.1 5586.9 5782.2 5801.7 5248.8 6213.4 6248.8 6262.2 6277.7 5931.4 6024.9 6277.9 6294.1 6316.1 5649.0 5660.2 5692.5 5750.1 5755.7 6160.4 6193.4 6235.8 6245.5 6311.3 5081.6 6038.5 6192.6 6205.9 6209.4 6120.1 6264.0 6281.1 6294.0 6307.7 6141.3 6149.5 6162.8 6197.8 6271.7 6251.7 6255.5 6256.3 6287.3 6319.9 4867.8 4981.6 4987.6 4998.6 5016.7 4379.3 4407.4 4411.5 4424.5 4467.0 5023.8 5902.0 5911.1 5916.9 5973.6 6269.2 6284.3 6284.6 6289.9 6304.9 5692.4 5742.5 5775.6 5916.3 5936.8 5399.1 5403.9 5424.3 5440.4 5504.9 6323.1 6334.0 6338.5 6343.1 6350.4 6133.0 6246.5 6277.3 6286.1 6288.2 6168.1 6214.3 6242.6 6248.3 6323.8 4908.3 6249.3 6250.5 6259.5 6266.1 6260.9 6263.4 6296.9 6299.8 6322.8 5724.4 5838.9 5871.2 5902.1 5951.6 5957.1 6079.1 6089.7 6135.7 6206.7 4826.4 5053.3 5160.8 5885.4 5967.5 6111.3 6134.0 6160.1 6160.6 6167.2 4868.5 6039.9 6057.1 6070.2 6077.8 12:23:17 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | [#1] ext.html Cycles:180 Average:62431.63 Median:35278.82 stddev:128803.64 (365.1%) stddev-sans-first:129080.81 12:23:17 INFO - PROCESS | 2568 | Values: 413.9 456.7 458.8 497.2 512.2 38542.8 40440.4 40505.8 40522.0 40542.0 42299.0 42476.0 42653.8 42672.4 42674.9 8437.4 8688.8 9208.2 9427.3 9458.5 11141.6 11426.7 11470.8 11562.9 11619.1 11329.2 11361.7 11373.4 11377.2 11395.0 11317.2 11400.5 11412.4 11415.4 11417.6 11247.8 11256.2 11331.5 11344.5 11354.5 39123.0 40037.2 40240.4 40264.0 40311.6 3498.8 4006.9 4021.6 4056.1 4180.7 30470.4 30983.0 31012.7 31015.7 31320.5 2544.8 3196.0 3285.7 3293.4 3293.9 461796.0 461923.0 462948.0 465897.7 466944.0 59739.4 59990.0 60222.1 60273.0 60320.7 57210.7 64401.4 64415.0 65022.0 66822.0 16014.0 16232.3 16253.9 16256.0 16264.9 11256.5 11535.9 12229.1 12288.4 12613.9 17306.5 17345.5 17458.2 17466.8 17508.0 5785.4 5832.9 6880.1 6932.3 6938.8 753.3 866.0 877.6 896.3 898.4 25186.6 28813.6 28893.9 30005.2 30015.0 999.0 1059.4 1091.8 1097.9 1199.6 13658.4 15475.5 15629.9 15663.1 15978.2 559234.0 562107.0 563706.0 564886.0 565835.0 56891.7 59827.0 60054.0 60216.4 60247.0 1394.5 1438.6 1455.9 1456.5 1466.5 29148.7 29171.4 29294.3 29510.3 29660.4 50764.5 51216.2 51233.0 51245.7 51455.5 408768.0 413162.0 414026.0 414881.0 417312.0 23819.5 24322.0 24369.9 24477.5 39972.8 34743.7 41128.0 42489.6 42991.8 45113.0 30342.8 30954.7 35395.5 35581.5 36318.6 34903.0 35201.6 35981.0 36204.8 36295.3 34787.7 34816.8 35356.0 35460.5 35472.1 31201.5 31397.1 31475.5 31579.5 31642.5 21669.2 23499.7 23589.6 23908.8 24181.3 12:23:17 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | [#2] jquery.html Cycles:180 Average:38089.23 Median:21691.40 stddev:111114.62 (512.3%) stddev-sans-first:111390.25 12:23:17 INFO - PROCESS | 2568 | Values: 382.3 387.5 388.0 388.7 415.1 14327.6 19741.6 19896.5 21206.7 21266.6 20616.1 20939.7 21390.4 21618.6 21631.2 5346.6 5715.9 5733.5 5777.0 5787.2 5543.4 5650.7 5712.5 5758.4 5764.4 5675.8 5714.6 5716.6 5729.0 5730.3 5468.6 5656.7 5719.2 5738.8 5745.0 5035.4 5043.8 5048.2 5048.9 5072.0 24920.8 25206.5 25521.4 25637.5 25775.7 3081.4 3113.8 3132.3 3138.8 3203.2 15286.2 15938.8 15989.5 16112.0 16257.7 1553.7 1805.3 1811.5 1826.4 1830.9 116118.4 122992.0 125555.0 133046.0 140060.0 11531.1 11679.8 11837.6 11901.5 11963.7 13102.6 13991.0 14487.3 15224.0 15558.9 7703.9 7782.5 8291.4 8589.3 8871.2 8517.6 8531.8 8535.0 8672.0 8693.8 4043.3 4692.1 4838.8 4870.3 4884.4 1760.8 2002.2 2059.2 2062.2 2114.2 958.6 1010.9 1015.7 1017.7 1024.9 16813.9 21072.7 21448.2 21506.4 21751.6 1037.7 1043.8 1076.0 1081.3 1084.6 10722.2 11309.9 11345.1 11352.1 11396.2 646285.0 658607.0 667827.0 669946.0 680262.0 126925.7 146946.5 147921.0 152044.0 153814.5 833.7 1000.0 1017.7 1018.3 1020.9 18355.9 19046.0 19048.3 19139.3 19158.3 44408.8 58184.7 59548.4 60221.0 61159.7 79487.8 83863.6 86989.0 89161.9 91957.0 8150.1 8315.0 8436.0 8502.8 8522.6 12496.1 12930.3 13360.5 13415.1 13420.7 9270.1 9532.0 11061.7 11581.1 11661.0 8968.9 11278.8 11432.1 11562.6 11617.9 9188.0 11720.3 11815.1 11828.5 11959.3 7387.5 8175.8 8351.6 8482.9 8593.4 5912.6 7470.6 10981.5 11410.2 11762.5 12:23:17 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | [#3] mootools.html Cycles:180 Average:5305.25 Median:5185.29 stddev:2834.75 (54.7%) stddev-sans-first:2827.66 12:23:17 INFO - PROCESS | 2568 | Values: 1420.1 1421.6 1442.4 1457.3 1475.4 6617.9 7474.2 7727.2 8410.1 8417.5 7917.3 8226.2 8240.4 8316.2 8322.5 2291.6 2320.9 2332.6 2336.8 2347.9 2141.8 2297.4 2318.7 2331.7 2334.9 2135.4 2284.7 2298.6 2309.4 2311.5 2175.7 2198.6 2198.7 2203.5 2227.3 2303.7 2329.7 2331.0 2334.8 2348.4 7966.0 8095.2 8115.6 8116.2 8119.6 5032.1 5181.7 5183.1 5187.5 5191.6 2803.2 3200.5 3257.4 3375.4 3382.1 1246.1 1261.3 1703.9 1736.3 1813.4 10620.2 10649.3 10752.0 10763.3 10796.8 3881.9 4497.2 4706.8 4711.9 4735.5 8552.4 8666.1 8700.6 8720.6 8721.7 5087.3 5113.9 5118.2 5120.4 5124.9 4225.6 4745.7 4775.6 4817.5 4818.1 3379.6 3581.1 3670.2 3708.9 3759.7 995.3 1129.8 1162.4 1168.6 1175.9 915.5 918.9 940.7 945.3 950.8 5499.1 5681.0 5737.3 5898.5 5959.3 946.0 949.8 967.5 980.4 1006.3 3678.3 3942.2 3963.2 3964.3 3997.0 9706.1 9978.4 10019.3 10037.6 10042.5 9321.6 9682.8 9707.7 9722.3 9980.1 6420.4 6493.9 7454.9 7676.0 7719.6 5469.5 5488.5 5501.3 5701.3 5814.8 9043.8 9171.9 9190.7 9234.4 9238.3 7068.9 7434.5 8279.7 8720.7 9355.3 7060.1 7262.2 7428.0 7584.4 7755.6 6557.4 7672.5 7794.1 7826.0 7827.1 5907.5 6009.3 6955.1 7026.6 7063.4 6792.6 6835.6 6852.4 6927.2 6935.5 6056.6 6080.5 6108.7 6173.5 6271.0 4817.8 4924.3 4937.2 4937.9 5048.4 4888.9 4975.4 5015.9 5022.2 5041.4 12:23:17 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | [#4] prototype.html Cycles:180 Average:4564.23 Median:4984.09 stddev:1376.89 (27.6%) stddev-sans-first:1344.09 12:23:17 INFO - PROCESS | 2568 | Values: 358.7 362.9 367.3 368.8 374.2 4445.5 5375.3 5435.3 5460.5 5487.8 4728.7 5264.9 5408.5 5411.5 5418.3 3153.6 3321.7 3875.8 4071.3 4217.6 3422.0 3587.7 4142.0 4227.7 4285.1 4204.3 4214.3 4227.7 4228.0 4244.6 3725.2 3882.2 3938.7 4063.9 4201.0 3411.8 3434.7 3718.9 3740.3 3752.1 4164.7 4575.5 5203.2 5276.9 5285.9 4253.8 4658.8 4704.2 4898.0 4948.9 5746.3 5755.5 5819.7 5849.0 5922.6 4089.8 4161.1 4168.4 4176.5 4187.8 5340.5 5564.7 5739.9 5873.7 6153.2 4075.5 4177.4 4192.2 4194.0 4197.3 3830.0 4076.0 4259.2 4272.8 4296.0 4314.4 4682.3 4884.2 4947.2 4955.2 5553.5 5579.2 5650.8 5738.8 5754.1 1228.8 1296.9 1326.4 1383.3 1397.6 790.3 880.7 905.7 927.7 973.8 4171.0 4415.0 4468.5 4804.9 5095.3 4973.0 4978.2 5143.8 5311.6 5655.2 4286.3 4491.0 4898.4 4899.8 4990.0 2632.6 2788.5 2908.5 2932.6 2945.7 5625.2 5838.5 5862.4 5870.7 5937.0 5002.2 5569.0 5575.3 5626.6 5838.9 5103.2 5874.9 5881.4 6072.7 6077.3 4822.6 5153.9 5430.7 5893.9 5920.3 4866.1 5513.4 5617.4 5791.7 5831.2 6008.5 6046.5 6069.6 6090.5 6116.4 3440.8 4144.5 4180.3 4188.3 4197.6 5286.3 5551.4 5664.9 5816.7 5841.8 4782.6 4837.8 4926.7 5007.5 5368.9 5478.2 5571.8 5660.6 5690.8 5736.4 4992.4 5153.5 5387.9 5558.8 5593.4 4660.7 5207.8 5450.4 5667.0 5694.9 4676.3 5247.3 5288.3 5523.6 5546.4 12:23:17 INFO - PROCESS | 2568 | 12:23:17 INFO - PROCESS | 2568 | [#5] yui.html Cycles:180 Average:20975.45 Median:4236.01 stddev:31125.99 (734.8%) stddev-sans-first:31176.11 12:23:17 INFO - PROCESS | 2568 | Values: 711.3 722.3 747.3 826.6 847.4 12095.2 14492.1 14925.3 14961.9 14967.0 14561.4 14601.7 14624.2 14651.3 14666.3 681.8 704.0 826.6 852.9 863.8 853.8 858.5 867.7 872.0 872.3 922.7 925.7 927.3 928.9 931.7 742.8 809.3 920.5 926.4 927.7 689.7 692.4 693.5 695.9 697.1 12701.3 15270.8 15578.5 15794.8 15886.0 13841.1 14516.6 14622.2 14627.9 14776.8 11724.3 11864.6 11872.2 12041.3 12103.8 3700.9 3726.3 3739.2 3746.1 3760.7 116636.0 116678.0 118941.4 119212.0 119679.0 11122.2 11162.0 11248.0 11266.9 11642.8 23385.6 23444.5 23451.5 23540.6 23612.9 6614.7 6849.2 7724.6 7758.9 7916.0 4169.4 4215.6 4256.4 4264.2 4329.0 2987.4 3493.7 3573.8 3597.4 3597.6 601.5 618.6 633.2 640.0 655.3 610.8 612.4 701.6 707.3 712.3 10091.5 10232.8 10353.9 10388.3 10644.9 677.0 684.2 701.2 724.5 727.0 5042.3 5058.4 5068.6 5073.9 5088.7 82253.6 103263.0 104438.5 106195.0 106627.0 93229.0 93667.5 94836.5 95270.0 95721.0 81956.6 81975.0 83750.0 83906.0 84090.6 9779.8 9799.1 9837.5 9845.7 9852.0 44020.6 51840.7 52529.9 52598.5 55278.0 58407.7 71374.0 71886.0 72586.6 73820.6 10112.8 11846.2 12011.3 12220.8 12322.4 12760.3 12974.9 13078.3 13577.7 13613.5 12207.3 12260.3 12492.3 12578.6 12670.4 11918.4 12000.0 12090.8 12100.4 12410.1 11734.8 11876.0 12101.8 12248.6 12515.0 9925.3 10431.5 10523.7 10538.0 10962.3 8853.4 9829.8 9841.0 9865.1 10049.4 12:23:17 INFO - PROCESS | 2568 | -------- Summary: end -------- 12:23:17 INFO - PROCESS | 2568 | 12:23:18 INFO - TEST-INFO | 2568: exit 0 12:23:18 INFO - TEST-OK | dromaeo_css | took 1115871ms 12:23:18 INFO - TEST-START | kraken 12:23:18 INFO - operating with platform_type : mac_ 12:23:18 INFO - Initialising browser for kraken test... 12:23:18 INFO - TEST-INFO | started process 2572 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpLoXr1F/profile http://localhost:52962/getInfo.html) 12:23:20 INFO - PROCESS | 2572 | 2017-01-11 12:23:20.908 plugin-container[2573:28078] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 12:23:20 INFO - PROCESS | 2572 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:23:20 INFO - PROCESS | 2572 | 2017-01-11 12:23:20.911 plugin-container[2573:28078] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 12:23:20 INFO - PROCESS | 2572 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:23:20 INFO - PROCESS | 2572 | 2017-01-11 12:23:20.911 plugin-container[2573:28078] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:23:20 INFO - PROCESS | 2572 | __metrics Screen width/height:1600/1200 12:23:20 INFO - PROCESS | 2572 | colorDepth:24 12:23:20 INFO - PROCESS | 2572 | Browser inner width/height: 1024/689 12:23:20 INFO - PROCESS | 2572 | __metrics 12:23:21 ERROR - PROCESS | 2572 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 12:23:21 INFO - TEST-INFO | 2572: exit 0 12:23:21 INFO - Browser initialized. 12:23:21 INFO - Running cycle 1/1 for kraken test... 12:23:21 INFO - TEST-INFO | started process 2574 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpLoXr1F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:24 INFO - PROCESS | 2574 | 2017-01-11 12:23:24.342 plugin-container[2575:28198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 12:23:24 INFO - PROCESS | 2574 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:23:24 INFO - PROCESS | 2574 | 2017-01-11 12:23:24.343 plugin-container[2575:28198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 12:23:24 INFO - PROCESS | 2574 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:23:24 INFO - PROCESS | 2574 | 2017-01-11 12:23:24.343 plugin-container[2575:28198] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:23:34 INFO - PROCESS | 2574 | RSS: Main: 234307584 12:23:34 INFO - PROCESS | 2574 | 12:23:36 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52962/tests/kraken/driver.html?testName=audio-beat-detection) 12:23:37 INFO - PROCESS | 2574 | RSS: Main: 246607872 12:23:37 INFO - PROCESS | 2574 | 12:23:40 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52962/tests/kraken/driver.html?testName=audio-dft) 12:23:41 INFO - PROCESS | 2574 | RSS: Main: 254439424 12:23:41 INFO - PROCESS | 2574 | 12:23:44 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52962/tests/kraken/driver.html?testName=audio-fft) 12:23:45 INFO - PROCESS | 2574 | RSS: Main: 257118208 12:23:45 INFO - PROCESS | 2574 | 12:23:47 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52962/tests/kraken/driver.html?testName=audio-oscillator) 12:23:47 INFO - PROCESS | 2574 | RSS: Main: 259383296 12:23:47 INFO - PROCESS | 2574 | 12:23:49 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52962/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:23:50 INFO - PROCESS | 2574 | RSS: Main: 259661824 12:23:50 INFO - PROCESS | 2574 | 12:23:53 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52962/tests/kraken/driver.html?testName=imaging-darkroom) 12:23:53 INFO - PROCESS | 2574 | RSS: Main: 265424896 12:23:53 INFO - PROCESS | 2574 | 12:23:57 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52962/tests/kraken/driver.html?testName=imaging-desaturate) 12:23:57 INFO - PROCESS | 2574 | RSS: Main: 264364032 12:23:57 INFO - PROCESS | 2574 | 12:24:01 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52962/tests/kraken/driver.html?testName=json-parse-financial) 12:24:01 INFO - PROCESS | 2574 | RSS: Main: 264220672 12:24:01 INFO - PROCESS | 2574 | 12:24:03 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52962/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:24:03 INFO - PROCESS | 2574 | RSS: Main: 263675904 12:24:03 INFO - PROCESS | 2574 | 12:24:05 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52962/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:24:06 INFO - PROCESS | 2574 | RSS: Main: 263667712 12:24:06 INFO - PROCESS | 2574 | 12:24:08 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52962/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:24:09 INFO - PROCESS | 2574 | RSS: Main: 263745536 12:24:09 INFO - PROCESS | 2574 | 12:24:12 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52962/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:24:13 INFO - PROCESS | 2574 | RSS: Main: 263942144 12:24:13 INFO - PROCESS | 2574 | 12:24:15 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52962/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:24:16 INFO - PROCESS | 2574 | RSS: Main: 263815168 12:24:16 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:52962/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52962/tests/kraken/driver.html?testName=ai-astar) 12:24:18 INFO - PROCESS | 2574 | RSS: Main: 265703424 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | __start_tp_report 12:24:18 INFO - PROCESS | 2574 | _x_x_mozilla_page_load 12:24:18 INFO - PROCESS | 2574 | _x_x_mozilla_page_load_details 12:24:18 INFO - PROCESS | 2574 | |i|pagename|runs| 12:24:18 INFO - PROCESS | 2574 | |0;ai-astar;106;115;117;118;117;118;116;113;117;119 12:24:18 INFO - PROCESS | 2574 | |1;audio-beat-detection;143;112;113;152;116;112;136;112;133;113 12:24:18 INFO - PROCESS | 2574 | |2;audio-dft;197;211;208;206;197;198;192;213;243;200 12:24:18 INFO - PROCESS | 2574 | |3;audio-fft;89;54;77;108;83;84;52;59;77;83 12:24:18 INFO - PROCESS | 2574 | |4;audio-oscillator;80;57;84;89;56;75;82;64;86;112 12:24:18 INFO - PROCESS | 2574 | |5;imaging-gaussian-blur;88;87;89;87;90;102;109;87;90;88 12:24:18 INFO - PROCESS | 2574 | |6;imaging-darkroom;101;113;137;172;160;101;130;114;174;169 12:24:18 INFO - PROCESS | 2574 | |7;imaging-desaturate;94;146;148;94;89;92;99;93;120;143 12:24:18 INFO - PROCESS | 2574 | |8;json-parse-financial;60;61;61;60;61;62;52;50;63;72 12:24:18 INFO - PROCESS | 2574 | |9;json-stringify-tinderbox;70;71;77;83;102;105;89;74;69;73 12:24:18 INFO - PROCESS | 2574 | |10;stanford-crypto-aes;89;88;106;81;90;92;92;93;87;94 12:24:18 INFO - PROCESS | 2574 | |11;stanford-crypto-ccm;167;155;158;132;142;158;141;144;190;143 12:24:18 INFO - PROCESS | 2574 | |12;stanford-crypto-pbkdf2;172;172;174;175;177;174;172;177;173;181 12:24:18 INFO - PROCESS | 2574 | |13;stanford-crypto-sha256-iterative;67;68;67;68;69;67;67;69;69;66 12:24:18 INFO - PROCESS | 2574 | __end_tp_report 12:24:18 INFO - PROCESS | 2574 | __start_cc_report 12:24:18 INFO - PROCESS | 2574 | _x_x_mozilla_cycle_collect,925 12:24:18 INFO - PROCESS | 2574 | __end_cc_report 12:24:18 INFO - PROCESS | 2574 | __startTimestamp1484166258541__endTimestamp 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | ------- Summary: start ------- 12:24:18 INFO - PROCESS | 2574 | Number of tests: 14 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#0] ai-astar Cycles:10 Average:115.60 Median:117.00 stddev:3.78 (3.2%) stddev-sans-first:1.80 12:24:18 INFO - PROCESS | 2574 | Values: 106.0 115.0 117.0 118.0 117.0 118.0 116.0 113.0 117.0 119.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:124.50 stddev:15.30 (12.3%) stddev-sans-first:14.64 12:24:18 INFO - PROCESS | 2574 | Values: 143.0 112.0 113.0 152.0 116.0 112.0 136.0 112.0 133.0 113.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#2] audio-dft Cycles:10 Average:206.50 Median:207.00 stddev:14.54 (7.0%) stddev-sans-first:15.01 12:24:18 INFO - PROCESS | 2574 | Values: 197.0 211.0 208.0 206.0 197.0 198.0 192.0 213.0 243.0 200.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#3] audio-fft Cycles:10 Average:76.60 Median:80.00 stddev:17.33 (21.7%) stddev-sans-first:17.79 12:24:18 INFO - PROCESS | 2574 | Values: 89.0 54.0 77.0 108.0 83.0 84.0 52.0 59.0 77.0 83.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:81.00 stddev:16.75 (20.7%) stddev-sans-first:17.76 12:24:18 INFO - PROCESS | 2574 | Values: 80.0 57.0 84.0 89.0 56.0 75.0 82.0 64.0 86.0 112.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:88.00 stddev:7.54 (8.6%) stddev-sans-first:7.88 12:24:18 INFO - PROCESS | 2574 | Values: 88.0 87.0 89.0 87.0 90.0 102.0 109.0 87.0 90.0 88.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#6] imaging-darkroom Cycles:10 Average:137.10 Median:148.50 stddev:29.61 (19.9%) stddev-sans-first:28.38 12:24:18 INFO - PROCESS | 2574 | Values: 101.0 113.0 137.0 172.0 160.0 101.0 130.0 114.0 174.0 169.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#7] imaging-desaturate Cycles:10 Average:111.80 Median:92.50 stddev:24.91 (26.9%) stddev-sans-first:25.57 12:24:18 INFO - PROCESS | 2574 | Values: 94.0 146.0 148.0 94.0 89.0 92.0 99.0 93.0 120.0 143.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:61.00 stddev:6.00 (9.8%) stddev-sans-first:6.36 12:24:18 INFO - PROCESS | 2574 | Values: 60.0 61.0 61.0 60.0 61.0 62.0 52.0 50.0 63.0 72.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#9] json-stringify-tinderbox Cycles:10 Average:81.30 Median:73.50 stddev:13.24 (18.0%) stddev-sans-first:13.40 12:24:18 INFO - PROCESS | 2574 | Values: 70.0 71.0 77.0 83.0 102.0 105.0 89.0 74.0 69.0 73.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:91.00 stddev:6.41 (7.0%) stddev-sans-first:6.75 12:24:18 INFO - PROCESS | 2574 | Values: 89.0 88.0 106.0 81.0 90.0 92.0 92.0 93.0 87.0 94.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:156.50 stddev:16.69 (10.7%) stddev-sans-first:16.91 12:24:18 INFO - PROCESS | 2574 | Values: 167.0 155.0 158.0 132.0 142.0 158.0 141.0 144.0 190.0 143.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:174.50 stddev:2.91 (1.7%) stddev-sans-first:2.92 12:24:18 INFO - PROCESS | 2574 | Values: 172.0 172.0 174.0 175.0 177.0 174.0 172.0 177.0 173.0 181.0 12:24:18 INFO - PROCESS | 2574 | 12:24:18 INFO - PROCESS | 2574 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 12:24:18 INFO - PROCESS | 2574 | Values: 67.0 68.0 67.0 68.0 69.0 67.0 67.0 69.0 69.0 66.0 12:24:18 INFO - PROCESS | 2574 | -------- Summary: end -------- 12:24:18 INFO - PROCESS | 2574 | 12:24:19 INFO - TEST-INFO | 2574: exit 0 12:24:19 INFO - TEST-OK | kraken | took 61234ms 12:24:19 INFO - SUITE-END | took 1177s 12:24:19 INFO - Completed test suite (00:19:37) 12:24:19 INFO - javascript benchmark 12:24:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7266.765160446566, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.3197444523876, 3325.7339412846973, 3378.577677790276, 3392.609849867266, 3397.6602339766027, 5511.173324001396, 5606.691631960241, 5611.186377975191, 5616.522595579376, 5620.662760234389, 5586.050371436855, 5695.45893140152, 5700.800471983482, 5744.827655170341, 5809.128630705396, 4690.748440748443, 5117.33474648297, 5147.22791581262, 5217.660852044605, 5242.528172464477, 5202.947970520303, 5287.39194992575, 5303.628745987782, 5317.787288508455, 5322.148456247001, 5100.795968161269, 5271.156614941629, 5276.657017293883, 5293.761780719877, 5303.8408847734245, 5154.793808247666, 5211.635185537014, 5211.973940130313, 5212.687238765686, 5224.947750522484, 5110.872228194287, 5157.71632560209, 5178.818741344073, 5181.119209734316, 5186.403563590197, 4447.355133505656, 5363.115086010804, 5586.944130558683, 5782.219400380979, 5801.7389217485315, 5248.845254044104, 6213.440790328892, 6248.812535623939, 6262.1546091278005, 6277.717502712343, 5931.436513531251, 6024.885396201673, 6277.937220627827, 6294.0558916162945, 6316.115743795872, 5649.0114230009995, 5660.150977353389, 5692.5161361284345, 5750.079987202028, 5755.740991655385, 6160.414760597736, 6193.411625895533, 6235.75056997715, 6245.531585131133, 6311.274203466568, 5081.618878584047, 6038.547108966845, 6192.628442293477, 6205.937940620626, 6209.441150296495, 6120.142398366953, 6263.96868015657, 6281.089242059968, 6293.96853015732, 6307.684615769193, 6141.293751218578, 6149.477294430024, 6162.815115546541, 6197.783077592353, 6271.717772700239, 6251.718672659741, 6255.468285195719, 6256.31180570146, 6287.339829317947, 6319.873602527924, 4867.805287788519, 4981.601471882241, 4987.600991920638, 4998.550130488274, 5016.698998060055, 4379.343098535245, 4407.4050003249795, 4411.536788637147, 4424.491183513873, 4466.9553304466535, 5023.824166154167, 5902.0, 5911.142884281755, 5916.911246331309, 5973.551983601247, 6269.247690277196, 6284.340144284875, 6284.622922624657, 6289.905651415233, 6304.936950630435, 5692.402297758758, 5742.454466825645, 5775.595708300462, 5916.290045194604, 5936.821895343147, 5399.082156033483, 5403.945960540423, 5424.267723857308, 5440.401555828856, 5504.862378440571, 6323.08315294289, 6334.049892516159, 6338.524610654219, 6343.1436756038265, 6350.396712312323, 6132.99804414626, 6246.476941112023, 6277.340879207617, 6286.057091436322, 6288.24541055058, 6168.1056246844855, 6214.316425193135, 6242.563020588696, 6248.312685604489, 6323.810285691437, 4908.288298196813, 6249.25008998923, 6250.531210159347, 6259.467945224706, 6266.091416744638, 6260.874767547833, 6263.3736626337, 6296.874062518816, 6299.811005669837, 6322.810315690536, 5724.3703192647945, 5838.854028649148, 5871.236739223874, 5902.1441890926435, 5951.613145145551, 5957.136215248678, 6079.148919151234, 6089.725962331794, 6135.693215339126, 6206.658633775005, 4826.420829500483, 5053.292539044463, 5160.767765450639, 5885.352611212677, 5967.46292833647, 6111.318268824492, 6134.0, 6160.106784516131, 6160.630362178104, 6167.167432396569, 4868.537488938545, 6039.909401358896, 6057.091436284592, 6070.2412198475195, 6077.817665470043], "value": 5653.8837467701715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.86135644559073, 456.6529437627402, 458.77749291593574, 497.1995087908467, 512.1523877981939, 38542.807285963565, 40440.393394099076, 40505.79747101261, 40521.98695032618, 40541.98645033876, 42299.0, 42476.0, 42653.7867310663, 42672.35991460123, 42674.93312667185, 8437.409381343308, 8688.782780430476, 9208.171264586186, 9427.34008619397, 9458.527073646324, 11141.554337826477, 11426.657200284004, 11470.827937580902, 11562.884371156264, 11619.12856535759, 11329.150313726503, 11361.659150225507, 11373.374464404455, 11377.2035957483, 11395.0, 11317.151213659008, 11400.543978240861, 11412.42937853104, 11415.372154531538, 11417.60038398652, 11247.775044499145, 11256.212065155443, 11331.546738130464, 11344.546218151265, 11354.545818167346, 39123.02192445183, 40037.19925601501, 40240.396394054114, 40263.99340016494, 40311.596884031074, 3498.7900725956524, 4006.859759908389, 4021.557628660845, 4056.1076563156353, 4180.686448516343, 30470.390592188254, 30983.0, 31012.689873101204, 31015.689843101507, 31320.53019204714, 2544.8091393145405, 3195.984020079908, 3285.6878596533293, 3293.4236508610898, 3293.9176520587175, 461796.0, 461923.0, 462948.0, 465897.67051164864, 466944.0, 59739.402605974254, 59990.0, 60222.09666855001, 60273.0, 60320.69839650774, 57210.71394643, 64401.355986439536, 64415.0, 65022.024669629995, 66822.0, 16014.039157650579, 16232.269547870374, 16253.918730406273, 16256.0, 16264.942778719345, 11256.549738010552, 11535.942320288512, 12229.14395992272, 12288.447019884126, 12613.936930315473, 17306.48080557585, 17345.47963561095, 17458.214380352914, 17466.825331746775, 17508.03705796168, 5785.392533783976, 5832.854178645568, 6880.097431443631, 6932.306769323028, 6938.757143499954, 753.3445902065238, 865.9610317535726, 877.622622272429, 896.3367108339781, 898.389095415124, 25186.622200667003, 28813.56779648307, 28893.855530722212, 30005.249868753457, 30015.0, 999.0409207161061, 1059.3855563772993, 1091.8143915534379, 1097.91765617579, 1199.6401079676061, 13658.385372658255, 15475.458358957383, 15629.921850390903, 15663.060216387054, 15978.201089945456, 559234.0, 562107.0, 563706.0, 564886.0, 565835.0, 56891.715541422855, 59827.0, 60054.0, 60216.397836021955, 60247.0, 1394.5188909826093, 1438.6475313548249, 1455.9417623294948, 1456.5411895253173, 1466.5453709350136, 29148.7085129146, 29171.41657166802, 29294.26764330909, 29510.262243444085, 29660.40679186361, 50764.492355077455, 51216.20743273968, 51232.97534049224, 51245.743771280904, 51455.48544514507, 408768.0, 413162.0, 414026.0, 414881.0, 417312.0, 23819.52360952806, 24322.0, 24369.878150609133, 24477.51044979126, 39972.80013599913, 34743.65256347404, 41128.0, 42489.575104248564, 42991.78504107585, 45113.0, 30342.84828575843, 30954.69045309518, 35395.46906796349, 35581.466278006366, 36318.636813632584, 34903.0, 35201.64798352086, 35981.0, 36204.818975906004, 36295.27409451743, 34787.65212347844, 34816.82591587026, 35356.0, 35460.46809297914, 35472.11319717028, 31201.53197701991, 31397.05808825739, 31475.527867081553, 31579.526307105873, 31642.525362119126, 21669.241576544744, 23499.727358350407, 23589.646155307342, 23908.76091239135, 24181.274561763174], "value": 19565.47982054065, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.32901258291696, 387.47690617666143, 387.9507074395251, 388.67157252121956, 415.14894466344003, 14327.64180895478, 19741.60516789667, 19896.50258743532, 21206.681899771516, 21266.57466850664, 20616.072276747545, 20939.68590471144, 21390.358289251308, 21618.56762864742, 21631.242906498275, 5346.599005074625, 5715.9428405716035, 5733.512651424634, 5776.960147173507, 5787.247657804501, 5543.390227075019, 5650.74571644275, 5712.542996560265, 5758.42415758425, 5764.394738552454, 5675.801346952858, 5714.599978001542, 5716.628419152742, 5729.0, 5730.255066841305, 5468.562514998791, 5656.717164141776, 5719.227039617711, 5738.7991420300295, 5744.999999999979, 5035.370578677665, 5043.773030213649, 5048.192289233705, 5048.949510504922, 5071.985602879439, 24920.750792492207, 25206.49587008268, 25521.361965950815, 25637.487250255075, 25775.742242577522, 3081.3837232553356, 3113.844307784602, 3132.342208136294, 3138.8116712997294, 3203.19920019995, 15286.235688215545, 15938.760918586231, 15989.520314390587, 16112.0, 16257.674846503121, 1553.689262147575, 1805.2869116698962, 1811.529002459357, 1826.3881599664128, 1830.8535317174594, 116118.41940790242, 122992.0, 125554.99999999908, 133046.0, 140060.0, 11531.1351648625, 11679.824802627967, 11837.585684501, 11901.51231096113, 11963.700907477209, 13102.606921792361, 13991.020628555903, 14487.348069336906, 15224.0, 15558.9222053889, 7703.8844417333785, 7782.49413788102, 8291.419600628018, 8589.26991205742, 8871.201591856763, 8517.57412129391, 8531.786705332417, 8535.0, 8671.956640216758, 8693.782655433664, 4043.2722110020477, 4692.1084993851055, 4838.806447742121, 4870.295396611158, 4884.389451318585, 1760.8063113057553, 2002.229141780425, 2059.1763294682246, 2062.2060506705025, 2114.238874005357, 958.6357184269933, 1010.8685870836745, 1015.6800607808517, 1017.6590842067842, 1024.9077583017565, 16813.91593042027, 21072.683909741365, 21448.24931127403, 21506.354809355744, 21751.56496870054, 1037.6990672704983, 1043.7767555107723, 1075.9515821788038, 1081.3349789879167, 1084.6312253833576, 10722.195835312226, 11309.943450282695, 11345.149113816331, 11352.091832653368, 11396.202265841312, 646285.0, 658607.0, 667827.0, 669946.0, 680262.0, 126925.749826261, 146946.53053469543, 147921.0, 152044.0, 153814.46185538225, 833.741540122564, 1000.0349662575504, 1017.6743442098606, 1018.2821111116793, 1020.9183265338755, 18355.908220458812, 19046.0, 19048.333308334142, 19139.330123445612, 19158.329458469445, 44408.77795611001, 58184.709076454346, 59548.40451595602, 60221.0, 61159.694201528706, 79487.80768288365, 83863.5806820962, 86989.0, 89161.8670506345, 91957.0, 8150.144234855255, 8315.00219973607, 8436.0, 8502.82994340122, 8522.616482258189, 12496.06279529057, 12930.288834114213, 13360.465581376637, 13415.128016678884, 13420.664483387993, 9270.119338662817, 9531.952340238255, 11061.723456913642, 11581.13141514373, 11661.0, 8968.86546701787, 11278.830817537908, 11432.113701239536, 11562.595309164475, 11617.8962998515, 9188.0, 11720.296782193096, 11815.113866459878, 11828.526858925547, 11959.282443053444, 7387.482876198615, 8175.773633954716, 8351.624176912175, 8482.872756908775, 8593.355498337774, 5912.615679980788, 7470.589117598584, 10981.450927453436, 11410.201285909907, 11762.470688818852], "value": 9889.446941448103, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.0769499825112, 1421.6354218420258, 1442.430240055179, 1457.2859298943515, 1475.3729664892421, 6617.86764264715, 7474.215207403226, 7727.188645192244, 8410.116937721543, 8417.537035463054, 7917.327027202695, 8226.17738226177, 8240.423170378075, 8316.168383161696, 8322.459040162385, 2291.5875142474347, 2320.907163713458, 2332.6151185054446, 2336.848104873178, 2347.94130146746, 2141.821997901156, 2297.357389466531, 2318.6637937499136, 2331.743508214095, 2334.949272827226, 2135.370065830576, 2284.7029886114783, 2298.609236429811, 2309.399556115414, 2311.4799170186657, 2175.7497887742875, 2198.6152423325852, 2198.7471440784357, 2203.4821816873023, 2227.2984010036785, 2303.7465878753324, 2329.7320808107115, 2330.9650355244685, 2334.8015418689433, 2348.3659411958674, 7966.0, 8095.150009249003, 8115.634796434175, 8116.228958248957, 8119.5940202990205, 5032.119379108642, 5181.740912954338, 5183.092958732244, 5187.481251874782, 5191.636585439021, 2803.215099772067, 3200.519922011694, 3257.364813861298, 3375.3586818504405, 3382.069930769034, 1246.0841281657974, 1261.344101067449, 1703.8839560088163, 1736.3401907275154, 1813.3743858368841, 10620.203484738675, 10649.25455218135, 10751.978562036673, 10763.300385474997, 10796.838047429217, 3881.922361552753, 4497.2129877271345, 4706.835260765857, 4711.929321060187, 4735.526447355306, 8552.44409113418, 8666.090060543553, 8700.564971751388, 8720.607572659246, 8721.651133954698, 5087.31321271631, 5113.948860511422, 5118.181091025418, 5120.411152717411, 5124.948750512522, 4225.57744225575, 4745.691530050535, 4775.594074503637, 4817.494163112893, 4818.084563932842, 3379.6312493440196, 3581.1405262737276, 3670.15586415126, 3708.9258214835554, 3759.6992240620684, 995.3331268050424, 1129.824877144044, 1162.442027826648, 1168.5851522709486, 1175.9118066145074, 915.4598786715736, 918.9494577798151, 940.6707652321633, 945.2910317262053, 950.8304785114348, 5499.065158922993, 5681.0, 5737.282839645044, 5898.498627616615, 5959.255093113361, 946.0066929723763, 949.8242825077383, 967.5141945170898, 980.3725615606018, 1006.3056491021172, 3678.301122786662, 3942.1918506706056, 3963.207358528306, 3964.273582832129, 3996.980015099964, 9706.126448619658, 9978.351407158512, 10019.348742331726, 10037.648682296085, 10042.497875106215, 9321.580528876217, 9682.75793105178, 9707.66023189185, 9722.2708296878, 9980.051895069957, 6420.357964203636, 6493.870122597522, 7454.925450745532, 7675.961620191975, 7719.610470115433, 5469.480399362054, 5488.4785945335125, 5501.339839219159, 5701.315842098975, 5814.76740930358, 9043.819123617359, 9171.862422063541, 9190.724278271662, 9234.353595248269, 9238.30712696564, 7068.939659051183, 7434.479586428898, 8279.668813247403, 8720.73837784842, 9355.251579873458, 7060.117485314336, 7262.164851041986, 7428.0, 7584.4311676625575, 7755.573443460664, 6557.377049180321, 7672.462927595015, 7794.142644309008, 7826.0, 7827.139014708264, 5907.5273978080795, 6009.338972713085, 6955.061066756126, 7026.578405295699, 7063.364297213276, 6792.592444453348, 6835.555688880207, 6852.417544508772, 6927.168739751262, 6935.514513983972, 6056.57603967718, 6080.452759251689, 6108.694565271808, 6173.536984726074, 6270.968645156745, 4817.78319975595, 4924.285978533164, 4937.185364414975, 4937.851864444073, 5048.444671086251, 4888.853334399973, 4975.4278258000795, 5015.899682006412, 5022.221555658879, 5041.42023667283], "value": 4363.548617180431, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.6753987641184, 362.8947605194494, 367.3057920530197, 368.7529355331927, 374.2328227134374, 4445.533219012002, 5375.3280839895015, 5435.33115147276, 5460.453954604547, 5487.7530511127, 4728.692634978724, 5264.886818371857, 5408.486193811592, 5411.4577345456555, 5418.2956215692, 3153.6057992750907, 3321.651226621206, 3875.7831000269707, 4071.2671719090554, 4217.599328063829, 3421.9981310933135, 3587.7488575799703, 4142.0, 4227.725197862144, 4285.100128972919, 4204.264253755596, 4214.346776249671, 4227.746335219881, 4227.978860105695, 4244.596763307481, 3725.217704282104, 3882.20481253497, 3938.7199160273026, 4063.8824323311032, 4201.033762234703, 3411.822921092219, 3434.6737059979264, 3718.851245950159, 3740.3454395480953, 3752.1182522107283, 4164.68764842638, 4575.542445755431, 5203.152880727363, 5276.947230527684, 5285.889963107752, 4253.787310634487, 4658.767061646904, 4704.200285951397, 4897.9959108383055, 4948.851534453972, 5746.25298711169, 5755.510781583571, 5819.73811178498, 5848.970755146197, 5922.58541902067, 4089.8364065437345, 4161.146964872194, 4168.416421700934, 4176.519700234481, 4187.848341706055, 5340.49265319794, 5564.749586268627, 5739.8565035874435, 5873.735681894325, 6153.230846144232, 4075.4950742361543, 4177.352510360899, 4192.245395828719, 4193.958060419357, 4197.265478541243, 3830.004198908304, 4075.959240407558, 4259.169461954951, 4272.786360681954, 4296.002358903125, 4314.417553630283, 4682.2508398656055, 4884.242942343943, 4947.158982972904, 4955.157623204064, 5553.472794981412, 5579.238277052885, 5650.802221922213, 5738.8278351649515, 5754.079347304411, 1228.834107395508, 1296.949470928551, 1326.4428939845288, 1383.2945197949118, 1397.6296281485415, 790.3440144679902, 880.7053631162181, 905.6694306578183, 927.7495076329488, 973.8441849304078, 4171.0, 4414.962483816301, 4468.503051477726, 4804.927926081112, 5095.299491269633, 4972.950270497321, 4978.203487442064, 5143.759431558095, 5311.575073994071, 5655.1799989001365, 4286.349755344153, 4490.955090449053, 4898.4121905371585, 4899.779509922062, 4989.981061457838, 2632.5919482480244, 2788.549954023896, 2908.5491748778973, 2932.5601159826197, 2945.6612489563972, 5625.174881080904, 5838.5037271831525, 5862.391235900069, 5870.683064068788, 5936.970315148396, 5002.247864528639, 5568.972155139199, 5575.275214222125, 5626.634268772516, 5838.86142202275, 5103.213875143744, 5874.882502349929, 5881.411858814085, 6072.696365181723, 6077.259737404831, 4822.590079843252, 5153.948460515347, 5430.723779911638, 5893.9115913260475, 5920.259967504062, 4866.1484240257105, 5513.42109078557, 5617.415988645269, 5791.739371728367, 5831.1836342912875, 6008.5493587981955, 6046.455818976313, 6069.575129740876, 6090.543209259412, 6116.3883611638485, 3440.8451619676675, 4144.461220041375, 4180.289350810431, 4188.308929026798, 4197.580241975778, 5286.273177409994, 5551.361593416647, 5664.915026274527, 5816.651000939788, 5841.824745257649, 4782.593479554138, 4837.782299796448, 4926.704397736147, 5007.474215207497, 5368.919466208088, 5478.220492200068, 5571.804986825442, 5660.632058916157, 5690.829275121753, 5736.365135936238, 4992.375953005874, 5153.484651534816, 5387.895481426376, 5558.805441809517, 5593.384727680033, 4660.720356778605, 5207.81772637971, 5450.427705091067, 5666.9716651416475, 5694.886102278014, 4676.275177347517, 5247.344081989751, 5288.259643649969, 5523.6409633373705, 5546.362168350692], "value": 4140.842006429606, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.2887112887113, 722.32823474169, 747.2676776758776, 826.6099094193931, 847.4322204123239, 12095.213811102272, 14492.130472171677, 14925.253737313118, 14961.925190374033, 14967.0, 14561.417543298281, 14601.707965840676, 14624.195669238188, 14651.340689668963, 14666.266686665651, 681.7681988124037, 703.9753608623699, 826.5949684654536, 852.9488230706162, 863.8272345530887, 853.7524118005771, 858.5449711652834, 867.6789587852472, 872.0451106038877, 872.2803686958254, 922.7047344849651, 925.6528801699362, 927.3276874266171, 928.8792456980583, 931.6878845586702, 742.8440027594212, 809.2918696140877, 920.5305294299922, 926.4163576946514, 927.7495076329353, 689.6517258784282, 692.435664933083, 693.4972145194744, 695.8573492434039, 697.104221075915, 12701.301428421433, 15270.770938435933, 15578.532644020697, 15794.842051579451, 15886.04683718969, 13841.100328478617, 14516.637084072983, 14622.195779232137, 14627.926860365737, 14776.772803877277, 11724.296542207494, 11864.644060678194, 11872.168948173632, 12041.277523348626, 12103.757924841542, 3700.9259814803822, 3726.2547490502006, 3739.1773809761808, 3746.138388170709, 3760.736748427611, 116636.0, 116678.0, 118941.40529297471, 119212.01969950733, 119679.0, 11122.165837562216, 11161.995420412202, 11248.0, 11266.943665281786, 11642.76714465706, 23385.649215261783, 23444.531109377716, 23451.537588553296, 23540.64689029666, 23612.881935590554, 6614.710131524403, 6849.212340580797, 7724.6137693115115, 7758.883616745753, 7915.960420197862, 4169.437125988014, 4215.620594146541, 4256.402321855473, 4264.189803937304, 4328.982689068066, 2987.4473222453917, 3493.703035242033, 3573.7677050992124, 3597.352476554245, 3597.6042635310096, 601.4977493792645, 618.6442795392667, 633.189517417706, 639.9536757401589, 655.2759201082735, 610.7587502936316, 612.3881644784996, 701.5650296816007, 707.3315716647821, 712.2948281201573, 10091.49542522871, 10232.795344093076, 10353.8859534345, 10388.272820902615, 10644.946775266075, 676.9864602707918, 684.2370756606448, 701.1516065560729, 724.5290561135304, 727.0185249912576, 5042.268871013755, 5058.3929928408825, 5068.56917162038, 5073.873153171126, 5088.6946783193125, 82253.58873205596, 103263.0, 104438.47780761047, 106195.0, 106627.0, 93229.0, 93667.53166234125, 94836.52581737048, 95270.0, 95721.0, 81956.59021704854, 81974.9999999988, 83750.0, 83906.0, 84090.57954710188, 9779.804403912025, 9799.069088436587, 9837.508124593885, 9845.655402060893, 9852.0, 44020.55979440165, 51840.74079629578, 52529.949401011, 52598.47401526089, 55278.0, 58407.66369345179, 71374.0, 71886.0, 72586.63706681432, 73820.63089684518, 10112.84830727525, 11846.229995050295, 12011.339376334385, 12220.75558488843, 12322.445489953154, 12760.29818359999, 12974.870251297367, 13078.346082696018, 13577.728445431234, 13613.455461781814, 12207.328596927255, 12260.26438413698, 12492.25046497213, 12578.622641320775, 12670.366481675695, 11918.40407979615, 12000.03999680006, 12090.758184836079, 12100.376087399236, 12410.069244806857, 11734.823977640513, 11876.0, 12101.81847272274, 12248.571300004458, 12515.0, 9925.305228634215, 10431.530581123716, 10523.684289471328, 10538.0, 10962.287451315638, 8853.380263381501, 9829.754256143367, 9840.950795246265, 9865.112139907444, 10049.447280399649], "value": 7448.380410472426, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1570.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 117.0, 118.0, 117.0, 118.0, 116.0, 113.0, 117.0, 119.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 113.0, 152.0, 116.0, 112.0, 136.0, 112.0, 133.0, 113.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 211.0, 208.0, 206.0, 197.0, 198.0, 192.0, 213.0, 243.0, 200.0], "value": 206.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 54.0, 77.0, 108.0, 83.0, 84.0, 52.0, 59.0, 77.0, 83.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 84.0, 89.0, 56.0, 75.0, 82.0, 64.0, 86.0, 112.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 87.0, 90.0, 102.0, 109.0, 87.0, 90.0, 88.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 137.0, 172.0, 160.0, 101.0, 130.0, 114.0, 174.0, 169.0], "value": 137.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 146.0, 148.0, 94.0, 89.0, 92.0, 99.0, 93.0, 120.0, 143.0], "value": 111.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 60.0, 61.0, 62.0, 52.0, 50.0, 63.0, 72.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 77.0, 83.0, 102.0, 105.0, 89.0, 74.0, 69.0, 73.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 106.0, 81.0, 90.0, 92.0, 92.0, 93.0, 87.0, 94.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 155.0, 158.0, 132.0, 142.0, 158.0, 141.0, 144.0, 190.0, 143.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 174.0, 175.0, 177.0, 174.0, 172.0, 177.0, 173.0, 181.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 69.0, 67.0, 67.0, 69.0, 69.0, 66.0], "value": 67.7, "unit": "score"}]}]} 12:24:19 INFO - Return code: 0 12:24:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:24:19 ERROR - # TBPL SUCCESS # 12:24:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:24:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:24:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:24:19 INFO - 2017-01-11 12:24:19.875 screenresolution[2579:28580] starting screenresolution argv=screenresolution get 12:24:19 INFO - 2017-01-11 12:24:19.877 screenresolution[2579:28580] Display 0: 1600x1200x32@60 12:24:19 INFO - 2017-01-11 12:24:19.883 screenresolution[2580:28583] starting screenresolution argv=screenresolution list 12:24:19 INFO - Available Modes on Display 0 12:24:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:24:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:24:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:24:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:24:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:24:20 INFO - Intel Iris: 12:24:20 INFO - Chipset Model: Intel Iris 12:24:20 INFO - Type: GPU 12:24:20 INFO - Bus: Built-In 12:24:20 INFO - VRAM (Dynamic, Max): 1536 MB 12:24:20 INFO - Vendor: Intel (0x8086) 12:24:20 INFO - Device ID: 0x0a2e 12:24:20 INFO - Revision ID: 0x0009 12:24:20 INFO - Displays: 12:24:20 INFO - Display: 12:24:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:24:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:24:20 INFO - Display Serial Number: 1600x1200 60 12:24:20 INFO - Main Display: Yes 12:24:20 INFO - Mirror: Off 12:24:20 INFO - Online: Yes 12:24:20 INFO - Rotation: Supported 12:24:20 INFO - Return code: 0 12:24:20 INFO - Running post-action listener: _resource_record_post_action 12:24:20 INFO - [mozharness: 2017-01-11 20:24:20.128451Z] Finished run-tests step (success) 12:24:20 INFO - Running post-run listener: _resource_record_post_run 12:24:20 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 11314176; Write bytes: 563819520; Read time: 138; Write time: 1763 12:24:20 INFO - TinderboxPrint: CPU usage
54.6% 12:24:20 INFO - TinderboxPrint: I/O read bytes / time
11,314,176 / 138 12:24:20 INFO - TinderboxPrint: I/O write bytes / time
563,819,520 / 1,763 12:24:20 INFO - TinderboxPrint: CPU idle
2,181.1 (45.4%) 12:24:20 INFO - TinderboxPrint: CPU system
972.6 (20.3%) 12:24:20 INFO - TinderboxPrint: CPU user
1,648.0 (34.3%) 12:24:20 INFO - TinderboxPrint: Swap in / out
254,234,624 / 0 12:24:20 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248668672; Write bytes: 244291584; Read time: 18144; Write time: 511 12:24:20 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 11314176; Write bytes: 308141056; Read time: 138; Write time: 1211 12:24:20 INFO - Running post-run listener: _upload_blobber_files 12:24:20 INFO - Blob upload gear active. 12:24:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:24: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 12:24: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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:24: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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:24:20 INFO - (blobuploader) - INFO - Open directory for files ... 12:24:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:24:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:24:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:24:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:24:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:24:22 INFO - (blobuploader) - INFO - Done attempting. 12:24:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:24:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:24:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:24:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:24:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:24:23 INFO - (blobuploader) - INFO - Done attempting. 12:24:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:24:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:24:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:24:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:24:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:24:24 INFO - (blobuploader) - INFO - Done attempting. 12:24:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:24:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:24:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:24:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:24:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:24:26 INFO - (blobuploader) - INFO - Done attempting. 12:24:26 INFO - (blobuploader) - INFO - Iteration through files over. 12:24:26 INFO - Return code: 0 12:24:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:24:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:24:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f3c580b9a7ba0672ece88b5dec11b5a7ba41e241d03c337dc736965268ba2f298d3a5221864340037401cf20dc859d71b1215f0d8b21297f804c8ea05ec235a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/823baadcab79013c41ab4d6a32b8e8f281e7d6df67b2dbd16b1eb46c9c8d86df8bcf895578c2893b29d21ee86cf17cf2585ecdd0f5fc9c0acaaeb2203572ad64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9bb0d7a9892cd7b08b63452d83bdba5831fa37e283e2bd22051636f725d5caabb3a9330c3c67fdb65d770a7dff0b82cad689f88ccb35642e9989ed0a97f61a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6c9587f7b312f7de08e6336b2c0e23844e90146555f4eae32a8a3fa901072b79534c760fe4f9d3b4ea69485e268db89601f15115334a39972b6c8bd3a6497ab3"} 12:24:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:24:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:24:26 INFO - Contents: 12:24:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f3c580b9a7ba0672ece88b5dec11b5a7ba41e241d03c337dc736965268ba2f298d3a5221864340037401cf20dc859d71b1215f0d8b21297f804c8ea05ec235a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/823baadcab79013c41ab4d6a32b8e8f281e7d6df67b2dbd16b1eb46c9c8d86df8bcf895578c2893b29d21ee86cf17cf2585ecdd0f5fc9c0acaaeb2203572ad64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9bb0d7a9892cd7b08b63452d83bdba5831fa37e283e2bd22051636f725d5caabb3a9330c3c67fdb65d770a7dff0b82cad689f88ccb35642e9989ed0a97f61a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6c9587f7b312f7de08e6336b2c0e23844e90146555f4eae32a8a3fa901072b79534c760fe4f9d3b4ea69485e268db89601f15115334a39972b6c8bd3a6497ab3"} 12:24:26 INFO - Running post-run listener: copy_logs_to_upload_dir 12:24:26 INFO - Copying logs to upload dir... 12:24:26 INFO - mkdir: /builds/slave/test/build/upload/logs 12:24:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.330541 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-11 12:24:26.788901) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 12:24:26.791904) ========= 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.0GJYw8c6g0/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.7AXVzYTajr/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f3c580b9a7ba0672ece88b5dec11b5a7ba41e241d03c337dc736965268ba2f298d3a5221864340037401cf20dc859d71b1215f0d8b21297f804c8ea05ec235a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/823baadcab79013c41ab4d6a32b8e8f281e7d6df67b2dbd16b1eb46c9c8d86df8bcf895578c2893b29d21ee86cf17cf2585ecdd0f5fc9c0acaaeb2203572ad64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9bb0d7a9892cd7b08b63452d83bdba5831fa37e283e2bd22051636f725d5caabb3a9330c3c67fdb65d770a7dff0b82cad689f88ccb35642e9989ed0a97f61a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6c9587f7b312f7de08e6336b2c0e23844e90146555f4eae32a8a3fa901072b79534c760fe4f9d3b4ea69485e268db89601f15115334a39972b6c8bd3a6497ab3"} build_url:https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011594 build_url: 'https://queue.taskcluster.net/v1/task/ZyJKe4jeTBitfCiAKkVyhA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f3c580b9a7ba0672ece88b5dec11b5a7ba41e241d03c337dc736965268ba2f298d3a5221864340037401cf20dc859d71b1215f0d8b21297f804c8ea05ec235a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/823baadcab79013c41ab4d6a32b8e8f281e7d6df67b2dbd16b1eb46c9c8d86df8bcf895578c2893b29d21ee86cf17cf2585ecdd0f5fc9c0acaaeb2203572ad64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9bb0d7a9892cd7b08b63452d83bdba5831fa37e283e2bd22051636f725d5caabb3a9330c3c67fdb65d770a7dff0b82cad689f88ccb35642e9989ed0a97f61a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6c9587f7b312f7de08e6336b2c0e23844e90146555f4eae32a8a3fa901072b79534c760fe4f9d3b4ea69485e268db89601f15115334a39972b6c8bd3a6497ab3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 12:24:26.826420) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:24:26.826739) ========= 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.0GJYw8c6g0/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.7AXVzYTajr/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.005611 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:24:26.874928) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 12:24:26.875212) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 12:24:26.875549) ========= ========= Total master_lag: 0.19 =========