builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0329 starttime: 1484782754.05 results: success (0) buildid: 20170118143333 builduid: e2dfc39c4d2943feb3efe792b80a5e4a revision: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:14.054819) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:14.055256) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 9 secs) (at 2017-01-18 15:39:14.055561) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 9 secs) (at 2017-01-18 15:39:23.269261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:23.269584) ========= 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.lIaETRzInL/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.Ati3OeSSsZ/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-18 15:39:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 62.8M=0s 2017-01-18 15:39:23 (62.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105535 ========= master_lag: 0.60 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:23.970769) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:23.971149) ========= 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.lIaETRzInL/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.Ati3OeSSsZ/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.025119 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:24.019868) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-18 15:39:24.020245) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lIaETRzInL/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.Ati3OeSSsZ/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-18 15:39:24,121 truncating revision to first 12 chars 2017-01-18 15:39:24,121 Setting DEBUG logging. 2017-01-18 15:39:24,121 attempt 1/10 2017-01-18 15:39:24,121 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ed6e5d97950a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-18 15:39:25,204 unpacking tar archive at: mozilla-aurora-ed6e5d97950a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.361706 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-18 15:39:25.558698) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:25.562331) ========= script_repo_revision: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:25.562800) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:25.563093) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:25.586025) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-01-18 15:39:25.586432) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lIaETRzInL/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.Ati3OeSSsZ/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:39:25 INFO - MultiFileLogger online at 20170118 15:39:25 in /builds/slave/test 15:39:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:39:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:25 INFO - {'append_to_log': False, 15:39:25 INFO - 'base_work_dir': '/builds/slave/test', 15:39:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:39:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:25 INFO - 'branch': 'Mozilla-Aurora', 15:39:25 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:25 INFO - 'config_files': ('talos/mac_config.py',), 15:39:25 INFO - 'default_actions': ('clobber', 15:39:25 INFO - 'read-buildbot-config', 15:39:25 INFO - 'download-and-extract', 15:39:25 INFO - 'populate-webroot', 15:39:25 INFO - 'create-virtualenv', 15:39:25 INFO - 'install', 15:39:25 INFO - 'run-tests'), 15:39:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:25 INFO - 'download_minidump_stackwalk': True, 15:39:25 INFO - 'download_symbols': 'ondemand', 15:39:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:25 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:25 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:25 INFO - 'installer_path': 'installer.exe', 15:39:25 INFO - 'log_level': 'info', 15:39:25 INFO - 'log_name': 'talos', 15:39:25 INFO - 'log_to_console': True, 15:39:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:39:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:39:25 INFO - 'opt_config_files': (), 15:39:25 INFO - 'pip_index': False, 15:39:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:25 INFO - 'cmd': ('bash', 15:39:25 INFO - '-c', 15:39:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:25 INFO - 'enabled': True, 15:39:25 INFO - 'halt_on_failure': False, 15:39:25 INFO - 'name': 'check_screen_resolution'},), 15:39:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:25 INFO - 'cmd': ('bash', 15:39:25 INFO - '-c', 15:39:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:25 INFO - 'enabled': True, 15:39:25 INFO - 'halt_on_failure': False, 15:39:25 INFO - 'name': 'check_screen_resolution'},), 15:39:25 INFO - 'run_cmd_checks_enabled': True, 15:39:25 INFO - 'sps_profile': False, 15:39:25 INFO - 'sps_profile_interval': 0, 15:39:25 INFO - 'suite': 'dromaeojs', 15:39:25 INFO - 'system_bits': '32', 15:39:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:25 INFO - 'title': 't-yosemite-r7-0329', 15:39:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:25 INFO - 'use_talos_json': True, 15:39:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:25 INFO - 'work_dir': 'build'} 15:39:25 INFO - [mozharness: 2017-01-18 23:39:25.793620Z] Running clobber step. 15:39:25 INFO - Running pre-action listener: _resource_record_pre_action 15:39:25 INFO - Running main action method: clobber 15:39:25 INFO - rmtree: /builds/slave/test/build 15:39:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:28 INFO - Running post-action listener: _resource_record_post_action 15:39:28 INFO - [mozharness: 2017-01-18 23:39:28.226907Z] Finished clobber step (success) 15:39:28 INFO - [mozharness: 2017-01-18 23:39:28.227011Z] Running read-buildbot-config step. 15:39:28 INFO - Running pre-action listener: _resource_record_pre_action 15:39:28 INFO - Running main action method: read_buildbot_config 15:39:28 INFO - Using buildbot properties: 15:39:28 INFO - { 15:39:28 INFO - "project": "", 15:39:28 INFO - "product": "firefox", 15:39:28 INFO - "script_repo_revision": "production", 15:39:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:39:28 INFO - "repository": "", 15:39:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:39:28 INFO - "buildid": "20170118143333", 15:39:28 INFO - "pgo_build": "False", 15:39:28 INFO - "basedir": "/builds/slave/test", 15:39:28 INFO - "buildnumber": 42, 15:39:28 INFO - "slavename": "t-yosemite-r7-0329", 15:39:28 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:39:28 INFO - "platform": "yosemite_r7", 15:39:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:39:28 INFO - "branch": "mozilla-aurora", 15:39:28 INFO - "repo_path": "releases/mozilla-aurora", 15:39:28 INFO - "revision": "ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b", 15:39:28 INFO - "stage_platform": "macosx64", 15:39:28 INFO - "builduid": "e2dfc39c4d2943feb3efe792b80a5e4a", 15:39:28 INFO - "slavebuilddir": "test" 15:39:28 INFO - } 15:39:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:39:28 INFO - Running post-action listener: _resource_record_post_action 15:39:28 INFO - [mozharness: 2017-01-18 23:39:28.230324Z] Finished read-buildbot-config step (success) 15:39:28 INFO - [mozharness: 2017-01-18 23:39:28.230438Z] Running download-and-extract step. 15:39:28 INFO - Running pre-action listener: _resource_record_pre_action 15:39:28 INFO - Running main action method: download_and_extract 15:39:28 INFO - mkdir: /builds/slave/test/build/tests 15:39:28 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')]} 15:39:28 INFO - trying https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:39:28 INFO - Downloading https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:39:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 15:39:29 INFO - Downloaded 1199 bytes. 15:39:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:39:29 INFO - Using the following test package requirements: 15:39:29 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:39:29 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:29 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:39:29 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:29 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:39:29 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:29 INFO - u'jsshell-mac.zip'], 15:39:29 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:29 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:39:29 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:39:29 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:29 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:39:29 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:29 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:39:29 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:29 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:39:29 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:29 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:39:29 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:39:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:39:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:39:29 INFO - Fetch https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:39:30 INFO - Http code: 200 15:39:30 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 15:39:30 INFO - x-amz-cf-id: FLLEO5tiYO28B2gt6Ubv_bYwPxernTiX_StRNGIP9BNTZWc3KIQMFA== 15:39:30 INFO - x-amz-version-id: sbyXcrSpsgywg.1yAkx5MFaMQ0_7PT.G 15:39:30 INFO - Expected file size: 16275843 15:39:30 INFO - Obtained file size: 16275843 15:39:37 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:39:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:39:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:39:37 INFO - Fetch https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:39:37 INFO - Http code: 200 15:39:37 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 15:39:37 INFO - x-amz-cf-id: ElZbfD4Snhu7RX4q5hma2AOwhKMx8IxeIGsdfQJ6sGknZiERGxve3g== 15:39:37 INFO - x-amz-version-id: sbyXcrSpsgywg.1yAkx5MFaMQ0_7PT.G 15:39:37 INFO - Expected file size: 16275843 15:39:37 INFO - Obtained file size: 16275843 15:39:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 15:39:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:39:44 INFO - Fetch https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 15:39:45 INFO - Http code: 200 15:39:45 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 15:39:45 INFO - x-amz-cf-id: om1KSBi7ePClW0Q00i8LnHwx_Q500CCJbnUULbAYCLAGnRsev1qX9w== 15:39:45 INFO - x-amz-version-id: z9HreXKrtQhR.q1PGRe0R1juWb36cUIz 15:39:45 INFO - Expected file size: 14409038 15:39:45 INFO - Obtained file size: 14409038 15:39:45 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')]} 15:39:45 INFO - trying https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:39:45 INFO - Downloading https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 15:39:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 15:39:48 INFO - Downloaded 110007319 bytes. 15:39:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:39:48 INFO - mkdir: /builds/slave/test/properties 15:39:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:39:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:39:48 INFO - Contents: 15:39:48 INFO - build_url:https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:39:49 INFO - Running post-action listener: _resource_record_post_action 15:39:49 INFO - Running post-action listener: set_extra_try_arguments 15:39:49 INFO - [mozharness: 2017-01-18 23:39:49.291907Z] Finished download-and-extract step (success) 15:39:49 INFO - [mozharness: 2017-01-18 23:39:49.292022Z] Running populate-webroot step. 15:39:49 INFO - Running pre-action listener: _resource_record_pre_action 15:39:49 INFO - Running main action method: populate_webroot 15:39:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:39:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:39:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:39:49 INFO - '--tppagecycles', 15:39:49 INFO - '7'], 15:39:49 INFO - 'tests': ['tsvgm']}}, 15:39:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:39:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:39:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:39:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:39:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:49 INFO - 'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:49 INFO - 'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['damp', 'tps']}, 15:39:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:49 INFO - 'tests': ['damp', 'tps']}, 15:39:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['dromaeo_dom']}, 15:39:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:39:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['basic_compositor_video']}, 15:39:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:39:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['a11yr', 15:39:49 INFO - 'ts_paint', 15:39:49 INFO - 'tpaint', 15:39:49 INFO - 'sessionrestore', 15:39:49 INFO - 'sessionrestore_no_auto_restore', 15:39:49 INFO - 'tabpaint']}, 15:39:49 INFO - 'other-e10s': {'tests': ['a11yr', 15:39:49 INFO - 'ts_paint', 15:39:49 INFO - 'tpaint', 15:39:49 INFO - 'sessionrestore', 15:39:49 INFO - 'sessionrestore_no_auto_restore', 15:39:49 INFO - 'tabpaint']}, 15:39:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:39:49 INFO - 'ts_paint', 15:39:49 INFO - 'tpaint', 15:39:49 INFO - 'sessionrestore', 15:39:49 INFO - 'sessionrestore_no_auto_restore', 15:39:49 INFO - 'tabpaint']}, 15:39:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:39:49 INFO - 'ts_paint', 15:39:49 INFO - 'tpaint', 15:39:49 INFO - 'sessionrestore', 15:39:49 INFO - 'sessionrestore_no_auto_restore', 15:39:49 INFO - 'tabpaint']}, 15:39:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['a11yr', 15:39:49 INFO - 'ts_paint', 15:39:49 INFO - 'tpaint', 15:39:49 INFO - 'sessionrestore', 15:39:49 INFO - 'sessionrestore_no_auto_restore', 15:39:49 INFO - 'tabpaint']}, 15:39:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['a11yr', 15:39:49 INFO - 'ts_paint', 15:39:49 INFO - 'tpaint', 15:39:49 INFO - 'sessionrestore', 15:39:49 INFO - 'sessionrestore_no_auto_restore', 15:39:49 INFO - 'tabpaint']}, 15:39:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['tsvgx', 15:39:49 INFO - 'tsvgr_opacity', 15:39:49 INFO - 'tart', 15:39:49 INFO - 'tscrollx', 15:39:49 INFO - 'cart']}, 15:39:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:39:49 INFO - 'tsvgr_opacity', 15:39:49 INFO - 'tart', 15:39:49 INFO - 'tscrollx', 15:39:49 INFO - 'cart']}, 15:39:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:49 INFO - 'talos_options': ['--disable-e10s'], 15:39:49 INFO - 'tests': ['tp5o']}, 15:39:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:49 INFO - 'tests': ['tp5o']}, 15:39:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:49 INFO - 'talos_options': ['--disable-e10s', 15:39:49 INFO - '--xperf_path', 15:39:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:49 INFO - 'tests': ['tp5n']}, 15:39:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:49 INFO - 'talos_options': ['--xperf_path', 15:39:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:49 INFO - 'tests': ['tp5n']}}, 15:39:49 INFO - 'talos.zip': {'path': '', 15:39:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:39:49 INFO - Running post-action listener: _resource_record_post_action 15:39:49 INFO - [mozharness: 2017-01-18 23:39:49.310875Z] Finished populate-webroot step (success) 15:39:49 INFO - [mozharness: 2017-01-18 23:39:49.311005Z] Running create-virtualenv step. 15:39:49 INFO - Running pre-action listener: _resource_record_pre_action 15:39:49 INFO - Running main action method: create_virtualenv 15:39:49 INFO - Creating virtualenv /builds/slave/test/build/venv 15:39:49 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 15:39:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:39:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:39:49 INFO - Using real prefix '/tools/python27' 15:39:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:39:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:39:52 INFO - Installing pip.................done. 15:39:52 INFO - Return code: 0 15:39:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:39:53 INFO - Reading from file tmpfile_stdout 15:39:53 INFO - Output received: 15:39:53 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) 15:39:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:39:53 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')]} 15:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:53 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')]} 15:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:53 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 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:39:53 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 15:39:53 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 15:39:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:39:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:53 INFO - 'HOME': '/Users/cltbld', 15:39:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:53 INFO - 'LOGNAME': 'cltbld', 15:39:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:53 INFO - 'MOZ_NO_REMOTE': '1', 15:39:53 INFO - 'NO_EM_RESTART': '1', 15:39:53 INFO - 'PAGER': '/bin/cat', 15:39:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:53 INFO - 'PWD': '/builds/slave/test', 15:39:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:53 INFO - 'SHELL': '/bin/bash', 15:39:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:39:53 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:39:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:53 INFO - 'USER': 'cltbld', 15:39:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:53 INFO - 'XPC_FLAGS': '0x0', 15:39:53 INFO - 'XPC_SERVICE_NAME': '0', 15:39:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:53 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 15:39:53 INFO - Cleaning up... 15:39:53 INFO - Return code: 0 15:39:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39:53 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')]} 15:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:53 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')]} 15:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:53 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 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:39:53 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 15:39:53 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 15:39:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:39:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:53 INFO - 'HOME': '/Users/cltbld', 15:39:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:53 INFO - 'LOGNAME': 'cltbld', 15:39:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:53 INFO - 'MOZ_NO_REMOTE': '1', 15:39:53 INFO - 'NO_EM_RESTART': '1', 15:39:53 INFO - 'PAGER': '/bin/cat', 15:39:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:53 INFO - 'PWD': '/builds/slave/test', 15:39:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:53 INFO - 'SHELL': '/bin/bash', 15:39:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:39:53 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:39:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:53 INFO - 'USER': 'cltbld', 15:39:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:53 INFO - 'XPC_FLAGS': '0x0', 15:39:53 INFO - 'XPC_SERVICE_NAME': '0', 15:39:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:53 INFO - Downloading/unpacking psutil>=3.1.1 15:39:53 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 15:39:53 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 15:39:53 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 15:39:53 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 15:39:53 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 15:39:53 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 15:39:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:57 INFO - Installing collected packages: psutil 15:39:57 INFO - Running setup.py install for psutil 15:39:57 INFO - building 'psutil._psutil_osx' extension 15:39:57 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 15:39:57 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 15:39:58 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 15:39:58 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 15:39:58 INFO - building 'psutil._psutil_posix' extension 15:39:58 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 15:39:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:39:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:39:58 INFO - ^ 15:39:58 INFO - 1 warning generated. 15:39:58 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 15:39:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:58 INFO - Successfully installed psutil 15:39:58 INFO - Cleaning up... 15:39:58 INFO - Return code: 0 15:39:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:39:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:39:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:39:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:58 INFO - 'HOME': '/Users/cltbld', 15:39:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:58 INFO - 'LOGNAME': 'cltbld', 15:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:58 INFO - 'MOZ_NO_REMOTE': '1', 15:39:58 INFO - 'NO_EM_RESTART': '1', 15:39:58 INFO - 'PAGER': '/bin/cat', 15:39:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:58 INFO - 'PWD': '/builds/slave/test', 15:39:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:58 INFO - 'SHELL': '/bin/bash', 15:39:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:39:58 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:39:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:58 INFO - 'USER': 'cltbld', 15:39:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:58 INFO - 'XPC_FLAGS': '0x0', 15:39:58 INFO - 'XPC_SERVICE_NAME': '0', 15:39:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39: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 15:39: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 15:39: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 15:39: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 15:39: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 15:39: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 15:40:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:40:01 INFO - Installing collected packages: mozsystemmonitor 15:40:01 INFO - Running setup.py install for mozsystemmonitor 15:40:01 INFO - Successfully installed mozsystemmonitor 15:40:01 INFO - Cleaning up... 15:40:01 INFO - Return code: 0 15:40:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:01 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 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:40:01 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 15:40:01 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 15:40:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:40:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:01 INFO - 'HOME': '/Users/cltbld', 15:40:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:01 INFO - 'LOGNAME': 'cltbld', 15:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:01 INFO - 'MOZ_NO_REMOTE': '1', 15:40:01 INFO - 'NO_EM_RESTART': '1', 15:40:01 INFO - 'PAGER': '/bin/cat', 15:40:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:01 INFO - 'PWD': '/builds/slave/test', 15:40:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:01 INFO - 'SHELL': '/bin/bash', 15:40:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:40:01 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:40:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:01 INFO - 'USER': 'cltbld', 15:40:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:01 INFO - 'XPC_FLAGS': '0x0', 15:40:01 INFO - 'XPC_SERVICE_NAME': '0', 15:40:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:01 INFO - Downloading/unpacking jsonschema==2.5.1 15:40:01 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 15:40:01 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 15:40:01 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 15:40:01 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 15:40:01 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 15:40:01 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 15:40:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:40:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:04 INFO - Downloading functools32-3.2.3-2.tar.gz 15:40:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:40:04 INFO - warning: no files found matching '*.txt' 15:40:04 INFO - no previously-included directories found matching 'build' 15:40:04 INFO - no previously-included directories found matching 'dist' 15:40:04 INFO - no previously-included directories found matching '.git*' 15:40:04 INFO - Installing collected packages: jsonschema, functools32 15:40:05 INFO - Running setup.py install for functools32 15:40:05 INFO - warning: no files found matching '*.txt' 15:40:05 INFO - no previously-included directories found matching 'build' 15:40:05 INFO - no previously-included directories found matching 'dist' 15:40:05 INFO - no previously-included directories found matching '.git*' 15:40:05 INFO - Successfully installed jsonschema functools32 15:40:05 INFO - Cleaning up... 15:40:05 INFO - Return code: 0 15:40:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:40:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:40:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:40:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:40:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:06 INFO - 'HOME': '/Users/cltbld', 15:40:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:06 INFO - 'LOGNAME': 'cltbld', 15:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:06 INFO - 'MOZ_NO_REMOTE': '1', 15:40:06 INFO - 'NO_EM_RESTART': '1', 15:40:06 INFO - 'PAGER': '/bin/cat', 15:40:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:06 INFO - 'PWD': '/builds/slave/test', 15:40:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:06 INFO - 'SHELL': '/bin/bash', 15:40:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:40:06 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:40:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:06 INFO - 'USER': 'cltbld', 15:40:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:06 INFO - 'XPC_FLAGS': '0x0', 15:40:06 INFO - 'XPC_SERVICE_NAME': '0', 15:40:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:40:06 INFO - Cleaning up... 15:40:06 INFO - Return code: 0 15:40:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:07 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 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:40:07 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 15:40:07 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 15:40:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:40:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:07 INFO - 'HOME': '/Users/cltbld', 15:40:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:07 INFO - 'LOGNAME': 'cltbld', 15:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:07 INFO - 'MOZ_NO_REMOTE': '1', 15:40:07 INFO - 'NO_EM_RESTART': '1', 15:40:07 INFO - 'PAGER': '/bin/cat', 15:40:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:07 INFO - 'PWD': '/builds/slave/test', 15:40:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:07 INFO - 'SHELL': '/bin/bash', 15:40:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:40:07 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:40:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:07 INFO - 'USER': 'cltbld', 15:40:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:07 INFO - 'XPC_FLAGS': '0x0', 15:40:07 INFO - 'XPC_SERVICE_NAME': '0', 15:40:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:07 INFO - Downloading/unpacking blobuploader==1.2.4 15:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:10 INFO - Downloading blobuploader-1.2.4.tar.gz 15:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:40:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:40:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:11 INFO - Downloading docopt-0.6.1.tar.gz 15:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:40:11 INFO - Installing collected packages: blobuploader, requests, docopt 15:40:11 INFO - Running setup.py install for blobuploader 15:40:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:40:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:40:11 INFO - Running setup.py install for requests 15:40:12 INFO - Running setup.py install for docopt 15:40:12 INFO - Successfully installed blobuploader requests docopt 15:40:12 INFO - Cleaning up... 15:40:12 INFO - Return code: 0 15:40:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:40:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:40:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:12 INFO - 'HOME': '/Users/cltbld', 15:40:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:12 INFO - 'LOGNAME': 'cltbld', 15:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:12 INFO - 'MOZ_NO_REMOTE': '1', 15:40:12 INFO - 'NO_EM_RESTART': '1', 15:40:12 INFO - 'PAGER': '/bin/cat', 15:40:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:12 INFO - 'PWD': '/builds/slave/test', 15:40:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:12 INFO - 'SHELL': '/bin/bash', 15:40:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:40:12 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:40:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:12 INFO - 'USER': 'cltbld', 15:40:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:12 INFO - 'XPC_FLAGS': '0x0', 15:40:12 INFO - 'XPC_SERVICE_NAME': '0', 15:40:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-CotJSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-oxf007-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-e0hjqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-NuQKel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-iERMfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-nbXkzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-gM5WNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-MIIhg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-zwO97h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-rDXoUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-y7JHhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-S84mHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-F1dn1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:14 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-zAgFht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:14 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-lFhISH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:14 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-MfaMoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:14 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-9C7Bhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:14 INFO - Running setup.py install for manifestparser 15:40:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:14 INFO - Running setup.py install for mozcrash 15:40:14 INFO - Running setup.py install for mozdebug 15:40:14 INFO - Running setup.py install for mozdevice 15:40:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Running setup.py install for mozfile 15:40:15 INFO - Running setup.py install for mozhttpd 15:40:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Running setup.py install for mozinfo 15:40:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Running setup.py install for mozInstall 15:40:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Running setup.py install for mozleak 15:40:15 INFO - Running setup.py install for mozlog 15:40:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:15 INFO - Running setup.py install for moznetwork 15:40:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:16 INFO - Running setup.py install for mozprocess 15:40:16 INFO - Running setup.py install for mozprofile 15:40:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:16 INFO - Running setup.py install for mozrunner 15:40:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:16 INFO - Running setup.py install for mozscreenshot 15:40:16 INFO - Running setup.py install for moztest 15:40:16 INFO - Running setup.py install for mozversion 15:40:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:17 INFO - Cleaning up... 15:40:17 INFO - Return code: 0 15:40:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:17 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')]} 15:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:17 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')]} 15:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:17 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 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:40:17 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 15:40:17 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 15:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:17 INFO - 'HOME': '/Users/cltbld', 15:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:17 INFO - 'LOGNAME': 'cltbld', 15:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:17 INFO - 'MOZ_NO_REMOTE': '1', 15:40:17 INFO - 'NO_EM_RESTART': '1', 15:40:17 INFO - 'PAGER': '/bin/cat', 15:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:17 INFO - 'PWD': '/builds/slave/test', 15:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:17 INFO - 'SHELL': '/bin/bash', 15:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:40:17 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:17 INFO - 'USER': 'cltbld', 15:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:17 INFO - 'XPC_FLAGS': '0x0', 15:40:17 INFO - 'XPC_SERVICE_NAME': '0', 15:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:17 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-2PUnjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:17 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Yag0SW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:17 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-sxhRcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:17 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-8UHmpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:17 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Ej5UgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:17 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-zkn_dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:17 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-CfODb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:18 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)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-laU61F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-5Txph_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-oNVgUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-PXVaNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-cRwldv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:18 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)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-8JU7Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-XPl42i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Hf7nFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-jNlr29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:18 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-CYPyYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:21 INFO - Downloading blessings-1.6.tar.gz 15:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:21 INFO - Installing collected packages: blessings 15:40:21 INFO - Running setup.py install for blessings 15:40:21 INFO - Successfully installed blessings 15:40:21 INFO - Cleaning up... 15:40:22 INFO - Return code: 0 15:40:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:22 INFO - Reading from file tmpfile_stdout 15:40:22 INFO - Current package versions: 15:40:22 INFO - blessings == 1.6 15:40:22 INFO - blobuploader == 1.2.4 15:40:22 INFO - docopt == 0.6.1 15:40:22 INFO - functools32 == 3.2.3-2 15:40:22 INFO - jsonschema == 2.5.1 15:40:22 INFO - manifestparser == 1.1 15:40:22 INFO - mozInstall == 1.12 15:40:22 INFO - mozcrash == 1.0 15:40:22 INFO - mozdebug == 0.1 15:40:22 INFO - mozdevice == 0.48 15:40:22 INFO - mozfile == 1.2 15:40:22 INFO - mozhttpd == 0.7 15:40:22 INFO - mozinfo == 0.9 15:40:22 INFO - mozleak == 0.1 15:40:22 INFO - mozlog == 3.4 15:40:22 INFO - moznetwork == 0.27 15:40:22 INFO - mozprocess == 0.23 15:40:22 INFO - mozprofile == 0.28 15:40:22 INFO - mozrunner == 6.13 15:40:22 INFO - mozscreenshot == 0.1 15:40:22 INFO - mozsystemmonitor == 0.3 15:40:22 INFO - moztest == 0.8 15:40:22 INFO - mozversion == 1.4 15:40:22 INFO - psutil == 3.1.1 15:40:22 INFO - requests == 1.2.3 15:40:22 INFO - wsgiref == 0.1.2 15:40:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:22 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')]} 15:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:22 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')]} 15:40:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:22 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 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:40:22 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 15:40:22 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 15:40:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:40:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:22 INFO - 'HOME': '/Users/cltbld', 15:40:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:22 INFO - 'LOGNAME': 'cltbld', 15:40:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:22 INFO - 'MOZ_NO_REMOTE': '1', 15:40:22 INFO - 'NO_EM_RESTART': '1', 15:40:22 INFO - 'PAGER': '/bin/cat', 15:40:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:22 INFO - 'PWD': '/builds/slave/test', 15:40:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:22 INFO - 'SHELL': '/bin/bash', 15:40:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:40:22 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:40:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:22 INFO - 'USER': 'cltbld', 15:40:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:22 INFO - 'XPC_FLAGS': '0x0', 15:40:22 INFO - 'XPC_SERVICE_NAME': '0', 15:40:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 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)) 15:40:22 INFO - Cleaning up... 15:40:22 INFO - Return code: 0 15:40:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:22 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')]} 15:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:22 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')]} 15:40:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:22 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 0x100caa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd734d3f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, '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.lIaETRzInL/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.Ati3OeSSsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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 15:40:22 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 15:40:22 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 15:40:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:40:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:22 INFO - 'HOME': '/Users/cltbld', 15:40:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:22 INFO - 'LOGNAME': 'cltbld', 15:40:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:22 INFO - 'MOZ_NO_REMOTE': '1', 15:40:22 INFO - 'NO_EM_RESTART': '1', 15:40:22 INFO - 'PAGER': '/bin/cat', 15:40:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:22 INFO - 'PWD': '/builds/slave/test', 15:40:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:22 INFO - 'SHELL': '/bin/bash', 15:40:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:40:22 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:40:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:22 INFO - 'USER': 'cltbld', 15:40:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:22 INFO - 'XPC_FLAGS': '0x0', 15:40:22 INFO - 'XPC_SERVICE_NAME': '0', 15:40:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:22 INFO - Cleaning up... 15:40:22 INFO - Return code: 0 15:40:22 INFO - Running post-action listener: _resource_record_post_action 15:40:22 INFO - Running post-action listener: _start_resource_monitoring 15:40:22 INFO - Starting resource monitoring. 15:40:22 INFO - [mozharness: 2017-01-18 23:40:22.939982Z] Finished create-virtualenv step (success) 15:40:22 INFO - [mozharness: 2017-01-18 23:40:22.940954Z] Running install step. 15:40:22 INFO - Running pre-action listener: _resource_record_pre_action 15:40:22 INFO - Running main action method: install 15:40:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:23 INFO - Reading from file tmpfile_stdout 15:40:23 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:23 INFO - Reading from file tmpfile_stdout 15:40:23 INFO - Output received: 15:40:23 INFO - Usage: mozinstall [options] installer 15:40:23 INFO - Options: 15:40:23 INFO - -h, --help show this help message and exit 15:40:23 INFO - -d DEST, --destination=DEST 15:40:23 INFO - Directory to install application into. [default: 15:40:23 INFO - "/builds/slave/test"] 15:40:23 INFO - --app=APP Application being installed. [default: firefox] 15:40:23 INFO - mkdir: /builds/slave/test/build/application 15:40:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:40:54 INFO - Reading from file tmpfile_stdout 15:40:54 INFO - Output received: 15:40:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:40:54 INFO - Running post-action listener: _resource_record_post_action 15:40:54 INFO - [mozharness: 2017-01-18 23:40:54.245676Z] Finished install step (success) 15:40:54 INFO - [mozharness: 2017-01-18 23:40:54.245844Z] Running run-tests step. 15:40:54 INFO - Running pre-action listener: _resource_record_pre_action 15:40:54 INFO - Running main action method: run_tests 15:40:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:40:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:40:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:40:54 INFO - 2017-01-18 15:40:54.260 screenresolution[1624:7418] starting screenresolution argv=screenresolution get 15:40:54 INFO - 2017-01-18 15:40:54.262 screenresolution[1624:7418] Display 0: 1600x1200x32@60 15:40:54 INFO - 2017-01-18 15:40:54.268 screenresolution[1625:7420] starting screenresolution argv=screenresolution list 15:40:54 INFO - Available Modes on Display 0 15:40:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:40:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:40:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:40:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:40:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:40:54 INFO - Intel Iris: 15:40:54 INFO - Chipset Model: Intel Iris 15:40:54 INFO - Type: GPU 15:40:54 INFO - Bus: Built-In 15:40:54 INFO - VRAM (Dynamic, Max): 1536 MB 15:40:54 INFO - Vendor: Intel (0x8086) 15:40:54 INFO - Device ID: 0x0a2e 15:40:54 INFO - Revision ID: 0x0009 15:40:54 INFO - Displays: 15:40:54 INFO - Display: 15:40:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:40:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:40:54 INFO - Display Serial Number: 1600x1200 60 15:40:54 INFO - Main Display: Yes 15:40:54 INFO - Mirror: Off 15:40:54 INFO - Online: Yes 15:40:54 INFO - Rotation: Supported 15:40:54 INFO - Return code: 0 15:40:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:54 INFO - Python 2.7.3 15:40:54 INFO - Return code: 0 15:40:54 INFO - grabbing minidump binary from tooltool 15:40:54 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')]} 15:40:54 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 0x100abed30>, '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 0x100924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ab6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:54 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 15:40:54 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 15:40:54 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 15:40:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:54 INFO - Return code: 0 15:40:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:40:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:40:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0329', '--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 15:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0329 --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 15:40:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIaETRzInL/Render', 15:40:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:54 INFO - 'HOME': '/Users/cltbld', 15:40:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:54 INFO - 'LOGNAME': 'cltbld', 15:40:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:54 INFO - 'MOZ_NO_REMOTE': '1', 15:40:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:54 INFO - 'NO_EM_RESTART': '1', 15:40:54 INFO - 'PAGER': '/bin/cat', 15:40:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:54 INFO - 'PWD': '/builds/slave/test', 15:40:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:54 INFO - 'SHELL': '/bin/bash', 15:40:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ati3OeSSsZ/Listeners', 15:40:54 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 15:40:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:54 INFO - 'USER': 'cltbld', 15:40:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:54 INFO - 'XPC_FLAGS': '0x0', 15:40:54 INFO - 'XPC_SERVICE_NAME': '0', 15:40:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0329', '--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 15:40:54 INFO - mozversion application_buildid: 20170118143333 15:40:54 INFO - mozversion application_changeset: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b 15:40:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:40:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:54 INFO - mozversion application_name: Firefox 15:40:54 INFO - mozversion application_remotingname: firefox-dev 15:40:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:40:54 INFO - mozversion application_vendor: Mozilla 15:40:54 INFO - mozversion application_version: 52.0a2 15:40:54 INFO - mozversion platform_buildid: 20170118143333 15:40:54 INFO - mozversion platform_changeset: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b 15:40:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:40:54 INFO - mozversion platform_version: 52.0a2 15:40:54 INFO - using testdate: 1484782854 15:40:54 INFO - actual date: 1484782854 15:40:54 INFO - starting webserver on 'localhost:49243' 15:40:54 INFO - SUITE-START | Running 2 tests 15:40:54 INFO - TEST-START | dromaeo_css 15:40:54 INFO - operating with platform_type : mac_ 15:40:54 INFO - Initialising browser for dromaeo_css test... 15:40:54 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpTcWCye/profile http://localhost:49243/getInfo.html) 15:40:57 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 15:40:57 INFO - PROCESS | 1635 | colorDepth:24 15:40:57 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 15:40:57 INFO - PROCESS | 1635 | __metrics 15:40:57 INFO - PROCESS | 1635 | 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" 15:40:57 INFO - PROCESS | 1635 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:40:58 INFO - TEST-INFO | 1635: exit 0 15:40:58 INFO - Browser initialized. 15:40:58 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpTcWCye/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:41:11 INFO - PROCESS | 1637 | RSS: Main: 222064640 15:41:11 INFO - PROCESS | 1637 | 15:44:14 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 15:44:16 INFO - PROCESS | 1637 | RSS: Main: 366174208 15:44:16 INFO - PROCESS | 1637 | 15:47:19 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 15:47:19 INFO - PROCESS | 1637 | RSS: Main: 347156480 15:47:19 INFO - PROCESS | 1637 | 15:50:22 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 15:50:22 INFO - PROCESS | 1637 | RSS: Main: 327147520 15:50:22 INFO - PROCESS | 1637 | 15:53:25 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 15:53:25 INFO - PROCESS | 1637 | RSS: Main: 318828544 15:53:25 INFO - PROCESS | 1637 | 15:56:29 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 15:56:29 INFO - PROCESS | 1637 | RSS: Main: 310628352 15:56:29 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 15:59:32 INFO - PROCESS | 1637 | RSS: Main: 360202240 15:59:32 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | __start_tp_report 15:59:32 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 15:59:32 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 15:59:32 INFO - PROCESS | 1637 | |i|pagename|runs| 15:59:32 INFO - PROCESS | 1637 | |0;dojo.html;3337.716294115;3353.228757385801;3361.9663803361973;3377.138829598452;3408.250184959308;5745.396733342996;5764.769409223636;5773.989551828434;5801;5851.473367396929;5664.801731939383;5749.252597162363;5751.884962300752;5752.856178595527;5780.913286300709;4389.736615803062;5211.218317252414;5273.789048438058;5276.92084618731;5285.577153827704;5166.17341225404;5178.456262092483;5180.922286165711;5346.064438723227;5372.785088596452;5212;5254.632175747699;5273.525382715555;5274.604404669646;5324.148136298193;5285.814996475124;5306.442823503515;5328.973355133238;5340.172273297645;5347;4850.69031361534;5186.222066689989;5259.76331065103;5260.552853007499;5290.418054014055;4688.132695451318;4859.173940430135;5627.521660658849;5678.716064196815;5688.175214593861;6197.25632924052;6207.782727604557;6235.87528249437;6262.843428914268;6272.1219029335925;6227.910115729729;6283.277423096311;6283.748650053993;6288.371162883721;6290.528210384239;5668.943310566883;5717.799877004368;5768.711564421762;5807.477327040587;5810.50610698087;6273.654948977759;6275.717592708339;6298.086777417248;6308.337624549448;6310.179676642099;6146.170267013986;6191.752329906843;6197.349278325801;6200.255969283715;6218.129461875341;6043.667598282137;6056.242969628796;6123.632582045091;6151.2618485781995;6273.529485288622;5187.714675692798;6147.538934579924;6184.907226391608;6219.689015549205;6242.375762423721;6151.5386346024225;6196.194494715748;6219.782307619212;6230.158928544679;6238.1266622672865;4838.467768545457;4888.731119788375;4891.413030436299;4959.231319145538;4961;4420.668449866273;4437.800298986554;4442.68901176921;4464.196444639996;4479.484859241164;5777.94222057774;5788.392218817048;5804.21643078179;5874.794382196603;5899.67551784656;6236.656983865931;6247.447124341208;6266.749330026839;6277.372262773777;6286.528510361742;5795.420457954255;5857.004309267435;5857.209276747584;5878.853028674317;5885.117232415172;5324.707141107277;5382.327209098863;5391.541718953855;5430.42980487051;5481.780728770805;6408.519361048034;6413.647249401328;6417.101605775289;6438.066480360228;6444.935550644527;6112.908306375497;6247.437730604267;6293.937060629335;6295.433410993032;6312;6213.813585592439;6289.08808222796;6310.5898116622275;6321.0834429008455;6415.679216039086;6225.221847269091;6234.625922444668;6256;6256.7810126645345;6260.40526150015;6282.905756413566;6288.402601752826;6288.874222515616;6294.8111556653375;6301.0863424804065;5853.64878107315;5973.581849270509;6111.633302001894;6148.016317389196;6196.318404975539;6107.755689772359;6119.654885717031;6147.293061297829;6172.598781079216;6183.938160618336;6026.035834266497;6031.939680603138;6042.305134909366;6051.152838602511;6055.212822333068;5977.940220597912;6039.969800150971;6129.908051379322;6169.907451388322;6200.441960223601;5954.523638109027;5980.730867110903;6017.308009578955;6056.06131049688;6100.420460056288 15:59:32 INFO - PROCESS | 1637 | |1;ext.html;485.1461427886918;505.6031015652711;508.83971548962074;513.3172880069508;553.9556835453164;30086.548701769465;31986.840065799697;32056;32116.35767284656;32545.349093018183;41979.160416791725;42505.14989700204;42615;42696.786516067456;42728.57271427285;9282.814343713122;9455.669051583196;9468.100530449596;9483.099105584959;9487;8345.332373410112;8589.95705021474;9271.443713377219;9398.483083430408;9403.341766076377;8513.446625969324;8561.95719021404;8564.74305770828;11110.666679999613;11391.658250252463;11292;11367.090632749443;11367.431628418546;11367.658970230907;11380.089592832554;11167.776644467147;11249.60626378081;11258.211925165242;11262.380569068699;11280.984711375933;31284.06147815569;31819.204519886956;38840.61159388398;39389.80305098456;39421.21157576861;3237.3525294941105;3257.250832308582;3258.657840926716;3306.9173270668184;3595.6044835068124;29441;29522.26194345137;29790.65942032592;30508.237294067603;30751;2785.749282564579;3042.421939831425;3111.9066428007195;3160.747140228776;3302.8018318900704;501525;501854.4350947736;502567;503589;505743;52705.472945270056;53998;59830;61576.384236157064;62215.688921555105;61486.69256653688;63836.04245936315;64555.67722161453;64655.38361540999;64676;15616;15718.13550254747;15749.976251543612;15772.526824195293;15786.36854525829;10094.19246460296;11863.46614402354;12029.578964736367;12083.879161208451;12097.576584819664;17548;17573.912130439265;17610.64778704419;17650.470485885446;17666.558336041446;5244.554212891947;5518.558515318765;6399.0564340403935;6440.581362211441;6477.935220647827;833.3832963607008;878.4904755242072;984.3749219245831;1034.1674951663983;1065.2702898514542;28823.279418014718;29090.563641545396;29239.85380073086;29803.701962980093;29850.552241716392;868.3955452693233;884.0364003236375;959.7696552827272;1019.9031092046243;1054.99472502637;14965.70068598622;15254.00848944815;15411.460598878986;15457.922710386376;15748.291326890318;618811;628073;629001;629529;630666;47708.284375734394;57905.71047144822;58085.12872306919;58264;58288.54278642983;1206.9336186509827;1250.4748005837753;1329.355262697591;1348.6021623621054;1376.4287820554557;28788;30507.694923050487;31469;31672.683273166975;32066.03801885947;55544.72227638836;56236.71881640566;56442.7177864108;56448.71775641095;56563;589070;589782;590723;591189;597611;36588.451173232956;39963.60036399599;39999;40081.79141190133;40284.59715402809;45515.089698205185;45786.85532861572;46190.53809461863;46501.53498464972;46686.76656616695;36319.63680363163;37572.812135939144;37844.243115138095;38350.23299534049;38702.80648596739;37243.882683519536;38001.09994500319;38335;39520.802395987834;39554;37725.62274377221;38204;38355.808220958716;38418;38420;33874.15314617154;34158.487622685185;34177.65822341844;34481;34769;25120.874395628634;25457.87271063633;25475;25964.22107336783;26022.73977260203 15:59:32 INFO - PROCESS | 1637 | |2;jquery.html;351.86277351832774;370.49982523593144;378.3473508198358;381.6679488844705;401.7509144330514;14617.853821461795;14766.335514901828;15865.365385384584;20012.399628011182;20425.28511502098;19247.711284330748;19622.215111395562;19801;19868.006599670032;19967.400977970683;5622.634528755628;5787;5821.912671309934;5892.968730472172;5918.319393269786;4754.882602588389;5840.649561026331;5872.823815285548;5878.1476685880525;5882.97058514709;5564.86087847803;5828.912566311487;5837.182794408786;5840.707964601774;5843.2695913010875;5729.28383952006;5804.796832110877;5838.357780644125;5840.503557197643;5849.093390524476;4878.390201224847;5045.924311135336;5128.794848206105;5134.794608215667;5141.6143789215585;25019.49961000788;25043;25208.621870671956;25373.111941081974;25409.61885571718;2271.4434963434064;2391.796697280733;2498.925032249035;2656.8405895646324;2748.6014527893544;15073.321700523502;15196.088234705952;15321.616959575987;15644.765328520081;16110.597235069099;1750.439859245042;1822.1618055694396;1839.3102586530051;1856.8793028453242;1871.1392759330724;105302;117713.41143294315;117793.41103294515;118341.40829295885;120409.39795300967;10548.208884333706;10742.355458672504;10854.511546980404;10978.066864316463;11195.60815371458;13772.380242889094;13776.10455320401;13787.586372408643;13791.793123103162;13816.447342106405;6946.37482626566;8378.539180345018;8502.489850608983;8506.276966457797;8661.740147795575;8457.23884850354;8459.661613535513;8594.831506719236;8618.741437756877;8625.180607842245;6426.646534440651;6547.279799222082;6578.27638959714;6580.341965803382;6587.440067594212;1766.7349897515476;1799.4704501173896;1800.3158799656046;1815.709486482183;1834.97247541287;923.2152670230385;956.7321150077989;981.0287815063178;1012.4937531234383;1016.1718199667248;21732.384036242172;22085.447863803533;22210.77789222087;22332.106715731512;22332.441688957904;972.1493693018608;972.1833659725864;986.225812736998;1044.827603445423;1063.7553362726462;11027.558897644005;11650.53397864093;11663.88336116628;11669.708257293636;11677.182597218283;563330;571943;572170;591074;592978;113843;114968;122372.38813805874;122595;124804.37597811953;964.8118616869643;966.6809952715587;988.125508924607;989.0307498651362;996.6960077176386;18380;19229.519262018563;19447.902760486108;19485.90257048706;19498.512537186685;44655.10689786251;48106;49222;50155.74922125366;50310.232937188804;66933.66533167497;68826.65586672234;68994.65502672455;70168.29831701618;71875.64062179656;7015.543989640657;7221.747238846615;7301.488895777457;7600.771976840703;7897.763067107996;11729.589464369048;12196.390180491117;12610.49558017669;12622.873771262537;12692.238465692088;10660.200484963814;10746.194035447217;10777;11139.777204456028;11272.154588405738;10686.62596809139;10780.191485638452;10847.186461015297;10990.505427255952;11322.094232461548;9075.682351117679;10083.445410502492;10519.526621302213;10749.785004300027;11150.721231969266;6057.878842423215;7189.676464559212;7286.489945703962;7370.96314518424;7496.212897645669;10202.846957295795;10767.461626918779;10993.285436446606;11139.997400234019;11158.330500170016 15:59:32 INFO - PROCESS | 1637 | |3;mootools.html;1311.0887932886646;1355.4036224061413;1467.7504824179885;1626.308818752031;1847.722841573763;7416.406687465003;7810.414218933574;8075.51546907186;8371.874421883662;8374.330053595713;7028.1566212054495;8306.335493160548;8391.664333426656;8397.622107005183;8413.66345346187;2303.2629558541275;2331.650252462136;2340.6137987232173;2341.648752687102;2342.9648555271683;2318.188633978111;2355.1168311883043;2371.1700904683394;2371.537550177716;2373.1100837186054;2294.850834695748;2297.483066310075;2322.756110608387;2323.1288266899915;2337.567550003255;2288.0618946231966;2292.0487997481027;2317.5480781247666;2319.5013072189477;2322.581935251654;2318.686977258082;2341.332720174742;2342.355852140658;2343.27358518858;2347.7534858839917;8330.250277474996;8357.373197010249;8435.789105272355;8449.32405407566;8460.957695211484;5020;5220;5246.711430871301;5263.4473380295285;5271.604629652791;3476.687098161178;3563.055790215595;3623.565172179355;3625.365561026836;3639.8180090995343;1053.1153830782102;1076.7631121153333;1082.1775450657558;1104.618906477264;1106.5684383090602;8676.60955257015;10928.890711092867;11017.063549598293;11027;11041.282316649393;4233.407322974786;4387.144506821203;4472.843450479218;4502.234620114588;4515.932261016087;8641;8649.610767515476;8650.35122365825;8657.134286571292;8663.35024873137;5086.516780905808;5096.668716533413;5100.69395836251;5103.821366252163;5105.15764898795;4057.5739547347102;4246.915061698811;4406.1187762447635;4407.691461597721;4771.618270538349;2918.255844759573;3664.6885014774043;3753.0242137044565;3764.642358975893;3772.2832661794173;1006.8892421833592;1014.8122597319519;1095.6767753512734;1109.6227282723912;1143.2397455692037;833.7082021292499;880.7709995401242;896.211334026066;907.9228265597499;916.134253130785;5531.170324451284;5615.691136987505;5706.087026075808;5810.866880958172;6028.728707208186;900.446225571269;916.757059379265;927.6706769097009;928;977.4184360305607;3699.9815000924823;3715.8327875245677;3775.320442320354;3814.7329686922203;3907.2185562887535;9898.30711850178;10094.24293178;10173.745656358651;10208.744781380376;10213.182945364353;10154.086132248132;10163.034511721375;10181.33821301613;10184.032516910882;10195.28632995698;7575.734849280359;7812.1797211292;7999.1600881907725;8013.398995075393;8018.799530011796;5465.480779325958;5540.113581826889;5744.339400968942;5811.912821307768;5822.679752613478;9194.356395052282;9389;9445;9513.191378732885;9573.856392153984;8207.58962051907;8728.258098061737;9398.2951278653;9674.85487717699;9764.560594773395;7151.32062454066;7398;7403.777886663409;7404.296591823768;7410.666520006721;7606.657700403606;7850.136484986761;7914.406419518444;8010.158933311918;8014.799630009296;7110.751123710852;7137.750178743719;7138.643067846483;7149.964250178716;7331.266873312626;5851.970740146272;7018.964905175441;7032.226455090038;7034.824129396806;7066.258042905626;5554.389017208023;6628.304028077176;6628.370304821035;6685.966570167118;6725.39471447572;4727.763611819464;4741.217699079613;4744.240921452551;4783.091212669582;4875.098106850244;3856.633619806114;4770.522947705202;4842.273658951185;4964.205727083649;4978.626603004716 15:59:32 INFO - PROCESS | 1637 | |4;prototype.html;286.7634201783529;298.3987265659695;299.4340696084401;302.90307101727456;305.0756208687677;5209.479052094794;5258.26384306197;5263.736813159341;5265.842024739254;5304.230886521452;5198.506141916512;5204.245384419257;5216.9478305217035;5225.608079394042;5238.947610523894;3369.780964237329;3681.889543313705;3745.557960185328;3749.762578349138;3887.241987812378;3796.810159492028;3898.883033508985;3952.738990332972;3990.2243501641783;4045.5549889512276;3909.824057917386;3936.2324346752393;3938.901527461822;3942.80285985701;3979.4531499840127;3730.8321125549146;3827.655511004023;3896.415537669344;3907.374820028796;3976.562578116405;2814.9718502814912;3280.343931213743;3393.06690660068;3463.341965026662;3466.2201004773974;3896.8636097736726;4790.976045119787;4874.122657921573;5009.499050094998;5028.354443028811;4402.955970440319;4519.107771026545;4523.705959112647;4541.818327266906;4544.568266014757;4278.486581610196;4575.705492045722;5122.872967947045;5393.325834270716;5496.31296087989;3543.6102028776813;3785.4889589905288;3793.1655035892336;3794.9430758538642;3800.600936901641;5742.272420600223;5781.653100813923;5795.971410006464;5797.88404231913;5802.796902108448;3671.375866102769;3711.740178187555;3804.6575808177395;3804.8965799917714;3805.4397696944675;3700.5744339401313;3838.865639702597;3921.764694118362;3927.3323534999117;3932.390479475686;3749.718771092179;4075.8777236682945;4490.730556166641;4509.458864936229;4537.319402089647;4636.235021221461;5472.398036216013;5478.780848766084;5495.433801366591;5496.367917689517;1097.742030622803;1131.1403333466624;1213.4842691855927;1222.6001228593211;1232.3345393487596;783.9412044096715;794.7655441644729;802.4262652203703;843.502333623153;845.7166849105496;4059.7970101494807;4509.864704058883;4974.676646017997;5108.092309383324;5134.840778968973;5153.768080436389;5499.697516636624;5680.670924920762;5701.914471282934;5721.914171287434;4239.342901850218;4703.153432382204;4922.286268491049;5215.566768247036;5224.582033437316;2334.459256890444;2512.623106533998;2708.079253053971;2759.503289407886;2775.6252905857855;4728.432588089451;4772.587599866112;5476.534494567926;5656.8020119295625;5667.659940403589;4727.841678788719;5132.5124113209185;5201.531862132426;5455.509004189642;5562.860928476821;5004.873903371712;5010.924836127534;5123.487651234921;5171.2333901363345;5626.521745651584;4869.975650121727;5214.478552144755;5337.57299416038;5398.734999350626;5399.676019438846;5112.795488180431;5288.497592728685;5301.754087789289;5412.323459567554;5654.886902262014;4750.358701575243;5232;5399.757010934439;5934.7922822701;5973.520988789777;3331.9666803332625;3830.9656392774355;3841.9231615368094;3861.80690965445;3907.5115610548683;5414.3232095988005;5548.417416171405;5594.608377413542;5617.915731263953;5638.830835074954;4743.173878056904;4747.905041899212;5204.609654275869;5328.2026935858985;5542.63972841764;5501.834944951658;5527.50252477279;5555.361133469541;5590.832275031756;5623.718814059363;5101.846944591668;5399.649022813662;5439.4288599696465;5451.0123237237485;5490.890182196414;5253.395859476056;5362.839114826562;5376.247325374373;5562.997320964369;5577.721113944368;4550.224363138416;5233.3310011893445;5410.296661433988;5442.809501667581;5486.945130548644 15:59:32 INFO - PROCESS | 1637 | |5;yui.html;758.4918104869737;784.7371130671224;787.094840932927;793.4683761879542;808.6644042722263;14082.577522674323;14536.491222807204;14549.199794011325;14613.707725845477;14697.632559186026;12714.936425317861;12832.422540985677;12838.422270997804;12845.293508857008;12854.614361569145;797.3740613618311;801.2148094867034;829.0714399872152;833.3208435125383;834.1283358889945;830.6594296338502;832.2094010689839;832.3216578488543;832.8958880139983;833.8832563441123;875.7722992022057;876.6975393489236;876.9123087691244;878.5343767803074;879.7536689726886;867.7830542364409;868.6438560190323;869.5652173913044;870.3863776037878;872.4416373520952;645.5029627187087;645.5352146454545;645.8514541655398;647.1554621219307;647.184547470191;14089.506867259699;14171.78742318866;14174.858251417458;14179.929100354535;14183.361748721332;12940.547080852219;13008.349582520836;13160.27618480983;13190.340482975813;13392.263425511594;10644.840327395095;11092.33445993235;11184.94407527957;11220.326780393203;11437.656870293822;3413.749881397651;3595.5505561804775;3612.8735494257576;3656.963430365689;3738.513993180869;70299.64850175717;86443.56778216132;89427;89691.23705155357;90381.64427533526;10062.295639305325;10197;10280.691579252634;10328;10605.151587872951;19898.104585293695;20028.799712002983;20063.799362006484;20112.296069637494;20265.79734202639;7249.275072492708;7332.706691732271;7414.814629634195;7567.659455324524;7574.015378000825;3233.4503134467195;4104.774237416971;4185.476815398075;4212.705110642226;4224.366345048267;3366.107981384935;3484.7212223022098;3485.6165821759587;3486.0587641337206;3498.352804731133;626.6271568416785;635.4376376906471;673.6530686696356;712.2805965974403;712.5297303779481;541.9187121931742;584.6287607369266;611.9938800611936;656.8883289840738;677.8949262864264;8665.826683466295;8989.370744047854;9014.541509490244;9184.862227066465;9220.953895230481;629.8866204083217;634.1439057272645;643.3437893348759;656.7996760987849;676.9604475903159;3964.83037503937;4254.872353829328;4369.80335884886;4390.297552391666;4406.942333839856;73710.894336585;74170.629146855;107380.46309768557;118182;119397.40301298612;60154.39845601488;61682.074768878505;70998.64500677463;94836.56797752241;98660;69867.65066174636;74600.62699686576;76981;78451.21548784553;81325.18674813294;8454.53500057478;9411.152996230372;9557.713268601952;9675.467849268076;9692.854607180758;50819.23771143362;51359.229611556606;51666.483335166165;51889.740551297;53396;70607;73318;73533.26466735265;75046.4990700172;75237.24762752303;11740.70648233801;11984.46069926838;12017.75964480688;12140.392980351124;12561.309127997682;11257.018880851432;11329.206955513364;11693.590724324607;12248.693782655504;13589.932050339685;11153.163512736415;11527.827082593936;11654.359010254517;11855.051595872483;11991.64025079249;12204.206726562745;12806.935965320487;12872.485100596246;12875;12961.870381296443;10947.671569852917;12179.817302740288;12497.687557811127;12657.367131643565;12744.362781860684;9889.802203956024;10443.582256709646;11087.501062452371;11423.314601123959;11646.94176529112;10593.52329145175;11232.92024149975;11393.886061139283;11478.253913495597;11496.770064598828 15:59:32 INFO - PROCESS | 1637 | __end_tp_report 15:59:32 INFO - PROCESS | 1637 | __start_cc_report 15:59:32 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1620 15:59:32 INFO - PROCESS | 1637 | __end_cc_report 15:59:32 INFO - PROCESS | 1637 | __startTimestamp1484783972659__endTimestamp 15:59:32 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | ------- Summary: start ------- 15:59:32 INFO - PROCESS | 1637 | Number of tests: 6 15:59:32 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5756.98 Median:6026.04 stddev:658.10 (10.9%) stddev-sans-first:632.16 15:59:32 INFO - PROCESS | 1637 | Values: 3337.7 3353.2 3362.0 3377.1 3408.3 5745.4 5764.8 5774.0 5801.0 5851.5 5664.8 5749.3 5751.9 5752.9 5780.9 4389.7 5211.2 5273.8 5276.9 5285.6 5166.2 5178.5 5180.9 5346.1 5372.8 5212.0 5254.6 5273.5 5274.6 5324.1 5285.8 5306.4 5329.0 5340.2 5347.0 4850.7 5186.2 5259.8 5260.6 5290.4 4688.1 4859.2 5627.5 5678.7 5688.2 6197.3 6207.8 6235.9 6262.8 6272.1 6227.9 6283.3 6283.7 6288.4 6290.5 5668.9 5717.8 5768.7 5807.5 5810.5 6273.7 6275.7 6298.1 6308.3 6310.2 6146.2 6191.8 6197.3 6200.3 6218.1 6043.7 6056.2 6123.6 6151.3 6273.5 5187.7 6147.5 6184.9 6219.7 6242.4 6151.5 6196.2 6219.8 6230.2 6238.1 4838.5 4888.7 4891.4 4959.2 4961.0 4420.7 4437.8 4442.7 4464.2 4479.5 5777.9 5788.4 5804.2 5874.8 5899.7 6236.7 6247.4 6266.7 6277.4 6286.5 5795.4 5857.0 5857.2 5878.9 5885.1 5324.7 5382.3 5391.5 5430.4 5481.8 6408.5 6413.6 6417.1 6438.1 6444.9 6112.9 6247.4 6293.9 6295.4 6312.0 6213.8 6289.1 6310.6 6321.1 6415.7 6225.2 6234.6 6256.0 6256.8 6260.4 6282.9 6288.4 6288.9 6294.8 6301.1 5853.6 5973.6 6111.6 6148.0 6196.3 6107.8 6119.7 6147.3 6172.6 6183.9 6026.0 6031.9 6042.3 6051.2 6055.2 5977.9 6040.0 6129.9 6169.9 6200.4 5954.5 5980.7 6017.3 6056.1 6100.4 15:59:32 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:70664.67 Median:38269.50 stddev:153983.35 (402.4%) stddev-sans-first:154325.16 15:59:32 INFO - PROCESS | 1637 | Values: 485.1 505.6 508.8 513.3 554.0 30086.5 31986.8 32056.0 32116.4 32545.3 41979.2 42505.1 42615.0 42696.8 42728.6 9282.8 9455.7 9468.1 9483.1 9487.0 8345.3 8590.0 9271.4 9398.5 9403.3 8513.4 8562.0 8564.7 11110.7 11391.7 11292.0 11367.1 11367.4 11367.7 11380.1 11167.8 11249.6 11258.2 11262.4 11281.0 31284.1 31819.2 38840.6 39389.8 39421.2 3237.4 3257.3 3258.7 3306.9 3595.6 29441.0 29522.3 29790.7 30508.2 30751.0 2785.7 3042.4 3111.9 3160.7 3302.8 501525.0 501854.4 502567.0 503589.0 505743.0 52705.5 53998.0 59830.0 61576.4 62215.7 61486.7 63836.0 64555.7 64655.4 64676.0 15616.0 15718.1 15750.0 15772.5 15786.4 10094.2 11863.5 12029.6 12083.9 12097.6 17548.0 17573.9 17610.6 17650.5 17666.6 5244.6 5518.6 6399.1 6440.6 6477.9 833.4 878.5 984.4 1034.2 1065.3 28823.3 29090.6 29239.9 29803.7 29850.6 868.4 884.0 959.8 1019.9 1055.0 14965.7 15254.0 15411.5 15457.9 15748.3 618811.0 628073.0 629001.0 629529.0 630666.0 47708.3 57905.7 58085.1 58264.0 58288.5 1206.9 1250.5 1329.4 1348.6 1376.4 28788.0 30507.7 31469.0 31672.7 32066.0 55544.7 56236.7 56442.7 56448.7 56563.0 589070.0 589782.0 590723.0 591189.0 597611.0 36588.5 39963.6 39999.0 40081.8 40284.6 45515.1 45786.9 46190.5 46501.5 46686.8 36319.6 37572.8 37844.2 38350.2 38702.8 37243.9 38001.1 38335.0 39520.8 39554.0 37725.6 38204.0 38355.8 38418.0 38420.0 33874.2 34158.5 34177.7 34481.0 34769.0 25120.9 25457.9 25475.0 25964.2 26022.7 15:59:32 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:33822.29 Median:13759.21 stddev:96345.76 (700.2%) stddev-sans-first:96583.26 15:59:32 INFO - PROCESS | 1637 | Values: 351.9 370.5 378.3 381.7 401.8 14617.9 14766.3 15865.4 20012.4 20425.3 19247.7 19622.2 19801.0 19868.0 19967.4 5622.6 5787.0 5821.9 5893.0 5918.3 4754.9 5840.6 5872.8 5878.1 5883.0 5564.9 5828.9 5837.2 5840.7 5843.3 5729.3 5804.8 5838.4 5840.5 5849.1 4878.4 5045.9 5128.8 5134.8 5141.6 25019.5 25043.0 25208.6 25373.1 25409.6 2271.4 2391.8 2498.9 2656.8 2748.6 15073.3 15196.1 15321.6 15644.8 16110.6 1750.4 1822.2 1839.3 1856.9 1871.1 105302.0 117713.4 117793.4 118341.4 120409.4 10548.2 10742.4 10854.5 10978.1 11195.6 13772.4 13776.1 13787.6 13791.8 13816.4 6946.4 8378.5 8502.5 8506.3 8661.7 8457.2 8459.7 8594.8 8618.7 8625.2 6426.6 6547.3 6578.3 6580.3 6587.4 1766.7 1799.5 1800.3 1815.7 1835.0 923.2 956.7 981.0 1012.5 1016.2 21732.4 22085.4 22210.8 22332.1 22332.4 972.1 972.2 986.2 1044.8 1063.8 11027.6 11650.5 11663.9 11669.7 11677.2 563330.0 571943.0 572170.0 591074.0 592978.0 113843.0 114968.0 122372.4 122595.0 124804.4 964.8 966.7 988.1 989.0 996.7 18380.0 19229.5 19447.9 19485.9 19498.5 44655.1 48106.0 49222.0 50155.7 50310.2 66933.7 68826.7 68994.7 70168.3 71875.6 7015.5 7221.7 7301.5 7600.8 7897.8 11729.6 12196.4 12610.5 12622.9 12692.2 10660.2 10746.2 10777.0 11139.8 11272.2 10686.6 10780.2 10847.2 10990.5 11322.1 9075.7 10083.4 10519.5 10749.8 11150.7 6057.9 7189.7 7286.5 7371.0 7496.2 10202.8 10767.5 10993.3 11140.0 11158.3 15:59:32 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5394.12 Median:5098.68 stddev:2955.86 (58.0%) stddev-sans-first:2948.22 15:59:32 INFO - PROCESS | 1637 | Values: 1311.1 1355.4 1467.8 1626.3 1847.7 7416.4 7810.4 8075.5 8371.9 8374.3 7028.2 8306.3 8391.7 8397.6 8413.7 2303.3 2331.7 2340.6 2341.6 2343.0 2318.2 2355.1 2371.2 2371.5 2373.1 2294.9 2297.5 2322.8 2323.1 2337.6 2288.1 2292.0 2317.5 2319.5 2322.6 2318.7 2341.3 2342.4 2343.3 2347.8 8330.3 8357.4 8435.8 8449.3 8461.0 5020.0 5220.0 5246.7 5263.4 5271.6 3476.7 3563.1 3623.6 3625.4 3639.8 1053.1 1076.8 1082.2 1104.6 1106.6 8676.6 10928.9 11017.1 11027.0 11041.3 4233.4 4387.1 4472.8 4502.2 4515.9 8641.0 8649.6 8650.4 8657.1 8663.4 5086.5 5096.7 5100.7 5103.8 5105.2 4057.6 4246.9 4406.1 4407.7 4771.6 2918.3 3664.7 3753.0 3764.6 3772.3 1006.9 1014.8 1095.7 1109.6 1143.2 833.7 880.8 896.2 907.9 916.1 5531.2 5615.7 5706.1 5810.9 6028.7 900.4 916.8 927.7 928.0 977.4 3700.0 3715.8 3775.3 3814.7 3907.2 9898.3 10094.2 10173.7 10208.7 10213.2 10154.1 10163.0 10181.3 10184.0 10195.3 7575.7 7812.2 7999.2 8013.4 8018.8 5465.5 5540.1 5744.3 5811.9 5822.7 9194.4 9389.0 9445.0 9513.2 9573.9 8207.6 8728.3 9398.3 9674.9 9764.6 7151.3 7398.0 7403.8 7404.3 7410.7 7606.7 7850.1 7914.4 8010.2 8014.8 7110.8 7137.8 7138.6 7150.0 7331.3 5852.0 7019.0 7032.2 7034.8 7066.3 5554.4 6628.3 6628.4 6686.0 6725.4 4727.8 4741.2 4744.2 4783.1 4875.1 3856.6 4770.5 4842.3 4964.2 4978.6 15:59:32 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4387.56 Median:5007.19 stddev:1359.99 (27.2%) stddev-sans-first:1328.52 15:59:32 INFO - PROCESS | 1637 | Values: 286.8 298.4 299.4 302.9 305.1 5209.5 5258.3 5263.7 5265.8 5304.2 5198.5 5204.2 5216.9 5225.6 5238.9 3369.8 3681.9 3745.6 3749.8 3887.2 3796.8 3898.9 3952.7 3990.2 4045.6 3909.8 3936.2 3938.9 3942.8 3979.5 3730.8 3827.7 3896.4 3907.4 3976.6 2815.0 3280.3 3393.1 3463.3 3466.2 3896.9 4791.0 4874.1 5009.5 5028.4 4403.0 4519.1 4523.7 4541.8 4544.6 4278.5 4575.7 5122.9 5393.3 5496.3 3543.6 3785.5 3793.2 3794.9 3800.6 5742.3 5781.7 5796.0 5797.9 5802.8 3671.4 3711.7 3804.7 3804.9 3805.4 3700.6 3838.9 3921.8 3927.3 3932.4 3749.7 4075.9 4490.7 4509.5 4537.3 4636.2 5472.4 5478.8 5495.4 5496.4 1097.7 1131.1 1213.5 1222.6 1232.3 783.9 794.8 802.4 843.5 845.7 4059.8 4509.9 4974.7 5108.1 5134.8 5153.8 5499.7 5680.7 5701.9 5721.9 4239.3 4703.2 4922.3 5215.6 5224.6 2334.5 2512.6 2708.1 2759.5 2775.6 4728.4 4772.6 5476.5 5656.8 5667.7 4727.8 5132.5 5201.5 5455.5 5562.9 5004.9 5010.9 5123.5 5171.2 5626.5 4870.0 5214.5 5337.6 5398.7 5399.7 5112.8 5288.5 5301.8 5412.3 5654.9 4750.4 5232.0 5399.8 5934.8 5973.5 3332.0 3831.0 3841.9 3861.8 3907.5 5414.3 5548.4 5594.6 5617.9 5638.8 4743.2 4747.9 5204.6 5328.2 5542.6 5501.8 5527.5 5555.4 5590.8 5623.7 5101.8 5399.6 5439.4 5451.0 5490.9 5253.4 5362.8 5376.2 5563.0 5577.7 4550.2 5233.3 5410.3 5442.8 5486.9 15:59:32 INFO - PROCESS | 1637 | 15:59:32 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19153.87 Median:4398.62 stddev:27500.27 (625.2%) stddev-sans-first:27542.73 15:59:32 INFO - PROCESS | 1637 | Values: 758.5 784.7 787.1 793.5 808.7 14082.6 14536.5 14549.2 14613.7 14697.6 12714.9 12832.4 12838.4 12845.3 12854.6 797.4 801.2 829.1 833.3 834.1 830.7 832.2 832.3 832.9 833.9 875.8 876.7 876.9 878.5 879.8 867.8 868.6 869.6 870.4 872.4 645.5 645.5 645.9 647.2 647.2 14089.5 14171.8 14174.9 14179.9 14183.4 12940.5 13008.3 13160.3 13190.3 13392.3 10644.8 11092.3 11184.9 11220.3 11437.7 3413.7 3595.6 3612.9 3657.0 3738.5 70299.6 86443.6 89427.0 89691.2 90381.6 10062.3 10197.0 10280.7 10328.0 10605.2 19898.1 20028.8 20063.8 20112.3 20265.8 7249.3 7332.7 7414.8 7567.7 7574.0 3233.5 4104.8 4185.5 4212.7 4224.4 3366.1 3484.7 3485.6 3486.1 3498.4 626.6 635.4 673.7 712.3 712.5 541.9 584.6 612.0 656.9 677.9 8665.8 8989.4 9014.5 9184.9 9221.0 629.9 634.1 643.3 656.8 677.0 3964.8 4254.9 4369.8 4390.3 4406.9 73710.9 74170.6 107380.5 118182.0 119397.4 60154.4 61682.1 70998.6 94836.6 98660.0 69867.7 74600.6 76981.0 78451.2 81325.2 8454.5 9411.2 9557.7 9675.5 9692.9 50819.2 51359.2 51666.5 51889.7 53396.0 70607.0 73318.0 73533.3 75046.5 75237.2 11740.7 11984.5 12017.8 12140.4 12561.3 11257.0 11329.2 11693.6 12248.7 13589.9 11153.2 11527.8 11654.4 11855.1 11991.6 12204.2 12806.9 12872.5 12875.0 12961.9 10947.7 12179.8 12497.7 12657.4 12744.4 9889.8 10443.6 11087.5 11423.3 11646.9 10593.5 11232.9 11393.9 11478.3 11496.8 15:59:32 INFO - PROCESS | 1637 | -------- Summary: end -------- 15:59:32 INFO - PROCESS | 1637 | 15:59:33 INFO - TEST-INFO | 1637: exit 0 15:59:33 INFO - TEST-OK | dromaeo_css | took 1118443ms 15:59:33 INFO - TEST-START | kraken 15:59:33 INFO - operating with platform_type : mac_ 15:59:33 INFO - Initialising browser for kraken test... 15:59:33 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpfNKoXV/profile http://localhost:49243/getInfo.html) 15:59:35 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 15:59:35 INFO - PROCESS | 1646 | colorDepth:24 15:59:35 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 15:59:35 INFO - PROCESS | 1646 | __metrics 15:59:35 INFO - PROCESS | 1646 | 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" 15:59:35 INFO - PROCESS | 1646 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:59:36 INFO - TEST-INFO | 1646: exit 0 15:59:36 INFO - Browser initialized. 15:59:36 INFO - Running cycle 1/1 for kraken test... 15:59:36 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpfNKoXV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:48 INFO - PROCESS | 1647 | RSS: Main: 222285824 15:59:48 INFO - PROCESS | 1647 | 15:59:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 15:59:52 INFO - PROCESS | 1647 | RSS: Main: 271790080 15:59:52 INFO - PROCESS | 1647 | 15:59:55 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 15:59:55 INFO - PROCESS | 1647 | RSS: Main: 282853376 15:59:55 INFO - PROCESS | 1647 | 15:59:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 15:59:59 INFO - PROCESS | 1647 | RSS: Main: 380313600 15:59:59 INFO - PROCESS | 1647 | 16:00:01 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 16:00:02 INFO - PROCESS | 1647 | RSS: Main: 283111424 16:00:02 INFO - PROCESS | 1647 | 16:00:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:00:04 INFO - PROCESS | 1647 | RSS: Main: 284381184 16:00:04 INFO - PROCESS | 1647 | 16:00:07 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 16:00:08 INFO - PROCESS | 1647 | RSS: Main: 324194304 16:00:08 INFO - PROCESS | 1647 | 16:00:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 16:00:11 INFO - PROCESS | 1647 | RSS: Main: 326758400 16:00:11 INFO - PROCESS | 1647 | 16:00:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 16:00:14 INFO - PROCESS | 1647 | RSS: Main: 324313088 16:00:14 INFO - PROCESS | 1647 | 16:00:16 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:00:17 INFO - PROCESS | 1647 | RSS: Main: 298655744 16:00:17 INFO - PROCESS | 1647 | 16:00:19 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:00:19 INFO - PROCESS | 1647 | RSS: Main: 300335104 16:00:19 INFO - PROCESS | 1647 | 16:00:22 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:22 INFO - PROCESS | 1647 | RSS: Main: 302190592 16:00:22 INFO - PROCESS | 1647 | 16:00:25 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:26 INFO - PROCESS | 1647 | RSS: Main: 320364544 16:00:26 INFO - PROCESS | 1647 | 16:00:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:29 INFO - PROCESS | 1647 | RSS: Main: 313901056 16:00:29 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 16:00:31 INFO - PROCESS | 1647 | RSS: Main: 357765120 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | __start_tp_report 16:00:31 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 16:00:31 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 16:00:31 INFO - PROCESS | 1647 | |i|pagename|runs| 16:00:31 INFO - PROCESS | 1647 | |0;ai-astar;114;113;112;112;110;112;114;112;111;112 16:00:31 INFO - PROCESS | 1647 | |1;audio-beat-detection;144;107;104;144;104;111;108;109;143;108 16:00:31 INFO - PROCESS | 1647 | |2;audio-dft;182;180;184;184;182;180;182;182;182;185 16:00:31 INFO - PROCESS | 1647 | |3;audio-fft;81;60;60;88;60;58;59;73;110;66 16:00:31 INFO - PROCESS | 1647 | |4;audio-oscillator;86;70;92;69;69;68;90;67;68;96 16:00:31 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;88;84;92;131;88;84;87;86;90;86 16:00:31 INFO - PROCESS | 1647 | |6;imaging-darkroom;93;93;93;93;103;93;93;96;93;93 16:00:31 INFO - PROCESS | 1647 | |7;imaging-desaturate;83;84;82;81;84;80;83;86;92;130 16:00:31 INFO - PROCESS | 1647 | |8;json-parse-financial;55;54;55;56;58;60;58;58;58;57 16:00:31 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;63;75;81;72;72;69;79;102;104;95 16:00:31 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;93;94;101;83;84;84;84;83;83;84 16:00:31 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;141;164;157;139;135;148;157;162;176;151 16:00:31 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;163;166;206;225;175;163;166;165;165;165 16:00:31 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;67;66;68;67;66;67;66;67;66;67 16:00:31 INFO - PROCESS | 1647 | __end_tp_report 16:00:31 INFO - PROCESS | 1647 | __start_cc_report 16:00:31 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1952 16:00:31 INFO - PROCESS | 1647 | __end_cc_report 16:00:31 INFO - PROCESS | 1647 | __startTimestamp1484784031849__endTimestamp 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | ------- Summary: start ------- 16:00:31 INFO - PROCESS | 1647 | Number of tests: 14 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.00 stddev:1.23 (1.1%) stddev-sans-first:1.12 16:00:31 INFO - PROCESS | 1647 | Values: 114.0 113.0 112.0 112.0 110.0 112.0 114.0 112.0 111.0 112.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:118.20 Median:110.00 stddev:17.70 (16.1%) stddev-sans-first:16.12 16:00:31 INFO - PROCESS | 1647 | Values: 144.0 107.0 104.0 144.0 104.0 111.0 108.0 109.0 143.0 108.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:182.30 Median:182.00 stddev:1.64 (0.9%) stddev-sans-first:1.73 16:00:31 INFO - PROCESS | 1647 | Values: 182.0 180.0 184.0 184.0 182.0 180.0 182.0 182.0 182.0 185.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:71.50 Median:63.00 stddev:17.04 (27.0%) stddev-sans-first:17.72 16:00:31 INFO - PROCESS | 1647 | Values: 81.0 60.0 60.0 88.0 60.0 58.0 59.0 73.0 110.0 66.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.00 stddev:11.89 (15.2%) stddev-sans-first:12.21 16:00:31 INFO - PROCESS | 1647 | Values: 86.0 70.0 92.0 69.0 69.0 68.0 90.0 67.0 68.0 96.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:87.50 stddev:14.06 (16.1%) stddev-sans-first:14.86 16:00:31 INFO - PROCESS | 1647 | Values: 88.0 84.0 92.0 131.0 88.0 84.0 87.0 86.0 90.0 86.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:93.00 stddev:3.20 (3.4%) stddev-sans-first:3.36 16:00:31 INFO - PROCESS | 1647 | Values: 93.0 93.0 93.0 93.0 103.0 93.0 93.0 96.0 93.0 93.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:88.50 Median:83.50 stddev:14.95 (17.9%) stddev-sans-first:15.73 16:00:31 INFO - PROCESS | 1647 | Values: 83.0 84.0 82.0 81.0 84.0 80.0 83.0 86.0 92.0 130.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:58.00 stddev:1.85 (3.2%) stddev-sans-first:1.83 16:00:31 INFO - PROCESS | 1647 | Values: 55.0 54.0 55.0 56.0 58.0 60.0 58.0 58.0 58.0 57.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:81.20 Median:73.50 stddev:14.28 (19.4%) stddev-sans-first:13.54 16:00:31 INFO - PROCESS | 1647 | Values: 63.0 75.0 81.0 72.0 72.0 69.0 79.0 102.0 104.0 95.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:84.00 stddev:6.36 (7.6%) stddev-sans-first:6.40 16:00:31 INFO - PROCESS | 1647 | Values: 93.0 94.0 101.0 83.0 84.0 84.0 84.0 83.0 83.0 84.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:157.00 stddev:12.72 (8.1%) stddev-sans-first:12.73 16:00:31 INFO - PROCESS | 1647 | Values: 141.0 164.0 157.0 139.0 135.0 148.0 157.0 162.0 176.0 151.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:166.00 stddev:21.61 (13.0%) stddev-sans-first:22.41 16:00:31 INFO - PROCESS | 1647 | Values: 163.0 166.0 206.0 225.0 175.0 163.0 166.0 165.0 165.0 165.0 16:00:31 INFO - PROCESS | 1647 | 16:00:31 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 16:00:31 INFO - PROCESS | 1647 | Values: 67.0 66.0 68.0 67.0 66.0 67.0 66.0 67.0 66.0 67.0 16:00:31 INFO - PROCESS | 1647 | -------- Summary: end -------- 16:00:31 INFO - PROCESS | 1647 | 16:00:32 INFO - TEST-INFO | 1647: exit 0 16:00:32 INFO - TEST-OK | kraken | took 59669ms 16:00:32 INFO - SUITE-END | took 1178s 16:00:33 INFO - Completed test suite (00:19:38) 16:00:33 INFO - javascript benchmark 16:00:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.716294115, 3353.228757385801, 3361.9663803361973, 3377.138829598452, 3408.250184959308, 5745.396733342996, 5764.769409223636, 5773.989551828434, 5801.0, 5851.473367396929, 5664.801731939383, 5749.252597162363, 5751.884962300752, 5752.856178595527, 5780.913286300709, 4389.736615803062, 5211.218317252414, 5273.789048438058, 5276.92084618731, 5285.577153827704, 5166.17341225404, 5178.456262092483, 5180.922286165711, 5346.064438723227, 5372.785088596452, 5212.0, 5254.632175747699, 5273.525382715555, 5274.604404669646, 5324.148136298193, 5285.814996475124, 5306.442823503515, 5328.973355133238, 5340.172273297645, 5347.0, 4850.69031361534, 5186.222066689989, 5259.76331065103, 5260.552853007499, 5290.418054014055, 4688.132695451318, 4859.173940430135, 5627.521660658849, 5678.716064196815, 5688.175214593861, 6197.25632924052, 6207.782727604557, 6235.87528249437, 6262.843428914268, 6272.1219029335925, 6227.910115729729, 6283.277423096311, 6283.748650053993, 6288.371162883721, 6290.528210384239, 5668.943310566883, 5717.799877004368, 5768.711564421762, 5807.477327040587, 5810.50610698087, 6273.654948977759, 6275.717592708339, 6298.086777417248, 6308.337624549448, 6310.179676642099, 6146.170267013986, 6191.752329906843, 6197.349278325801, 6200.255969283715, 6218.129461875341, 6043.667598282137, 6056.242969628796, 6123.632582045091, 6151.2618485781995, 6273.529485288622, 5187.714675692798, 6147.538934579924, 6184.907226391608, 6219.689015549205, 6242.375762423721, 6151.5386346024225, 6196.194494715748, 6219.782307619212, 6230.158928544679, 6238.1266622672865, 4838.467768545457, 4888.731119788375, 4891.413030436299, 4959.231319145538, 4961.0, 4420.668449866273, 4437.800298986554, 4442.68901176921, 4464.196444639996, 4479.484859241164, 5777.94222057774, 5788.392218817048, 5804.21643078179, 5874.794382196603, 5899.67551784656, 6236.656983865931, 6247.447124341208, 6266.749330026839, 6277.372262773777, 6286.528510361742, 5795.420457954255, 5857.004309267435, 5857.209276747584, 5878.853028674317, 5885.117232415172, 5324.707141107277, 5382.327209098863, 5391.541718953855, 5430.42980487051, 5481.780728770805, 6408.519361048034, 6413.647249401328, 6417.101605775289, 6438.066480360228, 6444.935550644527, 6112.908306375497, 6247.437730604267, 6293.937060629335, 6295.433410993032, 6312.0, 6213.813585592439, 6289.08808222796, 6310.5898116622275, 6321.0834429008455, 6415.679216039086, 6225.221847269091, 6234.625922444668, 6256.0, 6256.7810126645345, 6260.40526150015, 6282.905756413566, 6288.402601752826, 6288.874222515616, 6294.8111556653375, 6301.0863424804065, 5853.64878107315, 5973.581849270509, 6111.633302001894, 6148.016317389196, 6196.318404975539, 6107.755689772359, 6119.654885717031, 6147.293061297829, 6172.598781079216, 6183.938160618336, 6026.035834266497, 6031.939680603138, 6042.305134909366, 6051.152838602511, 6055.212822333068, 5977.940220597912, 6039.969800150971, 6129.908051379322, 6169.907451388322, 6200.441960223601, 5954.523638109027, 5980.730867110903, 6017.308009578955, 6056.06131049688, 6100.420460056288], "value": 5714.333947480421, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.1461427886918, 505.6031015652711, 508.83971548962074, 513.3172880069508, 553.9556835453164, 30086.548701769465, 31986.840065799697, 32056.0, 32116.35767284656, 32545.349093018183, 41979.160416791725, 42505.14989700204, 42615.0, 42696.786516067456, 42728.57271427285, 9282.814343713122, 9455.669051583196, 9468.100530449596, 9483.099105584959, 9487.0, 8345.332373410112, 8589.95705021474, 9271.443713377219, 9398.483083430408, 9403.341766076377, 8513.446625969324, 8561.95719021404, 8564.74305770828, 11110.666679999613, 11391.658250252463, 11292.0, 11367.090632749443, 11367.431628418546, 11367.658970230907, 11380.089592832554, 11167.776644467147, 11249.60626378081, 11258.211925165242, 11262.380569068699, 11280.984711375933, 31284.06147815569, 31819.204519886956, 38840.61159388398, 39389.80305098456, 39421.21157576861, 3237.3525294941105, 3257.250832308582, 3258.657840926716, 3306.9173270668184, 3595.6044835068124, 29441.0, 29522.26194345137, 29790.65942032592, 30508.237294067603, 30751.0, 2785.749282564579, 3042.421939831425, 3111.9066428007195, 3160.747140228776, 3302.8018318900704, 501525.0, 501854.4350947736, 502567.0, 503589.0, 505743.0, 52705.472945270056, 53998.0, 59830.0, 61576.384236157064, 62215.688921555105, 61486.69256653688, 63836.04245936315, 64555.67722161453, 64655.38361540999, 64676.0, 15616.0, 15718.13550254747, 15749.976251543612, 15772.526824195293, 15786.36854525829, 10094.19246460296, 11863.46614402354, 12029.578964736367, 12083.879161208451, 12097.576584819664, 17548.0, 17573.912130439265, 17610.64778704419, 17650.470485885446, 17666.558336041446, 5244.554212891947, 5518.558515318765, 6399.0564340403935, 6440.581362211441, 6477.935220647827, 833.3832963607008, 878.4904755242072, 984.3749219245831, 1034.1674951663983, 1065.2702898514542, 28823.279418014718, 29090.563641545396, 29239.85380073086, 29803.701962980093, 29850.552241716392, 868.3955452693233, 884.0364003236375, 959.7696552827272, 1019.9031092046243, 1054.99472502637, 14965.70068598622, 15254.00848944815, 15411.460598878986, 15457.922710386376, 15748.291326890318, 618811.0, 628073.0, 629001.0, 629529.0, 630666.0, 47708.284375734394, 57905.71047144822, 58085.12872306919, 58264.0, 58288.54278642983, 1206.9336186509827, 1250.4748005837753, 1329.355262697591, 1348.6021623621054, 1376.4287820554557, 28788.0, 30507.694923050487, 31469.0, 31672.683273166975, 32066.03801885947, 55544.72227638836, 56236.71881640566, 56442.7177864108, 56448.71775641095, 56563.0, 589070.0, 589782.0, 590723.0, 591189.0, 597611.0, 36588.451173232956, 39963.60036399599, 39999.0, 40081.79141190133, 40284.59715402809, 45515.089698205185, 45786.85532861572, 46190.53809461863, 46501.53498464972, 46686.76656616695, 36319.63680363163, 37572.812135939144, 37844.243115138095, 38350.23299534049, 38702.80648596739, 37243.882683519536, 38001.09994500319, 38335.0, 39520.802395987834, 39554.0, 37725.62274377221, 38204.0, 38355.808220958716, 38418.0, 38420.0, 33874.15314617154, 34158.487622685185, 34177.65822341844, 34481.0, 34769.0, 25120.874395628634, 25457.87271063633, 25475.0, 25964.22107336783, 26022.73977260203], "value": 19831.264274190653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.86277351832774, 370.49982523593144, 378.3473508198358, 381.6679488844705, 401.7509144330514, 14617.853821461795, 14766.335514901828, 15865.365385384584, 20012.399628011182, 20425.28511502098, 19247.711284330748, 19622.215111395562, 19801.0, 19868.006599670032, 19967.400977970683, 5622.634528755628, 5787.0, 5821.912671309934, 5892.968730472172, 5918.319393269786, 4754.882602588389, 5840.649561026331, 5872.823815285548, 5878.1476685880525, 5882.97058514709, 5564.86087847803, 5828.912566311487, 5837.182794408786, 5840.707964601774, 5843.2695913010875, 5729.28383952006, 5804.796832110877, 5838.357780644125, 5840.503557197643, 5849.093390524476, 4878.390201224847, 5045.924311135336, 5128.794848206105, 5134.794608215667, 5141.6143789215585, 25019.49961000788, 25043.0, 25208.621870671956, 25373.111941081974, 25409.61885571718, 2271.4434963434064, 2391.796697280733, 2498.925032249035, 2656.8405895646324, 2748.6014527893544, 15073.321700523502, 15196.088234705952, 15321.616959575987, 15644.765328520081, 16110.597235069099, 1750.439859245042, 1822.1618055694396, 1839.3102586530051, 1856.8793028453242, 1871.1392759330724, 105302.0, 117713.41143294315, 117793.41103294515, 118341.40829295885, 120409.39795300967, 10548.208884333706, 10742.355458672504, 10854.511546980404, 10978.066864316463, 11195.60815371458, 13772.380242889094, 13776.10455320401, 13787.586372408643, 13791.793123103162, 13816.447342106405, 6946.37482626566, 8378.539180345018, 8502.489850608983, 8506.276966457797, 8661.740147795575, 8457.23884850354, 8459.661613535513, 8594.831506719236, 8618.741437756877, 8625.180607842245, 6426.646534440651, 6547.279799222082, 6578.27638959714, 6580.341965803382, 6587.440067594212, 1766.7349897515476, 1799.4704501173896, 1800.3158799656046, 1815.709486482183, 1834.97247541287, 923.2152670230385, 956.7321150077989, 981.0287815063178, 1012.4937531234383, 1016.1718199667248, 21732.384036242172, 22085.447863803533, 22210.77789222087, 22332.106715731512, 22332.441688957904, 972.1493693018608, 972.1833659725864, 986.225812736998, 1044.827603445423, 1063.7553362726462, 11027.558897644005, 11650.53397864093, 11663.88336116628, 11669.708257293636, 11677.182597218283, 563330.0, 571943.0, 572170.0, 591074.0, 592978.0, 113843.0, 114968.0, 122372.38813805874, 122595.0, 124804.37597811953, 964.8118616869643, 966.6809952715587, 988.125508924607, 989.0307498651362, 996.6960077176386, 18380.0, 19229.519262018563, 19447.902760486108, 19485.90257048706, 19498.512537186685, 44655.10689786251, 48106.0, 49222.0, 50155.74922125366, 50310.232937188804, 66933.66533167497, 68826.65586672234, 68994.65502672455, 70168.29831701618, 71875.64062179656, 7015.543989640657, 7221.747238846615, 7301.488895777457, 7600.771976840703, 7897.763067107996, 11729.589464369048, 12196.390180491117, 12610.49558017669, 12622.873771262537, 12692.238465692088, 10660.200484963814, 10746.194035447217, 10777.0, 11139.777204456028, 11272.154588405738, 10686.62596809139, 10780.191485638452, 10847.186461015297, 10990.505427255952, 11322.094232461548, 9075.682351117679, 10083.445410502492, 10519.526621302213, 10749.785004300027, 11150.721231969266, 6057.878842423215, 7189.676464559212, 7286.489945703962, 7370.96314518424, 7496.212897645669, 10202.846957295795, 10767.461626918779, 10993.285436446606, 11139.997400234019, 11158.330500170016], "value": 9529.069846523147, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.0887932886646, 1355.4036224061413, 1467.7504824179885, 1626.308818752031, 1847.722841573763, 7416.406687465003, 7810.414218933574, 8075.51546907186, 8371.874421883662, 8374.330053595713, 7028.1566212054495, 8306.335493160548, 8391.664333426656, 8397.622107005183, 8413.66345346187, 2303.2629558541275, 2331.650252462136, 2340.6137987232173, 2341.648752687102, 2342.9648555271683, 2318.188633978111, 2355.1168311883043, 2371.1700904683394, 2371.537550177716, 2373.1100837186054, 2294.850834695748, 2297.483066310075, 2322.756110608387, 2323.1288266899915, 2337.567550003255, 2288.0618946231966, 2292.0487997481027, 2317.5480781247666, 2319.5013072189477, 2322.581935251654, 2318.686977258082, 2341.332720174742, 2342.355852140658, 2343.27358518858, 2347.7534858839917, 8330.250277474996, 8357.373197010249, 8435.789105272355, 8449.32405407566, 8460.957695211484, 5020.0, 5220.0, 5246.711430871301, 5263.4473380295285, 5271.604629652791, 3476.687098161178, 3563.055790215595, 3623.565172179355, 3625.365561026836, 3639.8180090995343, 1053.1153830782102, 1076.7631121153333, 1082.1775450657558, 1104.618906477264, 1106.5684383090602, 8676.60955257015, 10928.890711092867, 11017.063549598293, 11027.0, 11041.282316649393, 4233.407322974786, 4387.144506821203, 4472.843450479218, 4502.234620114588, 4515.932261016087, 8641.0, 8649.610767515476, 8650.35122365825, 8657.134286571292, 8663.35024873137, 5086.516780905808, 5096.668716533413, 5100.69395836251, 5103.821366252163, 5105.15764898795, 4057.5739547347102, 4246.915061698811, 4406.1187762447635, 4407.691461597721, 4771.618270538349, 2918.255844759573, 3664.6885014774043, 3753.0242137044565, 3764.642358975893, 3772.2832661794173, 1006.8892421833592, 1014.8122597319519, 1095.6767753512734, 1109.6227282723912, 1143.2397455692037, 833.7082021292499, 880.7709995401242, 896.211334026066, 907.9228265597499, 916.134253130785, 5531.170324451284, 5615.691136987505, 5706.087026075808, 5810.866880958172, 6028.728707208186, 900.446225571269, 916.757059379265, 927.6706769097009, 928.0, 977.4184360305607, 3699.9815000924823, 3715.8327875245677, 3775.320442320354, 3814.7329686922203, 3907.2185562887535, 9898.30711850178, 10094.24293178, 10173.745656358651, 10208.744781380376, 10213.182945364353, 10154.086132248132, 10163.034511721375, 10181.33821301613, 10184.032516910882, 10195.28632995698, 7575.734849280359, 7812.1797211292, 7999.1600881907725, 8013.398995075393, 8018.799530011796, 5465.480779325958, 5540.113581826889, 5744.339400968942, 5811.912821307768, 5822.679752613478, 9194.356395052282, 9389.0, 9445.0, 9513.191378732885, 9573.856392153984, 8207.58962051907, 8728.258098061737, 9398.2951278653, 9674.85487717699, 9764.560594773395, 7151.32062454066, 7398.0, 7403.777886663409, 7404.296591823768, 7410.666520006721, 7606.657700403606, 7850.136484986761, 7914.406419518444, 8010.158933311918, 8014.799630009296, 7110.751123710852, 7137.750178743719, 7138.643067846483, 7149.964250178716, 7331.266873312626, 5851.970740146272, 7018.964905175441, 7032.226455090038, 7034.824129396806, 7066.258042905626, 5554.389017208023, 6628.304028077176, 6628.370304821035, 6685.966570167118, 6725.39471447572, 4727.763611819464, 4741.217699079613, 4744.240921452551, 4783.091212669582, 4875.098106850244, 3856.633619806114, 4770.522947705202, 4842.273658951185, 4964.205727083649, 4978.626603004716], "value": 4372.046888422885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.7634201783529, 298.3987265659695, 299.4340696084401, 302.90307101727456, 305.0756208687677, 5209.479052094794, 5258.26384306197, 5263.736813159341, 5265.842024739254, 5304.230886521452, 5198.506141916512, 5204.245384419257, 5216.9478305217035, 5225.608079394042, 5238.947610523894, 3369.780964237329, 3681.889543313705, 3745.557960185328, 3749.762578349138, 3887.241987812378, 3796.810159492028, 3898.883033508985, 3952.738990332972, 3990.2243501641783, 4045.5549889512276, 3909.824057917386, 3936.2324346752393, 3938.901527461822, 3942.80285985701, 3979.4531499840127, 3730.8321125549146, 3827.655511004023, 3896.415537669344, 3907.374820028796, 3976.562578116405, 2814.9718502814912, 3280.343931213743, 3393.06690660068, 3463.341965026662, 3466.2201004773974, 3896.8636097736726, 4790.976045119787, 4874.122657921573, 5009.499050094998, 5028.354443028811, 4402.955970440319, 4519.107771026545, 4523.705959112647, 4541.818327266906, 4544.568266014757, 4278.486581610196, 4575.705492045722, 5122.872967947045, 5393.325834270716, 5496.31296087989, 3543.6102028776813, 3785.4889589905288, 3793.1655035892336, 3794.9430758538642, 3800.600936901641, 5742.272420600223, 5781.653100813923, 5795.971410006464, 5797.88404231913, 5802.796902108448, 3671.375866102769, 3711.740178187555, 3804.6575808177395, 3804.8965799917714, 3805.4397696944675, 3700.5744339401313, 3838.865639702597, 3921.764694118362, 3927.3323534999117, 3932.390479475686, 3749.718771092179, 4075.8777236682945, 4490.730556166641, 4509.458864936229, 4537.319402089647, 4636.235021221461, 5472.398036216013, 5478.780848766084, 5495.433801366591, 5496.367917689517, 1097.742030622803, 1131.1403333466624, 1213.4842691855927, 1222.6001228593211, 1232.3345393487596, 783.9412044096715, 794.7655441644729, 802.4262652203703, 843.502333623153, 845.7166849105496, 4059.7970101494807, 4509.864704058883, 4974.676646017997, 5108.092309383324, 5134.840778968973, 5153.768080436389, 5499.697516636624, 5680.670924920762, 5701.914471282934, 5721.914171287434, 4239.342901850218, 4703.153432382204, 4922.286268491049, 5215.566768247036, 5224.582033437316, 2334.459256890444, 2512.623106533998, 2708.079253053971, 2759.503289407886, 2775.6252905857855, 4728.432588089451, 4772.587599866112, 5476.534494567926, 5656.8020119295625, 5667.659940403589, 4727.841678788719, 5132.5124113209185, 5201.531862132426, 5455.509004189642, 5562.860928476821, 5004.873903371712, 5010.924836127534, 5123.487651234921, 5171.2333901363345, 5626.521745651584, 4869.975650121727, 5214.478552144755, 5337.57299416038, 5398.734999350626, 5399.676019438846, 5112.795488180431, 5288.497592728685, 5301.754087789289, 5412.323459567554, 5654.886902262014, 4750.358701575243, 5232.0, 5399.757010934439, 5934.7922822701, 5973.520988789777, 3331.9666803332625, 3830.9656392774355, 3841.9231615368094, 3861.80690965445, 3907.5115610548683, 5414.3232095988005, 5548.417416171405, 5594.608377413542, 5617.915731263953, 5638.830835074954, 4743.173878056904, 4747.905041899212, 5204.609654275869, 5328.2026935858985, 5542.63972841764, 5501.834944951658, 5527.50252477279, 5555.361133469541, 5590.832275031756, 5623.718814059363, 5101.846944591668, 5399.649022813662, 5439.4288599696465, 5451.0123237237485, 5490.890182196414, 5253.395859476056, 5362.839114826562, 5376.247325374373, 5562.997320964369, 5577.721113944368, 4550.224363138416, 5233.3310011893445, 5410.296661433988, 5442.809501667581, 5486.945130548644], "value": 3945.3034729124165, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.4918104869737, 784.7371130671224, 787.094840932927, 793.4683761879542, 808.6644042722263, 14082.577522674323, 14536.491222807204, 14549.199794011325, 14613.707725845477, 14697.632559186026, 12714.936425317861, 12832.422540985677, 12838.422270997804, 12845.293508857008, 12854.614361569145, 797.3740613618311, 801.2148094867034, 829.0714399872152, 833.3208435125383, 834.1283358889945, 830.6594296338502, 832.2094010689839, 832.3216578488543, 832.8958880139983, 833.8832563441123, 875.7722992022057, 876.6975393489236, 876.9123087691244, 878.5343767803074, 879.7536689726886, 867.7830542364409, 868.6438560190323, 869.5652173913044, 870.3863776037878, 872.4416373520952, 645.5029627187087, 645.5352146454545, 645.8514541655398, 647.1554621219307, 647.184547470191, 14089.506867259699, 14171.78742318866, 14174.858251417458, 14179.929100354535, 14183.361748721332, 12940.547080852219, 13008.349582520836, 13160.27618480983, 13190.340482975813, 13392.263425511594, 10644.840327395095, 11092.33445993235, 11184.94407527957, 11220.326780393203, 11437.656870293822, 3413.749881397651, 3595.5505561804775, 3612.8735494257576, 3656.963430365689, 3738.513993180869, 70299.64850175717, 86443.56778216132, 89427.0, 89691.23705155357, 90381.64427533526, 10062.295639305325, 10197.0, 10280.691579252634, 10328.0, 10605.151587872951, 19898.104585293695, 20028.799712002983, 20063.799362006484, 20112.296069637494, 20265.79734202639, 7249.275072492708, 7332.706691732271, 7414.814629634195, 7567.659455324524, 7574.015378000825, 3233.4503134467195, 4104.774237416971, 4185.476815398075, 4212.705110642226, 4224.366345048267, 3366.107981384935, 3484.7212223022098, 3485.6165821759587, 3486.0587641337206, 3498.352804731133, 626.6271568416785, 635.4376376906471, 673.6530686696356, 712.2805965974403, 712.5297303779481, 541.9187121931742, 584.6287607369266, 611.9938800611936, 656.8883289840738, 677.8949262864264, 8665.826683466295, 8989.370744047854, 9014.541509490244, 9184.862227066465, 9220.953895230481, 629.8866204083217, 634.1439057272645, 643.3437893348759, 656.7996760987849, 676.9604475903159, 3964.83037503937, 4254.872353829328, 4369.80335884886, 4390.297552391666, 4406.942333839856, 73710.894336585, 74170.629146855, 107380.46309768557, 118182.0, 119397.40301298612, 60154.39845601488, 61682.074768878505, 70998.64500677463, 94836.56797752241, 98660.0, 69867.65066174636, 74600.62699686576, 76981.0, 78451.21548784553, 81325.18674813294, 8454.53500057478, 9411.152996230372, 9557.713268601952, 9675.467849268076, 9692.854607180758, 50819.23771143362, 51359.229611556606, 51666.483335166165, 51889.740551297, 53396.0, 70607.0, 73318.0, 73533.26466735265, 75046.4990700172, 75237.24762752303, 11740.70648233801, 11984.46069926838, 12017.75964480688, 12140.392980351124, 12561.309127997682, 11257.018880851432, 11329.206955513364, 11693.590724324607, 12248.693782655504, 13589.932050339685, 11153.163512736415, 11527.827082593936, 11654.359010254517, 11855.051595872483, 11991.64025079249, 12204.206726562745, 12806.935965320487, 12872.485100596246, 12875.0, 12961.870381296443, 10947.671569852917, 12179.817302740288, 12497.687557811127, 12657.367131643565, 12744.362781860684, 9889.802203956024, 10443.582256709646, 11087.501062452371, 11423.314601123959, 11646.94176529112, 10593.52329145175, 11232.92024149975, 11393.886061139283, 11478.253913495597, 11496.770064598828], "value": 7108.454841491832, "unit": "score"}], "name": "dromaeo_css", "value": 7139.360709656544, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 112.0, 112.0, 110.0, 112.0, 114.0, 112.0, 111.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 104.0, 144.0, 104.0, 111.0, 108.0, 109.0, 143.0, 108.0], "value": 118.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 180.0, 184.0, 184.0, 182.0, 180.0, 182.0, 182.0, 182.0, 185.0], "value": 182.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 88.0, 60.0, 58.0, 59.0, 73.0, 110.0, 66.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 92.0, 69.0, 69.0, 68.0, 90.0, 67.0, 68.0, 96.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 92.0, 131.0, 88.0, 84.0, 87.0, 86.0, 90.0, 86.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 103.0, 93.0, 93.0, 96.0, 93.0, 93.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 82.0, 81.0, 84.0, 80.0, 83.0, 86.0, 92.0, 130.0], "value": 88.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 56.0, 58.0, 60.0, 58.0, 58.0, 58.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 75.0, 81.0, 72.0, 72.0, 69.0, 79.0, 102.0, 104.0, 95.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 101.0, 83.0, 84.0, 84.0, 84.0, 83.0, 83.0, 84.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 164.0, 157.0, 139.0, 135.0, 148.0, 157.0, 162.0, 176.0, 151.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 206.0, 225.0, 175.0, 163.0, 166.0, 165.0, 165.0, 165.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 67.0, 66.0, 67.0, 66.0, 67.0, 66.0, 67.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1457.1000000000001, "alertThreshold": 2.0}]} 16:00:33 INFO - Return code: 0 16:00:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:33 INFO - # TBPL SUCCESS # 16:00:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:00:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:00:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:00:33 INFO - 2017-01-18 16:00:33.181 screenresolution[1651:9804] starting screenresolution argv=screenresolution get 16:00:33 INFO - 2017-01-18 16:00:33.183 screenresolution[1651:9804] Display 0: 1600x1200x32@60 16:00:33 INFO - 2017-01-18 16:00:33.190 screenresolution[1652:9807] starting screenresolution argv=screenresolution list 16:00:33 INFO - Available Modes on Display 0 16:00:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:00:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:00:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:00:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:00:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:00:33 INFO - Intel Iris: 16:00:33 INFO - Chipset Model: Intel Iris 16:00:33 INFO - Type: GPU 16:00:33 INFO - Bus: Built-In 16:00:33 INFO - VRAM (Dynamic, Max): 1536 MB 16:00:33 INFO - Vendor: Intel (0x8086) 16:00:33 INFO - Device ID: 0x0a2e 16:00:33 INFO - Revision ID: 0x0009 16:00:33 INFO - Displays: 16:00:33 INFO - Display: 16:00:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:00:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:00:33 INFO - Display Serial Number: 1600x1200 60 16:00:33 INFO - Main Display: Yes 16:00:33 INFO - Mirror: Off 16:00:33 INFO - Online: Yes 16:00:33 INFO - Rotation: Supported 16:00:33 INFO - Return code: 0 16:00:33 INFO - Running post-action listener: _resource_record_post_action 16:00:33 INFO - [mozharness: 2017-01-19 00:00:33.435137Z] Finished run-tests step (success) 16:00:33 INFO - Running post-run listener: _resource_record_post_run 16:00:33 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 36271104; Write bytes: 658399232; Read time: 234; Write time: 1879 16:00:33 INFO - TinderboxPrint: CPU usage
53.3% 16:00:33 INFO - TinderboxPrint: I/O read bytes / time
36,271,104 / 234 16:00:33 INFO - TinderboxPrint: I/O write bytes / time
658,399,232 / 1,879 16:00:33 INFO - TinderboxPrint: CPU idle
2,259.0 (46.7%) 16:00:33 INFO - TinderboxPrint: CPU system
966.6 (20.0%) 16:00:33 INFO - TinderboxPrint: CPU user
1,616.0 (33.4%) 16:00:33 INFO - TinderboxPrint: Swap in / out
385,593,344 / 0 16:00:33 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346491392; Write bytes: 345712640; Read time: 26764; Write time: 698 16:00:33 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 34149376; Write bytes: 305506304; Read time: 213; Write time: 1152 16:00:33 INFO - Running post-run listener: _upload_blobber_files 16:00:33 INFO - Blob upload gear active. 16:00:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:33 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 16:00:33 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:00:33 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:00:34 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:00:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:00:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:36 INFO - (blobuploader) - INFO - Done attempting. 16:00:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:00:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:00:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:37 INFO - (blobuploader) - INFO - Done attempting. 16:00:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:39 INFO - (blobuploader) - INFO - Done attempting. 16:00:39 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:39 INFO - Return code: 0 16:00:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80777b46178655f8e47518c385db7fa8e81a999a642170f01fa76b3b838a5b32676396bee47665d9a858288dfa1c86aa9dd8ac0e5a98a99354db2217a61ffcc2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2381898bd76ce59308fd1a8d40823570ed774aeb003a1cce276b3fa590b0973b1dbcc5cbe0f76a9a07e8f2f093c18a9d15666d7a4f28cd8eb41ff59f1c29caf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9fcd9654765b8296b20c24efdb28148bf6cdc6324785e0a15f0edf39be0b4759be5c4c9eacd6eaf22f50bdd6831ba9d1900315b762c777b63aeac68f4fe21a95"} 16:00:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:39 INFO - Contents: 16:00:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80777b46178655f8e47518c385db7fa8e81a999a642170f01fa76b3b838a5b32676396bee47665d9a858288dfa1c86aa9dd8ac0e5a98a99354db2217a61ffcc2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2381898bd76ce59308fd1a8d40823570ed774aeb003a1cce276b3fa590b0973b1dbcc5cbe0f76a9a07e8f2f093c18a9d15666d7a4f28cd8eb41ff59f1c29caf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9fcd9654765b8296b20c24efdb28148bf6cdc6324785e0a15f0edf39be0b4759be5c4c9eacd6eaf22f50bdd6831ba9d1900315b762c777b63aeac68f4fe21a95"} 16:00:39 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:39 INFO - Copying logs to upload dir... 16:00:39 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.048478 ========= master_lag: 2.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-01-18 16:00:41.751612) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 30 secs) (at 2017-01-18 16:00:41.757392) ========= 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.lIaETRzInL/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.Ati3OeSSsZ/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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/Mozilla-Aurora/sha512/80777b46178655f8e47518c385db7fa8e81a999a642170f01fa76b3b838a5b32676396bee47665d9a858288dfa1c86aa9dd8ac0e5a98a99354db2217a61ffcc2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2381898bd76ce59308fd1a8d40823570ed774aeb003a1cce276b3fa590b0973b1dbcc5cbe0f76a9a07e8f2f093c18a9d15666d7a4f28cd8eb41ff59f1c29caf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9fcd9654765b8296b20c24efdb28148bf6cdc6324785e0a15f0edf39be0b4759be5c4c9eacd6eaf22f50bdd6831ba9d1900315b762c777b63aeac68f4fe21a95"} build_url:https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011127 build_url: 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80777b46178655f8e47518c385db7fa8e81a999a642170f01fa76b3b838a5b32676396bee47665d9a858288dfa1c86aa9dd8ac0e5a98a99354db2217a61ffcc2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2381898bd76ce59308fd1a8d40823570ed774aeb003a1cce276b3fa590b0973b1dbcc5cbe0f76a9a07e8f2f093c18a9d15666d7a4f28cd8eb41ff59f1c29caf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9fcd9654765b8296b20c24efdb28148bf6cdc6324785e0a15f0edf39be0b4759be5c4c9eacd6eaf22f50bdd6831ba9d1900315b762c777b63aeac68f4fe21a95"}' ========= master_lag: 30.33 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 30 secs) (at 2017-01-18 16:01:12.101809) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 19 secs) (at 2017-01-18 16:01:12.102169) ========= 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.lIaETRzInL/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.Ati3OeSSsZ/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.006237 ========= master_lag: 19.43 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 19 secs) (at 2017-01-18 16:01:31.542858) ========= ========= Started reboot skipped (results: 3, elapsed: 6 secs) (at 2017-01-18 16:01:31.543180) ========= ========= Finished reboot skipped (results: 3, elapsed: 6 secs) (at 2017-01-18 16:01:37.778703) ========= ========= Total master_lag: 52.68 =========