builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0318 starttime: 1480265639.75 results: success (0) buildid: 20161127030208 builduid: 9e07daac04eb472fa45bf8c774080d1f revision: 2a0abcff5cfce087c12f3e4820b5e8b773cffaca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 08:53:59.747368) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 08:53:59.748409) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 08:53:59.748908) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 08:53:59.778471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:53:59.779126) ========= 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.JRhCk503Tg/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.RB9PmuYvmV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-27 08:53:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.3M=0s 2016-11-27 08:54:00 (54.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.566772 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:00.431572) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:00.431894) ========= 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.JRhCk503Tg/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.RB9PmuYvmV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.024816 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:00.497023) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:00.497384) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2a0abcff5cfce087c12f3e4820b5e8b773cffaca --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 2a0abcff5cfce087c12f3e4820b5e8b773cffaca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JRhCk503Tg/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.RB9PmuYvmV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-27 08:54:00,601 truncating revision to first 12 chars 2016-11-27 08:54:00,601 Setting DEBUG logging. 2016-11-27 08:54:00,601 attempt 1/10 2016-11-27 08:54:00,601 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2a0abcff5cfc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-27 08:54:01,164 unpacking tar archive at: mozilla-central-2a0abcff5cfc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.834250 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:01.409765) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:01.410086) ========= script_repo_revision: 2a0abcff5cfce087c12f3e4820b5e8b773cffaca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:01.410485) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:01.410750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 08:54:01.439284) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-11-27 08:54:01.439573) ========= /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.JRhCk503Tg/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.RB9PmuYvmV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:01 INFO - MultiFileLogger online at 20161127 08:54:01 in /builds/slave/test 08:54:01 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 08:54:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:54:01 INFO - {'append_to_log': False, 08:54:01 INFO - 'base_work_dir': '/builds/slave/test', 08:54:01 INFO - 'blob_upload_branch': 'Firefox', 08:54:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:54:01 INFO - 'branch': 'Firefox', 08:54:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:54:01 INFO - 'config_files': ('talos/mac_config.py',), 08:54:01 INFO - 'default_actions': ('clobber', 08:54:01 INFO - 'read-buildbot-config', 08:54:01 INFO - 'download-and-extract', 08:54:01 INFO - 'populate-webroot', 08:54:01 INFO - 'create-virtualenv', 08:54:01 INFO - 'install', 08:54:01 INFO - 'run-tests'), 08:54:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:54:01 INFO - 'download_minidump_stackwalk': True, 08:54:01 INFO - 'download_symbols': 'ondemand', 08:54:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:54:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:54:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:54:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:54:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:54:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:54:01 INFO - 'installer_path': 'installer.exe', 08:54:01 INFO - 'log_level': 'info', 08:54:01 INFO - 'log_name': 'talos', 08:54:01 INFO - 'log_to_console': True, 08:54:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:54:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:54:01 INFO - 'opt_config_files': (), 08:54:01 INFO - 'pip_index': False, 08:54:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:54:01 INFO - 'cmd': ('bash', 08:54:01 INFO - '-c', 08:54:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:54:01 INFO - 'enabled': True, 08:54:01 INFO - 'halt_on_failure': False, 08:54:01 INFO - 'name': 'check_screen_resolution'},), 08:54:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:54:01 INFO - 'cmd': ('bash', 08:54:01 INFO - '-c', 08:54:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:54:01 INFO - 'enabled': True, 08:54:01 INFO - 'halt_on_failure': False, 08:54:01 INFO - 'name': 'check_screen_resolution'},), 08:54:01 INFO - 'run_cmd_checks_enabled': True, 08:54:01 INFO - 'sps_profile': False, 08:54:01 INFO - 'sps_profile_interval': 0, 08:54:01 INFO - 'suite': 'dromaeojs', 08:54:01 INFO - 'system_bits': '32', 08:54:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:54:01 INFO - 'title': 't-yosemite-r7-0318', 08:54:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:54:01 INFO - 'use_talos_json': True, 08:54:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:54:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:54:01 INFO - 'work_dir': 'build'} 08:54:01 INFO - [mozharness: 2016-11-27 16:54:01.642817Z] Running clobber step. 08:54:01 INFO - Running pre-action listener: _resource_record_pre_action 08:54:01 INFO - Running main action method: clobber 08:54:01 INFO - rmtree: /builds/slave/test/build 08:54:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:54:04 INFO - Running post-action listener: _resource_record_post_action 08:54:04 INFO - [mozharness: 2016-11-27 16:54:04.046816Z] Finished clobber step (success) 08:54:04 INFO - [mozharness: 2016-11-27 16:54:04.046944Z] Running read-buildbot-config step. 08:54:04 INFO - Running pre-action listener: _resource_record_pre_action 08:54:04 INFO - Running main action method: read_buildbot_config 08:54:04 INFO - Using buildbot properties: 08:54:04 INFO - { 08:54:04 INFO - "project": "", 08:54:04 INFO - "product": "firefox", 08:54:04 INFO - "script_repo_revision": "production", 08:54:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:54:04 INFO - "repository": "", 08:54:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:54:04 INFO - "buildid": "20161127030208", 08:54:04 INFO - "stage_platform": "macosx64", 08:54:04 INFO - "basedir": "/builds/slave/test", 08:54:04 INFO - "nightly_build": "True", 08:54:04 INFO - "buildnumber": 36, 08:54:04 INFO - "slavename": "t-yosemite-r7-0318", 08:54:04 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:54:04 INFO - "platform": "yosemite_r7", 08:54:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:54:04 INFO - "branch": "mozilla-central", 08:54:04 INFO - "repo_path": "mozilla-central", 08:54:04 INFO - "revision": "2a0abcff5cfce087c12f3e4820b5e8b773cffaca", 08:54:04 INFO - "pgo_build": "False", 08:54:04 INFO - "builduid": "9e07daac04eb472fa45bf8c774080d1f", 08:54:04 INFO - "slavebuilddir": "test" 08:54:04 INFO - } 08:54:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:54:04 INFO - Running post-action listener: _resource_record_post_action 08:54:04 INFO - [mozharness: 2016-11-27 16:54:04.050351Z] Finished read-buildbot-config step (success) 08:54:04 INFO - [mozharness: 2016-11-27 16:54:04.050456Z] Running download-and-extract step. 08:54:04 INFO - Running pre-action listener: _resource_record_pre_action 08:54:04 INFO - Running main action method: download_and_extract 08:54:04 INFO - mkdir: /builds/slave/test/build/tests 08:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:04 INFO - trying https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:54:04 INFO - Downloading https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:54:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:54:05 INFO - Downloaded 1199 bytes. 08:54:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:54:05 INFO - Using the following test package requirements: 08:54:05 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:54:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:54:05 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:54:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:54:05 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:54:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:54:05 INFO - u'jsshell-mac.zip'], 08:54:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:54:05 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:54:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:54:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:54:05 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:54:05 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:54:05 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:54:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:54:05 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:54:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:54:05 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:54:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:54:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:54:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:54:05 INFO - Fetch https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:54:06 INFO - Http code: 200 08:54:06 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 08:54:06 INFO - x-amz-cf-id: TAWnANUWcLTfA2lrU48Gtj_dFx1c31tA5otvUJZM_KNxE922e0-naw== 08:54:06 INFO - x-amz-version-id: mfjHvVMrsCq.Ur0U3KzKZQaoaxXze5Lh 08:54:06 INFO - Expected file size: 16282775 08:54:06 INFO - Obtained file size: 16282775 08:54:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:54:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:54:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:54:13 INFO - Fetch https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:54:14 INFO - Http code: 200 08:54:14 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 08:54:14 INFO - x-amz-cf-id: Wl8dnMCCKW6qV_9Qz9bzxsVkt-oJ2WA0WakE60zaZjQY8rc5wiVLew== 08:54:14 INFO - x-amz-version-id: mfjHvVMrsCq.Ur0U3KzKZQaoaxXze5Lh 08:54:14 INFO - Expected file size: 16282775 08:54:14 INFO - Obtained file size: 16282775 08:54:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:54:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:54:20 INFO - Fetch https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:54:21 INFO - Http code: 200 08:54:21 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 08:54:21 INFO - x-amz-cf-id: 5DbdQlL8Zm3aSLUVEzrT1xyZ-rOlwtbrHGqGrUEthF84bTJF4lOHxw== 08:54:21 INFO - x-amz-version-id: aUGi7nBzcfoRQHPQvq0UGSsD7pfqwSAr 08:54:22 INFO - Expected file size: 14409702 08:54:22 INFO - Obtained file size: 14409702 08:54:23 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')]} 08:54:23 INFO - trying https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:54:23 INFO - Downloading https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:54:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:54:29 INFO - Downloaded 107722781 bytes. 08:54:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:54:29 INFO - mkdir: /builds/slave/test/properties 08:54:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:54:29 INFO - Writing to file /builds/slave/test/properties/build_url 08:54:29 INFO - Contents: 08:54:29 INFO - build_url:https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:54:30 INFO - Running post-action listener: _resource_record_post_action 08:54:30 INFO - Running post-action listener: set_extra_try_arguments 08:54:30 INFO - [mozharness: 2016-11-27 16:54:30.339258Z] Finished download-and-extract step (success) 08:54:30 INFO - [mozharness: 2016-11-27 16:54:30.339372Z] Running populate-webroot step. 08:54:30 INFO - Running pre-action listener: _resource_record_pre_action 08:54:30 INFO - Running main action method: populate_webroot 08:54:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:54:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:54:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:54:30 INFO - '--tppagecycles', 08:54:30 INFO - '7'], 08:54:30 INFO - 'tests': ['tsvgm']}}, 08:54:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:54:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:54:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:54:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:54:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:54:30 INFO - 'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:54:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:54:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:54:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:54:30 INFO - 'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['damp', 'tps']}, 08:54:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:54:30 INFO - 'tests': ['damp', 'tps']}, 08:54:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['dromaeo_dom']}, 08:54:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:54:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['basic_compositor_video']}, 08:54:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:54:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['a11yr', 08:54:30 INFO - 'ts_paint', 08:54:30 INFO - 'tpaint', 08:54:30 INFO - 'sessionrestore', 08:54:30 INFO - 'sessionrestore_no_auto_restore', 08:54:30 INFO - 'tabpaint']}, 08:54:30 INFO - 'other-e10s': {'tests': ['a11yr', 08:54:30 INFO - 'ts_paint', 08:54:30 INFO - 'tpaint', 08:54:30 INFO - 'sessionrestore', 08:54:30 INFO - 'sessionrestore_no_auto_restore', 08:54:30 INFO - 'tabpaint']}, 08:54:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:54:30 INFO - 'ts_paint', 08:54:30 INFO - 'tpaint', 08:54:30 INFO - 'sessionrestore', 08:54:30 INFO - 'sessionrestore_no_auto_restore', 08:54:30 INFO - 'tabpaint']}, 08:54:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:54:30 INFO - 'ts_paint', 08:54:30 INFO - 'tpaint', 08:54:30 INFO - 'sessionrestore', 08:54:30 INFO - 'sessionrestore_no_auto_restore', 08:54:30 INFO - 'tabpaint']}, 08:54:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['a11yr', 08:54:30 INFO - 'ts_paint', 08:54:30 INFO - 'tpaint', 08:54:30 INFO - 'sessionrestore', 08:54:30 INFO - 'sessionrestore_no_auto_restore', 08:54:30 INFO - 'tabpaint']}, 08:54:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['a11yr', 08:54:30 INFO - 'ts_paint', 08:54:30 INFO - 'tpaint', 08:54:30 INFO - 'sessionrestore', 08:54:30 INFO - 'sessionrestore_no_auto_restore', 08:54:30 INFO - 'tabpaint']}, 08:54:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['tsvgx', 08:54:30 INFO - 'tsvgr_opacity', 08:54:30 INFO - 'tart', 08:54:30 INFO - 'tscrollx', 08:54:30 INFO - 'cart', 08:54:30 INFO - 'tsvg_static']}, 08:54:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:54:30 INFO - 'tsvgr_opacity', 08:54:30 INFO - 'tart', 08:54:30 INFO - 'tscrollx', 08:54:30 INFO - 'cart', 08:54:30 INFO - 'tsvg_static']}, 08:54:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:54:30 INFO - 'talos_options': ['--disable-e10s'], 08:54:30 INFO - 'tests': ['tp5o']}, 08:54:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:54:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:54:30 INFO - 'talos_options': ['--disable-e10s', 08:54:30 INFO - '--xperf_path', 08:54:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:54:30 INFO - 'tests': ['tp5n']}, 08:54:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:54:30 INFO - 'talos_options': ['--xperf_path', 08:54:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:54:30 INFO - 'tests': ['tp5n']}}, 08:54:30 INFO - 'talos.zip': {'path': '', 08:54:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:54:30 INFO - Running post-action listener: _resource_record_post_action 08:54:30 INFO - [mozharness: 2016-11-27 16:54:30.357567Z] Finished populate-webroot step (success) 08:54:30 INFO - [mozharness: 2016-11-27 16:54:30.357693Z] Running create-virtualenv step. 08:54:30 INFO - Running pre-action listener: _resource_record_pre_action 08:54:30 INFO - Running main action method: create_virtualenv 08:54:30 INFO - Creating virtualenv /builds/slave/test/build/venv 08:54:30 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 08:54:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:54:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:54:30 INFO - Using real prefix '/tools/python27' 08:54:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:54:31 INFO - Installing distribute.............................................................................................................................................................................................done. 08:54:34 INFO - Installing pip.................done. 08:54:34 INFO - Return code: 0 08:54:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:54:34 INFO - Reading from file tmpfile_stdout 08:54:34 INFO - Output received: 08:54:34 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) 08:54:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:54:34 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')]} 08:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:34 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')]} 08:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:34 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:34 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 08:54:34 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 08:54:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:54:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:34 INFO - 'HOME': '/Users/cltbld', 08:54:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:34 INFO - 'LOGNAME': 'cltbld', 08:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:34 INFO - 'MOZ_NO_REMOTE': '1', 08:54:34 INFO - 'NO_EM_RESTART': '1', 08:54:34 INFO - 'PAGER': '/bin/cat', 08:54:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:34 INFO - 'PWD': '/builds/slave/test', 08:54:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:34 INFO - 'SHELL': '/bin/bash', 08:54:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:54:34 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:54:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:34 INFO - 'USER': 'cltbld', 08:54:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:34 INFO - 'XPC_FLAGS': '0x0', 08:54:34 INFO - 'XPC_SERVICE_NAME': '0', 08:54:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:34 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 08:54:34 INFO - Cleaning up... 08:54:34 INFO - Return code: 0 08:54:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:54:34 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')]} 08:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:34 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')]} 08:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:34 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:34 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 08:54:34 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 08:54:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:54:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:34 INFO - 'HOME': '/Users/cltbld', 08:54:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:34 INFO - 'LOGNAME': 'cltbld', 08:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:34 INFO - 'MOZ_NO_REMOTE': '1', 08:54:34 INFO - 'NO_EM_RESTART': '1', 08:54:34 INFO - 'PAGER': '/bin/cat', 08:54:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:34 INFO - 'PWD': '/builds/slave/test', 08:54:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:34 INFO - 'SHELL': '/bin/bash', 08:54:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:54:34 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:54:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:34 INFO - 'USER': 'cltbld', 08:54:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:34 INFO - 'XPC_FLAGS': '0x0', 08:54:34 INFO - 'XPC_SERVICE_NAME': '0', 08:54:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:34 INFO - Downloading/unpacking psutil>=3.1.1 08:54:34 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 08:54:34 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 08:54:34 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 08:54:34 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 08:54:34 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 08:54:34 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 08:54:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:54:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:54:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:54:38 INFO - Installing collected packages: psutil 08:54:38 INFO - Running setup.py install for psutil 08:54:39 INFO - building 'psutil._psutil_osx' extension 08:54:39 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 08:54:39 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 08:54:39 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 08:54:39 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 08:54:39 INFO - building 'psutil._psutil_posix' extension 08:54:39 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 08:54:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:54:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:54:39 INFO - ^ 08:54:39 INFO - 1 warning generated. 08:54:39 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 08:54:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:54:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:54:40 INFO - Successfully installed psutil 08:54:40 INFO - Cleaning up... 08:54:40 INFO - Return code: 0 08:54:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:54:40 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')]} 08:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:40 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')]} 08:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:40 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:40 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 08:54:40 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 08:54:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:54:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:40 INFO - 'HOME': '/Users/cltbld', 08:54:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:40 INFO - 'LOGNAME': 'cltbld', 08:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:40 INFO - 'MOZ_NO_REMOTE': '1', 08:54:40 INFO - 'NO_EM_RESTART': '1', 08:54:40 INFO - 'PAGER': '/bin/cat', 08:54:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:40 INFO - 'PWD': '/builds/slave/test', 08:54:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:40 INFO - 'SHELL': '/bin/bash', 08:54:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:54:40 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:54:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:40 INFO - 'USER': 'cltbld', 08:54:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:40 INFO - 'XPC_FLAGS': '0x0', 08:54:40 INFO - 'XPC_SERVICE_NAME': '0', 08:54:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:54:40 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 08:54:40 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 08:54:40 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 08:54:40 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 08:54:40 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 08:54:40 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 08:54:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:54:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:54:43 INFO - Installing collected packages: mozsystemmonitor 08:54:43 INFO - Running setup.py install for mozsystemmonitor 08:54:43 INFO - Successfully installed mozsystemmonitor 08:54:43 INFO - Cleaning up... 08:54:43 INFO - Return code: 0 08:54:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:54:43 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')]} 08:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:43 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')]} 08:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:43 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:43 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 08:54:43 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 08:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:43 INFO - 'HOME': '/Users/cltbld', 08:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:43 INFO - 'LOGNAME': 'cltbld', 08:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:43 INFO - 'MOZ_NO_REMOTE': '1', 08:54:43 INFO - 'NO_EM_RESTART': '1', 08:54:43 INFO - 'PAGER': '/bin/cat', 08:54:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:43 INFO - 'PWD': '/builds/slave/test', 08:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:43 INFO - 'SHELL': '/bin/bash', 08:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:54:43 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:43 INFO - 'USER': 'cltbld', 08:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:43 INFO - 'XPC_FLAGS': '0x0', 08:54:43 INFO - 'XPC_SERVICE_NAME': '0', 08:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:43 INFO - Downloading/unpacking jsonschema==2.5.1 08:54:43 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 08:54:43 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 08:54:43 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 08:54:43 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 08:54:43 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 08:54:43 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 08:54:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:54:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:54:46 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 08:54:46 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 08:54:46 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 08:54:46 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 08:54:46 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 08:54:46 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 08:54:46 INFO - Downloading functools32-3.2.3-2.tar.gz 08:54:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:54:46 INFO - warning: no files found matching '*.txt' 08:54:46 INFO - no previously-included directories found matching 'build' 08:54:46 INFO - no previously-included directories found matching 'dist' 08:54:46 INFO - no previously-included directories found matching '.git*' 08:54:46 INFO - Installing collected packages: jsonschema, functools32 08:54:46 INFO - Running setup.py install for functools32 08:54:46 INFO - warning: no files found matching '*.txt' 08:54:46 INFO - no previously-included directories found matching 'build' 08:54:46 INFO - no previously-included directories found matching 'dist' 08:54:46 INFO - no previously-included directories found matching '.git*' 08:54:46 INFO - Successfully installed jsonschema functools32 08:54:46 INFO - Cleaning up... 08:54:46 INFO - Return code: 0 08:54:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:46 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:46 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 08:54:46 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 08:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:46 INFO - 'HOME': '/Users/cltbld', 08:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:46 INFO - 'LOGNAME': 'cltbld', 08:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:46 INFO - 'MOZ_NO_REMOTE': '1', 08:54:46 INFO - 'NO_EM_RESTART': '1', 08:54:46 INFO - 'PAGER': '/bin/cat', 08:54:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:46 INFO - 'PWD': '/builds/slave/test', 08:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:46 INFO - 'SHELL': '/bin/bash', 08:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:54:46 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:46 INFO - 'USER': 'cltbld', 08:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:46 INFO - 'XPC_FLAGS': '0x0', 08:54:46 INFO - 'XPC_SERVICE_NAME': '0', 08:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:54:47 INFO - Cleaning up... 08:54:47 INFO - Return code: 0 08:54:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:54:47 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')]} 08:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:47 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')]} 08:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:47 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:47 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 08:54:47 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 08:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:47 INFO - 'HOME': '/Users/cltbld', 08:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:47 INFO - 'LOGNAME': 'cltbld', 08:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:47 INFO - 'MOZ_NO_REMOTE': '1', 08:54:47 INFO - 'NO_EM_RESTART': '1', 08:54:47 INFO - 'PAGER': '/bin/cat', 08:54:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:47 INFO - 'PWD': '/builds/slave/test', 08:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:47 INFO - 'SHELL': '/bin/bash', 08:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:54:47 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:47 INFO - 'USER': 'cltbld', 08:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:47 INFO - 'XPC_FLAGS': '0x0', 08:54:47 INFO - 'XPC_SERVICE_NAME': '0', 08:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:47 INFO - Downloading/unpacking blobuploader==1.2.4 08:54:47 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 08:54:47 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 08:54:47 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 08:54:47 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 08:54:47 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 08:54:47 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 08:54:49 INFO - Downloading blobuploader-1.2.4.tar.gz 08:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:54:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:54:50 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 08:54:50 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 08:54:50 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 08:54:50 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 08:54:50 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 08:54:50 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 08:54:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:54:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:54:50 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 08:54:50 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 08:54:50 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 08:54:50 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 08:54:50 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 08:54:50 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 08:54:51 INFO - Downloading docopt-0.6.1.tar.gz 08:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:54:51 INFO - Installing collected packages: blobuploader, requests, docopt 08:54:51 INFO - Running setup.py install for blobuploader 08:54:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:54:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:54:51 INFO - Running setup.py install for requests 08:54:51 INFO - Running setup.py install for docopt 08:54:52 INFO - Successfully installed blobuploader requests docopt 08:54:52 INFO - Cleaning up... 08:54:52 INFO - Return code: 0 08:54:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:52 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:52 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 08:54:52 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 08:54:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:54:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:52 INFO - 'HOME': '/Users/cltbld', 08:54:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:52 INFO - 'LOGNAME': 'cltbld', 08:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:52 INFO - 'MOZ_NO_REMOTE': '1', 08:54:52 INFO - 'NO_EM_RESTART': '1', 08:54:52 INFO - 'PAGER': '/bin/cat', 08:54:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:52 INFO - 'PWD': '/builds/slave/test', 08:54:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:52 INFO - 'SHELL': '/bin/bash', 08:54:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:54:52 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:54:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:52 INFO - 'USER': 'cltbld', 08:54:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:52 INFO - 'XPC_FLAGS': '0x0', 08:54:52 INFO - 'XPC_SERVICE_NAME': '0', 08:54:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:52 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-dlNrjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:52 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-nfEpNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:52 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-I5suMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:52 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-L9R45L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:52 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-357DT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:52 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-PSd6b6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:52 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-XVB3vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-JIg4Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-QY_35M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-kDcRL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Q2mqgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-gsrlmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-0X9o6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-u_esiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-50Tofu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-v0Zsd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-paBlRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:54:54 INFO - Running setup.py install for manifestparser 08:54:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:54:54 INFO - Running setup.py install for mozcrash 08:54:54 INFO - Running setup.py install for mozdebug 08:54:54 INFO - Running setup.py install for mozdevice 08:54:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:54:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:54:54 INFO - Running setup.py install for mozfile 08:54:54 INFO - Running setup.py install for mozhttpd 08:54:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:54:55 INFO - Running setup.py install for mozinfo 08:54:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:54:55 INFO - Running setup.py install for mozInstall 08:54:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:54:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:54:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:54:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:54:55 INFO - Running setup.py install for mozleak 08:54:55 INFO - Running setup.py install for mozlog 08:54:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:54:55 INFO - Running setup.py install for moznetwork 08:54:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:54:55 INFO - Running setup.py install for mozprocess 08:54:55 INFO - Running setup.py install for mozprofile 08:54:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:54:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:54:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:54:56 INFO - Running setup.py install for mozrunner 08:54:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:54:56 INFO - Running setup.py install for mozscreenshot 08:54:56 INFO - Running setup.py install for moztest 08:54:56 INFO - Running setup.py install for mozversion 08:54:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:54:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:54:56 INFO - Cleaning up... 08:54:56 INFO - Return code: 0 08:54:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:56 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:54:56 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 08:54:56 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 08:54:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:54:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:56 INFO - 'HOME': '/Users/cltbld', 08:54:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:56 INFO - 'LOGNAME': 'cltbld', 08:54:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:56 INFO - 'MOZ_NO_REMOTE': '1', 08:54:56 INFO - 'NO_EM_RESTART': '1', 08:54:56 INFO - 'PAGER': '/bin/cat', 08:54:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:56 INFO - 'PWD': '/builds/slave/test', 08:54:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:56 INFO - 'SHELL': '/bin/bash', 08:54:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:54:56 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:54:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:56 INFO - 'USER': 'cltbld', 08:54:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:56 INFO - 'XPC_FLAGS': '0x0', 08:54:56 INFO - 'XPC_SERVICE_NAME': '0', 08:54:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:56 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-5xW9UZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-XpMNRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-H96Iq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-HqSnoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-jpIt0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Bg1iaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Rk6UXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-sdwXfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-kC9Z_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:57 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)) 08:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:57 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-hIt4hD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:58 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)) 08:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-wXf7ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:58 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)) 08:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-LVmTTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-RARngy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:58 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)) 08:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-qx3DZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:58 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)) 08:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-iySSBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:58 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)) 08:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-TSj6W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-4dY6nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:58 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)) 08:54:58 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)) 08:54:58 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)) 08:54:58 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)) 08:54:58 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)) 08:54:58 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)) 08:54:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:54: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 08:54: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 08:54: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 08:54: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 08:54: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 08:54: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 08:55:01 INFO - Downloading blessings-1.6.tar.gz 08:55:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:55:01 INFO - Installing collected packages: blessings 08:55:01 INFO - Running setup.py install for blessings 08:55:01 INFO - Successfully installed blessings 08:55:01 INFO - Cleaning up... 08:55:01 INFO - Return code: 0 08:55:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:55:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:55:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:55:01 INFO - Reading from file tmpfile_stdout 08:55:01 INFO - Current package versions: 08:55:01 INFO - blessings == 1.6 08:55:01 INFO - blobuploader == 1.2.4 08:55:01 INFO - docopt == 0.6.1 08:55:01 INFO - functools32 == 3.2.3-2 08:55:01 INFO - jsonschema == 2.5.1 08:55:01 INFO - manifestparser == 1.1 08:55:01 INFO - mozInstall == 1.12 08:55:01 INFO - mozcrash == 1.0 08:55:01 INFO - mozdebug == 0.1 08:55:01 INFO - mozdevice == 0.49 08:55:01 INFO - mozfile == 1.2 08:55:01 INFO - mozhttpd == 0.7 08:55:01 INFO - mozinfo == 0.9 08:55:01 INFO - mozleak == 0.1 08:55:01 INFO - mozlog == 3.4 08:55:01 INFO - moznetwork == 0.27 08:55:01 INFO - mozprocess == 0.23 08:55:01 INFO - mozprofile == 0.28 08:55:01 INFO - mozrunner == 6.13 08:55:01 INFO - mozscreenshot == 0.1 08:55:01 INFO - mozsystemmonitor == 0.3 08:55:01 INFO - moztest == 0.7 08:55:01 INFO - mozversion == 1.4 08:55:01 INFO - psutil == 3.1.1 08:55:01 INFO - requests == 1.2.3 08:55:01 INFO - wsgiref == 0.1.2 08:55:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:01 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:55:01 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 08:55:01 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 08:55:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:55:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:01 INFO - 'HOME': '/Users/cltbld', 08:55:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:01 INFO - 'LOGNAME': 'cltbld', 08:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:01 INFO - 'MOZ_NO_REMOTE': '1', 08:55:01 INFO - 'NO_EM_RESTART': '1', 08:55:01 INFO - 'PAGER': '/bin/cat', 08:55:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:01 INFO - 'PWD': '/builds/slave/test', 08:55:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:01 INFO - 'SHELL': '/bin/bash', 08:55:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:55:01 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:55:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:01 INFO - 'USER': 'cltbld', 08:55:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:01 INFO - 'XPC_FLAGS': '0x0', 08:55:01 INFO - 'XPC_SERVICE_NAME': '0', 08:55:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 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)) 08:55:02 INFO - Cleaning up... 08:55:02 INFO - Return code: 0 08:55:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:55:02 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')]} 08:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:02 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')]} 08:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:02 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 0x1035da3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103550570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f0d6cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, '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.JRhCk503Tg/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.RB9PmuYvmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 08:55:02 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 08:55:02 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 08:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:02 INFO - 'HOME': '/Users/cltbld', 08:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:02 INFO - 'LOGNAME': 'cltbld', 08:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:02 INFO - 'MOZ_NO_REMOTE': '1', 08:55:02 INFO - 'NO_EM_RESTART': '1', 08:55:02 INFO - 'PAGER': '/bin/cat', 08:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:02 INFO - 'PWD': '/builds/slave/test', 08:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:02 INFO - 'SHELL': '/bin/bash', 08:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:55:02 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:02 INFO - 'USER': 'cltbld', 08:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:02 INFO - 'XPC_FLAGS': '0x0', 08:55:02 INFO - 'XPC_SERVICE_NAME': '0', 08:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:55:02 INFO - Cleaning up... 08:55:02 INFO - Return code: 0 08:55:02 INFO - Running post-action listener: _resource_record_post_action 08:55:02 INFO - Running post-action listener: _start_resource_monitoring 08:55:02 INFO - Starting resource monitoring. 08:55:02 INFO - [mozharness: 2016-11-27 16:55:02.417979Z] Finished create-virtualenv step (success) 08:55:02 INFO - [mozharness: 2016-11-27 16:55:02.418976Z] Running install step. 08:55:02 INFO - Running pre-action listener: _resource_record_pre_action 08:55:02 INFO - Running main action method: install 08:55:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:55:02 INFO - Reading from file tmpfile_stdout 08:55:02 INFO - Detecting whether we're running mozinstall >=1.0... 08:55:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:55:02 INFO - Reading from file tmpfile_stdout 08:55:02 INFO - Output received: 08:55:02 INFO - Usage: mozinstall [options] installer 08:55:02 INFO - Options: 08:55:02 INFO - -h, --help show this help message and exit 08:55:02 INFO - -d DEST, --destination=DEST 08:55:02 INFO - Directory to install application into. [default: 08:55:02 INFO - "/builds/slave/test"] 08:55:02 INFO - --app=APP Application being installed. [default: firefox] 08:55:02 INFO - mkdir: /builds/slave/test/build/application 08:55:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:55:35 INFO - Reading from file tmpfile_stdout 08:55:35 INFO - Output received: 08:55:35 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 08:55:35 INFO - Running post-action listener: _resource_record_post_action 08:55:35 INFO - [mozharness: 2016-11-27 16:55:35.441951Z] Finished install step (success) 08:55:35 INFO - [mozharness: 2016-11-27 16:55:35.442095Z] Running run-tests step. 08:55:35 INFO - Running pre-action listener: _resource_record_pre_action 08:55:35 INFO - Running main action method: run_tests 08:55:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:55:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:55:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:55:35 INFO - 2016-11-27 08:55:35.454 screenresolution[1632:10290] starting screenresolution argv=screenresolution get 08:55:35 INFO - 2016-11-27 08:55:35.457 screenresolution[1632:10290] Display 0: 1600x1200x32@60 08:55:35 INFO - 2016-11-27 08:55:35.462 screenresolution[1633:10292] starting screenresolution argv=screenresolution list 08:55:35 INFO - Available Modes on Display 0 08:55:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:55:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:55:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:55:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:55:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:55:35 INFO - Intel Iris: 08:55:35 INFO - Chipset Model: Intel Iris 08:55:35 INFO - Type: GPU 08:55:35 INFO - Bus: Built-In 08:55:35 INFO - VRAM (Dynamic, Max): 1536 MB 08:55:35 INFO - Vendor: Intel (0x8086) 08:55:35 INFO - Device ID: 0x0a2e 08:55:35 INFO - Revision ID: 0x0009 08:55:35 INFO - Displays: 08:55:35 INFO - Display: 08:55:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:55:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:55:35 INFO - Display Serial Number: 1600x1200 60 08:55:35 INFO - Main Display: Yes 08:55:35 INFO - Mirror: Off 08:55:35 INFO - Online: Yes 08:55:35 INFO - Rotation: Supported 08:55:35 INFO - Return code: 0 08:55:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:55:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:55:35 INFO - Python 2.7.3 08:55:35 INFO - Return code: 0 08:55:35 INFO - grabbing minidump binary from tooltool 08:55:35 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')]} 08:55:35 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 0x1033f0d30>, '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 0x103257db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033e8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:55:35 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 08:55:35 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 08:55:35 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 08:55:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:55:35 INFO - Return code: 0 08:55:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:55:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:55:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:55:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:55:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:55:35 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 08:55:35 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 08:55:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRhCk503Tg/Render', 08:55:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:35 INFO - 'HOME': '/Users/cltbld', 08:55:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:35 INFO - 'LOGNAME': 'cltbld', 08:55:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:55:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:35 INFO - 'MOZ_NO_REMOTE': '1', 08:55:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:55:35 INFO - 'NO_EM_RESTART': '1', 08:55:35 INFO - 'PAGER': '/bin/cat', 08:55:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:35 INFO - 'PWD': '/builds/slave/test', 08:55:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:55:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:35 INFO - 'SHELL': '/bin/bash', 08:55:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RB9PmuYvmV/Listeners', 08:55:35 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 08:55:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:35 INFO - 'USER': 'cltbld', 08:55:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:35 INFO - 'XPC_FLAGS': '0x0', 08:55:35 INFO - 'XPC_SERVICE_NAME': '0', 08:55:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:35 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 08:55:36 INFO - mozversion application_buildid: 20161127030208 08:55:36 INFO - mozversion application_changeset: 2a0abcff5cfce087c12f3e4820b5e8b773cffaca 08:55:36 INFO - mozversion application_display_name: Nightly 08:55:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:55:36 INFO - mozversion application_name: Firefox 08:55:36 INFO - mozversion application_remotingname: firefox 08:55:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:55:36 INFO - mozversion application_vendor: Mozilla 08:55:36 INFO - mozversion application_version: 53.0a1 08:55:36 INFO - mozversion platform_buildid: 20161127030208 08:55:36 INFO - mozversion platform_changeset: 2a0abcff5cfce087c12f3e4820b5e8b773cffaca 08:55:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:55:36 INFO - mozversion platform_version: 53.0a1 08:55:36 INFO - using testdate: 1480265736 08:55:36 INFO - actual date: 1480265736 08:55:36 INFO - starting webserver on 'localhost:49236' 08:55:36 INFO - SUITE-START | Running 2 tests 08:55:36 INFO - TEST-START | dromaeo_css 08:55:36 INFO - operating with platform_type : mac_ 08:55:36 INFO - Initialising browser for dromaeo_css test... 08:55:36 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpxAZmCv/profile http://localhost:49236/getInfo.html) 08:55:38 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 08:55:38 INFO - PROCESS | 1643 | colorDepth:24 08:55:38 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 08:55:38 INFO - PROCESS | 1643 | __metrics 08:55:39 INFO - PROCESS | 1643 | 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" 08:55:39 INFO - PROCESS | 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:55:39 INFO - TEST-INFO | 1643: exit 0 08:55:39 INFO - Browser initialized. 08:55:39 INFO - Running cycle 1/1 for dromaeo_css test... 08:55:39 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpxAZmCv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:52 INFO - PROCESS | 1647 | RSS: Main: 237654016 08:55:52 INFO - PROCESS | 1647 | 08:56:43 INFO - PROCESS | 1647 | 1480265803722 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:58:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 08:58:59 INFO - PROCESS | 1647 | RSS: Main: 431935488 08:58:59 INFO - PROCESS | 1647 | 09:02:02 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 09:02:03 INFO - PROCESS | 1647 | RSS: Main: 374095872 09:02:03 INFO - PROCESS | 1647 | 09:05:05 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 09:05:06 INFO - PROCESS | 1647 | RSS: Main: 352899072 09:05:06 INFO - PROCESS | 1647 | 09:08:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 09:08:09 INFO - PROCESS | 1647 | RSS: Main: 345505792 09:08:09 INFO - PROCESS | 1647 | 09:11:12 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 09:11:13 INFO - PROCESS | 1647 | RSS: Main: 339189760 09:11:13 INFO - PROCESS | 1647 | 09:14:16 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 09:14:17 INFO - PROCESS | 1647 | RSS: Main: 450252800 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - PROCESS | 1647 | __start_tp_report 09:14:17 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 09:14:17 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 09:14:17 INFO - PROCESS | 1647 | |i|pagename|runs| 09:14:17 INFO - PROCESS | 1647 | |0;dojo.html;2806.915792526224;3270.7056364927157;3289.5723555937725;3291.1278511194523;3362.462006079028;5531.889362212753;5566.304211973504;5629.521490673287;5637.210790489335;5660.773569057243;5648.632838865471;5661.348944871341;5711.514521265697;5712.343080545738;5757.395473475288;5068.974655126719;5094.682593583966;5111.2333150027425;5113.233015047754;5177.974110129444;5205.765740541666;5268.104422248227;5269.604779641542;5282.841514754564;5286.286351342558;5110;5214.582833373321;5238.083335416287;5257.290265814105;5306.7346632668405;5108.463611320795;5139.0749665060275;5146.202338637517;5177.534021938006;5199.844004679866;5098.184290513537;5139.306193663845;5147.510986456281;5162.870928226786;5165.66423182492;5631.549476041907;5648.265725455664;5649.971750141264;5662.575306851962;5694.402087780765;5997;6038.3659715729655;6041.667708276043;6107.4503294703245;6120.265568131808;5097.847064588068;6149.477294429978;6149.815505534841;6161.4146656067605;6193.1639228704;5710.771569137188;5711.828645140652;5753.683547404933;5774.451427114459;5782.942170578324;6178.783742568989;6204.751809927642;6264.373562643699;6291.937080629226;6298.622082675054;5915.290165180206;5992.34084250732;6074.605150665193;6076.939230607726;6086.847828804315;4778.808847646055;6120.447133218794;6135.110179431455;6212.1303017577575;6219.720112594944;6080.817575472743;6136.386361363917;6143.38566143391;6145.754169833246;6153.507719382439;6159.949364817579;6174.166487524217;6190.690465476799;6206.379362063848;6209.844753881189;4789.568938795455;4854.393200849894;4858.05267972749;4858.80564777412;4871.561559459666;4329;4404.691671582958;4432;4436.800343984464;4441.666874984389;5661.433856614306;5678.602497825113;5679.716014199274;5681.630694004876;5685.232493613309;6136.23297087864;6161.969190154021;6169.136320915076;6169.198004259418;6175.84560385994;5657.915131273035;5666.15007748841;5679.858003549944;5681.0910254359105;5681.204631351614;5195.2986346843445;5303.920441193385;5329.094054010828;5337.172738225581;5342.252084708144;6257.374262573706;6321.367863213642;6321.494280457552;6351.523635727339;6395.936040639534;5534.893021395656;5898.997170480942;6261.592996455216;6263.62418254888;6274.623522588659;6184.350643182581;6243.43809057187;6247.718852651732;6358.2687990879795;6360.332165122595;6023.30731965812;6058.242719660043;6061.090836374579;6069.605475644069;6071.908921366095;5458.972705136449;6138.324784273816;6146.170267013896;6146.815595532141;6151.569390142826;5919;6014.879702405839;6116.082587611894;6122.2959359674205;6129.264488261437;6031.095335699505;6056.091586262096;6079.300880398634;6085.9695701521205;6087.969560152171;5735.655860648374;5750.079987202028;5854.648721076749;5862.032764593794;5867.647941123546;5832.096025116114;5908.763649453974;5980.730867111078;5988.610740301867;5994.490468310242;5761.913571296518;5803.332616749128;5829.183914252093;5841.036229022334;5927.644341339534 09:14:17 INFO - PROCESS | 1647 | |1;ext.html;435.2209544914604;463.2772874316069;469.95535424134704;510.2397427832526;523.9397469291033;27343;38180.42729359058;39403.80298098509;39595.802020989926;39664;40426.797866010704;40730.59269407305;40807;40911.18177636453;40943.79528102363;9113.043130471304;9271.351005429622;9291.674791382304;9300.162985331319;9313;8406.957965210197;8629.870551941725;10986.450677466095;11235;11279.774404511905;11179.552817887276;11259.930306620858;11286.435678216098;11308.491117899714;11311.15166362522;11044.171687123417;11096.889031109708;11152.442377881154;11181.161412893984;11181.664550063511;8375.58122093899;8603.4837909725;10721.35671859691;10993.120550355952;11063.059639930616;38568.4572617095;38825;39014.41478377798;39116;39285.80357098225;3327.4010678077934;3389.508521264403;3416.590009198912;3515.956649852664;3903.1217975955465;28347.43305133886;29367.706322936712;29535.40929181426;29704.70295297041;30173;3167.572377729;3169.492881139007;3187.2031992002;3283.294091770281;3288.6546912574313;389463;466181;466717;470981.64509177575;471563;54530;54673;54857.90284194294;54872.45127548673;54942.725286373316;57210.713946430835;57246;57261.854762904506;57369.426305737245;57408.42591574114;14964.551063468114;15513.45702900393;15535.689286214212;15538.378464861278;15570.143642099792;10985.725356865982;12162.574309899112;12244.571439999558;12245.142840001114;12264.0188784897;12813.103082784302;13157.210567365988;14385.712285754227;14678.853211467962;14809.629759256104;5739.971300143473;6519.771807986698;6596.406323430914;6715.731370745213;6732.764353247613;838.6351936907465;919.016652182172;919.3197034194648;968.3221744778683;968.4576637083256;25106.24681259565;28339.992009588575;28414.00550980706;28683;29153.56269655957;1021.1575450253422;1055.7044027672264;1080.9243352965218;1088.0479580367178;1092.3882625729616;13023.869761302265;14257.002009859418;14680.714942620649;14739.705205895822;15097.924510377377;578030;578215;581442;769679;783509;45527.544724552994;52343.476565234625;54211.91576168455;54547;57345;1338.6051114921122;1362.7002059546883;1379.1173648864935;1380.178793617796;1380.6203294094205;28885.566716498848;28933;28951.7104828949;29648.555271671372;29739.10782676523;46694.53305466902;46991.76504117458;47095;47276.52723472721;47556.7622161887;442393;442494;443679;443705;445679;26323.868380657976;35716.39276232524;36388;36765;36781.63218367782;42695.35956960586;42937.49718759953;43959;44539.554604453544;44770.55229447664;30323.393532129674;35707;36352.09119771921;36532.81733591315;36893.26213475769;35628.46557301591;35710.64289357073;36605.63394366022;36847;37054.44418333673;35257.29485410329;35337.469937951464;35609.28781424409;36008.4598731014;36168.457473137394;25261.74738252594;29889;30910;32071.87748428794;32392.83803580967;21449.78550214478;23816.993334685474;23883.52232955366;23963;24150.879245604363 09:14:17 INFO - PROCESS | 1647 | |2;jquery.html;349.7936217631597;363.3369101389963;370.30198076625555;378.31336124933244;397.45151690666876;14611.488597899075;15018.77471837922;16032.43886463974;20212.898935505338;20842.89578552105;18930.716039259423;19712;20235.089420976055;20431.387058388234;20489.487762805937;5829.708514574276;6117.44942955134;6126.509879209675;6127.969360153193;6133.478654314366;4679.438467383901;6691.6319602421845;6767.864642707143;6816.113905192318;6823.965880170617;6394.584352017104;6599.868002639944;6738.056672065914;6752.088468056799;6754.5609535380045;6633.402993730588;6659.234188068385;6660.134182556261;6665.7000434980055;6689.665516724144;5528.143137813645;5757.165211044375;5810.709464526799;5817.825465236007;5820.650760954356;24825.87587062071;25726.356841078934;25766.355841104123;25767.226983190347;26192.82132304051;2189.7372315322264;2200.614892393841;2238.29493709481;2259.9887000565054;2362.5511152881068;12489.375531223403;15095.54713358601;15406.460773872861;15618;15993;1486.814148231471;1776;1788.1148831328446;1825.917833697487;1853.3605905962422;95187.09625807445;98925.5053724734;122112.38943805313;129074.99999999905;130825;10872.67381978542;11121.551379448285;11339.773204535863;11383.317000979967;11598.76802463946;14507.564773056825;14519.05626134298;14526.927365363317;14533.200673962821;14548.12711237308;7860.5676687782725;8152.592370381457;8290.46112002718;8305.211004954519;8468.618912148968;6716.5634233774645;8119.79700507492;8186.836263274701;8216;8410.074891761902;4437.068215674744;4443.866683999485;4456.509783923766;4514.45826500822;4522.186006518792;1553.7936130694216;1812.4743824291072;1862.1620270878159;1874.240932422371;1889.2254175787862;879.4855009819199;900.4642237868458;915.6795121707349;962.7881865989611;1007.8891321954579;20785.792142078688;21250.831204283913;21296.935153242586;21366.038528266265;21561.353159405244;882.5702362173192;888.6845169964698;888.9822203556022;915.1946287267295;982.5774916785708;10335.069843714102;11143;11214;11234.775304493864;11249.943750281196;534847;536154;546895;547626;553010;122128.45100843033;123759;126417.10374344392;127933;129789;906.795970906554;935.4387367579398;951.4196340232453;951.8191543606692;957.0525180071818;17700.11499424998;18257.45227643173;18345.81654183441;18413.98723070244;18498.722519161955;50759.49240507548;51805.7409712949;51990.48009520008;52829.735851320496;53360.466395337105;70086;73280.26719732734;74608;75760.24239757532;78389;7903.367730581424;8209.220124088202;8245.917540824516;8254.215849494289;8493.278071363757;12424.006079513436;12910.806337905127;13126.409311581192;13378.13042152257;13400.93299533496;10385.792284154426;10796.082333001783;10896.291741036806;11353;11465.942670286595;11061.225714199929;11136.888631113585;11287.492062857355;11413.144014198802;11437.256578322382;11392.145589080686;11699.23954942926;11909.821352679543;12003.579874704343;12063.863395517054;6933.826654333682;7219.855602887807;7348.96325518369;7465;8318.45930014547;9854.310198286337;10925.781484370109;11184.440777960906;11625.24435911663;11696.06431485496 09:14:17 INFO - PROCESS | 1647 | |3;mootools.html;1313.3958379145593;1376.1467889908256;1464.611877852369;1622.7403615421526;1850.972235416469;7327.670254838532;7698.6535605897725;7895.526268423891;8192.262696357328;8196.221358970888;8082.67669293229;8086.272235498804;8104.594770261493;8153.551554664491;8182.386321025917;2022.130483891926;2180.836437267207;2215.4129155773735;2221.555688862226;2225.5882661707556;2129.9787002130015;2258.7854153855355;2327.3018094571644;2339.7426283108844;2348.365941195876;2288.7596802335684;2304.608216603173;2305.2162264829954;2307.7576854430295;2316.270374831931;2295.540891821634;2316.872572008539;2317.8725170115636;2322.4774425754154;2324.2562380038394;2303.4126297794123;2324.32594547581;2330.6387509935985;2339.257253346268;2342.8594284342994;8136.186381361876;8211.260986511243;8224.424290299681;8232.958835205785;8236.629351679188;4917.4344950330815;5009.774560144802;5087.516685914832;5094.083065048289;5101.285819985205;3192.952105718416;3396.320735852815;3418.7606867519285;3423.0903731828;3428.8457019433936;994.9552270147789;1053.8682664666917;1089.5641743302663;1093.753905371293;1096.6381094238961;10181.694549163536;10471.005254500886;10756.946215268952;10764.784704305948;10773.83839242412;4282.528921818597;4360.215161271001;4402.9306226073695;4408.867733967986;4418.293073108287;8484.490930544187;8489.74530764078;8494.490330580185;8500.97988241415;8529.232369086812;4337.7397356158735;4975;5010.3737032870895;5019.548240658285;5027.84916452507;4202.957970420257;4343.174796788663;4517.864464066083;4684.742339171378;4742.383490146259;3363.0079126657693;3532.205253817922;3569.286142771456;3669.156094098319;3672.4674922136674;1123.1183520936027;1129.4239937631867;1130.7003644034337;1142.6514912951463;1142.8457158283525;659.7333120408827;865.9177378149066;869.6564856881623;874.5976850648645;906.6509393883271;4853.514648535118;5369.812056578079;5502.697351645618;5674.77300907959;5720.313562372542;882.6954700628273;897.5198268926093;897.7979954510183;902.4793223418561;912.8950170730318;3389.57630296213;3621.239539696693;3685.963140368562;3689.317476266899;3783.9621603783607;9586.281028922858;9817.705468835946;9833.950830245803;9840.261980351503;9968.152707019839;9761.07269809367;9767.023297670175;9769.951150244204;9866.210703143732;9876.456794876207;7300.780976570712;7605.543667379975;7623.085229772463;7674.577898215652;7725.5364678119495;4956.553910148104;5590.385057643737;5670.5180059695385;5776.451237132465;5872.588918775816;8466.788330291793;9067.773305667148;9267.582958766738;9324.11420915012;9334.766630834012;9261.814763704822;9290.58192381358;9297.163255307054;9379.015203403544;9408.482533460725;6416.390442907917;7069.045678833291;7200.351968322876;7210.675519601526;7224.385927196037;6293.213348331458;6896.931030689629;7799.337056350274;7903.446758726834;7919.326857217201;6944.201416837129;6999.650017499206;7017.7192912282935;7033.085698859116;7169.426445884421;6620.735170593122;6823.965880170567;6914.9308506916295;6960.234374218933;6973;6212.565120441525;6253.280872699515;6465.030245463218;6534.4772418205475;6562.310957349409;4651.534846515321;4741.691790033637;4761.786834011087;4774.474807771212;4916.631252656137;4711.693739906895;4808.543188397097;4813.78337974785;4886.438059623047;4980.028894365564 09:14:17 INFO - PROCESS | 1647 | |4;prototype.html;297.24203281631833;302.75628119363915;304.24698870296015;307.5095223119124;312.25838633246053;4301.612854843063;5131.640785145037;5161.741912904358;5163.598926499177;5174.560162386197;4276.871693849182;5043.823466178685;5098.413682426522;5106.795728170869;5122.6158038147105;3067.647220569635;3370.9325813483715;3813.2754776592496;3830.578636349999;3938.9714297136998;3903.434002069698;3905.824237909301;3921.1570561836033;3937.4881265435597;3967.7024223183375;3222.8066316021113;3866.671332936704;3878.9806050969705;3881.6894648428056;3917.412388141773;3153.5783674712734;3695.2055308108884;3722.1997270586685;3898.7465814722095;3906.164102871646;3262.934741305184;3368.6799754023327;3409.874741335378;3413.8293085345636;3417.3848707232687;4065.227606754737;4933.840547471342;4936.901261974776;4951.554360107608;4982.82560110398;4271.572842715735;4444.377787109807;4454.57681520255;4472.865814025585;4474.261746811772;4668.43978722552;4887.409672873383;5332.76002579881;5333.173358129496;5357.491038351351;3738.102855314732;3742.3263812513737;3742.6257374262627;3750.906227344311;3753.6539662710807;4432.578905004019;4909.5090490950615;5571.9721401393135;5589.608727389084;5648.322201335866;3578.0160455874843;3623.5289412376223;3749.418840079792;3792.962070379316;3817.5800661927165;3845.9615403845605;3849.6920246380223;3858.2862170498547;3868.690504759669;3888.300105980894;4426.800793964279;4430.003249268889;4430.1425580501045;4431.379606855043;4432.512423633398;4682.344471773954;5109.616778741609;5130.256112863613;5420.945790542123;5469.808556700496;1070.7644318249972;1085.592902661502;1146.489812033637;1231.4643130238376;1241.453760345445;696.7770313499632;721.3791405931987;831.6631764135535;843.6583183810567;843.7047033538213;4806.94247265601;4874.902501949941;5001.724215481947;5038.168702164176;5064.82273120439;4669.929951050737;5117.744112794346;5323.722306646455;5558.666480011212;5580.149696709339;4289.957100429018;4486.461624605068;4490.910181796411;4717.481077081518;5115.7953681852305;2485.6644353012475;2556.501482210951;2611.315701372604;2699.986500067487;2765.4607351566656;4464.70067915024;5270;5432.429594892558;5491.615586908958;5544.279243698294;4543.68194226401;5059.949400506021;5370.838874833761;5402;5422.945770542244;4127.422160897417;4555.088982203572;5448.83653490396;5505.614047360844;5560.719175686284;4510.34599982994;4991.625628077982;5213.061648903234;5225.3990791057995;5305.549028332635;4798.95201047985;4814.759262036814;4880.6095512358215;5199.048722970494;5353.518183363516;5490.81947381315;5707.600467967202;5851.970740146272;5884.499087887545;5925.962956482685;3293.40718670632;3324.5511855900113;3498.737594680358;3627.2020155565733;3784.4512545680172;5067.949320506747;5116.565091967075;5627.5497960162265;5630.521405680399;5669.716514174357;4691.859244222679;4852.44196917359;5382.654336415896;5563.693996830051;5575;4795.95204047955;5210.857368392213;5501.092319767354;5526.668399896019;5552.5008247773685;4535.639308207458;4976.601871850171;5202.60980426462;5421.322334708161;5430.864228394164;5264.815731449534;5401.6218864679095;5528.894221155704;5556.360873542396;5560.916586251128;5244.580433565382;5281.815136470205;5386.380566234933;5405.675659460445;5429.0455436043785 09:14:17 INFO - PROCESS | 1647 | |5;yui.html;672.7847088931541;725.8802297620892;774.9225077492248;776.5767656627141;803.5821372886096;14627.341769620367;16806.49580512585;17500.299988000497;17504.21231044603;17532.912335438337;16129.193540322965;16293.348266069373;16315.83684163158;16330.428435004776;16342.83657163428;822.0505316359614;855.448235887852;856.1481326080559;860.4106187261715;860.5568132411781;656.8653426047673;677.3598948993204;678.1794029224644;682.3381320119477;682.6143229075582;706.2478460439642;854.7136709202425;876.9386142969995;882.9117088291184;900.3697411811726;865.1132589096164;881.6914080071988;885.4864178776294;885.7475619448426;885.9822803543925;631.4443289904855;651.8109748173024;655.0926966151866;658.9077529145875;658.980230593083;11902.58340958071;12309.138360314782;14621.342039608244;15068.856064582218;15194.848051519564;13612.727745445036;13839.238841863693;13929.86070139306;13960.790588141186;13983.79024314636;11587.88412115885;11672.883271167264;11696.18126731129;11890.298731141955;11914.642560723192;3087.938241235185;3592.892213233607;3611.3138503684477;3635.690966267844;3644.7813131211947;77503;109290.45354773175;110923;111021;113372;10298.485075746332;10404.68785936423;10458.843117353246;10510.737231569272;10763.19276054299;21235.362939111852;21273.36179914605;21308.36074917755;21328.466788330417;21347.78652213489;7253.492255542167;7322.231165727629;7325.377342925911;7454.813629659302;7614.048243969504;4088.12105397341;4173.4157217989505;4277.850275240352;4354.586314300136;4438.622717069085;3373.6795004474534;3400.268942177444;3457.6023757267735;3521.841517131735;3527.5943266524678;625.8591816841174;626.9999351035092;633.2369494758805;638.543441439373;648.4164252173082;602.9297996057034;606.8027890935464;621.4096608222206;667.5460686733068;669.293894940842;7620.314171724572;8877.467351958903;9019.639214431481;9265.953670231605;9435.858462123073;590.0647473754062;590.7075997381238;666.9566478178903;672.8721542906832;674.6289540752566;4119.649829764444;4511.932321015187;4521.909561808811;4627.398438203079;4634.21218392874;94292;112066.43966780114;112878;115840.42079789547;117068.8293117075;74407.25592744003;76379.23620763833;77062.61468692732;84073;85419;69301.96047059298;73827.63086184535;76185;77612.05969850799;78494;9616.326857119935;9820.950895245478;9847.31068825204;9864;9865.950670246602;49138.75430622824;49515.00969980508;49996;50221;51427.48572514227;67950.3204967944;70683.98372276622;71247;71813.92279115714;72303.27696722966;11796.351200684045;12277.938610306892;12359.3820308986;12431.06766992461;12640.517871437038;14220;14237.430502779773;14274.714505710035;14760.778588321398;14940.402383904522;10883.836742448711;13515.594532163657;13578.32108394596;13774.380152892943;14084.295785210494;12279.69300767488;12480.812787808009;12568.622941311774;12736.6815829605;12746.112450554521;10416.635417760343;10672.786544269227;12168.687306593887;12363.69090772738;12671.80992285135;10330.328528645614;10477.895221047693;10726.074252834504;10900.291481053706;11279.041281491165;9321.974582795752;10352.585896564355;10857.457127143452;11302.491387887729;11319.773604528027 09:14:17 INFO - PROCESS | 1647 | __end_tp_report 09:14:17 INFO - PROCESS | 1647 | __start_cc_report 09:14:17 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1997 09:14:17 INFO - PROCESS | 1647 | __end_cc_report 09:14:17 INFO - PROCESS | 1647 | __startTimestamp1480266857121__endTimestamp 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - PROCESS | 1647 | ------- Summary: start ------- 09:14:17 INFO - PROCESS | 1647 | Number of tests: 6 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5661.13 Median:5803.33 stddev:643.27 (11.1%) stddev-sans-first:605.02 09:14:17 INFO - PROCESS | 1647 | Values: 2806.9 3270.7 3289.6 3291.1 3362.5 5531.9 5566.3 5629.5 5637.2 5660.8 5648.6 5661.3 5711.5 5712.3 5757.4 5069.0 5094.7 5111.2 5113.2 5178.0 5205.8 5268.1 5269.6 5282.8 5286.3 5110.0 5214.6 5238.1 5257.3 5306.7 5108.5 5139.1 5146.2 5177.5 5199.8 5098.2 5139.3 5147.5 5162.9 5165.7 5631.5 5648.3 5650.0 5662.6 5694.4 5997.0 6038.4 6041.7 6107.5 6120.3 5097.8 6149.5 6149.8 6161.4 6193.2 5710.8 5711.8 5753.7 5774.5 5782.9 6178.8 6204.8 6264.4 6291.9 6298.6 5915.3 5992.3 6074.6 6076.9 6086.8 4778.8 6120.4 6135.1 6212.1 6219.7 6080.8 6136.4 6143.4 6145.8 6153.5 6159.9 6174.2 6190.7 6206.4 6209.8 4789.6 4854.4 4858.1 4858.8 4871.6 4329.0 4404.7 4432.0 4436.8 4441.7 5661.4 5678.6 5679.7 5681.6 5685.2 6136.2 6162.0 6169.1 6169.2 6175.8 5657.9 5666.2 5679.9 5681.1 5681.2 5195.3 5303.9 5329.1 5337.2 5342.3 6257.4 6321.4 6321.5 6351.5 6395.9 5534.9 5899.0 6261.6 6263.6 6274.6 6184.4 6243.4 6247.7 6358.3 6360.3 6023.3 6058.2 6061.1 6069.6 6071.9 5459.0 6138.3 6146.2 6146.8 6151.6 5919.0 6014.9 6116.1 6122.3 6129.3 6031.1 6056.1 6079.3 6086.0 6088.0 5735.7 5750.1 5854.6 5862.0 5867.6 5832.1 5908.8 5980.7 5988.6 5994.5 5761.9 5803.3 5829.2 5841.0 5927.6 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:64814.43 Median:36088.46 stddev:142061.17 (393.6%) stddev-sans-first:142377.46 09:14:17 INFO - PROCESS | 1647 | Values: 435.2 463.3 470.0 510.2 523.9 27343.0 38180.4 39403.8 39595.8 39664.0 40426.8 40730.6 40807.0 40911.2 40943.8 9113.0 9271.4 9291.7 9300.2 9313.0 8407.0 8629.9 10986.5 11235.0 11279.8 11179.6 11259.9 11286.4 11308.5 11311.2 11044.2 11096.9 11152.4 11181.2 11181.7 8375.6 8603.5 10721.4 10993.1 11063.1 38568.5 38825.0 39014.4 39116.0 39285.8 3327.4 3389.5 3416.6 3516.0 3903.1 28347.4 29367.7 29535.4 29704.7 30173.0 3167.6 3169.5 3187.2 3283.3 3288.7 389463.0 466181.0 466717.0 470981.6 471563.0 54530.0 54673.0 54857.9 54872.5 54942.7 57210.7 57246.0 57261.9 57369.4 57408.4 14964.6 15513.5 15535.7 15538.4 15570.1 10985.7 12162.6 12244.6 12245.1 12264.0 12813.1 13157.2 14385.7 14678.9 14809.6 5740.0 6519.8 6596.4 6715.7 6732.8 838.6 919.0 919.3 968.3 968.5 25106.2 28340.0 28414.0 28683.0 29153.6 1021.2 1055.7 1080.9 1088.0 1092.4 13023.9 14257.0 14680.7 14739.7 15097.9 578030.0 578215.0 581442.0 769679.0 783509.0 45527.5 52343.5 54211.9 54547.0 57345.0 1338.6 1362.7 1379.1 1380.2 1380.6 28885.6 28933.0 28951.7 29648.6 29739.1 46694.5 46991.8 47095.0 47276.5 47556.8 442393.0 442494.0 443679.0 443705.0 445679.0 26323.9 35716.4 36388.0 36765.0 36781.6 42695.4 42937.5 43959.0 44539.6 44770.6 30323.4 35707.0 36352.1 36532.8 36893.3 35628.5 35710.6 36605.6 36847.0 37054.4 35257.3 35337.5 35609.3 36008.5 36168.5 25261.7 29889.0 30910.0 32071.9 32392.8 21449.8 23817.0 23883.5 23963.0 24150.9 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:33350.18 Median:25766.79 stddev:91126.77 (353.7%) stddev-sans-first:91348.71 09:14:17 INFO - PROCESS | 1647 | Values: 349.8 363.3 370.3 378.3 397.5 14611.5 15018.8 16032.4 20212.9 20842.9 18930.7 19712.0 20235.1 20431.4 20489.5 5829.7 6117.4 6126.5 6128.0 6133.5 4679.4 6691.6 6767.9 6816.1 6824.0 6394.6 6599.9 6738.1 6752.1 6754.6 6633.4 6659.2 6660.1 6665.7 6689.7 5528.1 5757.2 5810.7 5817.8 5820.7 24825.9 25726.4 25766.4 25767.2 26192.8 2189.7 2200.6 2238.3 2260.0 2362.6 12489.4 15095.5 15406.5 15618.0 15993.0 1486.8 1776.0 1788.1 1825.9 1853.4 95187.1 98925.5 122112.4 129075.0 130825.0 10872.7 11121.6 11339.8 11383.3 11598.8 14507.6 14519.1 14526.9 14533.2 14548.1 7860.6 8152.6 8290.5 8305.2 8468.6 6716.6 8119.8 8186.8 8216.0 8410.1 4437.1 4443.9 4456.5 4514.5 4522.2 1553.8 1812.5 1862.2 1874.2 1889.2 879.5 900.5 915.7 962.8 1007.9 20785.8 21250.8 21296.9 21366.0 21561.4 882.6 888.7 889.0 915.2 982.6 10335.1 11143.0 11214.0 11234.8 11249.9 534847.0 536154.0 546895.0 547626.0 553010.0 122128.5 123759.0 126417.1 127933.0 129789.0 906.8 935.4 951.4 951.8 957.1 17700.1 18257.5 18345.8 18414.0 18498.7 50759.5 51805.7 51990.5 52829.7 53360.5 70086.0 73280.3 74608.0 75760.2 78389.0 7903.4 8209.2 8245.9 8254.2 8493.3 12424.0 12910.8 13126.4 13378.1 13400.9 10385.8 10796.1 10896.3 11353.0 11465.9 11061.2 11136.9 11287.5 11413.1 11437.3 11392.1 11699.2 11909.8 12003.6 12063.9 6933.8 7219.9 7349.0 7465.0 8318.5 9854.3 10925.8 11184.4 11625.2 11696.1 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5289.23 Median:5235.55 stddev:2890.82 (55.2%) stddev-sans-first:2883.48 09:14:17 INFO - PROCESS | 1647 | Values: 1313.4 1376.1 1464.6 1622.7 1851.0 7327.7 7698.7 7895.5 8192.3 8196.2 8082.7 8086.3 8104.6 8153.6 8182.4 2022.1 2180.8 2215.4 2221.6 2225.6 2130.0 2258.8 2327.3 2339.7 2348.4 2288.8 2304.6 2305.2 2307.8 2316.3 2295.5 2316.9 2317.9 2322.5 2324.3 2303.4 2324.3 2330.6 2339.3 2342.9 8136.2 8211.3 8224.4 8233.0 8236.6 4917.4 5009.8 5087.5 5094.1 5101.3 3193.0 3396.3 3418.8 3423.1 3428.8 995.0 1053.9 1089.6 1093.8 1096.6 10181.7 10471.0 10756.9 10764.8 10773.8 4282.5 4360.2 4402.9 4408.9 4418.3 8484.5 8489.7 8494.5 8501.0 8529.2 4337.7 4975.0 5010.4 5019.5 5027.8 4203.0 4343.2 4517.9 4684.7 4742.4 3363.0 3532.2 3569.3 3669.2 3672.5 1123.1 1129.4 1130.7 1142.7 1142.8 659.7 865.9 869.7 874.6 906.7 4853.5 5369.8 5502.7 5674.8 5720.3 882.7 897.5 897.8 902.5 912.9 3389.6 3621.2 3686.0 3689.3 3784.0 9586.3 9817.7 9834.0 9840.3 9968.2 9761.1 9767.0 9770.0 9866.2 9876.5 7300.8 7605.5 7623.1 7674.6 7725.5 4956.6 5590.4 5670.5 5776.5 5872.6 8466.8 9067.8 9267.6 9324.1 9334.8 9261.8 9290.6 9297.2 9379.0 9408.5 6416.4 7069.0 7200.4 7210.7 7224.4 6293.2 6896.9 7799.3 7903.4 7919.3 6944.2 6999.7 7017.7 7033.1 7169.4 6620.7 6824.0 6914.9 6960.2 6973.0 6212.6 6253.3 6465.0 6534.5 6562.3 4651.5 4741.7 4761.8 4774.5 4916.6 4711.7 4808.5 4813.8 4886.4 4980.0 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4307.25 Median:4810.85 stddev:1332.84 (27.7%) stddev-sans-first:1302.15 09:14:17 INFO - PROCESS | 1647 | Values: 297.2 302.8 304.2 307.5 312.3 4301.6 5131.6 5161.7 5163.6 5174.6 4276.9 5043.8 5098.4 5106.8 5122.6 3067.6 3370.9 3813.3 3830.6 3939.0 3903.4 3905.8 3921.2 3937.5 3967.7 3222.8 3866.7 3879.0 3881.7 3917.4 3153.6 3695.2 3722.2 3898.7 3906.2 3262.9 3368.7 3409.9 3413.8 3417.4 4065.2 4933.8 4936.9 4951.6 4982.8 4271.6 4444.4 4454.6 4472.9 4474.3 4668.4 4887.4 5332.8 5333.2 5357.5 3738.1 3742.3 3742.6 3750.9 3753.7 4432.6 4909.5 5572.0 5589.6 5648.3 3578.0 3623.5 3749.4 3793.0 3817.6 3846.0 3849.7 3858.3 3868.7 3888.3 4426.8 4430.0 4430.1 4431.4 4432.5 4682.3 5109.6 5130.3 5420.9 5469.8 1070.8 1085.6 1146.5 1231.5 1241.5 696.8 721.4 831.7 843.7 843.7 4806.9 4874.9 5001.7 5038.2 5064.8 4669.9 5117.7 5323.7 5558.7 5580.1 4290.0 4486.5 4490.9 4717.5 5115.8 2485.7 2556.5 2611.3 2700.0 2765.5 4464.7 5270.0 5432.4 5491.6 5544.3 4543.7 5059.9 5370.8 5402.0 5422.9 4127.4 4555.1 5448.8 5505.6 5560.7 4510.3 4991.6 5213.1 5225.4 5305.5 4799.0 4814.8 4880.6 5199.0 5353.5 5490.8 5707.6 5852.0 5884.5 5926.0 3293.4 3324.6 3498.7 3627.2 3784.5 5067.9 5116.6 5627.5 5630.5 5669.7 4691.9 4852.4 5382.7 5563.7 5575.0 4796.0 5210.9 5501.1 5526.7 5552.5 4535.6 4976.6 5202.6 5421.3 5430.9 5264.8 5401.6 5528.9 5556.4 5560.9 5244.6 5281.8 5386.4 5405.7 5429.0 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20234.27 Median:4316.22 stddev:29477.50 (682.9%) stddev-sans-first:29523.60 09:14:17 INFO - PROCESS | 1647 | Values: 672.8 725.9 774.9 776.6 803.6 14627.3 16806.5 17500.3 17504.2 17532.9 16129.2 16293.3 16315.8 16330.4 16342.8 822.1 855.4 856.1 860.4 860.6 656.9 677.4 678.2 682.3 682.6 706.2 854.7 876.9 882.9 900.4 865.1 881.7 885.5 885.7 886.0 631.4 651.8 655.1 658.9 659.0 11902.6 12309.1 14621.3 15068.9 15194.8 13612.7 13839.2 13929.9 13960.8 13983.8 11587.9 11672.9 11696.2 11890.3 11914.6 3087.9 3592.9 3611.3 3635.7 3644.8 77503.0 109290.5 110923.0 111021.0 113372.0 10298.5 10404.7 10458.8 10510.7 10763.2 21235.4 21273.4 21308.4 21328.5 21347.8 7253.5 7322.2 7325.4 7454.8 7614.0 4088.1 4173.4 4277.9 4354.6 4438.6 3373.7 3400.3 3457.6 3521.8 3527.6 625.9 627.0 633.2 638.5 648.4 602.9 606.8 621.4 667.5 669.3 7620.3 8877.5 9019.6 9266.0 9435.9 590.1 590.7 667.0 672.9 674.6 4119.6 4511.9 4521.9 4627.4 4634.2 94292.0 112066.4 112878.0 115840.4 117068.8 74407.3 76379.2 77062.6 84073.0 85419.0 69302.0 73827.6 76185.0 77612.1 78494.0 9616.3 9821.0 9847.3 9864.0 9866.0 49138.8 49515.0 49996.0 50221.0 51427.5 67950.3 70684.0 71247.0 71813.9 72303.3 11796.4 12277.9 12359.4 12431.1 12640.5 14220.0 14237.4 14274.7 14760.8 14940.4 10883.8 13515.6 13578.3 13774.4 14084.3 12279.7 12480.8 12568.6 12736.7 12746.1 10416.6 10672.8 12168.7 12363.7 12671.8 10330.3 10477.9 10726.1 10900.3 11279.0 9322.0 10352.6 10857.5 11302.5 11319.8 09:14:17 INFO - PROCESS | 1647 | -------- Summary: end -------- 09:14:17 INFO - PROCESS | 1647 | 09:14:17 INFO - TEST-INFO | 1647: exit 0 09:14:17 INFO - TEST-OK | dromaeo_css | took 1121793ms 09:14:17 INFO - TEST-START | kraken 09:14:17 INFO - operating with platform_type : mac_ 09:14:17 INFO - Initialising browser for kraken test... 09:14:17 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpJMw_rE/profile http://localhost:49236/getInfo.html) 09:14:20 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:14:20 INFO - PROCESS | 1652 | colorDepth:24 09:14:20 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:14:20 INFO - PROCESS | 1652 | __metrics 09:14:20 INFO - PROCESS | 1652 | 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" 09:14:20 INFO - PROCESS | 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:14:21 INFO - TEST-INFO | 1652: exit 0 09:14:21 INFO - Browser initialized. 09:14:21 INFO - Running cycle 1/1 for kraken test... 09:14:21 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpJMw_rE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:14:33 INFO - PROCESS | 1654 | RSS: Main: 245268480 09:14:33 INFO - PROCESS | 1654 | 09:14:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 09:14:37 INFO - PROCESS | 1654 | RSS: Main: 294998016 09:14:37 INFO - PROCESS | 1654 | 09:14:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 09:14:40 INFO - PROCESS | 1654 | RSS: Main: 295821312 09:14:40 INFO - PROCESS | 1654 | 09:14:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 09:14:44 INFO - PROCESS | 1654 | RSS: Main: 389836800 09:14:44 INFO - PROCESS | 1654 | 09:14:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 09:14:47 INFO - PROCESS | 1654 | RSS: Main: 292610048 09:14:47 INFO - PROCESS | 1654 | 09:14:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:14:49 INFO - PROCESS | 1654 | RSS: Main: 294748160 09:14:49 INFO - PROCESS | 1654 | 09:14:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 09:14:53 INFO - PROCESS | 1654 | RSS: Main: 336404480 09:14:53 INFO - PROCESS | 1654 | 09:14:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 09:14:56 INFO - PROCESS | 1654 | RSS: Main: 337334272 09:14:56 INFO - PROCESS | 1654 | 09:14:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 09:15:00 INFO - PROCESS | 1654 | RSS: Main: 332963840 09:15:00 INFO - PROCESS | 1654 | 09:15:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:15:02 INFO - PROCESS | 1654 | RSS: Main: 309035008 09:15:02 INFO - PROCESS | 1654 | 09:15:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:15:04 INFO - PROCESS | 1654 | RSS: Main: 312954880 09:15:04 INFO - PROCESS | 1654 | 09:15:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:15:07 INFO - PROCESS | 1654 | RSS: Main: 315224064 09:15:07 INFO - PROCESS | 1654 | 09:15:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:15:11 INFO - PROCESS | 1654 | RSS: Main: 332140544 09:15:11 INFO - PROCESS | 1654 | 09:15:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:15:15 INFO - PROCESS | 1654 | RSS: Main: 325779456 09:15:15 INFO - PROCESS | 1654 | 09:15:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 09:15:17 INFO - PROCESS | 1654 | RSS: Main: 369856512 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | __start_tp_report 09:15:17 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:15:17 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:15:17 INFO - PROCESS | 1654 | |i|pagename|runs| 09:15:17 INFO - PROCESS | 1654 | |0;ai-astar;112;115;115;114;114;115;114;114;113;114 09:15:17 INFO - PROCESS | 1654 | |1;audio-beat-detection;150;116;116;148;115;119;114;115;133;118 09:15:17 INFO - PROCESS | 1654 | |2;audio-dft;183;183;183;185;183;194;257;185;184;184 09:15:17 INFO - PROCESS | 1654 | |3;audio-fft;79;60;69;88;63;58;59;59;76;59 09:15:17 INFO - PROCESS | 1654 | |4;audio-oscillator;81;70;89;69;68;70;81;67;68;82 09:15:17 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;88;85;88;84;88;85;89;85;89;85 09:15:17 INFO - PROCESS | 1654 | |6;imaging-darkroom;152;151;93;97;94;94;93;97;94;93 09:15:17 INFO - PROCESS | 1654 | |7;imaging-desaturate;85;101;138;84;86;85;86;84;84;86 09:15:17 INFO - PROCESS | 1654 | |8;json-parse-financial;57;55;56;57;57;56;56;55;54;55 09:15:17 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;65;75;81;75;72;71;70;71;72;73 09:15:17 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;88;87;89;87;89;88;87;87;88;86 09:15:17 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;153;150;149;150;147;148;148;147;188;155 09:15:17 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;239;247;179;176;177;174;173;175;174;175 09:15:17 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;70;69;70;69;70;70;69;70;73;70 09:15:17 INFO - PROCESS | 1654 | __end_tp_report 09:15:17 INFO - PROCESS | 1654 | __start_cc_report 09:15:17 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1966 09:15:17 INFO - PROCESS | 1654 | __end_cc_report 09:15:17 INFO - PROCESS | 1654 | __startTimestamp1480266917185__endTimestamp 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:15:17 INFO - PROCESS | 1654 | Number of tests: 14 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.94 (0.8%) stddev-sans-first:0.67 09:15:17 INFO - PROCESS | 1654 | Values: 112.0 115.0 115.0 114.0 114.0 115.0 114.0 114.0 113.0 114.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:118.50 stddev:14.07 (11.9%) stddev-sans-first:11.48 09:15:17 INFO - PROCESS | 1654 | Values: 150.0 116.0 116.0 148.0 115.0 119.0 114.0 115.0 133.0 118.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:192.10 Median:184.50 stddev:23.04 (12.5%) stddev-sans-first:24.20 09:15:17 INFO - PROCESS | 1654 | Values: 183.0 183.0 183.0 185.0 183.0 194.0 257.0 185.0 184.0 184.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:67.00 Median:66.00 stddev:10.58 (16.0%) stddev-sans-first:10.30 09:15:17 INFO - PROCESS | 1654 | Values: 79.0 60.0 69.0 88.0 63.0 58.0 59.0 59.0 76.0 59.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:74.50 Median:75.50 stddev:7.91 (10.5%) stddev-sans-first:8.03 09:15:17 INFO - PROCESS | 1654 | Values: 81.0 70.0 89.0 69.0 68.0 70.0 81.0 67.0 68.0 82.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.96 (2.2%) stddev-sans-first:2.01 09:15:17 INFO - PROCESS | 1654 | Values: 88.0 85.0 88.0 84.0 88.0 85.0 89.0 85.0 89.0 85.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:105.80 Median:94.00 stddev:24.13 (25.7%) stddev-sans-first:18.94 09:15:17 INFO - PROCESS | 1654 | Values: 152.0 151.0 93.0 97.0 94.0 94.0 93.0 97.0 94.0 93.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:85.00 stddev:16.98 (20.0%) stddev-sans-first:17.83 09:15:17 INFO - PROCESS | 1654 | Values: 85.0 101.0 138.0 84.0 86.0 85.0 86.0 84.0 84.0 86.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.03 (1.8%) stddev-sans-first:1.00 09:15:17 INFO - PROCESS | 1654 | Values: 57.0 55.0 56.0 57.0 57.0 56.0 56.0 55.0 54.0 55.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:72.50 stddev:4.12 (5.7%) stddev-sans-first:3.35 09:15:17 INFO - PROCESS | 1654 | Values: 65.0 75.0 81.0 75.0 72.0 71.0 70.0 71.0 72.0 73.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:88.00 stddev:0.97 (1.1%) stddev-sans-first:1.01 09:15:17 INFO - PROCESS | 1654 | Values: 88.0 87.0 89.0 87.0 89.0 88.0 87.0 87.0 88.0 86.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:150.00 stddev:12.39 (8.3%) stddev-sans-first:13.14 09:15:17 INFO - PROCESS | 1654 | Values: 153.0 150.0 149.0 150.0 147.0 148.0 148.0 147.0 188.0 155.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.90 Median:176.50 stddev:28.63 (16.2%) stddev-sans-first:23.94 09:15:17 INFO - PROCESS | 1654 | Values: 239.0 247.0 179.0 176.0 177.0 174.0 173.0 175.0 174.0 175.0 09:15:17 INFO - PROCESS | 1654 | 09:15:17 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.00 stddev:1.15 (1.6%) stddev-sans-first:1.22 09:15:17 INFO - PROCESS | 1654 | Values: 70.0 69.0 70.0 69.0 70.0 70.0 69.0 70.0 73.0 70.0 09:15:17 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:15:17 INFO - PROCESS | 1654 | 09:15:18 INFO - TEST-INFO | 1654: exit 0 09:15:18 INFO - TEST-OK | kraken | took 60427ms 09:15:18 INFO - SUITE-END | took 1182s 09:15:18 INFO - Completed test suite (00:19:42) 09:15:18 INFO - javascript benchmark 09:15:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2806.915792526224, 3270.7056364927157, 3289.5723555937725, 3291.1278511194523, 3362.462006079028, 5531.889362212753, 5566.304211973504, 5629.521490673287, 5637.210790489335, 5660.773569057243, 5648.632838865471, 5661.348944871341, 5711.514521265697, 5712.343080545738, 5757.395473475288, 5068.974655126719, 5094.682593583966, 5111.2333150027425, 5113.233015047754, 5177.974110129444, 5205.765740541666, 5268.104422248227, 5269.604779641542, 5282.841514754564, 5286.286351342558, 5110.0, 5214.582833373321, 5238.083335416287, 5257.290265814105, 5306.7346632668405, 5108.463611320795, 5139.0749665060275, 5146.202338637517, 5177.534021938006, 5199.844004679866, 5098.184290513537, 5139.306193663845, 5147.510986456281, 5162.870928226786, 5165.66423182492, 5631.549476041907, 5648.265725455664, 5649.971750141264, 5662.575306851962, 5694.402087780765, 5997.0, 6038.3659715729655, 6041.667708276043, 6107.4503294703245, 6120.265568131808, 5097.847064588068, 6149.477294429978, 6149.815505534841, 6161.4146656067605, 6193.1639228704, 5710.771569137188, 5711.828645140652, 5753.683547404933, 5774.451427114459, 5782.942170578324, 6178.783742568989, 6204.751809927642, 6264.373562643699, 6291.937080629226, 6298.622082675054, 5915.290165180206, 5992.34084250732, 6074.605150665193, 6076.939230607726, 6086.847828804315, 4778.808847646055, 6120.447133218794, 6135.110179431455, 6212.1303017577575, 6219.720112594944, 6080.817575472743, 6136.386361363917, 6143.38566143391, 6145.754169833246, 6153.507719382439, 6159.949364817579, 6174.166487524217, 6190.690465476799, 6206.379362063848, 6209.844753881189, 4789.568938795455, 4854.393200849894, 4858.05267972749, 4858.80564777412, 4871.561559459666, 4329.0, 4404.691671582958, 4432.0, 4436.800343984464, 4441.666874984389, 5661.433856614306, 5678.602497825113, 5679.716014199274, 5681.630694004876, 5685.232493613309, 6136.23297087864, 6161.969190154021, 6169.136320915076, 6169.198004259418, 6175.84560385994, 5657.915131273035, 5666.15007748841, 5679.858003549944, 5681.0910254359105, 5681.204631351614, 5195.2986346843445, 5303.920441193385, 5329.094054010828, 5337.172738225581, 5342.252084708144, 6257.374262573706, 6321.367863213642, 6321.494280457552, 6351.523635727339, 6395.936040639534, 5534.893021395656, 5898.997170480942, 6261.592996455216, 6263.62418254888, 6274.623522588659, 6184.350643182581, 6243.43809057187, 6247.718852651732, 6358.2687990879795, 6360.332165122595, 6023.30731965812, 6058.242719660043, 6061.090836374579, 6069.605475644069, 6071.908921366095, 5458.972705136449, 6138.324784273816, 6146.170267013896, 6146.815595532141, 6151.569390142826, 5919.0, 6014.879702405839, 6116.082587611894, 6122.2959359674205, 6129.264488261437, 6031.095335699505, 6056.091586262096, 6079.300880398634, 6085.9695701521205, 6087.969560152171, 5735.655860648374, 5750.079987202028, 5854.648721076749, 5862.032764593794, 5867.647941123546, 5832.096025116114, 5908.763649453974, 5980.730867111078, 5988.610740301867, 5994.490468310242, 5761.913571296518, 5803.332616749128, 5829.183914252093, 5841.036229022334, 5927.644341339534], "value": 5619.270147114152, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.2209544914604, 463.2772874316069, 469.95535424134704, 510.2397427832526, 523.9397469291033, 27343.0, 38180.42729359058, 39403.80298098509, 39595.802020989926, 39664.0, 40426.797866010704, 40730.59269407305, 40807.0, 40911.18177636453, 40943.79528102363, 9113.043130471304, 9271.351005429622, 9291.674791382304, 9300.162985331319, 9313.0, 8406.957965210197, 8629.870551941725, 10986.450677466095, 11235.0, 11279.774404511905, 11179.552817887276, 11259.930306620858, 11286.435678216098, 11308.491117899714, 11311.15166362522, 11044.171687123417, 11096.889031109708, 11152.442377881154, 11181.161412893984, 11181.664550063511, 8375.58122093899, 8603.4837909725, 10721.35671859691, 10993.120550355952, 11063.059639930616, 38568.4572617095, 38825.0, 39014.41478377798, 39116.0, 39285.80357098225, 3327.4010678077934, 3389.508521264403, 3416.590009198912, 3515.956649852664, 3903.1217975955465, 28347.43305133886, 29367.706322936712, 29535.40929181426, 29704.70295297041, 30173.0, 3167.572377729, 3169.492881139007, 3187.2031992002, 3283.294091770281, 3288.6546912574313, 389463.0, 466181.0, 466717.0, 470981.64509177575, 471563.0, 54530.0, 54673.0, 54857.90284194294, 54872.45127548673, 54942.725286373316, 57210.713946430835, 57246.0, 57261.854762904506, 57369.426305737245, 57408.42591574114, 14964.551063468114, 15513.45702900393, 15535.689286214212, 15538.378464861278, 15570.143642099792, 10985.725356865982, 12162.574309899112, 12244.571439999558, 12245.142840001114, 12264.0188784897, 12813.103082784302, 13157.210567365988, 14385.712285754227, 14678.853211467962, 14809.629759256104, 5739.971300143473, 6519.771807986698, 6596.406323430914, 6715.731370745213, 6732.764353247613, 838.6351936907465, 919.016652182172, 919.3197034194648, 968.3221744778683, 968.4576637083256, 25106.24681259565, 28339.992009588575, 28414.00550980706, 28683.0, 29153.56269655957, 1021.1575450253422, 1055.7044027672264, 1080.9243352965218, 1088.0479580367178, 1092.3882625729616, 13023.869761302265, 14257.002009859418, 14680.714942620649, 14739.705205895822, 15097.924510377377, 578030.0, 578215.0, 581442.0, 769679.0, 783509.0, 45527.544724552994, 52343.476565234625, 54211.91576168455, 54547.0, 57345.0, 1338.6051114921122, 1362.7002059546883, 1379.1173648864935, 1380.178793617796, 1380.6203294094205, 28885.566716498848, 28933.0, 28951.7104828949, 29648.555271671372, 29739.10782676523, 46694.53305466902, 46991.76504117458, 47095.0, 47276.52723472721, 47556.7622161887, 442393.0, 442494.0, 443679.0, 443705.0, 445679.0, 26323.868380657976, 35716.39276232524, 36388.0, 36765.0, 36781.63218367782, 42695.35956960586, 42937.49718759953, 43959.0, 44539.554604453544, 44770.55229447664, 30323.393532129674, 35707.0, 36352.09119771921, 36532.81733591315, 36893.26213475769, 35628.46557301591, 35710.64289357073, 36605.63394366022, 36847.0, 37054.44418333673, 35257.29485410329, 35337.469937951464, 35609.28781424409, 36008.4598731014, 36168.457473137394, 25261.74738252594, 29889.0, 30910.0, 32071.87748428794, 32392.83803580967, 21449.78550214478, 23816.993334685474, 23883.52232955366, 23963.0, 24150.879245604363], "value": 19153.804736893588, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.7936217631597, 363.3369101389963, 370.30198076625555, 378.31336124933244, 397.45151690666876, 14611.488597899075, 15018.77471837922, 16032.43886463974, 20212.898935505338, 20842.89578552105, 18930.716039259423, 19712.0, 20235.089420976055, 20431.387058388234, 20489.487762805937, 5829.708514574276, 6117.44942955134, 6126.509879209675, 6127.969360153193, 6133.478654314366, 4679.438467383901, 6691.6319602421845, 6767.864642707143, 6816.113905192318, 6823.965880170617, 6394.584352017104, 6599.868002639944, 6738.056672065914, 6752.088468056799, 6754.5609535380045, 6633.402993730588, 6659.234188068385, 6660.134182556261, 6665.7000434980055, 6689.665516724144, 5528.143137813645, 5757.165211044375, 5810.709464526799, 5817.825465236007, 5820.650760954356, 24825.87587062071, 25726.356841078934, 25766.355841104123, 25767.226983190347, 26192.82132304051, 2189.7372315322264, 2200.614892393841, 2238.29493709481, 2259.9887000565054, 2362.5511152881068, 12489.375531223403, 15095.54713358601, 15406.460773872861, 15618.0, 15993.0, 1486.814148231471, 1776.0, 1788.1148831328446, 1825.917833697487, 1853.3605905962422, 95187.09625807445, 98925.5053724734, 122112.38943805313, 129074.99999999905, 130825.0, 10872.67381978542, 11121.551379448285, 11339.773204535863, 11383.317000979967, 11598.76802463946, 14507.564773056825, 14519.05626134298, 14526.927365363317, 14533.200673962821, 14548.12711237308, 7860.5676687782725, 8152.592370381457, 8290.46112002718, 8305.211004954519, 8468.618912148968, 6716.5634233774645, 8119.79700507492, 8186.836263274701, 8216.0, 8410.074891761902, 4437.068215674744, 4443.866683999485, 4456.509783923766, 4514.45826500822, 4522.186006518792, 1553.7936130694216, 1812.4743824291072, 1862.1620270878159, 1874.240932422371, 1889.2254175787862, 879.4855009819199, 900.4642237868458, 915.6795121707349, 962.7881865989611, 1007.8891321954579, 20785.792142078688, 21250.831204283913, 21296.935153242586, 21366.038528266265, 21561.353159405244, 882.5702362173192, 888.6845169964698, 888.9822203556022, 915.1946287267295, 982.5774916785708, 10335.069843714102, 11143.0, 11214.0, 11234.775304493864, 11249.943750281196, 534847.0, 536154.0, 546895.0, 547626.0, 553010.0, 122128.45100843033, 123759.0, 126417.10374344392, 127933.0, 129789.0, 906.795970906554, 935.4387367579398, 951.4196340232453, 951.8191543606692, 957.0525180071818, 17700.11499424998, 18257.45227643173, 18345.81654183441, 18413.98723070244, 18498.722519161955, 50759.49240507548, 51805.7409712949, 51990.48009520008, 52829.735851320496, 53360.466395337105, 70086.0, 73280.26719732734, 74608.0, 75760.24239757532, 78389.0, 7903.367730581424, 8209.220124088202, 8245.917540824516, 8254.215849494289, 8493.278071363757, 12424.006079513436, 12910.806337905127, 13126.409311581192, 13378.13042152257, 13400.93299533496, 10385.792284154426, 10796.082333001783, 10896.291741036806, 11353.0, 11465.942670286595, 11061.225714199929, 11136.888631113585, 11287.492062857355, 11413.144014198802, 11437.256578322382, 11392.145589080686, 11699.23954942926, 11909.821352679543, 12003.579874704343, 12063.863395517054, 6933.826654333682, 7219.855602887807, 7348.96325518369, 7465.0, 8318.45930014547, 9854.310198286337, 10925.781484370109, 11184.440777960906, 11625.24435911663, 11696.06431485496], "value": 9590.989906407352, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.3958379145593, 1376.1467889908256, 1464.611877852369, 1622.7403615421526, 1850.972235416469, 7327.670254838532, 7698.6535605897725, 7895.526268423891, 8192.262696357328, 8196.221358970888, 8082.67669293229, 8086.272235498804, 8104.594770261493, 8153.551554664491, 8182.386321025917, 2022.130483891926, 2180.836437267207, 2215.4129155773735, 2221.555688862226, 2225.5882661707556, 2129.9787002130015, 2258.7854153855355, 2327.3018094571644, 2339.7426283108844, 2348.365941195876, 2288.7596802335684, 2304.608216603173, 2305.2162264829954, 2307.7576854430295, 2316.270374831931, 2295.540891821634, 2316.872572008539, 2317.8725170115636, 2322.4774425754154, 2324.2562380038394, 2303.4126297794123, 2324.32594547581, 2330.6387509935985, 2339.257253346268, 2342.8594284342994, 8136.186381361876, 8211.260986511243, 8224.424290299681, 8232.958835205785, 8236.629351679188, 4917.4344950330815, 5009.774560144802, 5087.516685914832, 5094.083065048289, 5101.285819985205, 3192.952105718416, 3396.320735852815, 3418.7606867519285, 3423.0903731828, 3428.8457019433936, 994.9552270147789, 1053.8682664666917, 1089.5641743302663, 1093.753905371293, 1096.6381094238961, 10181.694549163536, 10471.005254500886, 10756.946215268952, 10764.784704305948, 10773.83839242412, 4282.528921818597, 4360.215161271001, 4402.9306226073695, 4408.867733967986, 4418.293073108287, 8484.490930544187, 8489.74530764078, 8494.490330580185, 8500.97988241415, 8529.232369086812, 4337.7397356158735, 4975.0, 5010.3737032870895, 5019.548240658285, 5027.84916452507, 4202.957970420257, 4343.174796788663, 4517.864464066083, 4684.742339171378, 4742.383490146259, 3363.0079126657693, 3532.205253817922, 3569.286142771456, 3669.156094098319, 3672.4674922136674, 1123.1183520936027, 1129.4239937631867, 1130.7003644034337, 1142.6514912951463, 1142.8457158283525, 659.7333120408827, 865.9177378149066, 869.6564856881623, 874.5976850648645, 906.6509393883271, 4853.514648535118, 5369.812056578079, 5502.697351645618, 5674.77300907959, 5720.313562372542, 882.6954700628273, 897.5198268926093, 897.7979954510183, 902.4793223418561, 912.8950170730318, 3389.57630296213, 3621.239539696693, 3685.963140368562, 3689.317476266899, 3783.9621603783607, 9586.281028922858, 9817.705468835946, 9833.950830245803, 9840.261980351503, 9968.152707019839, 9761.07269809367, 9767.023297670175, 9769.951150244204, 9866.210703143732, 9876.456794876207, 7300.780976570712, 7605.543667379975, 7623.085229772463, 7674.577898215652, 7725.5364678119495, 4956.553910148104, 5590.385057643737, 5670.5180059695385, 5776.451237132465, 5872.588918775816, 8466.788330291793, 9067.773305667148, 9267.582958766738, 9324.11420915012, 9334.766630834012, 9261.814763704822, 9290.58192381358, 9297.163255307054, 9379.015203403544, 9408.482533460725, 6416.390442907917, 7069.045678833291, 7200.351968322876, 7210.675519601526, 7224.385927196037, 6293.213348331458, 6896.931030689629, 7799.337056350274, 7903.446758726834, 7919.326857217201, 6944.201416837129, 6999.650017499206, 7017.7192912282935, 7033.085698859116, 7169.426445884421, 6620.735170593122, 6823.965880170567, 6914.9308506916295, 6960.234374218933, 6973.0, 6212.565120441525, 6253.280872699515, 6465.030245463218, 6534.4772418205475, 6562.310957349409, 4651.534846515321, 4741.691790033637, 4761.786834011087, 4774.474807771212, 4916.631252656137, 4711.693739906895, 4808.543188397097, 4813.78337974785, 4886.438059623047, 4980.028894365564], "value": 4290.078891181291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.24203281631833, 302.75628119363915, 304.24698870296015, 307.5095223119124, 312.25838633246053, 4301.612854843063, 5131.640785145037, 5161.741912904358, 5163.598926499177, 5174.560162386197, 4276.871693849182, 5043.823466178685, 5098.413682426522, 5106.795728170869, 5122.6158038147105, 3067.647220569635, 3370.9325813483715, 3813.2754776592496, 3830.578636349999, 3938.9714297136998, 3903.434002069698, 3905.824237909301, 3921.1570561836033, 3937.4881265435597, 3967.7024223183375, 3222.8066316021113, 3866.671332936704, 3878.9806050969705, 3881.6894648428056, 3917.412388141773, 3153.5783674712734, 3695.2055308108884, 3722.1997270586685, 3898.7465814722095, 3906.164102871646, 3262.934741305184, 3368.6799754023327, 3409.874741335378, 3413.8293085345636, 3417.3848707232687, 4065.227606754737, 4933.840547471342, 4936.901261974776, 4951.554360107608, 4982.82560110398, 4271.572842715735, 4444.377787109807, 4454.57681520255, 4472.865814025585, 4474.261746811772, 4668.43978722552, 4887.409672873383, 5332.76002579881, 5333.173358129496, 5357.491038351351, 3738.102855314732, 3742.3263812513737, 3742.6257374262627, 3750.906227344311, 3753.6539662710807, 4432.578905004019, 4909.5090490950615, 5571.9721401393135, 5589.608727389084, 5648.322201335866, 3578.0160455874843, 3623.5289412376223, 3749.418840079792, 3792.962070379316, 3817.5800661927165, 3845.9615403845605, 3849.6920246380223, 3858.2862170498547, 3868.690504759669, 3888.300105980894, 4426.800793964279, 4430.003249268889, 4430.1425580501045, 4431.379606855043, 4432.512423633398, 4682.344471773954, 5109.616778741609, 5130.256112863613, 5420.945790542123, 5469.808556700496, 1070.7644318249972, 1085.592902661502, 1146.489812033637, 1231.4643130238376, 1241.453760345445, 696.7770313499632, 721.3791405931987, 831.6631764135535, 843.6583183810567, 843.7047033538213, 4806.94247265601, 4874.902501949941, 5001.724215481947, 5038.168702164176, 5064.82273120439, 4669.929951050737, 5117.744112794346, 5323.722306646455, 5558.666480011212, 5580.149696709339, 4289.957100429018, 4486.461624605068, 4490.910181796411, 4717.481077081518, 5115.7953681852305, 2485.6644353012475, 2556.501482210951, 2611.315701372604, 2699.986500067487, 2765.4607351566656, 4464.70067915024, 5270.0, 5432.429594892558, 5491.615586908958, 5544.279243698294, 4543.68194226401, 5059.949400506021, 5370.838874833761, 5402.0, 5422.945770542244, 4127.422160897417, 4555.088982203572, 5448.83653490396, 5505.614047360844, 5560.719175686284, 4510.34599982994, 4991.625628077982, 5213.061648903234, 5225.3990791057995, 5305.549028332635, 4798.95201047985, 4814.759262036814, 4880.6095512358215, 5199.048722970494, 5353.518183363516, 5490.81947381315, 5707.600467967202, 5851.970740146272, 5884.499087887545, 5925.962956482685, 3293.40718670632, 3324.5511855900113, 3498.737594680358, 3627.2020155565733, 3784.4512545680172, 5067.949320506747, 5116.565091967075, 5627.5497960162265, 5630.521405680399, 5669.716514174357, 4691.859244222679, 4852.44196917359, 5382.654336415896, 5563.693996830051, 5575.0, 4795.95204047955, 5210.857368392213, 5501.092319767354, 5526.668399896019, 5552.5008247773685, 4535.639308207458, 4976.601871850171, 5202.60980426462, 5421.322334708161, 5430.864228394164, 5264.815731449534, 5401.6218864679095, 5528.894221155704, 5556.360873542396, 5560.916586251128, 5244.580433565382, 5281.815136470205, 5386.380566234933, 5405.675659460445, 5429.0455436043785], "value": 3877.8215595203405, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.7847088931541, 725.8802297620892, 774.9225077492248, 776.5767656627141, 803.5821372886096, 14627.341769620367, 16806.49580512585, 17500.299988000497, 17504.21231044603, 17532.912335438337, 16129.193540322965, 16293.348266069373, 16315.83684163158, 16330.428435004776, 16342.83657163428, 822.0505316359614, 855.448235887852, 856.1481326080559, 860.4106187261715, 860.5568132411781, 656.8653426047673, 677.3598948993204, 678.1794029224644, 682.3381320119477, 682.6143229075582, 706.2478460439642, 854.7136709202425, 876.9386142969995, 882.9117088291184, 900.3697411811726, 865.1132589096164, 881.6914080071988, 885.4864178776294, 885.7475619448426, 885.9822803543925, 631.4443289904855, 651.8109748173024, 655.0926966151866, 658.9077529145875, 658.980230593083, 11902.58340958071, 12309.138360314782, 14621.342039608244, 15068.856064582218, 15194.848051519564, 13612.727745445036, 13839.238841863693, 13929.86070139306, 13960.790588141186, 13983.79024314636, 11587.88412115885, 11672.883271167264, 11696.18126731129, 11890.298731141955, 11914.642560723192, 3087.938241235185, 3592.892213233607, 3611.3138503684477, 3635.690966267844, 3644.7813131211947, 77503.0, 109290.45354773175, 110923.0, 111021.0, 113372.0, 10298.485075746332, 10404.68785936423, 10458.843117353246, 10510.737231569272, 10763.19276054299, 21235.362939111852, 21273.36179914605, 21308.36074917755, 21328.466788330417, 21347.78652213489, 7253.492255542167, 7322.231165727629, 7325.377342925911, 7454.813629659302, 7614.048243969504, 4088.12105397341, 4173.4157217989505, 4277.850275240352, 4354.586314300136, 4438.622717069085, 3373.6795004474534, 3400.268942177444, 3457.6023757267735, 3521.841517131735, 3527.5943266524678, 625.8591816841174, 626.9999351035092, 633.2369494758805, 638.543441439373, 648.4164252173082, 602.9297996057034, 606.8027890935464, 621.4096608222206, 667.5460686733068, 669.293894940842, 7620.314171724572, 8877.467351958903, 9019.639214431481, 9265.953670231605, 9435.858462123073, 590.0647473754062, 590.7075997381238, 666.9566478178903, 672.8721542906832, 674.6289540752566, 4119.649829764444, 4511.932321015187, 4521.909561808811, 4627.398438203079, 4634.21218392874, 94292.0, 112066.43966780114, 112878.0, 115840.42079789547, 117068.8293117075, 74407.25592744003, 76379.23620763833, 77062.61468692732, 84073.0, 85419.0, 69301.96047059298, 73827.63086184535, 76185.0, 77612.05969850799, 78494.0, 9616.326857119935, 9820.950895245478, 9847.31068825204, 9864.0, 9865.950670246602, 49138.75430622824, 49515.00969980508, 49996.0, 50221.0, 51427.48572514227, 67950.3204967944, 70683.98372276622, 71247.0, 71813.92279115714, 72303.27696722966, 11796.351200684045, 12277.938610306892, 12359.3820308986, 12431.06766992461, 12640.517871437038, 14220.0, 14237.430502779773, 14274.714505710035, 14760.778588321398, 14940.402383904522, 10883.836742448711, 13515.594532163657, 13578.32108394596, 13774.380152892943, 14084.295785210494, 12279.69300767488, 12480.812787808009, 12568.622941311774, 12736.6815829605, 12746.112450554521, 10416.635417760343, 10672.786544269227, 12168.687306593887, 12363.69090772738, 12671.80992285135, 10330.328528645614, 10477.895221047693, 10726.074252834504, 10900.291481053706, 11279.041281491165, 9321.974582795752, 10352.585896564355, 10857.457127143452, 11302.491387887729, 11319.773604528027], "value": 7275.331608274947, "unit": "score"}], "name": "dromaeo_css", "value": 7070.644218267975, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 116.0, 148.0, 115.0, 119.0, 114.0, 115.0, 133.0, 118.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 183.0, 185.0, 183.0, 194.0, 257.0, 185.0, 184.0, 184.0], "value": 192.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 69.0, 88.0, 63.0, 58.0, 59.0, 59.0, 76.0, 59.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 89.0, 69.0, 68.0, 70.0, 81.0, 67.0, 68.0, 82.0], "value": 74.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 84.0, 88.0, 85.0, 89.0, 85.0, 89.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 93.0, 97.0, 94.0, 94.0, 93.0, 97.0, 94.0, 93.0], "value": 105.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 101.0, 138.0, 84.0, 86.0, 85.0, 86.0, 84.0, 84.0, 86.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 56.0, 57.0, 57.0, 56.0, 56.0, 55.0, 54.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 81.0, 75.0, 72.0, 71.0, 70.0, 71.0, 72.0, 73.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 87.0, 89.0, 88.0, 87.0, 87.0, 88.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 149.0, 150.0, 147.0, 148.0, 148.0, 147.0, 188.0, 155.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [239.0, 247.0, 179.0, 176.0, 177.0, 174.0, 173.0, 175.0, 174.0, 175.0], "value": 188.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 69.0, 70.0, 70.0, 69.0, 70.0, 73.0, 70.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1484.6, "alertThreshold": 2.0}]} 09:15:18 INFO - Return code: 0 09:15:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:15:18 INFO - # TBPL SUCCESS # 09:15:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:15:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:15:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:15:18 INFO - 2016-11-27 09:15:18.490 screenresolution[1658:12443] starting screenresolution argv=screenresolution get 09:15:18 INFO - 2016-11-27 09:15:18.492 screenresolution[1658:12443] Display 0: 1600x1200x32@60 09:15:18 INFO - 2016-11-27 09:15:18.497 screenresolution[1659:12446] starting screenresolution argv=screenresolution list 09:15:18 INFO - Available Modes on Display 0 09:15:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:15:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:15:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:15:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:15:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:15:18 INFO - Intel Iris: 09:15:18 INFO - Chipset Model: Intel Iris 09:15:18 INFO - Type: GPU 09:15:18 INFO - Bus: Built-In 09:15:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:15:18 INFO - Vendor: Intel (0x8086) 09:15:18 INFO - Device ID: 0x0a2e 09:15:18 INFO - Revision ID: 0x0009 09:15:18 INFO - Displays: 09:15:18 INFO - Display: 09:15:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:15:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:15:18 INFO - Display Serial Number: 1600x1200 60 09:15:18 INFO - Main Display: Yes 09:15:18 INFO - Mirror: Off 09:15:18 INFO - Online: Yes 09:15:18 INFO - Rotation: Supported 09:15:18 INFO - Return code: 0 09:15:18 INFO - Running post-action listener: _resource_record_post_action 09:15:18 INFO - [mozharness: 2016-11-27 17:15:18.732543Z] Finished run-tests step (success) 09:15:18 INFO - Running post-run listener: _resource_record_post_run 09:15:18 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 31955968; Write bytes: 692491776; Read time: 195; Write time: 2003 09:15:18 INFO - TinderboxPrint: CPU usage
53.5% 09:15:18 INFO - TinderboxPrint: I/O read bytes / time
31,955,968 / 195 09:15:18 INFO - TinderboxPrint: I/O write bytes / time
692,491,776 / 2,003 09:15:18 INFO - TinderboxPrint: CPU idle
2,260.3 (46.5%) 09:15:18 INFO - TinderboxPrint: CPU system
971.6 (20.0%) 09:15:18 INFO - TinderboxPrint: CPU user
1,629.8 (33.5%) 09:15:18 INFO - TinderboxPrint: Swap in / out
411,463,680 / 0 09:15:18 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 241664; Write bytes: 386750464; Read time: 4; Write time: 857 09:15:18 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 28736512; Write bytes: 304365056; Read time: 171; Write time: 1140 09:15:19 INFO - Running post-run listener: _upload_blobber_files 09:15:19 INFO - Blob upload gear active. 09:15:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:15:19 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 09:15:19 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'] 09:15:19 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 09:15:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:15:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:15:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:15:20 INFO - (blobuploader) - INFO - Done attempting. 09:15:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:15:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:15:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:15:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:15:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:15:21 INFO - (blobuploader) - INFO - Done attempting. 09:15:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:15:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:15:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:15:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:15:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:15:23 INFO - (blobuploader) - INFO - Done attempting. 09:15:23 INFO - (blobuploader) - INFO - Iteration through files over. 09:15:23 INFO - Return code: 0 09:15:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:15:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:15:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8a3005ba9f4972bb4e27cf02ac1853a293dafc01da47eae237adda98bd3ad307cc4e4d2216ceec5332b4ae6d66f5ee59e88584a4cc9fd6a0ed9315c7382d7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/501366430acdaba46090d2677179c45bb4c2de655ef9e2463909ea302943a7701ce50c2026d482eca6db3c9280d4c685e41bb0a812d7561cabfb709d6980eb8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e437bf279aa04ea4e49795fdf994c4545c3bc71aa1b71a672ebb2368ba2cc5c05409fede0cb197559bbeb4d923db035238cb42f0077af8ff404c68e00c7ff06"} 09:15:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:15:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:15:23 INFO - Contents: 09:15:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8a3005ba9f4972bb4e27cf02ac1853a293dafc01da47eae237adda98bd3ad307cc4e4d2216ceec5332b4ae6d66f5ee59e88584a4cc9fd6a0ed9315c7382d7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/501366430acdaba46090d2677179c45bb4c2de655ef9e2463909ea302943a7701ce50c2026d482eca6db3c9280d4c685e41bb0a812d7561cabfb709d6980eb8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e437bf279aa04ea4e49795fdf994c4545c3bc71aa1b71a672ebb2368ba2cc5c05409fede0cb197559bbeb4d923db035238cb42f0077af8ff404c68e00c7ff06"} 09:15:23 INFO - Running post-run listener: copy_logs_to_upload_dir 09:15:23 INFO - Copying logs to upload dir... 09:15:23 INFO - mkdir: /builds/slave/test/build/upload/logs 09:15:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.517619 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-11-27 09:15:23.998700) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 09:15:24.002666) ========= 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.JRhCk503Tg/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.RB9PmuYvmV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/2b8a3005ba9f4972bb4e27cf02ac1853a293dafc01da47eae237adda98bd3ad307cc4e4d2216ceec5332b4ae6d66f5ee59e88584a4cc9fd6a0ed9315c7382d7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/501366430acdaba46090d2677179c45bb4c2de655ef9e2463909ea302943a7701ce50c2026d482eca6db3c9280d4c685e41bb0a812d7561cabfb709d6980eb8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e437bf279aa04ea4e49795fdf994c4545c3bc71aa1b71a672ebb2368ba2cc5c05409fede0cb197559bbeb4d923db035238cb42f0077af8ff404c68e00c7ff06"} build_url:https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010734 build_url: 'https://queue.taskcluster.net/v1/task/ae-Y0v0BT2i2vtC7Z8HOPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b8a3005ba9f4972bb4e27cf02ac1853a293dafc01da47eae237adda98bd3ad307cc4e4d2216ceec5332b4ae6d66f5ee59e88584a4cc9fd6a0ed9315c7382d7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/501366430acdaba46090d2677179c45bb4c2de655ef9e2463909ea302943a7701ce50c2026d482eca6db3c9280d4c685e41bb0a812d7561cabfb709d6980eb8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e437bf279aa04ea4e49795fdf994c4545c3bc71aa1b71a672ebb2368ba2cc5c05409fede0cb197559bbeb4d923db035238cb42f0077af8ff404c68e00c7ff06"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 09:15:24.042298) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:15:24.042742) ========= 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.JRhCk503Tg/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.RB9PmuYvmV/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005070 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:15:24.092685) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 09:15:24.093121) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 09:15:24.093535) ========= ========= Total master_lag: 0.32 =========