builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0282 starttime: 1485866848.54 results: success (0) buildid: 20170131040708 builduid: 9d39540b3c04477b9b944dd8c0bec277 revision: adab5d5d0372d1a26685d6fbc59cdfc977ad76c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.542425) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.542840) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.543137) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.592463) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.592802) ========= 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.FFVhSTWPIy/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.hGapLZpspL/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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-31 04:47:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-01-31 04:47:28 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.179276 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.796754) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.797084) ========= 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.FFVhSTWPIy/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.hGapLZpspL/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.025474 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.845919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:28.846286) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev adab5d5d0372d1a26685d6fbc59cdfc977ad76c6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev adab5d5d0372d1a26685d6fbc59cdfc977ad76c6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FFVhSTWPIy/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.hGapLZpspL/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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-31 04:47:28,953 truncating revision to first 12 chars 2017-01-31 04:47:28,954 Setting DEBUG logging. 2017-01-31 04:47:28,954 attempt 1/10 2017-01-31 04:47:28,954 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/adab5d5d0372?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 04:47:29,634 unpacking tar archive at: mozilla-central-adab5d5d0372/testing/mozharness/ program finished with exit code 0 elapsedTime=0.952830 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:29.823177) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:29.823489) ========= script_repo_revision: adab5d5d0372d1a26685d6fbc59cdfc977ad76c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:29.834503) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:29.834819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 04:47:29.864367) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-31 04:47:29.864678) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FFVhSTWPIy/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.hGapLZpspL/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:47:30 INFO - MultiFileLogger online at 20170131 04:47:30 in /builds/slave/test 04:47:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:47:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:47:30 INFO - {'append_to_log': False, 04:47:30 INFO - 'base_work_dir': '/builds/slave/test', 04:47:30 INFO - 'blob_upload_branch': 'Firefox', 04:47:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:47:30 INFO - 'branch': 'Firefox', 04:47:30 INFO - 'buildbot_json_path': 'buildprops.json', 04:47:30 INFO - 'config_files': ('talos/mac_config.py',), 04:47:30 INFO - 'default_actions': ('clobber', 04:47:30 INFO - 'read-buildbot-config', 04:47:30 INFO - 'download-and-extract', 04:47:30 INFO - 'populate-webroot', 04:47:30 INFO - 'create-virtualenv', 04:47:30 INFO - 'install', 04:47:30 INFO - 'run-tests'), 04:47:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:47:30 INFO - 'download_minidump_stackwalk': True, 04:47:30 INFO - 'download_symbols': 'ondemand', 04:47:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:47:30 INFO - 'tooltool.py': '/tools/tooltool.py', 04:47:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:47:30 INFO - '/tools/misc-python/virtualenv.py')}, 04:47:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:47:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:47:30 INFO - 'installer_path': 'installer.exe', 04:47:30 INFO - 'log_level': 'info', 04:47:30 INFO - 'log_name': 'talos', 04:47:30 INFO - 'log_to_console': True, 04:47:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:47:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:47:30 INFO - 'opt_config_files': (), 04:47:30 INFO - 'pip_index': False, 04:47:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:30 INFO - 'cmd': ('bash', 04:47:30 INFO - '-c', 04:47:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:30 INFO - 'enabled': True, 04:47:30 INFO - 'halt_on_failure': False, 04:47:30 INFO - 'name': 'check_screen_resolution'},), 04:47:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:30 INFO - 'cmd': ('bash', 04:47:30 INFO - '-c', 04:47:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:30 INFO - 'enabled': True, 04:47:30 INFO - 'halt_on_failure': False, 04:47:30 INFO - 'name': 'check_screen_resolution'},), 04:47:30 INFO - 'run_cmd_checks_enabled': True, 04:47:30 INFO - 'sps_profile': False, 04:47:30 INFO - 'sps_profile_interval': 0, 04:47:30 INFO - 'suite': 'dromaeojs', 04:47:30 INFO - 'system_bits': '32', 04:47:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:47:30 INFO - 'title': 't-yosemite-r7-0282', 04:47:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:47:30 INFO - 'use_talos_json': True, 04:47:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:47:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:47:30 INFO - 'work_dir': 'build'} 04:47:30 INFO - [mozharness: 2017-01-31 12:47:30.075286Z] Running clobber step. 04:47:30 INFO - Running pre-action listener: _resource_record_pre_action 04:47:30 INFO - Running main action method: clobber 04:47:30 INFO - rmtree: /builds/slave/test/build 04:47:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:47:32 INFO - Running post-action listener: _resource_record_post_action 04:47:32 INFO - [mozharness: 2017-01-31 12:47:32.537253Z] Finished clobber step (success) 04:47:32 INFO - [mozharness: 2017-01-31 12:47:32.537377Z] Running read-buildbot-config step. 04:47:32 INFO - Running pre-action listener: _resource_record_pre_action 04:47:32 INFO - Running main action method: read_buildbot_config 04:47:32 INFO - Using buildbot properties: 04:47:32 INFO - { 04:47:32 INFO - "project": "", 04:47:32 INFO - "product": "firefox", 04:47:32 INFO - "script_repo_revision": "production", 04:47:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:47:32 INFO - "repository": "", 04:47:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:47:32 INFO - "buildid": "20170131040708", 04:47:32 INFO - "pgo_build": "False", 04:47:32 INFO - "basedir": "/builds/slave/test", 04:47:32 INFO - "buildnumber": 79, 04:47:32 INFO - "slavename": "t-yosemite-r7-0282", 04:47:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:47:32 INFO - "platform": "yosemite_r7", 04:47:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:47:32 INFO - "branch": "mozilla-central", 04:47:32 INFO - "repo_path": "mozilla-central", 04:47:32 INFO - "revision": "adab5d5d0372d1a26685d6fbc59cdfc977ad76c6", 04:47:32 INFO - "stage_platform": "macosx64", 04:47:32 INFO - "builduid": "9d39540b3c04477b9b944dd8c0bec277", 04:47:32 INFO - "slavebuilddir": "test" 04:47:32 INFO - } 04:47:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:47:32 INFO - Running post-action listener: _resource_record_post_action 04:47:32 INFO - [mozharness: 2017-01-31 12:47:32.540618Z] Finished read-buildbot-config step (success) 04:47:32 INFO - [mozharness: 2017-01-31 12:47:32.540751Z] Running download-and-extract step. 04:47:32 INFO - Running pre-action listener: _resource_record_pre_action 04:47:32 INFO - Running main action method: download_and_extract 04:47:32 INFO - mkdir: /builds/slave/test/build/tests 04:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:32 INFO - trying https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:47:32 INFO - Downloading https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:47:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 04:47:33 INFO - Downloaded 1199 bytes. 04:47:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:47:33 INFO - Using the following test package requirements: 04:47:33 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:47:33 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:47:33 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:47:33 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:47:33 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:47:33 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:47:33 INFO - u'jsshell-mac.zip'], 04:47:33 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:47:33 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:47:33 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:47:33 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:47:33 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:47:33 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:47:33 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:47:33 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:47:33 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:47:33 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:47:33 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:47:33 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:47:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:47:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:33 INFO - Fetch https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:47:34 INFO - Expected file size: 16368156 04:47:34 INFO - Obtained file size: 16368156 04:47:40 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:47:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:47:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:40 INFO - Fetch https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:47:41 INFO - Expected file size: 16368156 04:47:41 INFO - Obtained file size: 16368156 04:47:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:47:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:47:48 INFO - Fetch https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:47:49 INFO - Expected file size: 14403454 04:47:49 INFO - Obtained file size: 14403454 04:47:50 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')]} 04:47:50 INFO - trying https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:47:50 INFO - Downloading https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:47:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 04:47:53 INFO - Downloaded 70185255 bytes. 04:47:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:47:53 INFO - mkdir: /builds/slave/test/properties 04:47:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:53 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:53 INFO - Contents: 04:47:53 INFO - build_url:https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:47:54 INFO - Running post-action listener: _resource_record_post_action 04:47:54 INFO - Running post-action listener: set_extra_try_arguments 04:47:54 INFO - [mozharness: 2017-01-31 12:47:54.059690Z] Finished download-and-extract step (success) 04:47:54 INFO - [mozharness: 2017-01-31 12:47:54.059820Z] Running populate-webroot step. 04:47:54 INFO - Running pre-action listener: _resource_record_pre_action 04:47:54 INFO - Running main action method: populate_webroot 04:47:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:54 INFO - '--tppagecycles', 04:47:54 INFO - '7'], 04:47:54 INFO - 'tests': ['tsvgm']}}, 04:47:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:47:54 INFO - 'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:47:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:47:54 INFO - 'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['damp', 'tps']}, 04:47:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:47:54 INFO - 'tests': ['damp', 'tps']}, 04:47:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['dromaeo_dom']}, 04:47:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['basic_compositor_video']}, 04:47:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:47:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['a11yr', 04:47:54 INFO - 'ts_paint', 04:47:54 INFO - 'tpaint', 04:47:54 INFO - 'sessionrestore', 04:47:54 INFO - 'sessionrestore_no_auto_restore', 04:47:54 INFO - 'tabpaint']}, 04:47:54 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:54 INFO - 'ts_paint', 04:47:54 INFO - 'tpaint', 04:47:54 INFO - 'sessionrestore', 04:47:54 INFO - 'sessionrestore_no_auto_restore', 04:47:54 INFO - 'tabpaint']}, 04:47:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:54 INFO - 'ts_paint', 04:47:54 INFO - 'tpaint', 04:47:54 INFO - 'sessionrestore', 04:47:54 INFO - 'sessionrestore_no_auto_restore', 04:47:54 INFO - 'tabpaint']}, 04:47:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:54 INFO - 'ts_paint', 04:47:54 INFO - 'tpaint', 04:47:54 INFO - 'sessionrestore', 04:47:54 INFO - 'sessionrestore_no_auto_restore', 04:47:54 INFO - 'tabpaint']}, 04:47:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['a11yr', 04:47:54 INFO - 'ts_paint', 04:47:54 INFO - 'tpaint', 04:47:54 INFO - 'sessionrestore', 04:47:54 INFO - 'sessionrestore_no_auto_restore', 04:47:54 INFO - 'tabpaint']}, 04:47:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['a11yr', 04:47:54 INFO - 'ts_paint', 04:47:54 INFO - 'tpaint', 04:47:54 INFO - 'sessionrestore', 04:47:54 INFO - 'sessionrestore_no_auto_restore', 04:47:54 INFO - 'tabpaint']}, 04:47:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['tsvgx', 04:47:54 INFO - 'tsvgr_opacity', 04:47:54 INFO - 'tart', 04:47:54 INFO - 'tscrollx', 04:47:54 INFO - 'cart', 04:47:54 INFO - 'tsvg_static']}, 04:47:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:54 INFO - 'tsvgr_opacity', 04:47:54 INFO - 'tart', 04:47:54 INFO - 'tscrollx', 04:47:54 INFO - 'cart', 04:47:54 INFO - 'tsvg_static']}, 04:47:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:47:54 INFO - 'talos_options': ['--disable-e10s'], 04:47:54 INFO - 'tests': ['tp5o']}, 04:47:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:47:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:47:54 INFO - 'talos_options': ['--disable-e10s', 04:47:54 INFO - '--xperf_path', 04:47:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:54 INFO - 'tests': ['tp5n']}, 04:47:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:47:54 INFO - 'talos_options': ['--xperf_path', 04:47:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:54 INFO - 'tests': ['tp5n']}}, 04:47:54 INFO - 'talos.zip': {'path': '', 04:47:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:54 INFO - Running post-action listener: _resource_record_post_action 04:47:54 INFO - [mozharness: 2017-01-31 12:47:54.078106Z] Finished populate-webroot step (success) 04:47:54 INFO - [mozharness: 2017-01-31 12:47:54.078220Z] Running create-virtualenv step. 04:47:54 INFO - Running pre-action listener: _resource_record_pre_action 04:47:54 INFO - Running main action method: create_virtualenv 04:47:54 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:47:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:54 INFO - Using real prefix '/tools/python27' 04:47:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:54 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:57 INFO - Installing pip.................done. 04:47:57 INFO - Return code: 0 04:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:47:58 INFO - Reading from file tmpfile_stdout 04:47:58 INFO - Output received: 04:47:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:47:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:58 INFO - 'HOME': '/Users/cltbld', 04:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:58 INFO - 'LOGNAME': 'cltbld', 04:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:58 INFO - 'MOZ_NO_REMOTE': '1', 04:47:58 INFO - 'NO_EM_RESTART': '1', 04:47:58 INFO - 'PAGER': '/bin/cat', 04:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:58 INFO - 'PWD': '/builds/slave/test', 04:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:58 INFO - 'SHELL': '/bin/bash', 04:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:47:58 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:58 INFO - 'USER': 'cltbld', 04:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:58 INFO - 'XPC_FLAGS': '0x0', 04:47:58 INFO - 'XPC_SERVICE_NAME': '0', 04:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:47:58 INFO - Cleaning up... 04:47:58 INFO - Return code: 0 04:47:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:58 INFO - 'HOME': '/Users/cltbld', 04:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:58 INFO - 'LOGNAME': 'cltbld', 04:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:58 INFO - 'MOZ_NO_REMOTE': '1', 04:47:58 INFO - 'NO_EM_RESTART': '1', 04:47:58 INFO - 'PAGER': '/bin/cat', 04:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:58 INFO - 'PWD': '/builds/slave/test', 04:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:58 INFO - 'SHELL': '/bin/bash', 04:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:47:58 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:58 INFO - 'USER': 'cltbld', 04:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:58 INFO - 'XPC_FLAGS': '0x0', 04:47:58 INFO - 'XPC_SERVICE_NAME': '0', 04:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:58 INFO - Downloading/unpacking psutil>=3.1.1 04:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:48:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:02 INFO - Installing collected packages: psutil 04:48:02 INFO - Running setup.py install for psutil 04:48:02 INFO - building 'psutil._psutil_osx' extension 04:48:02 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 04:48:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:48:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:48:03 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 04:48:03 INFO - building 'psutil._psutil_posix' extension 04:48:03 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 04:48:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:48:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:48:03 INFO - ^ 04:48:03 INFO - 1 warning generated. 04:48:03 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 04:48:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:03 INFO - Successfully installed psutil 04:48:03 INFO - Cleaning up... 04:48:03 INFO - Return code: 0 04:48:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:48: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 mozsystemmonitor==0.3 04:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:03 INFO - 'HOME': '/Users/cltbld', 04:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:03 INFO - 'LOGNAME': 'cltbld', 04:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:03 INFO - 'MOZ_NO_REMOTE': '1', 04:48:03 INFO - 'NO_EM_RESTART': '1', 04:48:03 INFO - 'PAGER': '/bin/cat', 04:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:03 INFO - 'PWD': '/builds/slave/test', 04:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:03 INFO - 'SHELL': '/bin/bash', 04:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:03 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:03 INFO - 'USER': 'cltbld', 04:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:03 INFO - 'XPC_FLAGS': '0x0', 04:48:03 INFO - 'XPC_SERVICE_NAME': '0', 04:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:48:03 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 04:48:03 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 04:48:03 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 04:48:03 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 04:48:03 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 04:48:03 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 04:48:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:48:06 INFO - Installing collected packages: mozsystemmonitor 04:48:06 INFO - Running setup.py install for mozsystemmonitor 04:48:06 INFO - Successfully installed mozsystemmonitor 04:48:06 INFO - Cleaning up... 04:48:06 INFO - Return code: 0 04:48:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:06 INFO - 'HOME': '/Users/cltbld', 04:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:06 INFO - 'LOGNAME': 'cltbld', 04:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:06 INFO - 'MOZ_NO_REMOTE': '1', 04:48:06 INFO - 'NO_EM_RESTART': '1', 04:48:06 INFO - 'PAGER': '/bin/cat', 04:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:06 INFO - 'PWD': '/builds/slave/test', 04:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:06 INFO - 'SHELL': '/bin/bash', 04:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:06 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:06 INFO - 'USER': 'cltbld', 04:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:06 INFO - 'XPC_FLAGS': '0x0', 04:48:06 INFO - 'XPC_SERVICE_NAME': '0', 04:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:07 INFO - Downloading/unpacking jsonschema==2.5.1 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:48:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:10 INFO - Downloading functools32-3.2.3-2.tar.gz 04:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:48:10 INFO - warning: no files found matching '*.txt' 04:48:10 INFO - no previously-included directories found matching 'build' 04:48:10 INFO - no previously-included directories found matching 'dist' 04:48:10 INFO - no previously-included directories found matching '.git*' 04:48:10 INFO - Installing collected packages: jsonschema, functools32 04:48:10 INFO - Running setup.py install for functools32 04:48:10 INFO - warning: no files found matching '*.txt' 04:48:10 INFO - no previously-included directories found matching 'build' 04:48:10 INFO - no previously-included directories found matching 'dist' 04:48:10 INFO - no previously-included directories found matching '.git*' 04:48:10 INFO - Successfully installed jsonschema functools32 04:48:10 INFO - Cleaning up... 04:48:10 INFO - Return code: 0 04:48:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:48:10 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')]} 04:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:10 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')]} 04:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:10 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 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:48:10 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 04:48:10 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 04:48:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:10 INFO - 'HOME': '/Users/cltbld', 04:48:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:10 INFO - 'LOGNAME': 'cltbld', 04:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:10 INFO - 'MOZ_NO_REMOTE': '1', 04:48:10 INFO - 'NO_EM_RESTART': '1', 04:48:10 INFO - 'PAGER': '/bin/cat', 04:48:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:10 INFO - 'PWD': '/builds/slave/test', 04:48:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:10 INFO - 'SHELL': '/bin/bash', 04:48:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:10 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:10 INFO - 'USER': 'cltbld', 04:48:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:10 INFO - 'XPC_FLAGS': '0x0', 04:48:10 INFO - 'XPC_SERVICE_NAME': '0', 04:48:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:48:10 INFO - Cleaning up... 04:48:10 INFO - Return code: 0 04:48:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:48:10 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')]} 04:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:10 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')]} 04:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:10 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 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:48:10 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 04:48:10 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 04:48:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:10 INFO - 'HOME': '/Users/cltbld', 04:48:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:10 INFO - 'LOGNAME': 'cltbld', 04:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:10 INFO - 'MOZ_NO_REMOTE': '1', 04:48:10 INFO - 'NO_EM_RESTART': '1', 04:48:10 INFO - 'PAGER': '/bin/cat', 04:48:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:10 INFO - 'PWD': '/builds/slave/test', 04:48:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:10 INFO - 'SHELL': '/bin/bash', 04:48:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:10 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:10 INFO - 'USER': 'cltbld', 04:48:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:10 INFO - 'XPC_FLAGS': '0x0', 04:48:10 INFO - 'XPC_SERVICE_NAME': '0', 04:48:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:11 INFO - Downloading/unpacking blobuploader==1.2.4 04:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:13 INFO - Downloading blobuploader-1.2.4.tar.gz 04:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:48:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:48:13 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 04:48:13 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 04:48:13 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 04:48:13 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 04:48:13 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 04:48:13 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 04:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:48:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:48:14 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 04:48:14 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 04:48:14 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 04:48:14 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 04:48:14 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 04:48:14 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 04:48:14 INFO - Downloading docopt-0.6.1.tar.gz 04:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:48:15 INFO - Installing collected packages: blobuploader, requests, docopt 04:48:15 INFO - Running setup.py install for blobuploader 04:48:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:48:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:48:15 INFO - Running setup.py install for requests 04:48:15 INFO - Running setup.py install for docopt 04:48:15 INFO - Successfully installed blobuploader requests docopt 04:48:15 INFO - Cleaning up... 04:48:15 INFO - Return code: 0 04:48:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:15 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 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:48:15 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 04:48:15 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 04:48:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:15 INFO - 'HOME': '/Users/cltbld', 04:48:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:15 INFO - 'LOGNAME': 'cltbld', 04:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:15 INFO - 'MOZ_NO_REMOTE': '1', 04:48:15 INFO - 'NO_EM_RESTART': '1', 04:48:15 INFO - 'PAGER': '/bin/cat', 04:48:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:15 INFO - 'PWD': '/builds/slave/test', 04:48:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:15 INFO - 'SHELL': '/bin/bash', 04:48:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:15 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:15 INFO - 'USER': 'cltbld', 04:48:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:15 INFO - 'XPC_FLAGS': '0x0', 04:48:15 INFO - 'XPC_SERVICE_NAME': '0', 04:48:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:16 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-luiVa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:16 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-OpfHp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:16 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-5rOnNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:16 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-IqwJxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:16 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-r8IldX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:16 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-XLSy7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:16 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-LuzvhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:16 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-cYmazn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Ieal7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gsKRt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-4hD_Hu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-7EF7Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-qSbX1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-NQPEt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-UHBNpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gAizZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:17 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-wwsKdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:48:17 INFO - Running setup.py install for manifestparser 04:48:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:48:18 INFO - Running setup.py install for mozcrash 04:48:18 INFO - Running setup.py install for mozdebug 04:48:18 INFO - Running setup.py install for mozdevice 04:48:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:48:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:48:18 INFO - Running setup.py install for mozfile 04:48:18 INFO - Running setup.py install for mozhttpd 04:48:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:48:18 INFO - Running setup.py install for mozinfo 04:48:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Running setup.py install for mozInstall 04:48:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Running setup.py install for mozleak 04:48:19 INFO - Running setup.py install for mozlog 04:48:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Running setup.py install for moznetwork 04:48:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Running setup.py install for mozprocess 04:48:19 INFO - Running setup.py install for mozprofile 04:48:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:48:19 INFO - Running setup.py install for mozrunner 04:48:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Running setup.py install for mozscreenshot 04:48:20 INFO - Running setup.py install for moztest 04:48:20 INFO - Running setup.py install for mozversion 04:48:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:48:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:48:20 INFO - Cleaning up... 04:48:20 INFO - Return code: 0 04:48:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:20 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')]} 04:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:20 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')]} 04:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:20 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 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:48:20 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 04:48:20 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 04:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:20 INFO - 'HOME': '/Users/cltbld', 04:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:20 INFO - 'LOGNAME': 'cltbld', 04:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:20 INFO - 'MOZ_NO_REMOTE': '1', 04:48:20 INFO - 'NO_EM_RESTART': '1', 04:48:20 INFO - 'PAGER': '/bin/cat', 04:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:20 INFO - 'PWD': '/builds/slave/test', 04:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:20 INFO - 'SHELL': '/bin/bash', 04:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:20 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:20 INFO - 'USER': 'cltbld', 04:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:20 INFO - 'XPC_FLAGS': '0x0', 04:48:20 INFO - 'XPC_SERVICE_NAME': '0', 04:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:20 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-A9h9jN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:20 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)) 04:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:20 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-D0N4D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-CwXAQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-NzjZVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-1WYr24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-8Lin89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-_LS54r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-YhoDhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-wwJ6C3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-haD_C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:21 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)) 04:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:21 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Wd15ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gdGqg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-dpCuUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-g1jQMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-cZ_vpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-iR3RRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:22 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)) 04:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-_KUf4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:22 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)) 04:48:22 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)) 04:48:22 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)) 04:48:22 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)) 04:48:22 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)) 04:48:22 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)) 04:48:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:25 INFO - Downloading blessings-1.6.tar.gz 04:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:48:25 INFO - Installing collected packages: blessings 04:48:25 INFO - Running setup.py install for blessings 04:48:25 INFO - Successfully installed blessings 04:48:25 INFO - Cleaning up... 04:48:25 INFO - Return code: 0 04:48:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:25 INFO - Reading from file tmpfile_stdout 04:48:25 INFO - Current package versions: 04:48:25 INFO - blessings == 1.6 04:48:25 INFO - blobuploader == 1.2.4 04:48:25 INFO - docopt == 0.6.1 04:48:25 INFO - functools32 == 3.2.3-2 04:48:25 INFO - jsonschema == 2.5.1 04:48:25 INFO - manifestparser == 1.1 04:48:25 INFO - mozInstall == 1.12 04:48:25 INFO - mozcrash == 1.0 04:48:25 INFO - mozdebug == 0.1 04:48:25 INFO - mozdevice == 0.49 04:48:25 INFO - mozfile == 1.2 04:48:25 INFO - mozhttpd == 0.7 04:48:25 INFO - mozinfo == 0.9 04:48:25 INFO - mozleak == 0.1 04:48:25 INFO - mozlog == 3.4 04:48:25 INFO - moznetwork == 0.27 04:48:25 INFO - mozprocess == 0.24 04:48:25 INFO - mozprofile == 0.28 04:48:25 INFO - mozrunner == 6.13 04:48:25 INFO - mozscreenshot == 0.1 04:48:25 INFO - mozsystemmonitor == 0.3 04:48:25 INFO - moztest == 0.8 04:48:25 INFO - mozversion == 1.4 04:48:25 INFO - psutil == 3.1.1 04:48:25 INFO - requests == 1.2.3 04:48:25 INFO - wsgiref == 0.1.2 04:48:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:25 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')]} 04:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:25 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')]} 04:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:25 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 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:48:25 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 04:48:25 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 04:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:25 INFO - 'HOME': '/Users/cltbld', 04:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:25 INFO - 'LOGNAME': 'cltbld', 04:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:25 INFO - 'MOZ_NO_REMOTE': '1', 04:48:25 INFO - 'NO_EM_RESTART': '1', 04:48:25 INFO - 'PAGER': '/bin/cat', 04:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:25 INFO - 'PWD': '/builds/slave/test', 04:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:25 INFO - 'SHELL': '/bin/bash', 04:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:25 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:25 INFO - 'USER': 'cltbld', 04:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:25 INFO - 'XPC_FLAGS': '0x0', 04:48:25 INFO - 'XPC_SERVICE_NAME': '0', 04:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 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)) 04:48:26 INFO - Cleaning up... 04:48:26 INFO - Return code: 0 04:48:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:26 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 0x102281b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102284768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb440db8d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, '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.FFVhSTWPIy/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.hGapLZpspL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 04:48:26 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 04:48:26 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 04:48:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:26 INFO - 'HOME': '/Users/cltbld', 04:48:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:26 INFO - 'LOGNAME': 'cltbld', 04:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:26 INFO - 'MOZ_NO_REMOTE': '1', 04:48:26 INFO - 'NO_EM_RESTART': '1', 04:48:26 INFO - 'PAGER': '/bin/cat', 04:48:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:26 INFO - 'PWD': '/builds/slave/test', 04:48:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:26 INFO - 'SHELL': '/bin/bash', 04:48:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:26 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:26 INFO - 'USER': 'cltbld', 04:48:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:26 INFO - 'XPC_FLAGS': '0x0', 04:48:26 INFO - 'XPC_SERVICE_NAME': '0', 04:48:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:48:26 INFO - Cleaning up... 04:48:26 INFO - Return code: 0 04:48:26 INFO - Running post-action listener: _resource_record_post_action 04:48:26 INFO - Running post-action listener: _start_resource_monitoring 04:48:26 INFO - Starting resource monitoring. 04:48:26 INFO - [mozharness: 2017-01-31 12:48:26.545586Z] Finished create-virtualenv step (success) 04:48:26 INFO - [mozharness: 2017-01-31 12:48:26.546599Z] Running install step. 04:48:26 INFO - Running pre-action listener: _resource_record_pre_action 04:48:26 INFO - Running main action method: install 04:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:26 INFO - Reading from file tmpfile_stdout 04:48:26 INFO - Detecting whether we're running mozinstall >=1.0... 04:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:48:26 INFO - Reading from file tmpfile_stdout 04:48:26 INFO - Output received: 04:48:26 INFO - Usage: mozinstall [options] installer 04:48:26 INFO - Options: 04:48:26 INFO - -h, --help show this help message and exit 04:48:26 INFO - -d DEST, --destination=DEST 04:48:26 INFO - Directory to install application into. [default: 04:48:26 INFO - "/builds/slave/test"] 04:48:26 INFO - --app=APP Application being installed. [default: firefox] 04:48:26 INFO - mkdir: /builds/slave/test/build/application 04:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:48:49 INFO - Reading from file tmpfile_stdout 04:48:49 INFO - Output received: 04:48:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:48:49 INFO - Running post-action listener: _resource_record_post_action 04:48:49 INFO - [mozharness: 2017-01-31 12:48:49.160524Z] Finished install step (success) 04:48:49 INFO - [mozharness: 2017-01-31 12:48:49.160665Z] Running run-tests step. 04:48:49 INFO - Running pre-action listener: _resource_record_pre_action 04:48:49 INFO - Running main action method: run_tests 04:48:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:49 INFO - 2017-01-31 04:48:49.174 screenresolution[1627:7797] starting screenresolution argv=screenresolution get 04:48:49 INFO - 2017-01-31 04:48:49.177 screenresolution[1627:7797] Display 0: 1600x1200x32@60 04:48:49 INFO - 2017-01-31 04:48:49.183 screenresolution[1628:7799] starting screenresolution argv=screenresolution list 04:48:49 INFO - Available Modes on Display 0 04:48:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:49 INFO - Intel Iris: 04:48:49 INFO - Chipset Model: Intel Iris 04:48:49 INFO - Type: GPU 04:48:49 INFO - Bus: Built-In 04:48:49 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:49 INFO - Vendor: Intel (0x8086) 04:48:49 INFO - Device ID: 0x0a2e 04:48:49 INFO - Revision ID: 0x0009 04:48:49 INFO - Displays: 04:48:49 INFO - Display: 04:48:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:49 INFO - Display Serial Number: 1600x1200 60 04:48:49 INFO - Main Display: Yes 04:48:49 INFO - Mirror: Off 04:48:49 INFO - Online: Yes 04:48:49 INFO - Rotation: Supported 04:48:49 INFO - Return code: 0 04:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:49 INFO - Python 2.7.3 04:48:49 INFO - Return code: 0 04:48:49 INFO - grabbing minidump binary from tooltool 04:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:49 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 0x10205fd30>, '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 0x101f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102054be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:49 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 04:48:49 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 04:48:49 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 04:48:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:49 INFO - Return code: 0 04:48:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:48:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:48:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0282 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FFVhSTWPIy/Render', 04:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:49 INFO - 'HOME': '/Users/cltbld', 04:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:49 INFO - 'LOGNAME': 'cltbld', 04:48:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:49 INFO - 'MOZ_NO_REMOTE': '1', 04:48:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:49 INFO - 'NO_EM_RESTART': '1', 04:48:49 INFO - 'PAGER': '/bin/cat', 04:48:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:49 INFO - 'PWD': '/builds/slave/test', 04:48:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:49 INFO - 'SHELL': '/bin/bash', 04:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGapLZpspL/Listeners', 04:48:49 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 04:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:49 INFO - 'USER': 'cltbld', 04:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:49 INFO - 'XPC_FLAGS': '0x0', 04:48:49 INFO - 'XPC_SERVICE_NAME': '0', 04:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:48:49 INFO - mozversion application_buildid: 20170131040708 04:48:49 INFO - mozversion application_changeset: adab5d5d0372d1a26685d6fbc59cdfc977ad76c6 04:48:49 INFO - mozversion application_display_name: Nightly 04:48:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:49 INFO - mozversion application_name: Firefox 04:48:49 INFO - mozversion application_remotingname: firefox 04:48:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:48:49 INFO - mozversion application_vendor: Mozilla 04:48:49 INFO - mozversion application_version: 54.0a1 04:48:49 INFO - mozversion platform_buildid: 20170131040708 04:48:49 INFO - mozversion platform_changeset: adab5d5d0372d1a26685d6fbc59cdfc977ad76c6 04:48:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:48:49 INFO - mozversion platform_version: 54.0a1 04:48:49 INFO - using testdate: 1485866929 04:48:49 INFO - actual date: 1485866929 04:48:49 INFO - starting webserver on 'localhost:49243' 04:48:49 INFO - SUITE-START | Running 2 tests 04:48:49 INFO - TEST-START | dromaeo_css 04:48:49 INFO - operating with platform_type : mac_ 04:48:49 INFO - Initialising browser for dromaeo_css test... 04:48:49 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpf2PUs6/profile http://localhost:49243/getInfo.html) 04:48:51 INFO - PROCESS | 1638 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:48:52 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 04:48:52 INFO - PROCESS | 1638 | colorDepth:24 04:48:52 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 04:48:52 INFO - PROCESS | 1638 | __metrics 04:48:52 INFO - PROCESS | 1638 | 1485866932317 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:48:52 INFO - PROCESS | 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:48:52 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:48:53 INFO - TEST-INFO | 1638: exit 0 04:48:53 INFO - Browser initialized. 04:48:53 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:53 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpf2PUs6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:05 INFO - PROCESS | 1641 | RSS: Main: 236625920 04:49:05 INFO - PROCESS | 1641 | 04:52:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 04:52:10 INFO - PROCESS | 1641 | RSS: Main: 354803712 04:52:10 INFO - PROCESS | 1641 | 04:53:54 INFO - PROCESS | 1641 | ************************* 04:53:54 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 04:53:54 INFO - PROCESS | 1641 | 04:53:54 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:54 INFO - PROCESS | 1641 | Full stack: 04:53:54 INFO - PROCESS | 1641 | ************************* 04:55:13 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 04:55:14 INFO - PROCESS | 1641 | RSS: Main: 366915584 04:55:14 INFO - PROCESS | 1641 | 04:58:16 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 04:58:17 INFO - PROCESS | 1641 | RSS: Main: 343818240 04:58:17 INFO - PROCESS | 1641 | 05:01:19 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 05:01:20 INFO - PROCESS | 1641 | RSS: Main: 338235392 05:01:20 INFO - PROCESS | 1641 | 05:04:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 05:04:24 INFO - PROCESS | 1641 | RSS: Main: 325779456 05:04:24 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 05:07:27 INFO - PROCESS | 1641 | RSS: Main: 463470592 05:07:27 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | __start_tp_report 05:07:27 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 05:07:27 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 05:07:27 INFO - PROCESS | 1641 | |i|pagename|runs| 05:07:27 INFO - PROCESS | 1641 | |0;dojo.html;3246.464333384991;3257.153140183553;3259.0385836178334;3261.9184520387003;3318.6681331866816;5452.754626041828;5479.945200547993;5504.80733174339;5594.2447769551145;5626.240457538231;5481.588880833933;5502.862428439291;5571.777128914849;5576.9163462548095;5602.85992850179;5002.049610573998;5029.019341228462;5033.446320904697;5055.039542486934;5102.795888164469;5070.594352451813;5156.046131465672;5157.458466860982;5167.9224811627855;5188.221766734982;5009.998000399898;5097.3118628984985;5130.948690513084;5138.434772175057;5162.354705661793;5015.3730783652045;5040.899182016339;5051.318072060261;5079.136546787018;5125.410577783566;5025.899482010376;5058.140116180221;5062.519060689228;5079.23811428285;5103.974480127613;5511.476409741068;5535.391106978229;5561.721913904329;5565.415631358689;5596.356419011784;5924.2298501195;5988.371221021774;6000.519958403317;6051.818445446644;6082.908756368658;4454.465464144323;6157.138000679908;6221.129041934133;6224.657643829588;6233.470155036827;5668.801591944262;5675.914861277084;5688.374278829326;5689.715514224272;5734.197212390269;6073.635581865014;6204.100405441186;6273.37266273369;6287.559870808999;6289.465395441347;5771.105478650816;5806.274215723035;5817.796377126865;5904.645721256738;5908.675022873697;5906.497947674496;6110.050511076723;6114.945045103356;6118.010103775157;6124.055729405355;5856.092305692625;5920.437558431942;5950.9702451487465;5974.4324289192455;6060.151578778975;6101.16314281886;6103.223435583424;6108.77496446079;6118.811486836347;6119.085672178321;4699.4360676718325;4807.423109226846;4820.638452116105;4891.266310053449;4927.975360123177;4292.18448494785;4368.191884501377;4373.409589705413;4388.144311859221;4419.491758447755;5536.75084621193;5538.889222215533;5547.057000309957;5556.86107847299;5595.776168953278;6156.076588511759;6157.476614487819;6164.722587483484;6169.753209871645;6174.351693072163;5387.6228663993925;5427.945720542823;5441.374241962146;5443.809466668727;5533.501984821385;5200.713960732196;5242.266082748418;5262.684238945675;5271.973640131775;5306.018386598492;6212.968935155386;6250.499960003189;6258.749650013949;6275.466585340206;6278.215223097113;5343.011542864582;5810.94189058104;6009.06859436788;6055.939440605537;6086.6348019119;6214.720337584729;6230.15892854477;6276.121343012073;6297.023961286008;6339.524535659936;6095.299040610209;6096.725647345791;6111.663858487648;6122.204113465221;6126.172966649624;6067.393260673897;6097.054956481752;6180.845478862884;6189.938100618936;6212.06818977157;5636.718164091697;5744.253247077853;5746.195532625519;5804.100364443518;5811.186433899173;5766.711664416846;5786.9131963019745;5792.275965504312;5813.273340832396;5816.854578635568;5634.915476267777;5649.887002260014;5677;5689.402612725604;5690.317161940428;5844.581183009097;5936.673482958478;5958.374370691015;5975.850603734942;5978.730957106854;5776.884462310814;5782.537397008314;5787.971060144672;5797.4782269595935;5806.9419305806405 05:07:27 INFO - PROCESS | 1641 | |1;ext.html;483.550298222653;485.50963526837893;498.08601216767244;499.9700017998918;500.9774560144793;26804.86597567012;37932.62067379329;39759.60240397595;39843.80078099613;39884.40173397402;41116;41477;41497.58502414975;41516.792416037955;41541.37687934676;9094.590743416546;9316.8602470963;9323.06769323069;9323.487208203545;9331.253499720005;8289.088200297992;8498.235158835703;8506.702265420712;8592.05487396386;8601.311895048413;10941.398223097727;11222.494987725571;11230.213885028052;11344.659660210207;11412;11072.058874995635;11123.388213648246;11222.32666040032;11237.83143252852;11259.605913792979;8181.345492360656;8403.83192336156;10975.615853445091;11062.280951738192;11065.834012489819;31651;31948.680513194802;31994.840025799956;38377.6567820125;38958.415623765664;3923.1565213478953;3946.914598485411;3954.703397245218;3969.146633473788;3987.063040185554;29280.70719292801;29612.259693507836;29751.25621859471;29952;30240;3150.921226969344;3151.1176870476074;3167.0973772474736;3169.2710676544525;3267.2158681916408;484477;486227.56886215694;488183;489541;489952;56969.14546281809;57277;57294.42705572974;57348.71325643345;57440;49229.507704923206;52260.95478090493;59577;59682.70158649179;59829.40170598238;14257;14264.001519893507;14351;14814.333354999051;14836.70326593462;11336.886631133582;11467.31196128235;11470.36912969778;11480.655580332603;11497.885021149681;11628.767424651629;13323.267220302783;13622.455101795817;14707.338169782386;15284;5861.3845546217035;6056.7306443393645;6080.604760690541;6125.663088530085;6165.506759459233;803.586153131138;804.939629527776;846.0989046665281;860.8923884514436;867.1458613265928;28679.713202868123;28944.565831512544;29075.56386654202;29780.106596802132;29848.701512985026;903.8689390038408;1011.9747006324901;1071.603506702525;1072.6567498400436;1132.4960392625367;13013.414396351998;14096.295185240697;14981.250937453302;15296.847031529765;15336.386544538094;597664;616716;642229;642937;831354;46362;46643.766781165876;46825.531744682114;46841.828954275734;47102.82242943885;1353.282760137109;1364;1390.979135312971;1392.1160063359637;1392.958211253664;22209.66685499751;23201.651975220724;25134.497310054063;25907.740922591285;26571.601425979014;42340.576594233666;42398.788006059774;42583.787081064394;42614.78692606517;42695.5730442704;424229;462225;465445;466470;466688;22738.43153921165;22908.656370154793;22982.42543936415;23122.190723324602;23131.884340578188;33145;33869;38740.61259387483;39211.80394098011;39578.2084358317;31712.682873170972;32352.191195220308;33799.49300760441;34130.976070717916;34268;31817.681823181472;32325.676743232267;32860.34279314352;32912.1771955703;33818.830905845316;32372;32477.863274785268;32929.50605740868;32935.8472453463;33080.66919330776;28443.573346400233;30169.547456788605;30186;30382.10717348338;31531;22533.098676052774;23003.539929201655;23680;23722.644160337262;24256.63615045811 05:07:27 INFO - PROCESS | 1641 | |2;jquery.html;388.0918650358161;389.6921432068665;394.11324519830384;399.6902400639505;423.11568821163763;14395.712085758278;20490.590188196227;20643.9678016099;21264.4683882903;21289.99999999998;19569.21723131073;19727.802721972777;20839.687404688902;21278.255261065864;21324.8933755331;5599.356074051485;6013.639181649093;6067.120267561201;6082.635041897501;6083.908741368861;5814.331351894522;5828;5916;5933.110033494989;5938.970305148489;6082.635041897478;6205;6210;6210.09953556734;6214;5929.288485381739;6071.574989750719;6155.44600985909;6197.752089916398;6198.814035578917;5984.4613984741145;6001.939980600182;6007.849803754897;6024.1566180734735;6024.3072046714315;11227.887721122766;12343.703911089386;13171.934140329333;13529.052966292364;13628.182309061489;2606.387498937767;2606.543854825398;2612.4644447888136;2619.4630100829286;2634.7628713415693;9983.650572229937;9993.25050621199;10044.799104017951;10461.320014199053;10526.842097368546;1823.79026411963;1861.9069046547618;1886.1889387563342;1905.9332923347754;1908.6659834528903;121832.56334873341;128214;142153.8676919847;145128;148122;10970.341779493256;11533.653990380302;11755.706107347214;11791.764164716658;11942.044636429066;13579.66050848717;13645.317734113254;15470.303836327392;15492.845071549365;15526.689466210613;5687.488126068673;9760.414375137514;10343.844842327371;10605.893941060645;10701;7082.858342833115;8278.751637450887;8341.8331633367;8427.671069927344;8828.911710882938;9436.197923176469;10250.897491024994;10444.21668374875;10736.57053717858;10895.618653347095;3231.579894613685;3349.0790032741193;4085.4649054391325;4115.279826030493;4115.403266526337;817.6443247187492;843.7890527368158;846.3779122345071;867.4968518259394;883.1389395339493;20657.586848262952;21177.258795942376;21287.148514059572;21317.310191757788;21337;756.481809960179;860.1484530314943;874.7681864305964;882.3646974178581;888.9111088891189;10486.108680762221;11013.614523491638;11029.558817647365;11038.392888391216;11081.390523521284;660469;692938;706018;719439;730435;147340.84307049203;149439;149571.8249306367;149745;161242.38757612274;992.7319623701704;1004.6232662751469;1022.9028242316969;1024.5645600619707;1025.4411345816548;17676.911615442354;17948.82051179524;18378.72431913549;18410.90794546019;18418.079096044876;56392.718036409555;59255.40744592499;59764.701176495575;60125.39874601373;60416.395836041076;81655;86506.56746716225;87148.36348729525;87424.5628771852;88317.5584122101;8331.541765202972;8419.284360829153;8442.620082096197;8560.614772335135;8574.957125214334;11540.538378465104;12464.688382790502;12486.502699460327;12688.429020694275;12731.23612583248;10785.154913231529;10941.398223097487;11058.336499810037;11080.05819505351;11121.385549696219;8836.425632333878;10930.508127134139;11065.446727663424;11176;11322.490487928228;9321.86017209756;11228.87596415473;11251.549938002716;11453.828192576952;11466.025387842126;7476.5514069156025;8156.551389673625;8394.24451799341;8561.700340488302;8704.782380440287;10608.893911061099;11086.113110951266;11113.444327783416;11183.049440797624;11490.138239631893 05:07:27 INFO - PROCESS | 1641 | |3;mootools.html;1189.5004098278723;1384.3562743324353;1405.3675845869361;1405.3746082993066;1407.064302239011;7843.607819609026;8154.306883914859;8253.628586713598;8268.958655206723;8270.338372930166;7289.744858929938;7561.773146805591;7567.583782891938;7846.882296765553;7967.402444816647;2209.823214142865;2253.256425379621;2255.7744225577476;2260.9660855087186;2283.5889539882814;2244.7194100737406;2265.784750448713;2269.965950510744;2276.681264622954;2276.9089236430523;1627.4222650958916;1994.710766938793;2076.1280262289874;2079.8960051997415;2249.595072886888;2197.6923230747707;2262.468319944812;2267.1951457232776;2271.7841805028497;2272.6704627829035;2190.8028277454946;2255.3684968208927;2255.537614788964;2261.2311813983324;2267.7505474397803;8123.634436450374;8190.180981901822;8194.631241594143;8224.753257402288;8232.711855085103;4971.875703107415;5144.794208231666;5145.665531740425;5147;5148.510891465305;3195.2171717929255;3370.25854312051;3374.54443650109;3439.9828000859834;3448.586169659657;1137.8293256011582;1157.5774842182543;1202.4709127983658;1219.4573414830404;1224.687704635321;10704.678859634223;10722.678319650346;10727.356358618508;10736.838947415796;10737.731556711067;4256.914861702749;4597.609203217764;4649.325847752125;4659.184642687516;4678.485366609671;9151.63393464254;9371.390859594105;9375.953120234355;9378.812423751624;9414.340996130342;5197.480251974772;5200;5207.869803254949;5215.24378965048;5218.712970786567;4253.723507971972;4368.4321038264825;4414.757188354672;4641.303804429295;4691.413573303337;3682.9927689409337;3721.181340105227;3811.294910441577;3823.866164684223;3835.232953409329;1246.0841281657974;1252.117257333596;1252.918560293578;1261.5017068257987;1265.4495294546903;742.5136535569211;794.2295972906271;884.6992022712309;885.1325700813111;920.7836158502746;5457.672539647634;5500.6424582402815;5680.857978550569;5691.573132015115;5890.558208134407;845.6997765793178;854.478767951549;941.6939494664261;945.9763505912407;983.5672304186136;3314.0720598232533;3657.1040095176318;3691.1325838427947;3717.7769333839515;3756.1588637768673;9491.430514169171;9832.311738178401;9858.162056225301;9871.654492092886;9887.209023278121;9800.803983920425;9827.017298270115;9844.950775246221;9850.852237216448;9854.704358869245;7434.070741157356;7569.5079819811535;7571.242875712384;7714.382849372037;7767.572783496962;4885.316055752269;5423.023855706011;5468.28912241406;5546.223528705904;5590.105583106846;8947.642094316154;9039.367244292836;9089.727308180765;9167.908320916706;9206.493642849708;9051.954740226256;9125.498097604695;9294.72115836526;9384.718458446258;9394.530273486162;6539;6737.932620673927;6748.122744043243;7007.544509606859;7164.713411463483;7490.812729681802;7649.808754781175;7755.68977240904;7842.764717058498;7849.411294153076;6906.447484201352;6975.128108986377;7011.9649401754705;7037.366637002694;7107.786766397016;6869.313068693091;6886.793396198122;6895.620740859301;6917.654117294014;6933.7226510939;6086.939130608815;6264.498840092872;6345.619262844244;6400.807975760735;6405.359464053744;4894.412670479565;4927.753612319441;4974.801007959786;4974.925376119289;5040.218766091261;4285.485741711014;4923.876903077452;4961.900761984813;4980.676256043345;5029.74851257443 05:07:27 INFO - PROCESS | 1641 | |4;prototype.html;352.9682328590427;353.15595726214354;361.2738395824393;372.40229431762003;374.8425661222286;4420.823167073313;5317.441668624792;5357.678539287641;5361.946380536194;5362.8123015694455;3993.9800300998527;4963.602911767059;5109.386873575167;5250.513213691692;5276.889244302281;3485.738569607277;4108;4113.280175969208;4137.958620413788;4146.751194928299;3654.2775632154085;3896.47397601323;4138.861813001418;4170.97914510427;4182.971258939428;3916.2167566486673;4121.361189015707;4133.6693064554765;4146.668266538685;4159.708820382574;3477.7217822574385;4085.9182816343805;4099.221147981874;4117.176564687044;4169.270377683908;3179.72972297355;3617.5116359291687;3633.709303255733;3644.344018076745;3648.799316037617;5000.849974500771;5171.482851714836;5176.663516871391;5195;5217.608679349026;3879.573246942834;4302.462192225972;4546.40896683429;4590.908181836345;4610.838620648261;5638.787660652938;5645.745941432645;5653.943460565383;5656.434356564352;5690.886182276331;3844.2695887781233;3867.45855580219;3873.593272706353;3878.7672739635714;3903;5689.9146512802345;5884.235049443545;5944.881102377972;5956.583039187302;5978.581499295007;3886.7667939923695;3893.201893611803;3900.863469778601;3905.7851818149707;3915.8629447969183;3652.543432070991;3954.4661470701085;3959.544652365015;3971.0866500705;3976;3669.6513831185994;4393.51671316155;4404.2512772828695;4604.470485894098;4664.65015123867;4528.071745292207;4852.4662287148385;4897.461279259279;5430.307390974097;5647.887042259132;1074.978500429987;1158;1207.7077527046151;1270.0284282523692;1280.6030130659526;812.163471624228;833.820728543366;834.8789425533264;976.2921881635757;977.3402953006693;4205.600467955539;4704.788284527204;4937.061958227925;4940.888300132441;5011.473795251519;5048.116579598555;5371.677699338053;5485.50630443262;5566.276384070045;5595.677793715562;4499.415076040159;4711.7879695413785;4746;4770.8273997791775;4789.118309032154;2831.2497188245134;2911.966251980559;2913.956290655642;2924.722151395614;2929.8388588627404;4702.623790096784;4886.065074274937;5113.36372360841;5652;5769.096198254566;4458.732476051382;5293.153095504701;5477.945220547744;5530.557555395559;5646.6612003279115;4945.777440015136;4979.879527106445;5260.710660913686;5529.723513824292;5670.914936276042;4384.386185933908;4791.66354449224;5005.774740136775;5412.810551630674;5449.836504904859;4472.217361961579;4814.86850590104;5253.581532986149;5448.186269611208;5483.479069488392;5159.484051594811;5173;5594.356648985418;5749.540036796962;5790.392008839011;3217.0348895331776;3511.613722490473;3775.9616105571185;3802.94295585572;3897.279003384383;4677.555632214934;4710.8351207707565;5162.045021671003;5162.354705661793;5432.918506222489;4418.602325790565;4940.7035577865445;5004.898922237102;5206.55744261742;5331.933613277438;4628.60656844172;4721.561731971936;4927.581155601814;5200.323957885451;5259.368875734784;4373.113744928332;4665.953340466687;5036.974815125901;5113.897722045613;5183.637145399785;4793.760311984317;5320.8935821282585;5470.56235501151;5553.416891226525;5624.119357643213;5153.201253805666;5321.707306098202;5380.650257733391;5467.7266136692215;5501.5323697486165 05:07:27 INFO - PROCESS | 1641 | |5;yui.html;635.4662083849566;650.3723906430293;688.456119665464;717.3615482220824;718.1848601836916;14425.567232983001;14983.062969990178;15383.69232615347;15491.302891369887;15509.534713958572;14059.15645061297;14300;14396.352164152611;14494.637634059154;14532.927335363334;808.789714674186;822.7613991942329;824.4352618456346;825.5542007316042;829.1873963515758;658.3975662269019;681.2948598200863;682.3756263019332;809.5911564659868;823.074041703084;867.4231635962109;879.1120967822482;879.3317079019927;879.876817245583;882.5146169606722;875.7285241575069;875.7504111328273;875.934304927133;876.8334016536837;876.9736907892774;504.9192129259338;520.7396301849076;624.6564389585756;648.7437462202404;651.0559688451716;14751.18868462234;15269.923650381677;15283.694326113415;15285.61785955349;15292.694146117015;13483.595492135251;13577.524786632519;13650.590482285548;13752.587422377244;13866.653333666638;10354.896451035469;12433.10497833873;12638.368081595976;12671.556495522706;12902.870971290355;4304.698671092993;4376;4439.245328294198;4452.287633978548;4549.340345649895;109140.80054085149;113106.73786524306;113124;114577;115325;9054.592543335566;10832.241743078066;10845.403502807263;10925.125989920787;11346.94326528362;22220.555588888456;22229.333120006424;22237.332880013626;22306.21928232504;22344.88827555852;7493.213212612706;7526.887096693554;7683.769486915401;7739.380849532024;7777.92222077772;4168.520620128664;4183.163367326547;4219.493660760729;4226.028013556899;4358.934615980763;3158.4472717274384;3205.0865503331906;3241.692039256267;3244.4484437645656;3261.5107733840114;611.0559186057552;636.0649844728242;636.1729751323261;637.346719612401;637.4772686397203;573.1889376532243;583.8540364908773;591.3465620489297;591.963228637089;608.1546650156287;8850.955745221232;9507.904920950701;9611.807763844685;9657.372270802376;9876.408072284377;581.4592429041032;614.4285814192843;619.0775744141197;628.1959092361784;632.6615260835431;4288.378185163134;4623.653226008063;4623.930641040387;4630.884227894329;4640.69835460694;84523.30953380898;98525;109403;110059;110200.4489977545;92378;92945.5352723232;96659;97508;97707.51146244365;67389.6630516864;82332.17667823246;83738;84292.73560896592;84816.57591712002;8465.66137354499;9029.954850225708;9107.635694572144;9132.039528515257;9197.402168859002;49617.50382496129;49677.503224968736;50548;51719.741401292755;52200.738996306296;60809.695951519956;64714;64742;71362;73982;12770.74458510806;12824.294663793207;12824.328373114728;12964.962802975928;13025.609231723063;13971.441142354191;14049.859501405264;14131.293435327987;14537.41850325975;14905.25473726331;13342.93328533351;13380.130291531019;13486.20345497085;13551.86448135506;13638.659033524242;13459.596212113653;13576.524821631196;13639.931800340935;13692.246926419142;14157;13198.80201796993;13415.530456433979;13441.932790335986;13572.660683482596;13634.727305454035;8742.60658270392;11049.116070714485;11099.223054386439;11386.601468948547;11851.348175850411;10600;10701.678949631523;10722.303050301705;10725.088367488539;10842.945785271024 05:07:27 INFO - PROCESS | 1641 | __end_tp_report 05:07:27 INFO - PROCESS | 1641 | __start_cc_report 05:07:27 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1305 05:07:27 INFO - PROCESS | 1641 | __end_cc_report 05:07:27 INFO - PROCESS | 1641 | __startTimestamp1485868047555__endTimestamp 05:07:27 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | ------- Summary: start ------- 05:07:27 INFO - PROCESS | 1641 | Number of tests: 6 05:07:27 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5584.96 Median:5734.20 stddev:629.66 (11.0%) stddev-sans-first:604.28 05:07:27 INFO - PROCESS | 1641 | Values: 3246.5 3257.2 3259.0 3261.9 3318.7 5452.8 5479.9 5504.8 5594.2 5626.2 5481.6 5502.9 5571.8 5576.9 5602.9 5002.0 5029.0 5033.4 5055.0 5102.8 5070.6 5156.0 5157.5 5167.9 5188.2 5010.0 5097.3 5130.9 5138.4 5162.4 5015.4 5040.9 5051.3 5079.1 5125.4 5025.9 5058.1 5062.5 5079.2 5104.0 5511.5 5535.4 5561.7 5565.4 5596.4 5924.2 5988.4 6000.5 6051.8 6082.9 4454.5 6157.1 6221.1 6224.7 6233.5 5668.8 5675.9 5688.4 5689.7 5734.2 6073.6 6204.1 6273.4 6287.6 6289.5 5771.1 5806.3 5817.8 5904.6 5908.7 5906.5 6110.1 6114.9 6118.0 6124.1 5856.1 5920.4 5951.0 5974.4 6060.2 6101.2 6103.2 6108.8 6118.8 6119.1 4699.4 4807.4 4820.6 4891.3 4928.0 4292.2 4368.2 4373.4 4388.1 4419.5 5536.8 5538.9 5547.1 5556.9 5595.8 6156.1 6157.5 6164.7 6169.8 6174.4 5387.6 5427.9 5441.4 5443.8 5533.5 5200.7 5242.3 5262.7 5272.0 5306.0 6213.0 6250.5 6258.7 6275.5 6278.2 5343.0 5810.9 6009.1 6055.9 6086.6 6214.7 6230.2 6276.1 6297.0 6339.5 6095.3 6096.7 6111.7 6122.2 6126.2 6067.4 6097.1 6180.8 6189.9 6212.1 5636.7 5744.3 5746.2 5804.1 5811.2 5766.7 5786.9 5792.3 5813.3 5816.9 5634.9 5649.9 5677.0 5689.4 5690.3 5844.6 5936.7 5958.4 5975.9 5978.7 5776.9 5782.5 5788.0 5797.5 5806.9 05:07:27 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:65098.29 Median:33008.26 stddev:146689.48 (444.4%) stddev-sans-first:147020.76 05:07:27 INFO - PROCESS | 1641 | Values: 483.6 485.5 498.1 500.0 501.0 26804.9 37932.6 39759.6 39843.8 39884.4 41116.0 41477.0 41497.6 41516.8 41541.4 9094.6 9316.9 9323.1 9323.5 9331.3 8289.1 8498.2 8506.7 8592.1 8601.3 10941.4 11222.5 11230.2 11344.7 11412.0 11072.1 11123.4 11222.3 11237.8 11259.6 8181.3 8403.8 10975.6 11062.3 11065.8 31651.0 31948.7 31994.8 38377.7 38958.4 3923.2 3946.9 3954.7 3969.1 3987.1 29280.7 29612.3 29751.3 29952.0 30240.0 3150.9 3151.1 3167.1 3169.3 3267.2 484477.0 486227.6 488183.0 489541.0 489952.0 56969.1 57277.0 57294.4 57348.7 57440.0 49229.5 52261.0 59577.0 59682.7 59829.4 14257.0 14264.0 14351.0 14814.3 14836.7 11336.9 11467.3 11470.4 11480.7 11497.9 11628.8 13323.3 13622.5 14707.3 15284.0 5861.4 6056.7 6080.6 6125.7 6165.5 803.6 804.9 846.1 860.9 867.1 28679.7 28944.6 29075.6 29780.1 29848.7 903.9 1012.0 1071.6 1072.7 1132.5 13013.4 14096.3 14981.3 15296.8 15336.4 597664.0 616716.0 642229.0 642937.0 831354.0 46362.0 46643.8 46825.5 46841.8 47102.8 1353.3 1364.0 1391.0 1392.1 1393.0 22209.7 23201.7 25134.5 25907.7 26571.6 42340.6 42398.8 42583.8 42614.8 42695.6 424229.0 462225.0 465445.0 466470.0 466688.0 22738.4 22908.7 22982.4 23122.2 23131.9 33145.0 33869.0 38740.6 39211.8 39578.2 31712.7 32352.2 33799.5 34131.0 34268.0 31817.7 32325.7 32860.3 32912.2 33818.8 32372.0 32477.9 32929.5 32935.8 33080.7 28443.6 30169.5 30186.0 30382.1 31531.0 22533.1 23003.5 23680.0 23722.6 24256.6 05:07:27 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:39385.66 Median:21330.95 stddev:117437.90 (550.6%) stddev-sans-first:117730.83 05:07:27 INFO - PROCESS | 1641 | Values: 388.1 389.7 394.1 399.7 423.1 14395.7 20490.6 20644.0 21264.5 21290.0 19569.2 19727.8 20839.7 21278.3 21324.9 5599.4 6013.6 6067.1 6082.6 6083.9 5814.3 5828.0 5916.0 5933.1 5939.0 6082.6 6205.0 6210.0 6210.1 6214.0 5929.3 6071.6 6155.4 6197.8 6198.8 5984.5 6001.9 6007.8 6024.2 6024.3 11227.9 12343.7 13171.9 13529.1 13628.2 2606.4 2606.5 2612.5 2619.5 2634.8 9983.7 9993.3 10044.8 10461.3 10526.8 1823.8 1861.9 1886.2 1905.9 1908.7 121832.6 128214.0 142153.9 145128.0 148122.0 10970.3 11533.7 11755.7 11791.8 11942.0 13579.7 13645.3 15470.3 15492.8 15526.7 5687.5 9760.4 10343.8 10605.9 10701.0 7082.9 8278.8 8341.8 8427.7 8828.9 9436.2 10250.9 10444.2 10736.6 10895.6 3231.6 3349.1 4085.5 4115.3 4115.4 817.6 843.8 846.4 867.5 883.1 20657.6 21177.3 21287.1 21317.3 21337.0 756.5 860.1 874.8 882.4 888.9 10486.1 11013.6 11029.6 11038.4 11081.4 660469.0 692938.0 706018.0 719439.0 730435.0 147340.8 149439.0 149571.8 149745.0 161242.4 992.7 1004.6 1022.9 1024.6 1025.4 17676.9 17948.8 18378.7 18410.9 18418.1 56392.7 59255.4 59764.7 60125.4 60416.4 81655.0 86506.6 87148.4 87424.6 88317.6 8331.5 8419.3 8442.6 8560.6 8575.0 11540.5 12464.7 12486.5 12688.4 12731.2 10785.2 10941.4 11058.3 11080.1 11121.4 8836.4 10930.5 11065.4 11176.0 11322.5 9321.9 11228.9 11251.5 11453.8 11466.0 7476.6 8156.6 8394.2 8561.7 8704.8 10608.9 11086.1 11113.4 11183.0 11490.1 05:07:27 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5331.86 Median:5320.87 stddev:2920.98 (54.9%) stddev-sans-first:2912.58 05:07:27 INFO - PROCESS | 1641 | Values: 1189.5 1384.4 1405.4 1405.4 1407.1 7843.6 8154.3 8253.6 8269.0 8270.3 7289.7 7561.8 7567.6 7846.9 7967.4 2209.8 2253.3 2255.8 2261.0 2283.6 2244.7 2265.8 2270.0 2276.7 2276.9 1627.4 1994.7 2076.1 2079.9 2249.6 2197.7 2262.5 2267.2 2271.8 2272.7 2190.8 2255.4 2255.5 2261.2 2267.8 8123.6 8190.2 8194.6 8224.8 8232.7 4971.9 5144.8 5145.7 5147.0 5148.5 3195.2 3370.3 3374.5 3440.0 3448.6 1137.8 1157.6 1202.5 1219.5 1224.7 10704.7 10722.7 10727.4 10736.8 10737.7 4256.9 4597.6 4649.3 4659.2 4678.5 9151.6 9371.4 9376.0 9378.8 9414.3 5197.5 5200.0 5207.9 5215.2 5218.7 4253.7 4368.4 4414.8 4641.3 4691.4 3683.0 3721.2 3811.3 3823.9 3835.2 1246.1 1252.1 1252.9 1261.5 1265.4 742.5 794.2 884.7 885.1 920.8 5457.7 5500.6 5680.9 5691.6 5890.6 845.7 854.5 941.7 946.0 983.6 3314.1 3657.1 3691.1 3717.8 3756.2 9491.4 9832.3 9858.2 9871.7 9887.2 9800.8 9827.0 9845.0 9850.9 9854.7 7434.1 7569.5 7571.2 7714.4 7767.6 4885.3 5423.0 5468.3 5546.2 5590.1 8947.6 9039.4 9089.7 9167.9 9206.5 9052.0 9125.5 9294.7 9384.7 9394.5 6539.0 6737.9 6748.1 7007.5 7164.7 7490.8 7649.8 7755.7 7842.8 7849.4 6906.4 6975.1 7012.0 7037.4 7107.8 6869.3 6886.8 6895.6 6917.7 6933.7 6086.9 6264.5 6345.6 6400.8 6405.4 4894.4 4927.8 4974.8 4974.9 5040.2 4285.5 4923.9 4961.9 4980.7 5029.7 05:07:27 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4380.55 Median:4790.39 stddev:1300.44 (27.1%) stddev-sans-first:1268.47 05:07:27 INFO - PROCESS | 1641 | Values: 353.0 353.2 361.3 372.4 374.8 4420.8 5317.4 5357.7 5361.9 5362.8 3994.0 4963.6 5109.4 5250.5 5276.9 3485.7 4108.0 4113.3 4138.0 4146.8 3654.3 3896.5 4138.9 4171.0 4183.0 3916.2 4121.4 4133.7 4146.7 4159.7 3477.7 4085.9 4099.2 4117.2 4169.3 3179.7 3617.5 3633.7 3644.3 3648.8 5000.8 5171.5 5176.7 5195.0 5217.6 3879.6 4302.5 4546.4 4590.9 4610.8 5638.8 5645.7 5653.9 5656.4 5690.9 3844.3 3867.5 3873.6 3878.8 3903.0 5689.9 5884.2 5944.9 5956.6 5978.6 3886.8 3893.2 3900.9 3905.8 3915.9 3652.5 3954.5 3959.5 3971.1 3976.0 3669.7 4393.5 4404.3 4604.5 4664.7 4528.1 4852.5 4897.5 5430.3 5647.9 1075.0 1158.0 1207.7 1270.0 1280.6 812.2 833.8 834.9 976.3 977.3 4205.6 4704.8 4937.1 4940.9 5011.5 5048.1 5371.7 5485.5 5566.3 5595.7 4499.4 4711.8 4746.0 4770.8 4789.1 2831.2 2912.0 2914.0 2924.7 2929.8 4702.6 4886.1 5113.4 5652.0 5769.1 4458.7 5293.2 5477.9 5530.6 5646.7 4945.8 4979.9 5260.7 5529.7 5670.9 4384.4 4791.7 5005.8 5412.8 5449.8 4472.2 4814.9 5253.6 5448.2 5483.5 5159.5 5173.0 5594.4 5749.5 5790.4 3217.0 3511.6 3776.0 3802.9 3897.3 4677.6 4710.8 5162.0 5162.4 5432.9 4418.6 4940.7 5004.9 5206.6 5331.9 4628.6 4721.6 4927.6 5200.3 5259.4 4373.1 4666.0 5037.0 5113.9 5183.6 4793.8 5320.9 5470.6 5553.4 5624.1 5153.2 5321.7 5380.7 5467.7 5501.5 05:07:27 INFO - PROCESS | 1641 | 05:07:27 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20867.25 Median:4500.81 stddev:30608.90 (680.1%) stddev-sans-first:30657.06 05:07:27 INFO - PROCESS | 1641 | Values: 635.5 650.4 688.5 717.4 718.2 14425.6 14983.1 15383.7 15491.3 15509.5 14059.2 14300.0 14396.4 14494.6 14532.9 808.8 822.8 824.4 825.6 829.2 658.4 681.3 682.4 809.6 823.1 867.4 879.1 879.3 879.9 882.5 875.7 875.8 875.9 876.8 877.0 504.9 520.7 624.7 648.7 651.1 14751.2 15269.9 15283.7 15285.6 15292.7 13483.6 13577.5 13650.6 13752.6 13866.7 10354.9 12433.1 12638.4 12671.6 12902.9 4304.7 4376.0 4439.2 4452.3 4549.3 109140.8 113106.7 113124.0 114577.0 115325.0 9054.6 10832.2 10845.4 10925.1 11346.9 22220.6 22229.3 22237.3 22306.2 22344.9 7493.2 7526.9 7683.8 7739.4 7777.9 4168.5 4183.2 4219.5 4226.0 4358.9 3158.4 3205.1 3241.7 3244.4 3261.5 611.1 636.1 636.2 637.3 637.5 573.2 583.9 591.3 592.0 608.2 8851.0 9507.9 9611.8 9657.4 9876.4 581.5 614.4 619.1 628.2 632.7 4288.4 4623.7 4623.9 4630.9 4640.7 84523.3 98525.0 109403.0 110059.0 110200.4 92378.0 92945.5 96659.0 97508.0 97707.5 67389.7 82332.2 83738.0 84292.7 84816.6 8465.7 9030.0 9107.6 9132.0 9197.4 49617.5 49677.5 50548.0 51719.7 52200.7 60809.7 64714.0 64742.0 71362.0 73982.0 12770.7 12824.3 12824.3 12965.0 13025.6 13971.4 14049.9 14131.3 14537.4 14905.3 13342.9 13380.1 13486.2 13551.9 13638.7 13459.6 13576.5 13639.9 13692.2 14157.0 13198.8 13415.5 13441.9 13572.7 13634.7 8742.6 11049.1 11099.2 11386.6 11851.3 10600.0 10701.7 10722.3 10725.1 10842.9 05:07:27 INFO - PROCESS | 1641 | -------- Summary: end -------- 05:07:27 INFO - PROCESS | 1641 | 05:07:28 INFO - TEST-INFO | 1641: exit 0 05:07:28 INFO - TEST-OK | dromaeo_css | took 1118438ms 05:07:28 INFO - TEST-START | kraken 05:07:28 INFO - operating with platform_type : mac_ 05:07:28 INFO - Initialising browser for kraken test... 05:07:28 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpD40LNE/profile http://localhost:49243/getInfo.html) 05:07:30 INFO - PROCESS | 1647 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 05:07:30 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 05:07:30 INFO - PROCESS | 1647 | colorDepth:24 05:07:30 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 05:07:30 INFO - PROCESS | 1647 | __metrics 05:07:30 INFO - PROCESS | 1647 | 1485868050468 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:07:30 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:07:30 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:31 INFO - TEST-INFO | 1647: exit 0 05:07:31 INFO - Browser initialized. 05:07:31 INFO - Running cycle 1/1 for kraken test... 05:07:31 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpD40LNE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:43 INFO - PROCESS | 1648 | RSS: Main: 228896768 05:07:43 INFO - PROCESS | 1648 | 05:07:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:46 INFO - PROCESS | 1648 | RSS: Main: 288923648 05:07:46 INFO - PROCESS | 1648 | 05:07:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 05:07:50 INFO - PROCESS | 1648 | RSS: Main: 291520512 05:07:50 INFO - PROCESS | 1648 | 05:07:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 05:07:54 INFO - PROCESS | 1648 | RSS: Main: 382758912 05:07:54 INFO - PROCESS | 1648 | 05:07:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 05:07:56 INFO - PROCESS | 1648 | RSS: Main: 284188672 05:07:56 INFO - PROCESS | 1648 | 05:07:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:59 INFO - PROCESS | 1648 | RSS: Main: 288350208 05:07:59 INFO - PROCESS | 1648 | 05:08:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 05:08:02 INFO - PROCESS | 1648 | RSS: Main: 329375744 05:08:02 INFO - PROCESS | 1648 | 05:08:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 05:08:06 INFO - PROCESS | 1648 | RSS: Main: 331763712 05:08:06 INFO - PROCESS | 1648 | 05:08:09 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 05:08:09 INFO - PROCESS | 1648 | RSS: Main: 330043392 05:08:09 INFO - PROCESS | 1648 | 05:08:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:08:11 INFO - PROCESS | 1648 | RSS: Main: 304590848 05:08:11 INFO - PROCESS | 1648 | 05:08:13 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:08:14 INFO - PROCESS | 1648 | RSS: Main: 309469184 05:08:14 INFO - PROCESS | 1648 | 05:08:16 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:08:17 INFO - PROCESS | 1648 | RSS: Main: 311336960 05:08:17 INFO - PROCESS | 1648 | 05:08:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:08:20 INFO - PROCESS | 1648 | RSS: Main: 328679424 05:08:20 INFO - PROCESS | 1648 | 05:08:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:08:24 INFO - PROCESS | 1648 | RSS: Main: 322842624 05:08:24 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 05:08:26 INFO - PROCESS | 1648 | RSS: Main: 367923200 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | __start_tp_report 05:08:26 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 05:08:26 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 05:08:26 INFO - PROCESS | 1648 | |i|pagename|runs| 05:08:26 INFO - PROCESS | 1648 | |0;ai-astar;112;114;114;114;114;114;114;114;114;115 05:08:26 INFO - PROCESS | 1648 | |1;audio-beat-detection;138;111;110;147;112;113;109;111;129;109 05:08:26 INFO - PROCESS | 1648 | |2;audio-dft;185;183;183;182;185;184;197;249;182;181 05:08:26 INFO - PROCESS | 1648 | |3;audio-fft;78;60;59;86;61;59;57;59;74;58 05:08:26 INFO - PROCESS | 1648 | |4;audio-oscillator;90;58;82;83;97;99;104;69;70;84 05:08:26 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;90;86;90;87;90;86;90;88;90;87 05:08:26 INFO - PROCESS | 1648 | |6;imaging-darkroom;95;95;95;94;95;95;95;95;95;94 05:08:26 INFO - PROCESS | 1648 | |7;imaging-desaturate;87;87;87;87;88;86;84;89;85;86 05:08:26 INFO - PROCESS | 1648 | |8;json-parse-financial;60;58;61;59;61;60;60;59;59;60 05:08:26 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;66;78;66;77;72;70;69;70;75;101 05:08:26 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;87;87;87;86;86;86;87;87;85;84 05:08:26 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;144;142;150;139;167;153;139;138;178;156 05:08:26 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;174;176;175;176;176;179;174;174;175;175 05:08:26 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;68;69;69;68;69;67;68;65;68;67 05:08:26 INFO - PROCESS | 1648 | __end_tp_report 05:08:26 INFO - PROCESS | 1648 | __start_cc_report 05:08:26 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1958 05:08:26 INFO - PROCESS | 1648 | __end_cc_report 05:08:26 INFO - PROCESS | 1648 | __startTimestamp1485868106528__endTimestamp 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | ------- Summary: start ------- 05:08:26 INFO - PROCESS | 1648 | Number of tests: 14 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.33 05:08:26 INFO - PROCESS | 1648 | Values: 112.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:118.90 Median:112.50 stddev:13.90 (12.4%) stddev-sans-first:12.91 05:08:26 INFO - PROCESS | 1648 | Values: 138.0 111.0 110.0 147.0 112.0 113.0 109.0 111.0 129.0 109.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:191.10 Median:184.50 stddev:20.85 (11.3%) stddev-sans-first:21.99 05:08:26 INFO - PROCESS | 1648 | Values: 185.0 183.0 183.0 182.0 185.0 184.0 197.0 249.0 182.0 181.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:65.10 Median:60.50 stddev:10.29 (17.0%) stddev-sans-first:9.80 05:08:26 INFO - PROCESS | 1648 | Values: 78.0 60.0 59.0 86.0 61.0 59.0 57.0 59.0 74.0 58.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:83.50 stddev:14.65 (17.5%) stddev-sans-first:15.35 05:08:26 INFO - PROCESS | 1648 | Values: 90.0 58.0 82.0 83.0 97.0 99.0 104.0 69.0 70.0 84.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:90.00 stddev:1.78 (2.0%) stddev-sans-first:1.79 05:08:26 INFO - PROCESS | 1648 | Values: 90.0 86.0 90.0 87.0 90.0 86.0 90.0 88.0 90.0 87.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:08:26 INFO - PROCESS | 1648 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:86.60 Median:87.00 stddev:1.43 (1.6%) stddev-sans-first:1.51 05:08:26 INFO - PROCESS | 1648 | Values: 87.0 87.0 87.0 87.0 88.0 86.0 84.0 89.0 85.0 86.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:60.00 stddev:0.95 (1.6%) stddev-sans-first:1.00 05:08:26 INFO - PROCESS | 1648 | Values: 60.0 58.0 61.0 59.0 61.0 60.0 60.0 59.0 59.0 60.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:71.00 stddev:10.23 (14.4%) stddev-sans-first:10.39 05:08:26 INFO - PROCESS | 1648 | Values: 66.0 78.0 66.0 77.0 72.0 70.0 69.0 70.0 75.0 101.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:87.00 stddev:1.03 (1.2%) stddev-sans-first:1.05 05:08:26 INFO - PROCESS | 1648 | Values: 87.0 87.0 87.0 86.0 86.0 86.0 87.0 87.0 85.0 84.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:151.50 stddev:13.33 (8.8%) stddev-sans-first:13.93 05:08:26 INFO - PROCESS | 1648 | Values: 144.0 142.0 150.0 139.0 167.0 153.0 139.0 138.0 178.0 156.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:175.50 stddev:1.51 (0.9%) stddev-sans-first:1.51 05:08:26 INFO - PROCESS | 1648 | Values: 174.0 176.0 175.0 176.0 176.0 179.0 174.0 174.0 175.0 175.0 05:08:26 INFO - PROCESS | 1648 | 05:08:26 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 05:08:26 INFO - PROCESS | 1648 | Values: 68.0 69.0 69.0 68.0 69.0 67.0 68.0 65.0 68.0 67.0 05:08:26 INFO - PROCESS | 1648 | -------- Summary: end -------- 05:08:26 INFO - PROCESS | 1648 | 05:08:27 INFO - TEST-INFO | 1648: exit 0 05:08:27 INFO - TEST-OK | kraken | took 59249ms 05:08:27 INFO - SUITE-END | took 1177s 05:08:27 INFO - Completed test suite (00:19:37) 05:08:27 INFO - javascript benchmark 05:08:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7116.642300867755, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3246.464333384991, 3257.153140183553, 3259.0385836178334, 3261.9184520387003, 3318.6681331866816, 5452.754626041828, 5479.945200547993, 5504.80733174339, 5594.2447769551145, 5626.240457538231, 5481.588880833933, 5502.862428439291, 5571.777128914849, 5576.9163462548095, 5602.85992850179, 5002.049610573998, 5029.019341228462, 5033.446320904697, 5055.039542486934, 5102.795888164469, 5070.594352451813, 5156.046131465672, 5157.458466860982, 5167.9224811627855, 5188.221766734982, 5009.998000399898, 5097.3118628984985, 5130.948690513084, 5138.434772175057, 5162.354705661793, 5015.3730783652045, 5040.899182016339, 5051.318072060261, 5079.136546787018, 5125.410577783566, 5025.899482010376, 5058.140116180221, 5062.519060689228, 5079.23811428285, 5103.974480127613, 5511.476409741068, 5535.391106978229, 5561.721913904329, 5565.415631358689, 5596.356419011784, 5924.2298501195, 5988.371221021774, 6000.519958403317, 6051.818445446644, 6082.908756368658, 4454.465464144323, 6157.138000679908, 6221.129041934133, 6224.657643829588, 6233.470155036827, 5668.801591944262, 5675.914861277084, 5688.374278829326, 5689.715514224272, 5734.197212390269, 6073.635581865014, 6204.100405441186, 6273.37266273369, 6287.559870808999, 6289.465395441347, 5771.105478650816, 5806.274215723035, 5817.796377126865, 5904.645721256738, 5908.675022873697, 5906.497947674496, 6110.050511076723, 6114.945045103356, 6118.010103775157, 6124.055729405355, 5856.092305692625, 5920.437558431942, 5950.9702451487465, 5974.4324289192455, 6060.151578778975, 6101.16314281886, 6103.223435583424, 6108.77496446079, 6118.811486836347, 6119.085672178321, 4699.4360676718325, 4807.423109226846, 4820.638452116105, 4891.266310053449, 4927.975360123177, 4292.18448494785, 4368.191884501377, 4373.409589705413, 4388.144311859221, 4419.491758447755, 5536.75084621193, 5538.889222215533, 5547.057000309957, 5556.86107847299, 5595.776168953278, 6156.076588511759, 6157.476614487819, 6164.722587483484, 6169.753209871645, 6174.351693072163, 5387.6228663993925, 5427.945720542823, 5441.374241962146, 5443.809466668727, 5533.501984821385, 5200.713960732196, 5242.266082748418, 5262.684238945675, 5271.973640131775, 5306.018386598492, 6212.968935155386, 6250.499960003189, 6258.749650013949, 6275.466585340206, 6278.215223097113, 5343.011542864582, 5810.94189058104, 6009.06859436788, 6055.939440605537, 6086.6348019119, 6214.720337584729, 6230.15892854477, 6276.121343012073, 6297.023961286008, 6339.524535659936, 6095.299040610209, 6096.725647345791, 6111.663858487648, 6122.204113465221, 6126.172966649624, 6067.393260673897, 6097.054956481752, 6180.845478862884, 6189.938100618936, 6212.06818977157, 5636.718164091697, 5744.253247077853, 5746.195532625519, 5804.100364443518, 5811.186433899173, 5766.711664416846, 5786.9131963019745, 5792.275965504312, 5813.273340832396, 5816.854578635568, 5634.915476267777, 5649.887002260014, 5677.0, 5689.402612725604, 5690.317161940428, 5844.581183009097, 5936.673482958478, 5958.374370691015, 5975.850603734942, 5978.730957106854, 5776.884462310814, 5782.537397008314, 5787.971060144672, 5797.4782269595935, 5806.9419305806405], "value": 5545.131769373733, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.550298222653, 485.50963526837893, 498.08601216767244, 499.9700017998918, 500.9774560144793, 26804.86597567012, 37932.62067379329, 39759.60240397595, 39843.80078099613, 39884.40173397402, 41116.0, 41477.0, 41497.58502414975, 41516.792416037955, 41541.37687934676, 9094.590743416546, 9316.8602470963, 9323.06769323069, 9323.487208203545, 9331.253499720005, 8289.088200297992, 8498.235158835703, 8506.702265420712, 8592.05487396386, 8601.311895048413, 10941.398223097727, 11222.494987725571, 11230.213885028052, 11344.659660210207, 11412.0, 11072.058874995635, 11123.388213648246, 11222.32666040032, 11237.83143252852, 11259.605913792979, 8181.345492360656, 8403.83192336156, 10975.615853445091, 11062.280951738192, 11065.834012489819, 31651.0, 31948.680513194802, 31994.840025799956, 38377.6567820125, 38958.415623765664, 3923.1565213478953, 3946.914598485411, 3954.703397245218, 3969.146633473788, 3987.063040185554, 29280.70719292801, 29612.259693507836, 29751.25621859471, 29952.0, 30240.0, 3150.921226969344, 3151.1176870476074, 3167.0973772474736, 3169.2710676544525, 3267.2158681916408, 484477.0, 486227.56886215694, 488183.0, 489541.0, 489952.0, 56969.14546281809, 57277.0, 57294.42705572974, 57348.71325643345, 57440.0, 49229.507704923206, 52260.95478090493, 59577.0, 59682.70158649179, 59829.40170598238, 14257.0, 14264.001519893507, 14351.0, 14814.333354999051, 14836.70326593462, 11336.886631133582, 11467.31196128235, 11470.36912969778, 11480.655580332603, 11497.885021149681, 11628.767424651629, 13323.267220302783, 13622.455101795817, 14707.338169782386, 15284.0, 5861.3845546217035, 6056.7306443393645, 6080.604760690541, 6125.663088530085, 6165.506759459233, 803.586153131138, 804.939629527776, 846.0989046665281, 860.8923884514436, 867.1458613265928, 28679.713202868123, 28944.565831512544, 29075.56386654202, 29780.106596802132, 29848.701512985026, 903.8689390038408, 1011.9747006324901, 1071.603506702525, 1072.6567498400436, 1132.4960392625367, 13013.414396351998, 14096.295185240697, 14981.250937453302, 15296.847031529765, 15336.386544538094, 597664.0, 616716.0, 642229.0, 642937.0, 831354.0, 46362.0, 46643.766781165876, 46825.531744682114, 46841.828954275734, 47102.82242943885, 1353.282760137109, 1364.0, 1390.979135312971, 1392.1160063359637, 1392.958211253664, 22209.66685499751, 23201.651975220724, 25134.497310054063, 25907.740922591285, 26571.601425979014, 42340.576594233666, 42398.788006059774, 42583.787081064394, 42614.78692606517, 42695.5730442704, 424229.0, 462225.0, 465445.0, 466470.0, 466688.0, 22738.43153921165, 22908.656370154793, 22982.42543936415, 23122.190723324602, 23131.884340578188, 33145.0, 33869.0, 38740.61259387483, 39211.80394098011, 39578.2084358317, 31712.682873170972, 32352.191195220308, 33799.49300760441, 34130.976070717916, 34268.0, 31817.681823181472, 32325.676743232267, 32860.34279314352, 32912.1771955703, 33818.830905845316, 32372.0, 32477.863274785268, 32929.50605740868, 32935.8472453463, 33080.66919330776, 28443.573346400233, 30169.547456788605, 30186.0, 30382.10717348338, 31531.0, 22533.098676052774, 23003.539929201655, 23680.0, 23722.644160337262, 24256.63615045811], "value": 18395.912806491717, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.0918650358161, 389.6921432068665, 394.11324519830384, 399.6902400639505, 423.11568821163763, 14395.712085758278, 20490.590188196227, 20643.9678016099, 21264.4683882903, 21289.99999999998, 19569.21723131073, 19727.802721972777, 20839.687404688902, 21278.255261065864, 21324.8933755331, 5599.356074051485, 6013.639181649093, 6067.120267561201, 6082.635041897501, 6083.908741368861, 5814.331351894522, 5828.0, 5916.0, 5933.110033494989, 5938.970305148489, 6082.635041897478, 6205.0, 6210.0, 6210.09953556734, 6214.0, 5929.288485381739, 6071.574989750719, 6155.44600985909, 6197.752089916398, 6198.814035578917, 5984.4613984741145, 6001.939980600182, 6007.849803754897, 6024.1566180734735, 6024.3072046714315, 11227.887721122766, 12343.703911089386, 13171.934140329333, 13529.052966292364, 13628.182309061489, 2606.387498937767, 2606.543854825398, 2612.4644447888136, 2619.4630100829286, 2634.7628713415693, 9983.650572229937, 9993.25050621199, 10044.799104017951, 10461.320014199053, 10526.842097368546, 1823.79026411963, 1861.9069046547618, 1886.1889387563342, 1905.9332923347754, 1908.6659834528903, 121832.56334873341, 128214.0, 142153.8676919847, 145128.0, 148122.0, 10970.341779493256, 11533.653990380302, 11755.706107347214, 11791.764164716658, 11942.044636429066, 13579.66050848717, 13645.317734113254, 15470.303836327392, 15492.845071549365, 15526.689466210613, 5687.488126068673, 9760.414375137514, 10343.844842327371, 10605.893941060645, 10701.0, 7082.858342833115, 8278.751637450887, 8341.8331633367, 8427.671069927344, 8828.911710882938, 9436.197923176469, 10250.897491024994, 10444.21668374875, 10736.57053717858, 10895.618653347095, 3231.579894613685, 3349.0790032741193, 4085.4649054391325, 4115.279826030493, 4115.403266526337, 817.6443247187492, 843.7890527368158, 846.3779122345071, 867.4968518259394, 883.1389395339493, 20657.586848262952, 21177.258795942376, 21287.148514059572, 21317.310191757788, 21337.0, 756.481809960179, 860.1484530314943, 874.7681864305964, 882.3646974178581, 888.9111088891189, 10486.108680762221, 11013.614523491638, 11029.558817647365, 11038.392888391216, 11081.390523521284, 660469.0, 692938.0, 706018.0, 719439.0, 730435.0, 147340.84307049203, 149439.0, 149571.8249306367, 149745.0, 161242.38757612274, 992.7319623701704, 1004.6232662751469, 1022.9028242316969, 1024.5645600619707, 1025.4411345816548, 17676.911615442354, 17948.82051179524, 18378.72431913549, 18410.90794546019, 18418.079096044876, 56392.718036409555, 59255.40744592499, 59764.701176495575, 60125.39874601373, 60416.395836041076, 81655.0, 86506.56746716225, 87148.36348729525, 87424.5628771852, 88317.5584122101, 8331.541765202972, 8419.284360829153, 8442.620082096197, 8560.614772335135, 8574.957125214334, 11540.538378465104, 12464.688382790502, 12486.502699460327, 12688.429020694275, 12731.23612583248, 10785.154913231529, 10941.398223097487, 11058.336499810037, 11080.05819505351, 11121.385549696219, 8836.425632333878, 10930.508127134139, 11065.446727663424, 11176.0, 11322.490487928228, 9321.86017209756, 11228.87596415473, 11251.549938002716, 11453.828192576952, 11466.025387842126, 7476.5514069156025, 8156.551389673625, 8394.24451799341, 8561.700340488302, 8704.782380440287, 10608.893911061099, 11086.113110951266, 11113.444327783416, 11183.049440797624, 11490.138239631893], "value": 10053.139351188804, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1189.5004098278723, 1384.3562743324353, 1405.3675845869361, 1405.3746082993066, 1407.064302239011, 7843.607819609026, 8154.306883914859, 8253.628586713598, 8268.958655206723, 8270.338372930166, 7289.744858929938, 7561.773146805591, 7567.583782891938, 7846.882296765553, 7967.402444816647, 2209.823214142865, 2253.256425379621, 2255.7744225577476, 2260.9660855087186, 2283.5889539882814, 2244.7194100737406, 2265.784750448713, 2269.965950510744, 2276.681264622954, 2276.9089236430523, 1627.4222650958916, 1994.710766938793, 2076.1280262289874, 2079.8960051997415, 2249.595072886888, 2197.6923230747707, 2262.468319944812, 2267.1951457232776, 2271.7841805028497, 2272.6704627829035, 2190.8028277454946, 2255.3684968208927, 2255.537614788964, 2261.2311813983324, 2267.7505474397803, 8123.634436450374, 8190.180981901822, 8194.631241594143, 8224.753257402288, 8232.711855085103, 4971.875703107415, 5144.794208231666, 5145.665531740425, 5147.0, 5148.510891465305, 3195.2171717929255, 3370.25854312051, 3374.54443650109, 3439.9828000859834, 3448.586169659657, 1137.8293256011582, 1157.5774842182543, 1202.4709127983658, 1219.4573414830404, 1224.687704635321, 10704.678859634223, 10722.678319650346, 10727.356358618508, 10736.838947415796, 10737.731556711067, 4256.914861702749, 4597.609203217764, 4649.325847752125, 4659.184642687516, 4678.485366609671, 9151.63393464254, 9371.390859594105, 9375.953120234355, 9378.812423751624, 9414.340996130342, 5197.480251974772, 5200.0, 5207.869803254949, 5215.24378965048, 5218.712970786567, 4253.723507971972, 4368.4321038264825, 4414.757188354672, 4641.303804429295, 4691.413573303337, 3682.9927689409337, 3721.181340105227, 3811.294910441577, 3823.866164684223, 3835.232953409329, 1246.0841281657974, 1252.117257333596, 1252.918560293578, 1261.5017068257987, 1265.4495294546903, 742.5136535569211, 794.2295972906271, 884.6992022712309, 885.1325700813111, 920.7836158502746, 5457.672539647634, 5500.6424582402815, 5680.857978550569, 5691.573132015115, 5890.558208134407, 845.6997765793178, 854.478767951549, 941.6939494664261, 945.9763505912407, 983.5672304186136, 3314.0720598232533, 3657.1040095176318, 3691.1325838427947, 3717.7769333839515, 3756.1588637768673, 9491.430514169171, 9832.311738178401, 9858.162056225301, 9871.654492092886, 9887.209023278121, 9800.803983920425, 9827.017298270115, 9844.950775246221, 9850.852237216448, 9854.704358869245, 7434.070741157356, 7569.5079819811535, 7571.242875712384, 7714.382849372037, 7767.572783496962, 4885.316055752269, 5423.023855706011, 5468.28912241406, 5546.223528705904, 5590.105583106846, 8947.642094316154, 9039.367244292836, 9089.727308180765, 9167.908320916706, 9206.493642849708, 9051.954740226256, 9125.498097604695, 9294.72115836526, 9384.718458446258, 9394.530273486162, 6539.0, 6737.932620673927, 6748.122744043243, 7007.544509606859, 7164.713411463483, 7490.812729681802, 7649.808754781175, 7755.68977240904, 7842.764717058498, 7849.411294153076, 6906.447484201352, 6975.128108986377, 7011.9649401754705, 7037.366637002694, 7107.786766397016, 6869.313068693091, 6886.793396198122, 6895.620740859301, 6917.654117294014, 6933.7226510939, 6086.939130608815, 6264.498840092872, 6345.619262844244, 6400.807975760735, 6405.359464053744, 4894.412670479565, 4927.753612319441, 4974.801007959786, 4974.925376119289, 5040.218766091261, 4285.485741711014, 4923.876903077452, 4961.900761984813, 4980.676256043345, 5029.74851257443], "value": 4320.265271938282, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.9682328590427, 353.15595726214354, 361.2738395824393, 372.40229431762003, 374.8425661222286, 4420.823167073313, 5317.441668624792, 5357.678539287641, 5361.946380536194, 5362.8123015694455, 3993.9800300998527, 4963.602911767059, 5109.386873575167, 5250.513213691692, 5276.889244302281, 3485.738569607277, 4108.0, 4113.280175969208, 4137.958620413788, 4146.751194928299, 3654.2775632154085, 3896.47397601323, 4138.861813001418, 4170.97914510427, 4182.971258939428, 3916.2167566486673, 4121.361189015707, 4133.6693064554765, 4146.668266538685, 4159.708820382574, 3477.7217822574385, 4085.9182816343805, 4099.221147981874, 4117.176564687044, 4169.270377683908, 3179.72972297355, 3617.5116359291687, 3633.709303255733, 3644.344018076745, 3648.799316037617, 5000.849974500771, 5171.482851714836, 5176.663516871391, 5195.0, 5217.608679349026, 3879.573246942834, 4302.462192225972, 4546.40896683429, 4590.908181836345, 4610.838620648261, 5638.787660652938, 5645.745941432645, 5653.943460565383, 5656.434356564352, 5690.886182276331, 3844.2695887781233, 3867.45855580219, 3873.593272706353, 3878.7672739635714, 3903.0, 5689.9146512802345, 5884.235049443545, 5944.881102377972, 5956.583039187302, 5978.581499295007, 3886.7667939923695, 3893.201893611803, 3900.863469778601, 3905.7851818149707, 3915.8629447969183, 3652.543432070991, 3954.4661470701085, 3959.544652365015, 3971.0866500705, 3976.0, 3669.6513831185994, 4393.51671316155, 4404.2512772828695, 4604.470485894098, 4664.65015123867, 4528.071745292207, 4852.4662287148385, 4897.461279259279, 5430.307390974097, 5647.887042259132, 1074.978500429987, 1158.0, 1207.7077527046151, 1270.0284282523692, 1280.6030130659526, 812.163471624228, 833.820728543366, 834.8789425533264, 976.2921881635757, 977.3402953006693, 4205.600467955539, 4704.788284527204, 4937.061958227925, 4940.888300132441, 5011.473795251519, 5048.116579598555, 5371.677699338053, 5485.50630443262, 5566.276384070045, 5595.677793715562, 4499.415076040159, 4711.7879695413785, 4746.0, 4770.8273997791775, 4789.118309032154, 2831.2497188245134, 2911.966251980559, 2913.956290655642, 2924.722151395614, 2929.8388588627404, 4702.623790096784, 4886.065074274937, 5113.36372360841, 5652.0, 5769.096198254566, 4458.732476051382, 5293.153095504701, 5477.945220547744, 5530.557555395559, 5646.6612003279115, 4945.777440015136, 4979.879527106445, 5260.710660913686, 5529.723513824292, 5670.914936276042, 4384.386185933908, 4791.66354449224, 5005.774740136775, 5412.810551630674, 5449.836504904859, 4472.217361961579, 4814.86850590104, 5253.581532986149, 5448.186269611208, 5483.479069488392, 5159.484051594811, 5173.0, 5594.356648985418, 5749.540036796962, 5790.392008839011, 3217.0348895331776, 3511.613722490473, 3775.9616105571185, 3802.94295585572, 3897.279003384383, 4677.555632214934, 4710.8351207707565, 5162.045021671003, 5162.354705661793, 5432.918506222489, 4418.602325790565, 4940.7035577865445, 5004.898922237102, 5206.55744261742, 5331.933613277438, 4628.60656844172, 4721.561731971936, 4927.581155601814, 5200.323957885451, 5259.368875734784, 4373.113744928332, 4665.953340466687, 5036.974815125901, 5113.897722045613, 5183.637145399785, 4793.760311984317, 5320.8935821282585, 5470.56235501151, 5553.416891226525, 5624.119357643213, 5153.201253805666, 5321.707306098202, 5380.650257733391, 5467.7266136692215, 5501.5323697486165], "value": 3982.973298003666, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.4662083849566, 650.3723906430293, 688.456119665464, 717.3615482220824, 718.1848601836916, 14425.567232983001, 14983.062969990178, 15383.69232615347, 15491.302891369887, 15509.534713958572, 14059.15645061297, 14300.0, 14396.352164152611, 14494.637634059154, 14532.927335363334, 808.789714674186, 822.7613991942329, 824.4352618456346, 825.5542007316042, 829.1873963515758, 658.3975662269019, 681.2948598200863, 682.3756263019332, 809.5911564659868, 823.074041703084, 867.4231635962109, 879.1120967822482, 879.3317079019927, 879.876817245583, 882.5146169606722, 875.7285241575069, 875.7504111328273, 875.934304927133, 876.8334016536837, 876.9736907892774, 504.9192129259338, 520.7396301849076, 624.6564389585756, 648.7437462202404, 651.0559688451716, 14751.18868462234, 15269.923650381677, 15283.694326113415, 15285.61785955349, 15292.694146117015, 13483.595492135251, 13577.524786632519, 13650.590482285548, 13752.587422377244, 13866.653333666638, 10354.896451035469, 12433.10497833873, 12638.368081595976, 12671.556495522706, 12902.870971290355, 4304.698671092993, 4376.0, 4439.245328294198, 4452.287633978548, 4549.340345649895, 109140.80054085149, 113106.73786524306, 113124.0, 114577.0, 115325.0, 9054.592543335566, 10832.241743078066, 10845.403502807263, 10925.125989920787, 11346.94326528362, 22220.555588888456, 22229.333120006424, 22237.332880013626, 22306.21928232504, 22344.88827555852, 7493.213212612706, 7526.887096693554, 7683.769486915401, 7739.380849532024, 7777.92222077772, 4168.520620128664, 4183.163367326547, 4219.493660760729, 4226.028013556899, 4358.934615980763, 3158.4472717274384, 3205.0865503331906, 3241.692039256267, 3244.4484437645656, 3261.5107733840114, 611.0559186057552, 636.0649844728242, 636.1729751323261, 637.346719612401, 637.4772686397203, 573.1889376532243, 583.8540364908773, 591.3465620489297, 591.963228637089, 608.1546650156287, 8850.955745221232, 9507.904920950701, 9611.807763844685, 9657.372270802376, 9876.408072284377, 581.4592429041032, 614.4285814192843, 619.0775744141197, 628.1959092361784, 632.6615260835431, 4288.378185163134, 4623.653226008063, 4623.930641040387, 4630.884227894329, 4640.69835460694, 84523.30953380898, 98525.0, 109403.0, 110059.0, 110200.4489977545, 92378.0, 92945.5352723232, 96659.0, 97508.0, 97707.51146244365, 67389.6630516864, 82332.17667823246, 83738.0, 84292.73560896592, 84816.57591712002, 8465.66137354499, 9029.954850225708, 9107.635694572144, 9132.039528515257, 9197.402168859002, 49617.50382496129, 49677.503224968736, 50548.0, 51719.741401292755, 52200.738996306296, 60809.695951519956, 64714.0, 64742.0, 71362.0, 73982.0, 12770.74458510806, 12824.294663793207, 12824.328373114728, 12964.962802975928, 13025.609231723063, 13971.441142354191, 14049.859501405264, 14131.293435327987, 14537.41850325975, 14905.25473726331, 13342.93328533351, 13380.130291531019, 13486.20345497085, 13551.86448135506, 13638.659033524242, 13459.596212113653, 13576.524821631196, 13639.931800340935, 13692.246926419142, 14157.0, 13198.80201796993, 13415.530456433979, 13441.932790335986, 13572.660683482596, 13634.727305454035, 8742.60658270392, 11049.116070714485, 11099.223054386439, 11386.601468948547, 11851.348175850411, 10600.0, 10701.678949631523, 10722.303050301705, 10725.088367488539, 10842.945785271024], "value": 7361.233229383905, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 111.0, 110.0, 147.0, 112.0, 113.0, 109.0, 111.0, 129.0, 109.0], "value": 118.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 183.0, 182.0, 185.0, 184.0, 197.0, 249.0, 182.0, 181.0], "value": 191.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 60.0, 59.0, 86.0, 61.0, 59.0, 57.0, 59.0, 74.0, 58.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 58.0, 82.0, 83.0, 97.0, 99.0, 104.0, 69.0, 70.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 87.0, 90.0, 86.0, 90.0, 88.0, 90.0, 87.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 87.0, 88.0, 86.0, 84.0, 89.0, 85.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 61.0, 59.0, 61.0, 60.0, 60.0, 59.0, 59.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 66.0, 77.0, 72.0, 70.0, 69.0, 70.0, 75.0, 101.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 86.0, 86.0, 86.0, 87.0, 87.0, 85.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 142.0, 150.0, 139.0, 167.0, 153.0, 139.0, 138.0, 178.0, 156.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 175.0, 176.0, 176.0, 179.0, 174.0, 174.0, 175.0, 175.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 68.0, 69.0, 67.0, 68.0, 65.0, 68.0, 67.0], "value": 67.8, "unit": "score"}]}]} 05:08:27 INFO - Return code: 0 05:08:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:08:27 ERROR - # TBPL SUCCESS # 05:08:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:08:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:08:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:08:27 INFO - 2017-01-31 05:08:27.831 screenresolution[1652:10102] starting screenresolution argv=screenresolution get 05:08:27 INFO - 2017-01-31 05:08:27.833 screenresolution[1652:10102] Display 0: 1600x1200x32@60 05:08:27 INFO - 2017-01-31 05:08:27.839 screenresolution[1653:10105] starting screenresolution argv=screenresolution list 05:08:27 INFO - Available Modes on Display 0 05:08:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:08:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:08:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:08:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:08:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:08:28 INFO - Intel Iris: 05:08:28 INFO - Chipset Model: Intel Iris 05:08:28 INFO - Type: GPU 05:08:28 INFO - Bus: Built-In 05:08:28 INFO - VRAM (Dynamic, Max): 1536 MB 05:08:28 INFO - Vendor: Intel (0x8086) 05:08:28 INFO - Device ID: 0x0a2e 05:08:28 INFO - Revision ID: 0x0009 05:08:28 INFO - Displays: 05:08:28 INFO - Display: 05:08:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:08:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:08:28 INFO - Display Serial Number: 1600x1200 60 05:08:28 INFO - Main Display: Yes 05:08:28 INFO - Mirror: Off 05:08:28 INFO - Online: Yes 05:08:28 INFO - Rotation: Supported 05:08:28 INFO - Return code: 0 05:08:28 INFO - Running post-action listener: _resource_record_post_action 05:08:28 INFO - [mozharness: 2017-01-31 13:08:28.079434Z] Finished run-tests step (success) 05:08:28 INFO - Running post-run listener: _resource_record_post_run 05:08:28 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 32447488; Write bytes: 553964544; Read time: 199; Write time: 1640 05:08:28 INFO - TinderboxPrint: CPU usage
53.7% 05:08:28 INFO - TinderboxPrint: I/O read bytes / time
32,447,488 / 199 05:08:28 INFO - TinderboxPrint: I/O write bytes / time
553,964,544 / 1,640 05:08:28 INFO - TinderboxPrint: CPU idle
2,223.8 (46.3%) 05:08:28 INFO - TinderboxPrint: CPU system
959.6 (20.0%) 05:08:28 INFO - TinderboxPrint: CPU user
1,622.2 (33.8%) 05:08:28 INFO - TinderboxPrint: Swap in / out
280,440,832 / 0 05:08:28 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 240357888; Write bytes: 235190272; Read time: 17872; Write time: 482 05:08:28 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30612480; Write bytes: 297405440; Read time: 179; Write time: 1109 05:08:28 INFO - Running post-run listener: _upload_blobber_files 05:08:28 INFO - Blob upload gear active. 05:08:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:08:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:08:28 INFO - (blobuploader) - INFO - Open directory for files ... 05:08:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:08:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:08:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:30 INFO - (blobuploader) - INFO - Done attempting. 05:08:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:08:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:08:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:31 INFO - (blobuploader) - INFO - Done attempting. 05:08:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:08:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:08:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:32 INFO - (blobuploader) - INFO - Done attempting. 05:08:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:08:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:08:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:35 INFO - (blobuploader) - INFO - Done attempting. 05:08:35 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:35 INFO - Return code: 0 05:08:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887cc274e1f999a617b274bc7e139f4faa3354933fdbd0161b730ac822f7ecaed30bcbdb3156d41ff053bc6b89c705b8fc20768418ec67193bfba013f751457d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50fad8ca680b97d41d425e65b04af0f59dd00f22fb0e9ba1b10465bb34da0d09d6f8c83df01ea83eb5d13729398856c9053166fc20d6d7cc3c2bcd015dd4bba6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/781f53e3c747848235c16031a032ce0d91c2a2e5914e3a89857bd17e8964977c0d302ed907883adaab38bc8e3ddc14d97b285eee31cd3f6f513c7f33676ffa70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433c98787681c34e733abe7b4bd4cf41b8a080b3edd85ea7b2bab6ad6168a75dd1e0fb4bf5047fd83eed56afca188f2b7c5eaf600f149c92b6d6cbdcfe8b1d80"} 05:08:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:35 INFO - Contents: 05:08:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887cc274e1f999a617b274bc7e139f4faa3354933fdbd0161b730ac822f7ecaed30bcbdb3156d41ff053bc6b89c705b8fc20768418ec67193bfba013f751457d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50fad8ca680b97d41d425e65b04af0f59dd00f22fb0e9ba1b10465bb34da0d09d6f8c83df01ea83eb5d13729398856c9053166fc20d6d7cc3c2bcd015dd4bba6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/781f53e3c747848235c16031a032ce0d91c2a2e5914e3a89857bd17e8964977c0d302ed907883adaab38bc8e3ddc14d97b285eee31cd3f6f513c7f33676ffa70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433c98787681c34e733abe7b4bd4cf41b8a080b3edd85ea7b2bab6ad6168a75dd1e0fb4bf5047fd83eed56afca188f2b7c5eaf600f149c92b6d6cbdcfe8b1d80"} 05:08:35 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:35 INFO - Copying logs to upload dir... 05:08:35 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.313148 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-31 05:08:35.216999) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 05:08:35.220414) ========= 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.FFVhSTWPIy/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.hGapLZpspL/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887cc274e1f999a617b274bc7e139f4faa3354933fdbd0161b730ac822f7ecaed30bcbdb3156d41ff053bc6b89c705b8fc20768418ec67193bfba013f751457d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50fad8ca680b97d41d425e65b04af0f59dd00f22fb0e9ba1b10465bb34da0d09d6f8c83df01ea83eb5d13729398856c9053166fc20d6d7cc3c2bcd015dd4bba6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/781f53e3c747848235c16031a032ce0d91c2a2e5914e3a89857bd17e8964977c0d302ed907883adaab38bc8e3ddc14d97b285eee31cd3f6f513c7f33676ffa70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433c98787681c34e733abe7b4bd4cf41b8a080b3edd85ea7b2bab6ad6168a75dd1e0fb4bf5047fd83eed56afca188f2b7c5eaf600f149c92b6d6cbdcfe8b1d80"} build_url:https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/C8IttgsaQr29RFnkNqhZkQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887cc274e1f999a617b274bc7e139f4faa3354933fdbd0161b730ac822f7ecaed30bcbdb3156d41ff053bc6b89c705b8fc20768418ec67193bfba013f751457d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50fad8ca680b97d41d425e65b04af0f59dd00f22fb0e9ba1b10465bb34da0d09d6f8c83df01ea83eb5d13729398856c9053166fc20d6d7cc3c2bcd015dd4bba6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/781f53e3c747848235c16031a032ce0d91c2a2e5914e3a89857bd17e8964977c0d302ed907883adaab38bc8e3ddc14d97b285eee31cd3f6f513c7f33676ffa70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433c98787681c34e733abe7b4bd4cf41b8a080b3edd85ea7b2bab6ad6168a75dd1e0fb4bf5047fd83eed56afca188f2b7c5eaf600f149c92b6d6cbdcfe8b1d80"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 05:08:35.253798) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 05:08:35.254136) ========= 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.FFVhSTWPIy/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.hGapLZpspL/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.005277 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 05:08:35.302915) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 05:08:35.303226) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 05:08:35.303545) ========= ========= Total master_lag: 0.18 =========