builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0286 starttime: 1479218032.84 results: success (0) buildid: 20161115032613 builduid: ea2098eee9814a1b9e908f937c645cc7 revision: 85a9d908e91a7071bbb1f554b275fb1b4e796e34 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:52.844240) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:52.844680) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:52.844970) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:52.870126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:52.870523) ========= 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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-15 05:53:52-- 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% 21.8M=0.001s 2016-11-15 05:53:52 (21.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086988 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:52.980365) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:52.980765) ========= 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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.024468 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:53.029786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-15 05:53:53.030172) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 85a9d908e91a7071bbb1f554b275fb1b4e796e34 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 85a9d908e91a7071bbb1f554b275fb1b4e796e34 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZIMDfoS5gz/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.9trn6LjzEO/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-15 05:53:53,127 truncating revision to first 12 chars 2016-11-15 05:53:53,127 Setting DEBUG logging. 2016-11-15 05:53:53,127 attempt 1/10 2016-11-15 05:53:53,127 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/85a9d908e91a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-15 05:53:54,195 unpacking tar archive at: mozilla-central-85a9d908e91a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.352174 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-15 05:53:54.403901) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:54.404224) ========= script_repo_revision: 85a9d908e91a7071bbb1f554b275fb1b4e796e34 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:54.404599) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:54.408905) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 05:53:54.428699) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-15 05:53:54.429077) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZIMDfoS5gz/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.9trn6LjzEO/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:53:54 INFO - MultiFileLogger online at 20161115 05:53:54 in /builds/slave/test 05:53:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:53:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:53:54 INFO - {'append_to_log': False, 05:53:54 INFO - 'base_work_dir': '/builds/slave/test', 05:53:54 INFO - 'blob_upload_branch': 'Firefox', 05:53:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:53:54 INFO - 'branch': 'Firefox', 05:53:54 INFO - 'buildbot_json_path': 'buildprops.json', 05:53:54 INFO - 'config_files': ('talos/mac_config.py',), 05:53:54 INFO - 'default_actions': ('clobber', 05:53:54 INFO - 'read-buildbot-config', 05:53:54 INFO - 'download-and-extract', 05:53:54 INFO - 'populate-webroot', 05:53:54 INFO - 'create-virtualenv', 05:53:54 INFO - 'install', 05:53:54 INFO - 'run-tests'), 05:53:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:53:54 INFO - 'download_minidump_stackwalk': True, 05:53:54 INFO - 'download_symbols': 'ondemand', 05:53:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:53:54 INFO - 'tooltool.py': '/tools/tooltool.py', 05:53:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:53:54 INFO - '/tools/misc-python/virtualenv.py')}, 05:53:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:53:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:53:54 INFO - 'installer_path': 'installer.exe', 05:53:54 INFO - 'log_level': 'info', 05:53:54 INFO - 'log_name': 'talos', 05:53:54 INFO - 'log_to_console': True, 05:53:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:53:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:53:54 INFO - 'opt_config_files': (), 05:53:54 INFO - 'pip_index': False, 05:53:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:53:54 INFO - 'cmd': ('bash', 05:53:54 INFO - '-c', 05:53:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:53:54 INFO - 'enabled': True, 05:53:54 INFO - 'halt_on_failure': False, 05:53:54 INFO - 'name': 'check_screen_resolution'},), 05:53:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:53:54 INFO - 'cmd': ('bash', 05:53:54 INFO - '-c', 05:53:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:53:54 INFO - 'enabled': True, 05:53:54 INFO - 'halt_on_failure': False, 05:53:54 INFO - 'name': 'check_screen_resolution'},), 05:53:54 INFO - 'run_cmd_checks_enabled': True, 05:53:54 INFO - 'sps_profile': False, 05:53:54 INFO - 'sps_profile_interval': 0, 05:53:54 INFO - 'suite': 'dromaeojs', 05:53:54 INFO - 'system_bits': '32', 05:53:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:53:54 INFO - 'title': 't-yosemite-r7-0286', 05:53:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:53:54 INFO - 'use_talos_json': True, 05:53:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:53:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:53:54 INFO - 'work_dir': 'build'} 05:53:54 INFO - [mozharness: 2016-11-15 13:53:54.628490Z] Running clobber step. 05:53:54 INFO - Running pre-action listener: _resource_record_pre_action 05:53:54 INFO - Running main action method: clobber 05:53:54 INFO - rmtree: /builds/slave/test/build 05:53:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:53:56 INFO - Running post-action listener: _resource_record_post_action 05:53:56 INFO - [mozharness: 2016-11-15 13:53:56.974151Z] Finished clobber step (success) 05:53:56 INFO - [mozharness: 2016-11-15 13:53:56.974330Z] Running read-buildbot-config step. 05:53:56 INFO - Running pre-action listener: _resource_record_pre_action 05:53:56 INFO - Running main action method: read_buildbot_config 05:53:56 INFO - Using buildbot properties: 05:53:56 INFO - { 05:53:56 INFO - "project": "", 05:53:56 INFO - "product": "firefox", 05:53:56 INFO - "script_repo_revision": "production", 05:53:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:53:56 INFO - "repository": "", 05:53:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:53:56 INFO - "buildid": "20161115032613", 05:53:56 INFO - "pgo_build": "False", 05:53:56 INFO - "basedir": "/builds/slave/test", 05:53:56 INFO - "buildnumber": 15, 05:53:56 INFO - "slavename": "t-yosemite-r7-0286", 05:53:56 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:53:56 INFO - "platform": "yosemite_r7", 05:53:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:53:56 INFO - "branch": "mozilla-central", 05:53:56 INFO - "repo_path": "mozilla-central", 05:53:56 INFO - "revision": "85a9d908e91a7071bbb1f554b275fb1b4e796e34", 05:53:56 INFO - "stage_platform": "macosx64", 05:53:56 INFO - "builduid": "ea2098eee9814a1b9e908f937c645cc7", 05:53:56 INFO - "slavebuilddir": "test" 05:53:56 INFO - } 05:53:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:53:56 INFO - Running post-action listener: _resource_record_post_action 05:53:56 INFO - [mozharness: 2016-11-15 13:53:56.977735Z] Finished read-buildbot-config step (success) 05:53:56 INFO - [mozharness: 2016-11-15 13:53:56.977850Z] Running download-and-extract step. 05:53:56 INFO - Running pre-action listener: _resource_record_pre_action 05:53:56 INFO - Running main action method: download_and_extract 05:53:56 INFO - mkdir: /builds/slave/test/build/tests 05:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:56 INFO - trying https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:53:56 INFO - Downloading https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:53:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:53:58 INFO - Downloaded 1199 bytes. 05:53:58 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:53:58 INFO - Using the following test package requirements: 05:53:58 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:53:58 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:53:58 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:53:58 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:53:58 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:53:58 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:53:58 INFO - u'jsshell-mac.zip'], 05:53:58 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:53:58 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:53:58 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:53:58 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:53:58 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:53:58 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:53:58 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:53:58 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:53:58 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:53:58 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:53:58 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:53:58 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:53:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:53:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:53:58 INFO - Fetch https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:53:58 INFO - Http code: 200 05:53:58 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 05:53:58 INFO - x-amz-cf-id: SF_I5f3bfKd7kp65rlr1CDrlo5u4uPnyA8bPe4mP6vzzpJ_GtSUZcA== 05:53:58 INFO - x-amz-version-id: p5JkqVM_k7LbPF4xMpZiDrMiBq4apsh5 05:53:59 INFO - Expected file size: 16328772 05:53:59 INFO - Obtained file size: 16328772 05:54:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:54:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:54:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:54:05 INFO - Fetch https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:54:06 INFO - Http code: 200 05:54:06 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 05:54:06 INFO - x-amz-cf-id: HhV7D-9lu3o1dvOTUg6xjbLafb8Uh-1r4s68teso4Pd_VTe8IH1Yyg== 05:54:06 INFO - x-amz-version-id: p5JkqVM_k7LbPF4xMpZiDrMiBq4apsh5 05:54:06 INFO - Expected file size: 16328772 05:54:06 INFO - Obtained file size: 16328772 05:54:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:54:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:54:12 INFO - Fetch https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:54:13 INFO - Http code: 200 05:54:13 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 05:54:13 INFO - x-amz-cf-id: 4h_arWjiuXrgq4WnPqM97I-NrpOtw66lWij0othiGcLaVChCXCeO8A== 05:54:13 INFO - x-amz-version-id: T8tAQil6Z.r3d5tU0_vJtvvwKCd2V4c. 05:54:13 INFO - Expected file size: 14409038 05:54:13 INFO - Obtained file size: 14409038 05:54:14 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')]} 05:54:14 INFO - trying https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:54:14 INFO - Downloading https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:54:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:54:16 INFO - Downloaded 111000299 bytes. 05:54:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:54:16 INFO - mkdir: /builds/slave/test/properties 05:54:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:54:16 INFO - Writing to file /builds/slave/test/properties/build_url 05:54:16 INFO - Contents: 05:54:16 INFO - build_url:https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:54:17 INFO - Running post-action listener: _resource_record_post_action 05:54:17 INFO - Running post-action listener: set_extra_try_arguments 05:54:17 INFO - [mozharness: 2016-11-15 13:54:17.871614Z] Finished download-and-extract step (success) 05:54:17 INFO - [mozharness: 2016-11-15 13:54:17.871708Z] Running populate-webroot step. 05:54:17 INFO - Running pre-action listener: _resource_record_pre_action 05:54:17 INFO - Running main action method: populate_webroot 05:54:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:54:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:54:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:54:17 INFO - '--tppagecycles', 05:54:17 INFO - '7'], 05:54:17 INFO - 'tests': ['tsvgm']}}, 05:54:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:54:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:54:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:54:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:54:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:17 INFO - 'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:17 INFO - 'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['damp', 'tps']}, 05:54:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:17 INFO - 'tests': ['damp', 'tps']}, 05:54:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['dromaeo_dom']}, 05:54:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:54:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['basic_compositor_video']}, 05:54:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:54:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['a11yr', 05:54:17 INFO - 'ts_paint', 05:54:17 INFO - 'tpaint', 05:54:17 INFO - 'sessionrestore', 05:54:17 INFO - 'sessionrestore_no_auto_restore', 05:54:17 INFO - 'tabpaint']}, 05:54:17 INFO - 'other-e10s': {'tests': ['a11yr', 05:54:17 INFO - 'ts_paint', 05:54:17 INFO - 'tpaint', 05:54:17 INFO - 'sessionrestore', 05:54:17 INFO - 'sessionrestore_no_auto_restore', 05:54:17 INFO - 'tabpaint']}, 05:54:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:54:17 INFO - 'ts_paint', 05:54:17 INFO - 'tpaint', 05:54:17 INFO - 'sessionrestore', 05:54:17 INFO - 'sessionrestore_no_auto_restore', 05:54:17 INFO - 'tabpaint']}, 05:54:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:54:17 INFO - 'ts_paint', 05:54:17 INFO - 'tpaint', 05:54:17 INFO - 'sessionrestore', 05:54:17 INFO - 'sessionrestore_no_auto_restore', 05:54:17 INFO - 'tabpaint']}, 05:54:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['a11yr', 05:54:17 INFO - 'ts_paint', 05:54:17 INFO - 'tpaint', 05:54:17 INFO - 'sessionrestore', 05:54:17 INFO - 'sessionrestore_no_auto_restore', 05:54:17 INFO - 'tabpaint']}, 05:54:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['a11yr', 05:54:17 INFO - 'ts_paint', 05:54:17 INFO - 'tpaint', 05:54:17 INFO - 'sessionrestore', 05:54:17 INFO - 'sessionrestore_no_auto_restore', 05:54:17 INFO - 'tabpaint']}, 05:54:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['tsvgx', 05:54:17 INFO - 'tsvgr_opacity', 05:54:17 INFO - 'tart', 05:54:17 INFO - 'tscrollx', 05:54:17 INFO - 'cart']}, 05:54:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:54:17 INFO - 'tsvgr_opacity', 05:54:17 INFO - 'tart', 05:54:17 INFO - 'tscrollx', 05:54:17 INFO - 'cart']}, 05:54:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:17 INFO - 'talos_options': ['--disable-e10s'], 05:54:17 INFO - 'tests': ['tp5o']}, 05:54:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:17 INFO - 'tests': ['tp5o']}, 05:54:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:17 INFO - 'talos_options': ['--disable-e10s', 05:54:17 INFO - '--xperf_path', 05:54:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:17 INFO - 'tests': ['tp5n']}, 05:54:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:17 INFO - 'talos_options': ['--xperf_path', 05:54:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:17 INFO - 'tests': ['tp5n']}}, 05:54:17 INFO - 'talos.zip': {'path': '', 05:54:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:17 INFO - Running post-action listener: _resource_record_post_action 05:54:17 INFO - [mozharness: 2016-11-15 13:54:17.890464Z] Finished populate-webroot step (success) 05:54:17 INFO - [mozharness: 2016-11-15 13:54:17.890599Z] Running create-virtualenv step. 05:54:17 INFO - Running pre-action listener: _resource_record_pre_action 05:54:17 INFO - Running main action method: create_virtualenv 05:54:17 INFO - Creating virtualenv /builds/slave/test/build/venv 05:54:17 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 05:54:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:54:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:54:18 INFO - Using real prefix '/tools/python27' 05:54:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:54:18 INFO - Installing distribute.............................................................................................................................................................................................done. 05:54:21 INFO - Installing pip.................done. 05:54:21 INFO - Return code: 0 05:54:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:54:21 INFO - Reading from file tmpfile_stdout 05:54:21 INFO - Output received: 05:54:21 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) 05:54:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:54:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:21 INFO - 'HOME': '/Users/cltbld', 05:54:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:21 INFO - 'LOGNAME': 'cltbld', 05:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:21 INFO - 'MOZ_NO_REMOTE': '1', 05:54:21 INFO - 'NO_EM_RESTART': '1', 05:54:21 INFO - 'PAGER': '/bin/cat', 05:54:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:21 INFO - 'PWD': '/builds/slave/test', 05:54:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:21 INFO - 'SHELL': '/bin/bash', 05:54:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:21 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:21 INFO - 'USER': 'cltbld', 05:54:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:21 INFO - 'XPC_FLAGS': '0x0', 05:54:21 INFO - 'XPC_SERVICE_NAME': '0', 05:54:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:22 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 05:54:22 INFO - Cleaning up... 05:54:22 INFO - Return code: 0 05:54:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:54: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')]} 05:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54: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')]} 05:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54: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', '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 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:54: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 psutil>=3.1.1 05:54:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:22 INFO - 'HOME': '/Users/cltbld', 05:54:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:22 INFO - 'LOGNAME': 'cltbld', 05:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:22 INFO - 'MOZ_NO_REMOTE': '1', 05:54:22 INFO - 'NO_EM_RESTART': '1', 05:54:22 INFO - 'PAGER': '/bin/cat', 05:54:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:22 INFO - 'PWD': '/builds/slave/test', 05:54:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:22 INFO - 'SHELL': '/bin/bash', 05:54:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:22 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:22 INFO - 'USER': 'cltbld', 05:54:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:22 INFO - 'XPC_FLAGS': '0x0', 05:54:22 INFO - 'XPC_SERVICE_NAME': '0', 05:54:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:22 INFO - Downloading/unpacking psutil>=3.1.1 05:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:54:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:25 INFO - Installing collected packages: psutil 05:54:25 INFO - Running setup.py install for psutil 05:54:25 INFO - building 'psutil._psutil_osx' extension 05:54:25 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 05:54:26 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 05:54:26 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 05:54:26 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 05:54:26 INFO - building 'psutil._psutil_posix' extension 05:54:26 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 05:54:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:54:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:54:26 INFO - ^ 05:54:26 INFO - 1 warning generated. 05:54:26 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 05:54:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:26 INFO - Successfully installed psutil 05:54:26 INFO - Cleaning up... 05:54:26 INFO - Return code: 0 05:54:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:54:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:26 INFO - 'HOME': '/Users/cltbld', 05:54:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:26 INFO - 'LOGNAME': 'cltbld', 05:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:26 INFO - 'MOZ_NO_REMOTE': '1', 05:54:26 INFO - 'NO_EM_RESTART': '1', 05:54:26 INFO - 'PAGER': '/bin/cat', 05:54:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:26 INFO - 'PWD': '/builds/slave/test', 05:54:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:26 INFO - 'SHELL': '/bin/bash', 05:54:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:26 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:26 INFO - 'USER': 'cltbld', 05:54:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:26 INFO - 'XPC_FLAGS': '0x0', 05:54:26 INFO - 'XPC_SERVICE_NAME': '0', 05:54:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:54:27 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 05:54:27 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 05:54:27 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 05:54:27 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 05:54:27 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 05:54:27 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 05:54:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:54:29 INFO - Installing collected packages: mozsystemmonitor 05:54:29 INFO - Running setup.py install for mozsystemmonitor 05:54:29 INFO - Successfully installed mozsystemmonitor 05:54:29 INFO - Cleaning up... 05:54:30 INFO - Return code: 0 05:54:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:54:30 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')]} 05:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:30 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')]} 05:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:30 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 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:30 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 05:54:30 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 05:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:30 INFO - 'HOME': '/Users/cltbld', 05:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:30 INFO - 'LOGNAME': 'cltbld', 05:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:30 INFO - 'MOZ_NO_REMOTE': '1', 05:54:30 INFO - 'NO_EM_RESTART': '1', 05:54:30 INFO - 'PAGER': '/bin/cat', 05:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:30 INFO - 'PWD': '/builds/slave/test', 05:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:30 INFO - 'SHELL': '/bin/bash', 05:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:30 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:30 INFO - 'USER': 'cltbld', 05:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:30 INFO - 'XPC_FLAGS': '0x0', 05:54:30 INFO - 'XPC_SERVICE_NAME': '0', 05:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:30 INFO - Downloading/unpacking jsonschema==2.5.1 05:54:30 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 05:54:30 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 05:54:30 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 05:54:30 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 05:54:30 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 05:54:30 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 05:54:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:54:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:54:32 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 05:54:32 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 05:54:32 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 05:54:32 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 05:54:32 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 05:54:32 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 05:54:33 INFO - Downloading functools32-3.2.3-2.tar.gz 05:54:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:54:33 INFO - warning: no files found matching '*.txt' 05:54:33 INFO - no previously-included directories found matching 'build' 05:54:33 INFO - no previously-included directories found matching 'dist' 05:54:33 INFO - no previously-included directories found matching '.git*' 05:54:33 INFO - Installing collected packages: jsonschema, functools32 05:54:33 INFO - Running setup.py install for functools32 05:54:33 INFO - warning: no files found matching '*.txt' 05:54:33 INFO - no previously-included directories found matching 'build' 05:54:33 INFO - no previously-included directories found matching 'dist' 05:54:33 INFO - no previously-included directories found matching '.git*' 05:54:33 INFO - Successfully installed jsonschema functools32 05:54:33 INFO - Cleaning up... 05:54:33 INFO - Return code: 0 05:54:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:54:33 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')]} 05:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:33 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')]} 05:54:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:33 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 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:33 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 05:54:33 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 05:54:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:33 INFO - 'HOME': '/Users/cltbld', 05:54:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:33 INFO - 'LOGNAME': 'cltbld', 05:54:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:33 INFO - 'MOZ_NO_REMOTE': '1', 05:54:33 INFO - 'NO_EM_RESTART': '1', 05:54:33 INFO - 'PAGER': '/bin/cat', 05:54:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:33 INFO - 'PWD': '/builds/slave/test', 05:54:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:33 INFO - 'SHELL': '/bin/bash', 05:54:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:33 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:33 INFO - 'USER': 'cltbld', 05:54:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:33 INFO - 'XPC_FLAGS': '0x0', 05:54:33 INFO - 'XPC_SERVICE_NAME': '0', 05:54:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:54:33 INFO - Cleaning up... 05:54:33 INFO - Return code: 0 05:54:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:54:33 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')]} 05:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:33 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')]} 05:54:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:33 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 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:33 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 05:54:33 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 05:54:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:33 INFO - 'HOME': '/Users/cltbld', 05:54:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:33 INFO - 'LOGNAME': 'cltbld', 05:54:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:33 INFO - 'MOZ_NO_REMOTE': '1', 05:54:33 INFO - 'NO_EM_RESTART': '1', 05:54:33 INFO - 'PAGER': '/bin/cat', 05:54:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:33 INFO - 'PWD': '/builds/slave/test', 05:54:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:33 INFO - 'SHELL': '/bin/bash', 05:54:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:33 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:33 INFO - 'USER': 'cltbld', 05:54:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:33 INFO - 'XPC_FLAGS': '0x0', 05:54:33 INFO - 'XPC_SERVICE_NAME': '0', 05:54:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:36 INFO - Downloading blobuploader-1.2.4.tar.gz 05:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:54:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:54:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:54:37 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 05:54:37 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 05:54:37 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 05:54:37 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 05:54:37 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 05:54:37 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 05:54:37 INFO - Downloading docopt-0.6.1.tar.gz 05:54:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:54:37 INFO - Installing collected packages: blobuploader, requests, docopt 05:54:37 INFO - Running setup.py install for blobuploader 05:54:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:54:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:54:38 INFO - Running setup.py install for requests 05:54:38 INFO - Running setup.py install for docopt 05:54:38 INFO - Successfully installed blobuploader requests docopt 05:54:38 INFO - Cleaning up... 05:54:38 INFO - Return code: 0 05:54:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:38 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')]} 05:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:38 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')]} 05:54:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:38 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 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:38 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 05:54:38 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 05:54:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:38 INFO - 'HOME': '/Users/cltbld', 05:54:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:38 INFO - 'LOGNAME': 'cltbld', 05:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:38 INFO - 'MOZ_NO_REMOTE': '1', 05:54:38 INFO - 'NO_EM_RESTART': '1', 05:54:38 INFO - 'PAGER': '/bin/cat', 05:54:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:38 INFO - 'PWD': '/builds/slave/test', 05:54:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:38 INFO - 'SHELL': '/bin/bash', 05:54:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:38 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:38 INFO - 'USER': 'cltbld', 05:54:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:38 INFO - 'XPC_FLAGS': '0x0', 05:54:38 INFO - 'XPC_SERVICE_NAME': '0', 05:54:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-4XfKET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-j13fkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Nh0uDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-G9ERQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-SoE9Wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ssSlIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-QWm2_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-mzsLjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-_UQpMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-BKq7fq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-40GcNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-aWzQWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-MPR8iB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-LaaPQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-dsmxZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-23XJmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-y6_vmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:54:40 INFO - Running setup.py install for manifestparser 05:54:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:54:41 INFO - Running setup.py install for mozcrash 05:54:41 INFO - Running setup.py install for mozdebug 05:54:41 INFO - Running setup.py install for mozdevice 05:54:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:54:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:54:41 INFO - Running setup.py install for mozfile 05:54:41 INFO - Running setup.py install for mozhttpd 05:54:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:54:41 INFO - Running setup.py install for mozinfo 05:54:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:54:41 INFO - Running setup.py install for mozInstall 05:54:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for mozleak 05:54:42 INFO - Running setup.py install for mozlog 05:54:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for moznetwork 05:54:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for mozprocess 05:54:42 INFO - Running setup.py install for mozprofile 05:54:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for mozrunner 05:54:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:54:43 INFO - Running setup.py install for mozscreenshot 05:54:43 INFO - Running setup.py install for moztest 05:54:43 INFO - Running setup.py install for mozversion 05:54:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:54:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:54:43 INFO - Cleaning up... 05:54:43 INFO - Return code: 0 05:54:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:43 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 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:43 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 05:54:43 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 05:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:43 INFO - 'HOME': '/Users/cltbld', 05:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:43 INFO - 'LOGNAME': 'cltbld', 05:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:43 INFO - 'MOZ_NO_REMOTE': '1', 05:54:43 INFO - 'NO_EM_RESTART': '1', 05:54:43 INFO - 'PAGER': '/bin/cat', 05:54:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:43 INFO - 'PWD': '/builds/slave/test', 05:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:43 INFO - 'SHELL': '/bin/bash', 05:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:43 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:43 INFO - 'USER': 'cltbld', 05:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:43 INFO - 'XPC_FLAGS': '0x0', 05:54:43 INFO - 'XPC_SERVICE_NAME': '0', 05:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:43 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-sDZxwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:43 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)) 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:43 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Sov1JZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:43 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)) 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:43 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-PHT6kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-4dmo1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-JwacD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Q8c0wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Q7K0ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ME_1vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-eJVw4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-apBa6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-eQqXUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:44 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-pAAhok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:45 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)) 05:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:45 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Djhyb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:45 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)) 05:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:45 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-23qaQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:45 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)) 05:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:45 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-uco85L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:45 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)) 05:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:45 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-DvybPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:45 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-jKBt9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:45 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)) 05:54:45 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)) 05:54:45 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)) 05:54:45 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)) 05:54:45 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)) 05:54:45 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)) 05:54:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:54:45 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 05:54:45 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 05:54:45 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 05:54:45 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 05:54:45 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 05:54:45 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 05:54:49 INFO - Downloading blessings-1.6.tar.gz 05:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:54:49 INFO - Installing collected packages: blessings 05:54:49 INFO - Running setup.py install for blessings 05:54:49 INFO - Successfully installed blessings 05:54:49 INFO - Cleaning up... 05:54:49 INFO - Return code: 0 05:54:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:54:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:49 INFO - Reading from file tmpfile_stdout 05:54:49 INFO - Current package versions: 05:54:49 INFO - blessings == 1.6 05:54:49 INFO - blobuploader == 1.2.4 05:54:49 INFO - docopt == 0.6.1 05:54:49 INFO - functools32 == 3.2.3-2 05:54:49 INFO - jsonschema == 2.5.1 05:54:49 INFO - manifestparser == 1.1 05:54:49 INFO - mozInstall == 1.12 05:54:49 INFO - mozcrash == 1.0 05:54:49 INFO - mozdebug == 0.1 05:54:49 INFO - mozdevice == 0.48 05:54:49 INFO - mozfile == 1.2 05:54:49 INFO - mozhttpd == 0.7 05:54:49 INFO - mozinfo == 0.9 05:54:49 INFO - mozleak == 0.1 05:54:49 INFO - mozlog == 3.4 05:54:49 INFO - moznetwork == 0.27 05:54:49 INFO - mozprocess == 0.23 05:54:49 INFO - mozprofile == 0.28 05:54:49 INFO - mozrunner == 6.13 05:54:49 INFO - mozscreenshot == 0.1 05:54:49 INFO - mozsystemmonitor == 0.3 05:54:49 INFO - moztest == 0.7 05:54:49 INFO - mozversion == 1.4 05:54:49 INFO - psutil == 3.1.1 05:54:49 INFO - requests == 1.2.3 05:54:49 INFO - wsgiref == 0.1.2 05:54:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:50 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 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:50 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 05:54:50 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 05:54:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:50 INFO - 'HOME': '/Users/cltbld', 05:54:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:50 INFO - 'LOGNAME': 'cltbld', 05:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:50 INFO - 'MOZ_NO_REMOTE': '1', 05:54:50 INFO - 'NO_EM_RESTART': '1', 05:54:50 INFO - 'PAGER': '/bin/cat', 05:54:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:50 INFO - 'PWD': '/builds/slave/test', 05:54:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:50 INFO - 'SHELL': '/bin/bash', 05:54:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:50 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:50 INFO - 'USER': 'cltbld', 05:54:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:50 INFO - 'XPC_FLAGS': '0x0', 05:54:50 INFO - 'XPC_SERVICE_NAME': '0', 05:54:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 INFO - Cleaning up... 05:54:50 INFO - Return code: 0 05:54:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd091cada60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, '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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 05:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:51 INFO - 'HOME': '/Users/cltbld', 05:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:51 INFO - 'LOGNAME': 'cltbld', 05:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:51 INFO - 'MOZ_NO_REMOTE': '1', 05:54:51 INFO - 'NO_EM_RESTART': '1', 05:54:51 INFO - 'PAGER': '/bin/cat', 05:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:51 INFO - 'PWD': '/builds/slave/test', 05:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:51 INFO - 'SHELL': '/bin/bash', 05:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:54:51 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:51 INFO - 'USER': 'cltbld', 05:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:51 INFO - 'XPC_FLAGS': '0x0', 05:54:51 INFO - 'XPC_SERVICE_NAME': '0', 05:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:54:51 INFO - Cleaning up... 05:54:51 INFO - Return code: 0 05:54:51 INFO - Running post-action listener: _resource_record_post_action 05:54:51 INFO - Running post-action listener: _start_resource_monitoring 05:54:51 INFO - Starting resource monitoring. 05:54:51 INFO - [mozharness: 2016-11-15 13:54:51.535959Z] Finished create-virtualenv step (success) 05:54:51 INFO - [mozharness: 2016-11-15 13:54:51.537006Z] Running install step. 05:54:51 INFO - Running pre-action listener: _resource_record_pre_action 05:54:51 INFO - Running main action method: install 05:54:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:51 INFO - Reading from file tmpfile_stdout 05:54:51 INFO - Detecting whether we're running mozinstall >=1.0... 05:54:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:54:51 INFO - Reading from file tmpfile_stdout 05:54:51 INFO - Output received: 05:54:51 INFO - Usage: mozinstall [options] installer 05:54:51 INFO - Options: 05:54:51 INFO - -h, --help show this help message and exit 05:54:51 INFO - -d DEST, --destination=DEST 05:54:51 INFO - Directory to install application into. [default: 05:54:51 INFO - "/builds/slave/test"] 05:54:51 INFO - --app=APP Application being installed. [default: firefox] 05:54:51 INFO - mkdir: /builds/slave/test/build/application 05:54:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:55:25 INFO - Reading from file tmpfile_stdout 05:55:25 INFO - Output received: 05:55:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:55:25 INFO - Running post-action listener: _resource_record_post_action 05:55:25 INFO - [mozharness: 2016-11-15 13:55:25.497911Z] Finished install step (success) 05:55:25 INFO - [mozharness: 2016-11-15 13:55:25.498034Z] Running run-tests step. 05:55:25 INFO - Running pre-action listener: _resource_record_pre_action 05:55:25 INFO - Running main action method: run_tests 05:55:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:55:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:55:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:55:25 INFO - 2016-11-15 05:55:25.512 screenresolution[1625:7288] starting screenresolution argv=screenresolution get 05:55:25 INFO - 2016-11-15 05:55:25.515 screenresolution[1625:7288] Display 0: 1600x1200x32@60 05:55:25 INFO - 2016-11-15 05:55:25.520 screenresolution[1626:7290] starting screenresolution argv=screenresolution list 05:55:25 INFO - Available Modes on Display 0 05:55:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:55:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:55:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:55:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:55:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:55:25 INFO - Intel Iris: 05:55:25 INFO - Chipset Model: Intel Iris 05:55:25 INFO - Type: GPU 05:55:25 INFO - Bus: Built-In 05:55:25 INFO - VRAM (Dynamic, Max): 1536 MB 05:55:25 INFO - Vendor: Intel (0x8086) 05:55:25 INFO - Device ID: 0x0a2e 05:55:25 INFO - Revision ID: 0x0009 05:55:25 INFO - Displays: 05:55:25 INFO - Display: 05:55:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:55:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:55:25 INFO - Display Serial Number: 1600x1200 60 05:55:25 INFO - Main Display: Yes 05:55:25 INFO - Mirror: Off 05:55:25 INFO - Online: Yes 05:55:25 INFO - Rotation: Supported 05:55:25 INFO - Return code: 0 05:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:55:25 INFO - Python 2.7.3 05:55:25 INFO - Return code: 0 05:55:25 INFO - grabbing minidump binary from tooltool 05:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:25 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 0x10f39ad30>, '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 0x10f1c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f392b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:55:25 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 05:55:25 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 05:55:25 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 05:55:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:55:25 INFO - Return code: 0 05:55:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:55:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:55:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:55:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:55:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 05:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0286 --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 05:55:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZIMDfoS5gz/Render', 05:55:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:25 INFO - 'HOME': '/Users/cltbld', 05:55:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:25 INFO - 'LOGNAME': 'cltbld', 05:55:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:55:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:25 INFO - 'MOZ_NO_REMOTE': '1', 05:55:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:55:25 INFO - 'NO_EM_RESTART': '1', 05:55:25 INFO - 'PAGER': '/bin/cat', 05:55:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:25 INFO - 'PWD': '/builds/slave/test', 05:55:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:55:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:25 INFO - 'SHELL': '/bin/bash', 05:55:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9trn6LjzEO/Listeners', 05:55:25 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 05:55:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:25 INFO - 'USER': 'cltbld', 05:55:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:25 INFO - 'XPC_FLAGS': '0x0', 05:55:25 INFO - 'XPC_SERVICE_NAME': '0', 05:55:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 05:55:26 INFO - mozversion application_buildid: 20161115032613 05:55:26 INFO - mozversion application_changeset: 85a9d908e91a7071bbb1f554b275fb1b4e796e34 05:55:26 INFO - mozversion application_display_name: Nightly 05:55:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:55:26 INFO - mozversion application_name: Firefox 05:55:26 INFO - mozversion application_remotingname: firefox 05:55:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:55:26 INFO - mozversion application_vendor: Mozilla 05:55:26 INFO - mozversion application_version: 53.0a1 05:55:26 INFO - mozversion platform_buildid: 20161115032613 05:55:26 INFO - mozversion platform_changeset: 85a9d908e91a7071bbb1f554b275fb1b4e796e34 05:55:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:55:26 INFO - mozversion platform_version: 53.0a1 05:55:26 INFO - using testdate: 1479218126 05:55:26 INFO - actual date: 1479218126 05:55:26 INFO - starting webserver on 'localhost:49244' 05:55:26 INFO - SUITE-START | Running 2 tests 05:55:26 INFO - TEST-START | dromaeo_css 05:55:26 INFO - operating with platform_type : mac_ 05:55:26 INFO - Initialising browser for dromaeo_css test... 05:55:26 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpYFVPuv/profile http://localhost:49244/getInfo.html) 05:55:28 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 05:55:28 INFO - PROCESS | 1636 | colorDepth:24 05:55:28 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 05:55:28 INFO - PROCESS | 1636 | __metrics 05:55:28 INFO - PROCESS | 1636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:55:28 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:55:29 INFO - TEST-INFO | 1636: exit 0 05:55:29 INFO - Browser initialized. 05:55:29 INFO - Running cycle 1/1 for dromaeo_css test... 05:55:29 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpYFVPuv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:41 INFO - PROCESS | 1637 | RSS: Main: 230998016 05:55:41 INFO - PROCESS | 1637 | 05:56:33 INFO - PROCESS | 1637 | 1479218193415 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:58:46 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:58:47 INFO - PROCESS | 1637 | RSS: Main: 405389312 05:58:47 INFO - PROCESS | 1637 | 06:01:50 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 06:01:51 INFO - PROCESS | 1637 | RSS: Main: 374845440 06:01:51 INFO - PROCESS | 1637 | 06:04:53 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 06:04:54 INFO - PROCESS | 1637 | RSS: Main: 356585472 06:04:54 INFO - PROCESS | 1637 | 06:07:56 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 06:07:57 INFO - PROCESS | 1637 | RSS: Main: 347402240 06:07:57 INFO - PROCESS | 1637 | 06:11:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 06:11:01 INFO - PROCESS | 1637 | RSS: Main: 330981376 06:11:01 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 06:14:04 INFO - PROCESS | 1637 | RSS: Main: 408907776 06:14:04 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | __start_tp_report 06:14:04 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 06:14:04 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 06:14:04 INFO - PROCESS | 1637 | |i|pagename|runs| 06:14:04 INFO - PROCESS | 1637 | |0;dojo.html;3267.2485328374473;3286.457734473812;3314.436545787216;3340.331933613278;3353.9161520961975;5424.538914192299;5528.723563821813;5534.418886016965;5575.442455754428;5606.747696353658;5499.780008799643;5515.283013208277;5614.9438505614935;5615.578831587637;5631.63394379364;5048.1670524363435;5057.848264552069;5074.594032477411;5137.614678899079;5159.277701121846;5072.112380333445;5221.86945326368;5224.63427560071;5225.163973764198;5276.762545685434;5027.648064635477;5067.366579177603;5178.145605975028;5202.06362854686;5269.894602107936;5082.898342033176;5118.053160165381;5120.15517439622;5125.564327032207;5126.461721519224;5009.624278179152;5073.746312684388;5085.313482679828;5089;5092.465291144451;5502.532284755801;5514.558835293167;5514.779408823642;5521.806736764194;5532.225488431623;5948.167256584082;5953.791617293417;5967.7911273105665;5978.372270911535;5985.221921150222;6105;6111.23542428084;6124.319907527348;6128.3258841527395;6143.539234557426;5553.194786755978;5573.386927437979;5653.858653533694;5671.06427439472;5671.631343962712;6170.969145154246;6243.282022567463;6261.092141639437;6261.9373806262265;6263.404976527223;5916;5918.852028699231;5921.911171332434;5931.08068249422;5937.317208520989;5895.29256489224;5947.256592925884;5997.310309314485;5999.370066143079;6034.034554471264;5949.613275137189;5953.821385358447;5974.074018527135;5990.400959903975;6029.728662210211;5988.191594134736;6002.6698531580305;6003.129546215774;6007.879842403127;6008.158857759917;4688.203005489075;4705.482396936334;4745.952540474551;4752.762361881962;4761.166795810719;4228;4238.978805106017;4271.658267338606;4309.978450107729;4330.458692663417;5614.9157762633595;5618.522425593788;5622.690752008679;5639.577031722637;5656.547476201894;6098.908516372258;6123.479504242189;6137.140800287963;6138.631682099089;6146.661933593606;5506.256655351556;5543.722813859291;5543.77824887;5548.667079975215;5562.721863906789;5257.842264732064;5265.236540701577;5278.313819203479;5296.629235953524;5339;6275.811725648238;6289.433950944254;6299.244090709144;6301.4328710416285;6302.275238347649;6028.4272994065495;6103.115048318058;6121.296050954019;6152.846178845564;6187.628742275478;6197.752089916353;6201.503879689524;6239.532035097295;6259.593126446766;6278.529110316836;6041.972864612939;6048.153258543895;6053.4551890330085;6053.515718742402;6092.208012958287;6097.939020609737;6109.969450152721;6151.415615516519;6158.230221222348;6172.135900973777;5531.585131115263;5541.362743284574;5787.710614469175;5870.765169393177;5878.412158784258;5895.852603684943;5906.852328691817;5930.9110363343725;5938;5963.016102343065;5747.48272655463;5760.6255593386295;5760.913586296125;5766.59633825645;5777.422257774357;5800.622959507618;5830.941690583039;5834.795782147605;5871.559633027455;5882.7058647066615;5764.3371012332445;5778.797742079007;5791.53667706574;5845.766169353178;5848.853778655568 06:14:04 INFO - PROCESS | 1637 | |1;ext.html;447.62846837125187;509.8597885581465;512.5054322578712;522.4592546714149;533.0671325180933;31582.68417315826;34400;40104.59895401045;41412.17175656485;43695;41857.37213941786;41994.79002604983;42445.36331955023;42512.787436062776;43673.34489982653;9352.999999999984;9544.713658590219;9547;9553.426794392324;9573.569189386459;9461.952690236574;9551.140397364235;9570.952145239264;9574.18619417347;9614.13472787449;9544.665936692218;9580.90419095807;9582.52087395631;9584.856227156599;9613.519324033805;9480.81038379232;9540.952295238549;9567.712968610953;9568.617255309779;9568.712938611818;9407.576659050359;9486.810263794754;9500.477473738942;9502.762430939212;9513.66702165421;39368.80315598432;39719;39739.403908941385;39777.20445591101;39886.80339589817;2720.795940304465;3317.402867483852;3332.96667033329;3348.7990720556845;3424.1610805352598;30100.698993010228;30385.392292154032;30403.543946840815;30696.84651576728;31003;3183.8726450941936;3220.951685724716;3232.3858466891215;3282.983585082059;3341.913877989663;500088;500614;503316;504148;504168;32746.181345466553;60136.39863601308;60609.39390606037;60694.69652651709;60797;33743.493847592305;34776;35012.12469688278;35045.1238719029;35168.64831351654;14163.78754318686;14309.998300118892;14314.928425357806;14320.06919550226;14330.283485825667;11991.280523168638;12075.215111017756;12107.939460302818;12117.575884843987;12119.878801212051;7721.581875993574;7733.690652373842;7778.844423111619;7824.29581337671;7836.059672839296;2435.4641978764644;3121.7034381733697;3215.13191438315;3217.533457648628;3217.887373941901;930.8976012638605;938.9248860091176;986.3637953520083;1036.0369343677078;1072.5977758340623;23770.64344034841;28369.865205391965;28519.001834935676;29340;30091.699083009327;724.7390939261861;941.270515350595;1011.5751384418562;1012.5038731021747;1066.6373433032807;14648.853511464962;15267.694646107017;15297.847021529642;15575.143367114692;15627.453039143802;914087;915336;920793;922004;924588.901721386;56220;56741;56864.43135568674;56983;57244.71377643169;1224.5591587028662;1268.4672437576055;1270.3838638260436;1348.480834878559;1383.7094210215766;29928.401431971673;30637.540436893018;30994;31629.367412651158;32357.191070223435;55305.446945531636;55419;56803;56844.715776422505;59412.70293648504;591162.4860632706;596978;597329;598538;600485;25734.099306524182;26644.600330994665;41644.58355416407;42114.368284475146;42279.154416910875;45765.77117114393;46895;46965;47296;47383.76308118437;36155;38338.61661383462;38800.22399552049;39785.00537486586;39968.4004739935;38190.045248869;38503;39408.40887386634;39728.404073938334;39893.40159897662;37054.44418333673;37064.62935370612;37428;37935.43096853494;38125.61874381221;32589.644597505052;32962.83518582488;34032.14919627029;34711.652883471856;34761.47857782186;24034.51930961336;24957.251282461555;25038.74961250437;25751.871240643675;25850.35374115662 06:14:04 INFO - PROCESS | 1637 | |2;jquery.html;339.5517916350417;353.05733691044907;369.3831301726117;380.13708880840494;383.451664120308;14635.560933172008;14881.702365952675;15685.137317447521;19745.210191592356;19960.201591936304;18854.71717924229;19887.204511819546;20218.19127234909;20514;20656.17375304986;5549.556035517159;5888.146218798272;5895.292564892219;5909.4090590941;5925.822225333225;5267.762075912157;5577.888442231153;5799.478046975772;5871;5882.735276912528;5686.658800471964;5704.543636509069;5789.015867302569;5822.068469044953;5828.446297601721;5398.568114550846;5699.658020518782;5739.3973632768375;5806.274215723035;5808.506276966463;5502.761878577328;5596.2165296858475;5607.439256074401;5611.66330020196;5793.826185214451;12399.070069744805;12655.62033138998;12665;12748.470183577938;14716;2051.7230173926478;2060.763012253595;2129.6111665004987;2242.4057624729458;2297.7127859017623;9212.723618291462;9626.903730962671;9881.950590247076;10065.396076235449;10247.538860751209;1790.9641807163914;1839.2183322088192;1870.5697689531485;1894.876833005864;1937.0411646235063;53861;109707;111151;111272;112416;11069.612563560398;11121.666350009513;11188.496517656726;11204.495797689124;11364.034057105238;14236.145831250158;14245.501407450689;14274;14274.571762847132;14297.642558935902;6952.826179345456;8010.5994700264755;8246.587670616445;8307.626156822958;8451.239388454946;8177.67289308421;8193.918060819435;8201.71294004707;8281.585920703941;8358.328334333157;3988.6210809973004;4278.550752170977;4420.977895110504;4430.645548356124;4533.52397998212;1521.7260893039138;1683.2593658790092;1876.9061546922599;1883.50087226885;1926.49911023135;645.8901986662279;646.1438593863151;675.9932400676029;756.3495393961189;964.6768332608655;18659.720104198448;19332.80667193338;19756.70364944527;20277.898610506854;20724.58550828975;875.6366108065209;935.8736570562887;936.9250459963187;945.9101385368378;981.9165370943407;10078.193744500422;10151.086402223835;10569.947150264199;10604.681859544226;10693.984071513349;518425;554612;555335;557088;558078;120984;121623;122685.38657306656;123482;124924;898.3352319283814;912.6030176873081;935.4434111703525;940.6331530703031;956.6125719083782;13965;17915.820841791414;18425.355112570996;18560.443186704422;19022;47063.529364705915;47839.28241076317;47959.76020119877;48253.27620085772;48694.513054869;60157.488188386924;61930.69034654798;66285;66541;69166;5226;7641.847163056819;7724.558378932249;7911;7968.521888686697;10260.333078349884;11743.41282935867;12049.156559040783;12083.637490875288;12353.505859765508;9857.901420985698;10765.946170269099;10809.513571889127;10840.5121769519;10969.067629251284;8846.557672116498;9069.77325566866;9865.556049977624;10167.542460589144;10749.086327662237;10352.792944141225;11019.889801101886;11050.613228536962;11229.3262404256;11278.041366483942;7795.41534384912;7858.764237072897;7866.252705992922;7985.680572777024;8203.548804815793;9104.726858194264;10435.060844524029;10727.249092563447;10802.405867677038;10901.454927253448 06:14:04 INFO - PROCESS | 1637 | |3;mootools.html;1484.2282013353056;1510.4939845151873;1682.9831701682988;1805.079409501154;1856.9907150464246;8070.354371650268;8526.403151779377;8533.530655813927;8548.487090774557;8567.52878591678;7406.925930740691;7683.193264707209;8031.598420078987;8274.172582741723;8298.95850520748;2000.879947203165;2290.80528155107;2319.211468100845;2323.4191452136965;2326.1974618756585;2299.9195028174017;2312.606856834334;2336.719593648756;2351.870647114408;2353.470469144446;2039.8980050997466;2119.79861913118;2119.9894000530053;2236.7539570647214;2309.2379514760173;2020.7575090989176;2306.642470417088;2331.0559223514333;2331.370529956902;2333.603287441139;2309.261036468331;2352.917647882316;2354.2348736660656;2355.364051706047;2356.78788908999;8400.243978041945;8495.235428811437;8500.659973601048;8508.149185081504;8513.957430212871;5067.77195026221;5242.266082748418;5245;5247.501487358695;5258.55302299305;3010.744086752629;3164.8259345735974;3255.9837200813845;3259.282957749291;3262.4453842846774;853.4879072556479;881.5504092912661;923.3305853256403;924.1359329027314;925.1627277314042;11006.064484518825;11056.723581910435;11080.445977701083;11081.058110060736;11120.388378639173;4378.408914796525;4577.725336479755;4700.623950084054;4712.410948631421;4719.079779442975;9520.047995200424;9527.571259293349;9534.90465095354;9542.856857147148;9554.952225238829;5235.97898409814;5236.895262094737;5244.108501554745;5251.317328747315;5262.92105618416;4565.56627120423;4571.954280457219;4661.440627124699;4806.7836947337455;4813.350197723263;3732.9440058399146;3773.1887644156636;3808.7143464240294;3824.29250588642;3854.1713531590844;1262.1417436143327;1263.9115261931577;1266.5187228852976;1280.7566562353309;1282.3908643394313;620.2092332276294;651.9413252807175;839.202757380491;850.5577099908137;907.9773005674779;5426.67439953604;5578.246936663499;5868.94131058684;5871.706414679249;5941.7623295067715;821.404481750726;899.5771987166022;905.2576886952636;907.5054095517959;946.417952958939;3234.3369609230053;3805.543334799842;3835.4055121455663;3926.489556357655;3948.526176858795;9921.65274215399;10157.796844063078;10223.488825558692;10244.692659220234;10268.897311026943;10185.083342499063;10187.235957303232;10199.745006374751;10211.438370889524;10223.437710925822;7372.852542949111;7760.14638389788;7775.377969762519;7944.960275198702;7946.245106714853;5293.920591191212;5620.971895140498;5695.629784064023;5879.970600146972;5965.403459654;9218.400803947721;9379.531023448937;9394.295427843075;9461.148496635336;9593.32846700752;9362.906370936476;9538.904610953801;9587.233021358414;9627.422354658744;9740.366876153028;7147.249538798559;7301.817454563678;7312.707491700273;7315.231900650355;7340.889886651811;7679.692812287446;7730.497517661334;7830.608469576612;7849.843003139791;7857.05715314166;7085.282315067849;7112.857742845011;7127.037849890198;7231.855362892607;7336.926630733625;6997.230304666585;7041.190263119602;7061.6822243000215;7097.254788247366;7100.715971361088;6647.734090636513;6680.432163265982;6758.831029224112;6779.525433219627;6788.898166527407;4561.429821272341;4744.525547445228;4804.135255654016;4914.852554423373;5020.924686129638;4834.95165048345;4844.491328410466;4965.379327584052;4997.450280469073;5038.748062596782 06:14:04 INFO - PROCESS | 1637 | |4;prototype.html;254.24109034531924;302.7441811669139;308.75145507866165;314.4858156913447;316.0313638697394;5069.898602027962;5071.923921141186;5107.74461276936;5135.563477104451;5171.844844654657;4739.810407583693;4891.559759621633;4964.578010869081;4971.751412429382;5125.436202017775;3858.1704933439305;3916.353801622743;3936.7244292899436;3937.5078115235597;3989.2619865324864;3588.4258518637025;3884.5338559372913;3926.685865130797;3949.5063117110362;3984.6015398460213;3525.92948141037;3678.6689197972178;3750.699944004487;3759.16183281622;3931.3709806430975;3473.801538469224;3691.8338674759566;3924.841337264493;3974.24489347026;3991.840326386941;3425.4348032574594;3456.3605732939236;3468.8612455501748;3480.8433620486885;3489.930201395983;4943.209086546135;4968.130577148984;5021.774020169101;5023.547880690719;5075.264086707407;4326.913461730779;4491.326301054835;4492.191405547001;4530.411046563959;4565.61192298655;4900.333886478579;4981;5343.545798607123;5352.91970620441;5400.893651475928;3783.5244254740674;3788.488554045196;3796.7532110413013;3822.0698547233746;3825.3114439400897;4510.616597589209;4956.851294461172;5472.945270547243;5505.834824955257;5732.426757324276;3442.7762195457717;3581.2658405026905;3901.668358189529;3918.5885482024546;3922.842112099787;3978.8607398741483;3979.940300895431;3984.322665146874;3991.261616600938;3994.8402063917697;3771.86798462058;3825.5026846509777;4368.8907777305185;4579.17574836526;4600.3099535070005;5454.754536045887;5477.452254774491;5482;5495.780168793283;5531.585131115183;1092.4373947417087;1113.3486910157649;1197.4910662968205;1271.7138643805256;1284.991281843748;796.3430170109683;833.3208435125354;858.5492616376328;861.5261606116611;882.9249513791272;3781.470594116826;4351.6518678505645;4383.495897971774;4771.856844294677;4791.976040119846;4947;5140.485951404904;5241.379310344828;5307.814226502054;5345.3852806926925;3957.248122856648;4027.2952233359047;4215.704900656925;4422.867313980586;4643.721376717408;2523.5583772840046;2676.9598456023177;2680.209338245222;2681.795254670689;2731.4400547887635;4575.675986193082;5000.224965130411;5195;5255.605829562798;5295.523402893681;4296.334068219431;4500.594946454835;4811.869210735475;5134.89469317578;5138.948610513922;4687.414073240845;4783.4499032611;5041.899162016813;5188.740562971836;5220.451852555427;4471;4484.372187893632;5101.112588342337;5130.307408499804;5214.765335559985;4523.683342166017;4840.443349014909;5050.671706339076;5487.396386397573;5538.535351768424;5687.658740475585;5725.971370143123;5792.478676919098;5795.884082318245;5806.361300256884;3535.505029295952;3679.282539904693;3693.8337774800734;3704.2776658552402;3730.6828919542145;4562.612177964777;4739.739314337607;5318.361796584435;5505.697186654773;5571.192177134212;5317.288183026607;5319.680319680319;5333.333333333333;5368.7047212403695;5448.482394172547;4812;5155.716435596079;5290.3335449333335;5309.707966061749;5330.867652938856;4508.842190523447;5040.924386134137;5182.823214249948;5225;5233.142234506695;4285.307303614993;4694.554017368345;4703.31801888731;5078.695278283315;5432.320959880015;5271.314729085195;5272.868178295574;5287.333366618342;5330.520253177233;5332.413434522122 06:14:04 INFO - PROCESS | 1637 | |5;yui.html;703.6376266222893;705.7247673407376;743.0600290632351;750.5684231566848;781.2460975158972;14271;14870.33083511242;14874.47939322124;14884.776728349083;14907.925460372697;13281.203127812314;13419.261940593264;13428.060035797498;13434.32828358583;13436.731265374687;542.6011881267266;675.0313300413898;684.5379368925979;797.5453991225004;823.3289868756959;827.7599496146111;831.2601784411878;840.8108175660489;843.9704610338639;844.3835999720208;892.3485855325616;896.1979028769248;896.4576431259093;897.5153417154729;898.5597057441872;692.0588000319561;720.3768740039828;729.3217307903648;895.1630225738922;895.7939673875013;539.438983457204;543.7227014222775;641.4932203559183;666.3236814633151;671.0538141220856;13812.930935345259;13834.031617786759;13840.861591384159;13850.653733656638;13880.375383107683;12785.936070319682;12863.099583029098;13004.349782510837;13092.214467132002;13104.279264640441;11965.700857478545;12029.819552706716;12035.39823008846;12118.030557555463;12146;4006.757905049444;4285.185814695229;4307.91384172318;4406.735595864258;4442.577955094261;75606.62196688981;77283.6135819323;78413.21586784117;81470;82082.58958705228;10651.84022239667;10664.41345725977;10689.9465502672;10818.513166907507;10920;18235.45293641193;19099.2360305589;19103.617927641648;20121.295754648516;20132.496687582927;6777.695003724844;7483;7705.845883082419;7783.027121609799;7865.4494185406475;4201.285781417167;4302.440682711227;4315.697901146952;4321.308590625485;4431.711938723973;3306.3883181611;3415.9316813663586;3439.0542600784984;3451.2234747181683;3455.1016735648914;647.0326861342229;648.251269783401;657.3656421553225;665.1220389086457;666.6000399760105;518.401246560216;528.4873672537632;622.5859803230892;654.4012228810681;659.120074700271;8337.333013358917;8748;8980.416272942239;9056.547172641342;9300.488473133908;530.8401143501385;551.7351671197847;563.2115038945509;660.9603423794491;664.727461740684;4163.354680024601;4364.258076127066;4444.244478438609;4457.77711114443;4506.909861802811;66698.66650666716;67868.98196527056;68147.65926170436;114043;115920;65501;91774.08225917655;102767;103182.45226321611;105454.47272763588;91939;92234;94163.52918235502;94407.52796235975;95948;8707.390482666153;8718.346124040596;8720.345974052098;8722.563871806511;8877.511736854534;49843.50156498534;53033.73483132714;56133;57175.42824571701;58267.125993109286;53985.822410189576;55884.72057639686;61565;65651.34348656452;66729.33270667231;12229.388530573258;12281.263124212575;12385.752284954431;12489.932285296632;12628.621141365773;11425.885741142481;13961.232132232824;14027.50903718365;14314.570562883131;14534.491292804702;12620.55828046014;12958.416871240628;12996.346886912506;13104.279264640536;13556.93221533886;12640.620781376574;12915;12992.48030078824;13011.21932684042;13073;11197.216194866282;11716.355600442057;12463;12540.686482838002;12552.560660376843;9038.231750301298;9514.571844266886;10436;10663.040326370354;11147.163962702667;9461.905380946102;10878.075363594184;10994.615188468686;11162.441877906234;11231 06:14:04 INFO - PROCESS | 1637 | __end_tp_report 06:14:04 INFO - PROCESS | 1637 | __start_cc_report 06:14:04 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1747 06:14:04 INFO - PROCESS | 1637 | __end_cc_report 06:14:04 INFO - PROCESS | 1637 | __startTimestamp1479219244926__endTimestamp 06:14:04 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | ------- Summary: start ------- 06:14:04 INFO - PROCESS | 1637 | Number of tests: 6 06:14:04 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5603.24 Median:5777.42 stddev:619.95 (10.7%) stddev-sans-first:594.15 06:14:04 INFO - PROCESS | 1637 | Values: 3267.2 3286.5 3314.4 3340.3 3353.9 5424.5 5528.7 5534.4 5575.4 5606.7 5499.8 5515.3 5614.9 5615.6 5631.6 5048.2 5057.8 5074.6 5137.6 5159.3 5072.1 5221.9 5224.6 5225.2 5276.8 5027.6 5067.4 5178.1 5202.1 5269.9 5082.9 5118.1 5120.2 5125.6 5126.5 5009.6 5073.7 5085.3 5089.0 5092.5 5502.5 5514.6 5514.8 5521.8 5532.2 5948.2 5953.8 5967.8 5978.4 5985.2 6105.0 6111.2 6124.3 6128.3 6143.5 5553.2 5573.4 5653.9 5671.1 5671.6 6171.0 6243.3 6261.1 6261.9 6263.4 5916.0 5918.9 5921.9 5931.1 5937.3 5895.3 5947.3 5997.3 5999.4 6034.0 5949.6 5953.8 5974.1 5990.4 6029.7 5988.2 6002.7 6003.1 6007.9 6008.2 4688.2 4705.5 4746.0 4752.8 4761.2 4228.0 4239.0 4271.7 4310.0 4330.5 5614.9 5618.5 5622.7 5639.6 5656.5 6098.9 6123.5 6137.1 6138.6 6146.7 5506.3 5543.7 5543.8 5548.7 5562.7 5257.8 5265.2 5278.3 5296.6 5339.0 6275.8 6289.4 6299.2 6301.4 6302.3 6028.4 6103.1 6121.3 6152.8 6187.6 6197.8 6201.5 6239.5 6259.6 6278.5 6042.0 6048.2 6053.5 6053.5 6092.2 6097.9 6110.0 6151.4 6158.2 6172.1 5531.6 5541.4 5787.7 5870.8 5878.4 5895.9 5906.9 5930.9 5938.0 5963.0 5747.5 5760.6 5760.9 5766.6 5777.4 5800.6 5830.9 5834.8 5871.6 5882.7 5764.3 5778.8 5791.5 5845.8 5848.9 06:14:04 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:77757.21 Median:39781.10 stddev:188020.23 (472.6%) stddev-sans-first:188458.08 06:14:04 INFO - PROCESS | 1637 | Values: 447.6 509.9 512.5 522.5 533.1 31582.7 34400.0 40104.6 41412.2 43695.0 41857.4 41994.8 42445.4 42512.8 43673.3 9353.0 9544.7 9547.0 9553.4 9573.6 9462.0 9551.1 9571.0 9574.2 9614.1 9544.7 9580.9 9582.5 9584.9 9613.5 9480.8 9541.0 9567.7 9568.6 9568.7 9407.6 9486.8 9500.5 9502.8 9513.7 39368.8 39719.0 39739.4 39777.2 39886.8 2720.8 3317.4 3333.0 3348.8 3424.2 30100.7 30385.4 30403.5 30696.8 31003.0 3183.9 3221.0 3232.4 3283.0 3341.9 500088.0 500614.0 503316.0 504148.0 504168.0 32746.2 60136.4 60609.4 60694.7 60797.0 33743.5 34776.0 35012.1 35045.1 35168.6 14163.8 14310.0 14314.9 14320.1 14330.3 11991.3 12075.2 12107.9 12117.6 12119.9 7721.6 7733.7 7778.8 7824.3 7836.1 2435.5 3121.7 3215.1 3217.5 3217.9 930.9 938.9 986.4 1036.0 1072.6 23770.6 28369.9 28519.0 29340.0 30091.7 724.7 941.3 1011.6 1012.5 1066.6 14648.9 15267.7 15297.8 15575.1 15627.5 914087.0 915336.0 920793.0 922004.0 924588.9 56220.0 56741.0 56864.4 56983.0 57244.7 1224.6 1268.5 1270.4 1348.5 1383.7 29928.4 30637.5 30994.0 31629.4 32357.2 55305.4 55419.0 56803.0 56844.7 59412.7 591162.5 596978.0 597329.0 598538.0 600485.0 25734.1 26644.6 41644.6 42114.4 42279.2 45765.8 46895.0 46965.0 47296.0 47383.8 36155.0 38338.6 38800.2 39785.0 39968.4 38190.0 38503.0 39408.4 39728.4 39893.4 37054.4 37064.6 37428.0 37935.4 38125.6 32589.6 32962.8 34032.1 34711.7 34761.5 24034.5 24957.3 25038.7 25751.9 25850.4 06:14:04 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:31766.43 Median:2186.04 stddev:91564.40 (4188.6%) stddev-sans-first:91790.85 06:14:04 INFO - PROCESS | 1637 | Values: 339.6 353.1 369.4 380.1 383.5 14635.6 14881.7 15685.1 19745.2 19960.2 18854.7 19887.2 20218.2 20514.0 20656.2 5549.6 5888.1 5895.3 5909.4 5925.8 5267.8 5577.9 5799.5 5871.0 5882.7 5686.7 5704.5 5789.0 5822.1 5828.4 5398.6 5699.7 5739.4 5806.3 5808.5 5502.8 5596.2 5607.4 5611.7 5793.8 12399.1 12655.6 12665.0 12748.5 14716.0 2051.7 2060.8 2129.6 2242.4 2297.7 9212.7 9626.9 9882.0 10065.4 10247.5 1791.0 1839.2 1870.6 1894.9 1937.0 53861.0 109707.0 111151.0 111272.0 112416.0 11069.6 11121.7 11188.5 11204.5 11364.0 14236.1 14245.5 14274.0 14274.6 14297.6 6952.8 8010.6 8246.6 8307.6 8451.2 8177.7 8193.9 8201.7 8281.6 8358.3 3988.6 4278.6 4421.0 4430.6 4533.5 1521.7 1683.3 1876.9 1883.5 1926.5 645.9 646.1 676.0 756.3 964.7 18659.7 19332.8 19756.7 20277.9 20724.6 875.6 935.9 936.9 945.9 981.9 10078.2 10151.1 10569.9 10604.7 10694.0 518425.0 554612.0 555335.0 557088.0 558078.0 120984.0 121623.0 122685.4 123482.0 124924.0 898.3 912.6 935.4 940.6 956.6 13965.0 17915.8 18425.4 18560.4 19022.0 47063.5 47839.3 47959.8 48253.3 48694.5 60157.5 61930.7 66285.0 66541.0 69166.0 5226.0 7641.8 7724.6 7911.0 7968.5 10260.3 11743.4 12049.2 12083.6 12353.5 9857.9 10765.9 10809.5 10840.5 10969.1 8846.6 9069.8 9865.6 10167.5 10749.1 10352.8 11019.9 11050.6 11229.3 11278.0 7795.4 7858.8 7866.3 7985.7 8203.5 9104.7 10435.1 10727.2 10802.4 10901.5 06:14:04 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5476.87 Median:5249.41 stddev:3038.74 (57.9%) stddev-sans-first:3032.45 06:14:04 INFO - PROCESS | 1637 | Values: 1484.2 1510.5 1683.0 1805.1 1857.0 8070.4 8526.4 8533.5 8548.5 8567.5 7406.9 7683.2 8031.6 8274.2 8299.0 2000.9 2290.8 2319.2 2323.4 2326.2 2299.9 2312.6 2336.7 2351.9 2353.5 2039.9 2119.8 2120.0 2236.8 2309.2 2020.8 2306.6 2331.1 2331.4 2333.6 2309.3 2352.9 2354.2 2355.4 2356.8 8400.2 8495.2 8500.7 8508.1 8514.0 5067.8 5242.3 5245.0 5247.5 5258.6 3010.7 3164.8 3256.0 3259.3 3262.4 853.5 881.6 923.3 924.1 925.2 11006.1 11056.7 11080.4 11081.1 11120.4 4378.4 4577.7 4700.6 4712.4 4719.1 9520.0 9527.6 9534.9 9542.9 9555.0 5236.0 5236.9 5244.1 5251.3 5262.9 4565.6 4572.0 4661.4 4806.8 4813.4 3732.9 3773.2 3808.7 3824.3 3854.2 1262.1 1263.9 1266.5 1280.8 1282.4 620.2 651.9 839.2 850.6 908.0 5426.7 5578.2 5868.9 5871.7 5941.8 821.4 899.6 905.3 907.5 946.4 3234.3 3805.5 3835.4 3926.5 3948.5 9921.7 10157.8 10223.5 10244.7 10268.9 10185.1 10187.2 10199.7 10211.4 10223.4 7372.9 7760.1 7775.4 7945.0 7946.2 5293.9 5621.0 5695.6 5880.0 5965.4 9218.4 9379.5 9394.3 9461.1 9593.3 9362.9 9538.9 9587.2 9627.4 9740.4 7147.2 7301.8 7312.7 7315.2 7340.9 7679.7 7730.5 7830.6 7849.8 7857.1 7085.3 7112.9 7127.0 7231.9 7336.9 6997.2 7041.2 7061.7 7097.3 7100.7 6647.7 6680.4 6758.8 6779.5 6788.9 4561.4 4744.5 4804.1 4914.9 5020.9 4835.0 4844.5 4965.4 4997.5 5038.7 06:14:04 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4276.87 Median:4721.53 stddev:1282.63 (27.2%) stddev-sans-first:1250.19 06:14:04 INFO - PROCESS | 1637 | Values: 254.2 302.7 308.8 314.5 316.0 5069.9 5071.9 5107.7 5135.6 5171.8 4739.8 4891.6 4964.6 4971.8 5125.4 3858.2 3916.4 3936.7 3937.5 3989.3 3588.4 3884.5 3926.7 3949.5 3984.6 3525.9 3678.7 3750.7 3759.2 3931.4 3473.8 3691.8 3924.8 3974.2 3991.8 3425.4 3456.4 3468.9 3480.8 3489.9 4943.2 4968.1 5021.8 5023.5 5075.3 4326.9 4491.3 4492.2 4530.4 4565.6 4900.3 4981.0 5343.5 5352.9 5400.9 3783.5 3788.5 3796.8 3822.1 3825.3 4510.6 4956.9 5472.9 5505.8 5732.4 3442.8 3581.3 3901.7 3918.6 3922.8 3978.9 3979.9 3984.3 3991.3 3994.8 3771.9 3825.5 4368.9 4579.2 4600.3 5454.8 5477.5 5482.0 5495.8 5531.6 1092.4 1113.3 1197.5 1271.7 1285.0 796.3 833.3 858.5 861.5 882.9 3781.5 4351.7 4383.5 4771.9 4792.0 4947.0 5140.5 5241.4 5307.8 5345.4 3957.2 4027.3 4215.7 4422.9 4643.7 2523.6 2677.0 2680.2 2681.8 2731.4 4575.7 5000.2 5195.0 5255.6 5295.5 4296.3 4500.6 4811.9 5134.9 5138.9 4687.4 4783.4 5041.9 5188.7 5220.5 4471.0 4484.4 5101.1 5130.3 5214.8 4523.7 4840.4 5050.7 5487.4 5538.5 5687.7 5726.0 5792.5 5795.9 5806.4 3535.5 3679.3 3693.8 3704.3 3730.7 4562.6 4739.7 5318.4 5505.7 5571.2 5317.3 5319.7 5333.3 5368.7 5448.5 4812.0 5155.7 5290.3 5309.7 5330.9 4508.8 5040.9 5182.8 5225.0 5233.1 4285.3 4694.6 4703.3 5078.7 5432.3 5271.3 5272.9 5287.3 5330.5 5332.4 06:14:04 INFO - PROCESS | 1637 | 06:14:04 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19410.26 Median:4482.34 stddev:27950.82 (623.6%) stddev-sans-first:27993.94 06:14:04 INFO - PROCESS | 1637 | Values: 703.6 705.7 743.1 750.6 781.2 14271.0 14870.3 14874.5 14884.8 14907.9 13281.2 13419.3 13428.1 13434.3 13436.7 542.6 675.0 684.5 797.5 823.3 827.8 831.3 840.8 844.0 844.4 892.3 896.2 896.5 897.5 898.6 692.1 720.4 729.3 895.2 895.8 539.4 543.7 641.5 666.3 671.1 13812.9 13834.0 13840.9 13850.7 13880.4 12785.9 12863.1 13004.3 13092.2 13104.3 11965.7 12029.8 12035.4 12118.0 12146.0 4006.8 4285.2 4307.9 4406.7 4442.6 75606.6 77283.6 78413.2 81470.0 82082.6 10651.8 10664.4 10689.9 10818.5 10920.0 18235.5 19099.2 19103.6 20121.3 20132.5 6777.7 7483.0 7705.8 7783.0 7865.4 4201.3 4302.4 4315.7 4321.3 4431.7 3306.4 3415.9 3439.1 3451.2 3455.1 647.0 648.3 657.4 665.1 666.6 518.4 528.5 622.6 654.4 659.1 8337.3 8748.0 8980.4 9056.5 9300.5 530.8 551.7 563.2 661.0 664.7 4163.4 4364.3 4444.2 4457.8 4506.9 66698.7 67869.0 68147.7 114043.0 115920.0 65501.0 91774.1 102767.0 103182.5 105454.5 91939.0 92234.0 94163.5 94407.5 95948.0 8707.4 8718.3 8720.3 8722.6 8877.5 49843.5 53033.7 56133.0 57175.4 58267.1 53985.8 55884.7 61565.0 65651.3 66729.3 12229.4 12281.3 12385.8 12489.9 12628.6 11425.9 13961.2 14027.5 14314.6 14534.5 12620.6 12958.4 12996.3 13104.3 13556.9 12640.6 12915.0 12992.5 13011.2 13073.0 11197.2 11716.4 12463.0 12540.7 12552.6 9038.2 9514.6 10436.0 10663.0 11147.2 9461.9 10878.1 10994.6 11162.4 11231.0 06:14:04 INFO - PROCESS | 1637 | -------- Summary: end -------- 06:14:04 INFO - PROCESS | 1637 | 06:14:05 INFO - TEST-INFO | 1637: exit 0 06:14:05 INFO - TEST-OK | dromaeo_css | took 1119508ms 06:14:05 INFO - TEST-START | kraken 06:14:05 INFO - operating with platform_type : mac_ 06:14:05 INFO - Initialising browser for kraken test... 06:14:05 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmp3zlFEo/profile http://localhost:49244/getInfo.html) 06:14:07 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 06:14:07 INFO - PROCESS | 1648 | colorDepth:24 06:14:07 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 06:14:07 INFO - PROCESS | 1648 | __metrics 06:14:07 INFO - PROCESS | 1648 | 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" 06:14:07 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:14:08 INFO - TEST-INFO | 1648: exit 0 06:14:08 INFO - Browser initialized. 06:14:08 INFO - Running cycle 1/1 for kraken test... 06:14:08 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmp3zlFEo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:14:20 INFO - PROCESS | 1649 | RSS: Main: 238018560 06:14:20 INFO - PROCESS | 1649 | 06:14:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 06:14:24 INFO - PROCESS | 1649 | RSS: Main: 289062912 06:14:24 INFO - PROCESS | 1649 | 06:14:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 06:14:27 INFO - PROCESS | 1649 | RSS: Main: 289091584 06:14:27 INFO - PROCESS | 1649 | 06:14:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 06:14:31 INFO - PROCESS | 1649 | RSS: Main: 381976576 06:14:31 INFO - PROCESS | 1649 | 06:14:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 06:14:34 INFO - PROCESS | 1649 | RSS: Main: 280809472 06:14:34 INFO - PROCESS | 1649 | 06:14:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:14:37 INFO - PROCESS | 1649 | RSS: Main: 286646272 06:14:37 INFO - PROCESS | 1649 | 06:14:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 06:14:40 INFO - PROCESS | 1649 | RSS: Main: 328552448 06:14:40 INFO - PROCESS | 1649 | 06:14:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 06:14:44 INFO - PROCESS | 1649 | RSS: Main: 331296768 06:14:44 INFO - PROCESS | 1649 | 06:14:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 06:14:47 INFO - PROCESS | 1649 | RSS: Main: 326828032 06:14:47 INFO - PROCESS | 1649 | 06:14:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:14:49 INFO - PROCESS | 1649 | RSS: Main: 303878144 06:14:49 INFO - PROCESS | 1649 | 06:14:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:14:52 INFO - PROCESS | 1649 | RSS: Main: 306470912 06:14:52 INFO - PROCESS | 1649 | 06:14:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:14:55 INFO - PROCESS | 1649 | RSS: Main: 308887552 06:14:55 INFO - PROCESS | 1649 | 06:14:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:14:58 INFO - PROCESS | 1649 | RSS: Main: 329023488 06:14:58 INFO - PROCESS | 1649 | 06:15:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:15:02 INFO - PROCESS | 1649 | RSS: Main: 322588672 06:15:02 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 06:15:04 INFO - PROCESS | 1649 | RSS: Main: 366596096 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | __start_tp_report 06:15:04 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 06:15:04 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 06:15:04 INFO - PROCESS | 1649 | |i|pagename|runs| 06:15:04 INFO - PROCESS | 1649 | |0;ai-astar;103;113;113;112;112;114;113;113;112;113 06:15:04 INFO - PROCESS | 1649 | |1;audio-beat-detection;143;109;111;153;140;140;113;114;140;111 06:15:04 INFO - PROCESS | 1649 | |2;audio-dft;184;185;183;181;185;189;182;177;185;182 06:15:04 INFO - PROCESS | 1649 | |3;audio-fft;80;57;76;114;85;58;59;58;81;57 06:15:04 INFO - PROCESS | 1649 | |4;audio-oscillator;88;72;92;69;67;68;88;67;67;92 06:15:04 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;87;84;88;84;87;84;87;85;87;84 06:15:04 INFO - PROCESS | 1649 | |6;imaging-darkroom;93;102;147;123;91;94;92;92;92;92 06:15:04 INFO - PROCESS | 1649 | |7;imaging-desaturate;81;82;77;78;84;84;86;83;82;83 06:15:04 INFO - PROCESS | 1649 | |8;json-parse-financial;53;53;53;53;56;54;55;54;53;54 06:15:04 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;67;69;71;75;72;70;69;70;70;71 06:15:04 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;87;101;79;87;97;109;106;86;89;85 06:15:04 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;125;148;159;163;150;150;149;147;188;155 06:15:04 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;211;176;175;228;220;172;172;175;173;178 06:15:04 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;68;68;67;67;68;66;66;67;66;66 06:15:04 INFO - PROCESS | 1649 | __end_tp_report 06:15:04 INFO - PROCESS | 1649 | __start_cc_report 06:15:04 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1972 06:15:04 INFO - PROCESS | 1649 | __end_cc_report 06:15:04 INFO - PROCESS | 1649 | __startTimestamp1479219304503__endTimestamp 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | ------- Summary: start ------- 06:15:04 INFO - PROCESS | 1649 | Number of tests: 14 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.16 (2.8%) stddev-sans-first:0.67 06:15:04 INFO - PROCESS | 1649 | Values: 103.0 113.0 113.0 112.0 112.0 114.0 113.0 113.0 112.0 113.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:140.00 stddev:17.12 (12.2%) stddev-sans-first:17.20 06:15:04 INFO - PROCESS | 1649 | Values: 143.0 109.0 111.0 153.0 140.0 140.0 113.0 114.0 140.0 111.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.50 stddev:3.16 (1.7%) stddev-sans-first:3.35 06:15:04 INFO - PROCESS | 1649 | Values: 184.0 185.0 183.0 181.0 185.0 189.0 182.0 177.0 185.0 182.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:72.50 Median:67.50 stddev:18.57 (27.5%) stddev-sans-first:19.49 06:15:04 INFO - PROCESS | 1649 | Values: 80.0 57.0 76.0 114.0 85.0 58.0 59.0 58.0 81.0 57.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:80.00 stddev:11.36 (14.2%) stddev-sans-first:11.33 06:15:04 INFO - PROCESS | 1649 | Values: 88.0 72.0 92.0 69.0 67.0 68.0 88.0 67.0 67.0 92.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:1.64 (1.9%) stddev-sans-first:1.67 06:15:04 INFO - PROCESS | 1649 | Values: 87.0 84.0 88.0 84.0 87.0 84.0 87.0 85.0 87.0 84.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:101.80 Median:92.00 stddev:18.65 (20.3%) stddev-sans-first:19.51 06:15:04 INFO - PROCESS | 1649 | Values: 93.0 102.0 147.0 123.0 91.0 94.0 92.0 92.0 92.0 92.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:83.00 stddev:2.75 (3.3%) stddev-sans-first:2.89 06:15:04 INFO - PROCESS | 1649 | Values: 81.0 82.0 77.0 78.0 84.0 84.0 86.0 83.0 82.0 83.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.03 (1.9%) stddev-sans-first:1.05 06:15:04 INFO - PROCESS | 1649 | Values: 53.0 53.0 53.0 53.0 56.0 54.0 55.0 54.0 53.0 54.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.50 stddev:2.12 (3.0%) stddev-sans-first:1.86 06:15:04 INFO - PROCESS | 1649 | Values: 67.0 69.0 71.0 75.0 72.0 70.0 69.0 70.0 70.0 71.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:86.50 stddev:10.00 (11.6%) stddev-sans-first:10.40 06:15:04 INFO - PROCESS | 1649 | Values: 87.0 101.0 79.0 87.0 97.0 109.0 106.0 86.0 89.0 85.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:152.50 stddev:15.78 (10.4%) stddev-sans-first:12.97 06:15:04 INFO - PROCESS | 1649 | Values: 125.0 148.0 159.0 163.0 150.0 150.0 149.0 147.0 188.0 155.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.00 Median:177.00 stddev:22.29 (12.6%) stddev-sans-first:22.03 06:15:04 INFO - PROCESS | 1649 | Values: 211.0 176.0 175.0 228.0 220.0 172.0 172.0 175.0 173.0 178.0 06:15:04 INFO - PROCESS | 1649 | 06:15:04 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.83 06:15:04 INFO - PROCESS | 1649 | Values: 68.0 68.0 67.0 67.0 68.0 66.0 66.0 67.0 66.0 66.0 06:15:04 INFO - PROCESS | 1649 | -------- Summary: end -------- 06:15:04 INFO - PROCESS | 1649 | 06:15:05 INFO - TEST-INFO | 1649: exit 0 06:15:05 INFO - TEST-OK | kraken | took 59832ms 06:15:05 INFO - SUITE-END | took 1179s 06:15:05 INFO - Completed test suite (00:19:39) 06:15:05 INFO - javascript benchmark 06:15:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3267.2485328374473, 3286.457734473812, 3314.436545787216, 3340.331933613278, 3353.9161520961975, 5424.538914192299, 5528.723563821813, 5534.418886016965, 5575.442455754428, 5606.747696353658, 5499.780008799643, 5515.283013208277, 5614.9438505614935, 5615.578831587637, 5631.63394379364, 5048.1670524363435, 5057.848264552069, 5074.594032477411, 5137.614678899079, 5159.277701121846, 5072.112380333445, 5221.86945326368, 5224.63427560071, 5225.163973764198, 5276.762545685434, 5027.648064635477, 5067.366579177603, 5178.145605975028, 5202.06362854686, 5269.894602107936, 5082.898342033176, 5118.053160165381, 5120.15517439622, 5125.564327032207, 5126.461721519224, 5009.624278179152, 5073.746312684388, 5085.313482679828, 5089.0, 5092.465291144451, 5502.532284755801, 5514.558835293167, 5514.779408823642, 5521.806736764194, 5532.225488431623, 5948.167256584082, 5953.791617293417, 5967.7911273105665, 5978.372270911535, 5985.221921150222, 6105.0, 6111.23542428084, 6124.319907527348, 6128.3258841527395, 6143.539234557426, 5553.194786755978, 5573.386927437979, 5653.858653533694, 5671.06427439472, 5671.631343962712, 6170.969145154246, 6243.282022567463, 6261.092141639437, 6261.9373806262265, 6263.404976527223, 5916.0, 5918.852028699231, 5921.911171332434, 5931.08068249422, 5937.317208520989, 5895.29256489224, 5947.256592925884, 5997.310309314485, 5999.370066143079, 6034.034554471264, 5949.613275137189, 5953.821385358447, 5974.074018527135, 5990.400959903975, 6029.728662210211, 5988.191594134736, 6002.6698531580305, 6003.129546215774, 6007.879842403127, 6008.158857759917, 4688.203005489075, 4705.482396936334, 4745.952540474551, 4752.762361881962, 4761.166795810719, 4228.0, 4238.978805106017, 4271.658267338606, 4309.978450107729, 4330.458692663417, 5614.9157762633595, 5618.522425593788, 5622.690752008679, 5639.577031722637, 5656.547476201894, 6098.908516372258, 6123.479504242189, 6137.140800287963, 6138.631682099089, 6146.661933593606, 5506.256655351556, 5543.722813859291, 5543.77824887, 5548.667079975215, 5562.721863906789, 5257.842264732064, 5265.236540701577, 5278.313819203479, 5296.629235953524, 5339.0, 6275.811725648238, 6289.433950944254, 6299.244090709144, 6301.4328710416285, 6302.275238347649, 6028.4272994065495, 6103.115048318058, 6121.296050954019, 6152.846178845564, 6187.628742275478, 6197.752089916353, 6201.503879689524, 6239.532035097295, 6259.593126446766, 6278.529110316836, 6041.972864612939, 6048.153258543895, 6053.4551890330085, 6053.515718742402, 6092.208012958287, 6097.939020609737, 6109.969450152721, 6151.415615516519, 6158.230221222348, 6172.135900973777, 5531.585131115263, 5541.362743284574, 5787.710614469175, 5870.765169393177, 5878.412158784258, 5895.852603684943, 5906.852328691817, 5930.9110363343725, 5938.0, 5963.016102343065, 5747.48272655463, 5760.6255593386295, 5760.913586296125, 5766.59633825645, 5777.422257774357, 5800.622959507618, 5830.941690583039, 5834.795782147605, 5871.559633027455, 5882.7058647066615, 5764.3371012332445, 5778.797742079007, 5791.53667706574, 5845.766169353178, 5848.853778655568], "value": 5563.02295128903, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.62846837125187, 509.8597885581465, 512.5054322578712, 522.4592546714149, 533.0671325180933, 31582.68417315826, 34400.0, 40104.59895401045, 41412.17175656485, 43695.0, 41857.37213941786, 41994.79002604983, 42445.36331955023, 42512.787436062776, 43673.34489982653, 9352.999999999984, 9544.713658590219, 9547.0, 9553.426794392324, 9573.569189386459, 9461.952690236574, 9551.140397364235, 9570.952145239264, 9574.18619417347, 9614.13472787449, 9544.665936692218, 9580.90419095807, 9582.52087395631, 9584.856227156599, 9613.519324033805, 9480.81038379232, 9540.952295238549, 9567.712968610953, 9568.617255309779, 9568.712938611818, 9407.576659050359, 9486.810263794754, 9500.477473738942, 9502.762430939212, 9513.66702165421, 39368.80315598432, 39719.0, 39739.403908941385, 39777.20445591101, 39886.80339589817, 2720.795940304465, 3317.402867483852, 3332.96667033329, 3348.7990720556845, 3424.1610805352598, 30100.698993010228, 30385.392292154032, 30403.543946840815, 30696.84651576728, 31003.0, 3183.8726450941936, 3220.951685724716, 3232.3858466891215, 3282.983585082059, 3341.913877989663, 500088.0, 500614.0, 503316.0, 504148.0, 504168.0, 32746.181345466553, 60136.39863601308, 60609.39390606037, 60694.69652651709, 60797.0, 33743.493847592305, 34776.0, 35012.12469688278, 35045.1238719029, 35168.64831351654, 14163.78754318686, 14309.998300118892, 14314.928425357806, 14320.06919550226, 14330.283485825667, 11991.280523168638, 12075.215111017756, 12107.939460302818, 12117.575884843987, 12119.878801212051, 7721.581875993574, 7733.690652373842, 7778.844423111619, 7824.29581337671, 7836.059672839296, 2435.4641978764644, 3121.7034381733697, 3215.13191438315, 3217.533457648628, 3217.887373941901, 930.8976012638605, 938.9248860091176, 986.3637953520083, 1036.0369343677078, 1072.5977758340623, 23770.64344034841, 28369.865205391965, 28519.001834935676, 29340.0, 30091.699083009327, 724.7390939261861, 941.270515350595, 1011.5751384418562, 1012.5038731021747, 1066.6373433032807, 14648.853511464962, 15267.694646107017, 15297.847021529642, 15575.143367114692, 15627.453039143802, 914087.0, 915336.0, 920793.0, 922004.0, 924588.901721386, 56220.0, 56741.0, 56864.43135568674, 56983.0, 57244.71377643169, 1224.5591587028662, 1268.4672437576055, 1270.3838638260436, 1348.480834878559, 1383.7094210215766, 29928.401431971673, 30637.540436893018, 30994.0, 31629.367412651158, 32357.191070223435, 55305.446945531636, 55419.0, 56803.0, 56844.715776422505, 59412.70293648504, 591162.4860632706, 596978.0, 597329.0, 598538.0, 600485.0, 25734.099306524182, 26644.600330994665, 41644.58355416407, 42114.368284475146, 42279.154416910875, 45765.77117114393, 46895.0, 46965.0, 47296.0, 47383.76308118437, 36155.0, 38338.61661383462, 38800.22399552049, 39785.00537486586, 39968.4004739935, 38190.045248869, 38503.0, 39408.40887386634, 39728.404073938334, 39893.40159897662, 37054.44418333673, 37064.62935370612, 37428.0, 37935.43096853494, 38125.61874381221, 32589.644597505052, 32962.83518582488, 34032.14919627029, 34711.652883471856, 34761.47857782186, 24034.51930961336, 24957.251282461555, 25038.74961250437, 25751.871240643675, 25850.35374115662], "value": 18810.877632301326, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.5517916350417, 353.05733691044907, 369.3831301726117, 380.13708880840494, 383.451664120308, 14635.560933172008, 14881.702365952675, 15685.137317447521, 19745.210191592356, 19960.201591936304, 18854.71717924229, 19887.204511819546, 20218.19127234909, 20514.0, 20656.17375304986, 5549.556035517159, 5888.146218798272, 5895.292564892219, 5909.4090590941, 5925.822225333225, 5267.762075912157, 5577.888442231153, 5799.478046975772, 5871.0, 5882.735276912528, 5686.658800471964, 5704.543636509069, 5789.015867302569, 5822.068469044953, 5828.446297601721, 5398.568114550846, 5699.658020518782, 5739.3973632768375, 5806.274215723035, 5808.506276966463, 5502.761878577328, 5596.2165296858475, 5607.439256074401, 5611.66330020196, 5793.826185214451, 12399.070069744805, 12655.62033138998, 12665.0, 12748.470183577938, 14716.0, 2051.7230173926478, 2060.763012253595, 2129.6111665004987, 2242.4057624729458, 2297.7127859017623, 9212.723618291462, 9626.903730962671, 9881.950590247076, 10065.396076235449, 10247.538860751209, 1790.9641807163914, 1839.2183322088192, 1870.5697689531485, 1894.876833005864, 1937.0411646235063, 53861.0, 109707.0, 111151.0, 111272.0, 112416.0, 11069.612563560398, 11121.666350009513, 11188.496517656726, 11204.495797689124, 11364.034057105238, 14236.145831250158, 14245.501407450689, 14274.0, 14274.571762847132, 14297.642558935902, 6952.826179345456, 8010.5994700264755, 8246.587670616445, 8307.626156822958, 8451.239388454946, 8177.67289308421, 8193.918060819435, 8201.71294004707, 8281.585920703941, 8358.328334333157, 3988.6210809973004, 4278.550752170977, 4420.977895110504, 4430.645548356124, 4533.52397998212, 1521.7260893039138, 1683.2593658790092, 1876.9061546922599, 1883.50087226885, 1926.49911023135, 645.8901986662279, 646.1438593863151, 675.9932400676029, 756.3495393961189, 964.6768332608655, 18659.720104198448, 19332.80667193338, 19756.70364944527, 20277.898610506854, 20724.58550828975, 875.6366108065209, 935.8736570562887, 936.9250459963187, 945.9101385368378, 981.9165370943407, 10078.193744500422, 10151.086402223835, 10569.947150264199, 10604.681859544226, 10693.984071513349, 518425.0, 554612.0, 555335.0, 557088.0, 558078.0, 120984.0, 121623.0, 122685.38657306656, 123482.0, 124924.0, 898.3352319283814, 912.6030176873081, 935.4434111703525, 940.6331530703031, 956.6125719083782, 13965.0, 17915.820841791414, 18425.355112570996, 18560.443186704422, 19022.0, 47063.529364705915, 47839.28241076317, 47959.76020119877, 48253.27620085772, 48694.513054869, 60157.488188386924, 61930.69034654798, 66285.0, 66541.0, 69166.0, 5226.0, 7641.847163056819, 7724.558378932249, 7911.0, 7968.521888686697, 10260.333078349884, 11743.41282935867, 12049.156559040783, 12083.637490875288, 12353.505859765508, 9857.901420985698, 10765.946170269099, 10809.513571889127, 10840.5121769519, 10969.067629251284, 8846.557672116498, 9069.77325566866, 9865.556049977624, 10167.542460589144, 10749.086327662237, 10352.792944141225, 11019.889801101886, 11050.613228536962, 11229.3262404256, 11278.041366483942, 7795.41534384912, 7858.764237072897, 7866.252705992922, 7985.680572777024, 8203.548804815793, 9104.726858194264, 10435.060844524029, 10727.249092563447, 10802.405867677038, 10901.454927253448], "value": 8851.26439533039, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1484.2282013353056, 1510.4939845151873, 1682.9831701682988, 1805.079409501154, 1856.9907150464246, 8070.354371650268, 8526.403151779377, 8533.530655813927, 8548.487090774557, 8567.52878591678, 7406.925930740691, 7683.193264707209, 8031.598420078987, 8274.172582741723, 8298.95850520748, 2000.879947203165, 2290.80528155107, 2319.211468100845, 2323.4191452136965, 2326.1974618756585, 2299.9195028174017, 2312.606856834334, 2336.719593648756, 2351.870647114408, 2353.470469144446, 2039.8980050997466, 2119.79861913118, 2119.9894000530053, 2236.7539570647214, 2309.2379514760173, 2020.7575090989176, 2306.642470417088, 2331.0559223514333, 2331.370529956902, 2333.603287441139, 2309.261036468331, 2352.917647882316, 2354.2348736660656, 2355.364051706047, 2356.78788908999, 8400.243978041945, 8495.235428811437, 8500.659973601048, 8508.149185081504, 8513.957430212871, 5067.77195026221, 5242.266082748418, 5245.0, 5247.501487358695, 5258.55302299305, 3010.744086752629, 3164.8259345735974, 3255.9837200813845, 3259.282957749291, 3262.4453842846774, 853.4879072556479, 881.5504092912661, 923.3305853256403, 924.1359329027314, 925.1627277314042, 11006.064484518825, 11056.723581910435, 11080.445977701083, 11081.058110060736, 11120.388378639173, 4378.408914796525, 4577.725336479755, 4700.623950084054, 4712.410948631421, 4719.079779442975, 9520.047995200424, 9527.571259293349, 9534.90465095354, 9542.856857147148, 9554.952225238829, 5235.97898409814, 5236.895262094737, 5244.108501554745, 5251.317328747315, 5262.92105618416, 4565.56627120423, 4571.954280457219, 4661.440627124699, 4806.7836947337455, 4813.350197723263, 3732.9440058399146, 3773.1887644156636, 3808.7143464240294, 3824.29250588642, 3854.1713531590844, 1262.1417436143327, 1263.9115261931577, 1266.5187228852976, 1280.7566562353309, 1282.3908643394313, 620.2092332276294, 651.9413252807175, 839.202757380491, 850.5577099908137, 907.9773005674779, 5426.67439953604, 5578.246936663499, 5868.94131058684, 5871.706414679249, 5941.7623295067715, 821.404481750726, 899.5771987166022, 905.2576886952636, 907.5054095517959, 946.417952958939, 3234.3369609230053, 3805.543334799842, 3835.4055121455663, 3926.489556357655, 3948.526176858795, 9921.65274215399, 10157.796844063078, 10223.488825558692, 10244.692659220234, 10268.897311026943, 10185.083342499063, 10187.235957303232, 10199.745006374751, 10211.438370889524, 10223.437710925822, 7372.852542949111, 7760.14638389788, 7775.377969762519, 7944.960275198702, 7946.245106714853, 5293.920591191212, 5620.971895140498, 5695.629784064023, 5879.970600146972, 5965.403459654, 9218.400803947721, 9379.531023448937, 9394.295427843075, 9461.148496635336, 9593.32846700752, 9362.906370936476, 9538.904610953801, 9587.233021358414, 9627.422354658744, 9740.366876153028, 7147.249538798559, 7301.817454563678, 7312.707491700273, 7315.231900650355, 7340.889886651811, 7679.692812287446, 7730.497517661334, 7830.608469576612, 7849.843003139791, 7857.05715314166, 7085.282315067849, 7112.857742845011, 7127.037849890198, 7231.855362892607, 7336.926630733625, 6997.230304666585, 7041.190263119602, 7061.6822243000215, 7097.254788247366, 7100.715971361088, 6647.734090636513, 6680.432163265982, 6758.831029224112, 6779.525433219627, 6788.898166527407, 4561.429821272341, 4744.525547445228, 4804.135255654016, 4914.852554423373, 5020.924686129638, 4834.95165048345, 4844.491328410466, 4965.379327584052, 4997.450280469073, 5038.748062596782], "value": 4395.690867323737, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.24109034531924, 302.7441811669139, 308.75145507866165, 314.4858156913447, 316.0313638697394, 5069.898602027962, 5071.923921141186, 5107.74461276936, 5135.563477104451, 5171.844844654657, 4739.810407583693, 4891.559759621633, 4964.578010869081, 4971.751412429382, 5125.436202017775, 3858.1704933439305, 3916.353801622743, 3936.7244292899436, 3937.5078115235597, 3989.2619865324864, 3588.4258518637025, 3884.5338559372913, 3926.685865130797, 3949.5063117110362, 3984.6015398460213, 3525.92948141037, 3678.6689197972178, 3750.699944004487, 3759.16183281622, 3931.3709806430975, 3473.801538469224, 3691.8338674759566, 3924.841337264493, 3974.24489347026, 3991.840326386941, 3425.4348032574594, 3456.3605732939236, 3468.8612455501748, 3480.8433620486885, 3489.930201395983, 4943.209086546135, 4968.130577148984, 5021.774020169101, 5023.547880690719, 5075.264086707407, 4326.913461730779, 4491.326301054835, 4492.191405547001, 4530.411046563959, 4565.61192298655, 4900.333886478579, 4981.0, 5343.545798607123, 5352.91970620441, 5400.893651475928, 3783.5244254740674, 3788.488554045196, 3796.7532110413013, 3822.0698547233746, 3825.3114439400897, 4510.616597589209, 4956.851294461172, 5472.945270547243, 5505.834824955257, 5732.426757324276, 3442.7762195457717, 3581.2658405026905, 3901.668358189529, 3918.5885482024546, 3922.842112099787, 3978.8607398741483, 3979.940300895431, 3984.322665146874, 3991.261616600938, 3994.8402063917697, 3771.86798462058, 3825.5026846509777, 4368.8907777305185, 4579.17574836526, 4600.3099535070005, 5454.754536045887, 5477.452254774491, 5482.0, 5495.780168793283, 5531.585131115183, 1092.4373947417087, 1113.3486910157649, 1197.4910662968205, 1271.7138643805256, 1284.991281843748, 796.3430170109683, 833.3208435125354, 858.5492616376328, 861.5261606116611, 882.9249513791272, 3781.470594116826, 4351.6518678505645, 4383.495897971774, 4771.856844294677, 4791.976040119846, 4947.0, 5140.485951404904, 5241.379310344828, 5307.814226502054, 5345.3852806926925, 3957.248122856648, 4027.2952233359047, 4215.704900656925, 4422.867313980586, 4643.721376717408, 2523.5583772840046, 2676.9598456023177, 2680.209338245222, 2681.795254670689, 2731.4400547887635, 4575.675986193082, 5000.224965130411, 5195.0, 5255.605829562798, 5295.523402893681, 4296.334068219431, 4500.594946454835, 4811.869210735475, 5134.89469317578, 5138.948610513922, 4687.414073240845, 4783.4499032611, 5041.899162016813, 5188.740562971836, 5220.451852555427, 4471.0, 4484.372187893632, 5101.112588342337, 5130.307408499804, 5214.765335559985, 4523.683342166017, 4840.443349014909, 5050.671706339076, 5487.396386397573, 5538.535351768424, 5687.658740475585, 5725.971370143123, 5792.478676919098, 5795.884082318245, 5806.361300256884, 3535.505029295952, 3679.282539904693, 3693.8337774800734, 3704.2776658552402, 3730.6828919542145, 4562.612177964777, 4739.739314337607, 5318.361796584435, 5505.697186654773, 5571.192177134212, 5317.288183026607, 5319.680319680319, 5333.333333333333, 5368.7047212403695, 5448.482394172547, 4812.0, 5155.716435596079, 5290.3335449333335, 5309.707966061749, 5330.867652938856, 4508.842190523447, 5040.924386134137, 5182.823214249948, 5225.0, 5233.142234506695, 4285.307303614993, 4694.554017368345, 4703.31801888731, 5078.695278283315, 5432.320959880015, 5271.314729085195, 5272.868178295574, 5287.333366618342, 5330.520253177233, 5332.413434522122], "value": 3866.7799524257625, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.6376266222893, 705.7247673407376, 743.0600290632351, 750.5684231566848, 781.2460975158972, 14271.0, 14870.33083511242, 14874.47939322124, 14884.776728349083, 14907.925460372697, 13281.203127812314, 13419.261940593264, 13428.060035797498, 13434.32828358583, 13436.731265374687, 542.6011881267266, 675.0313300413898, 684.5379368925979, 797.5453991225004, 823.3289868756959, 827.7599496146111, 831.2601784411878, 840.8108175660489, 843.9704610338639, 844.3835999720208, 892.3485855325616, 896.1979028769248, 896.4576431259093, 897.5153417154729, 898.5597057441872, 692.0588000319561, 720.3768740039828, 729.3217307903648, 895.1630225738922, 895.7939673875013, 539.438983457204, 543.7227014222775, 641.4932203559183, 666.3236814633151, 671.0538141220856, 13812.930935345259, 13834.031617786759, 13840.861591384159, 13850.653733656638, 13880.375383107683, 12785.936070319682, 12863.099583029098, 13004.349782510837, 13092.214467132002, 13104.279264640441, 11965.700857478545, 12029.819552706716, 12035.39823008846, 12118.030557555463, 12146.0, 4006.757905049444, 4285.185814695229, 4307.91384172318, 4406.735595864258, 4442.577955094261, 75606.62196688981, 77283.6135819323, 78413.21586784117, 81470.0, 82082.58958705228, 10651.84022239667, 10664.41345725977, 10689.9465502672, 10818.513166907507, 10920.0, 18235.45293641193, 19099.2360305589, 19103.617927641648, 20121.295754648516, 20132.496687582927, 6777.695003724844, 7483.0, 7705.845883082419, 7783.027121609799, 7865.4494185406475, 4201.285781417167, 4302.440682711227, 4315.697901146952, 4321.308590625485, 4431.711938723973, 3306.3883181611, 3415.9316813663586, 3439.0542600784984, 3451.2234747181683, 3455.1016735648914, 647.0326861342229, 648.251269783401, 657.3656421553225, 665.1220389086457, 666.6000399760105, 518.401246560216, 528.4873672537632, 622.5859803230892, 654.4012228810681, 659.120074700271, 8337.333013358917, 8748.0, 8980.416272942239, 9056.547172641342, 9300.488473133908, 530.8401143501385, 551.7351671197847, 563.2115038945509, 660.9603423794491, 664.727461740684, 4163.354680024601, 4364.258076127066, 4444.244478438609, 4457.77711114443, 4506.909861802811, 66698.66650666716, 67868.98196527056, 68147.65926170436, 114043.0, 115920.0, 65501.0, 91774.08225917655, 102767.0, 103182.45226321611, 105454.47272763588, 91939.0, 92234.0, 94163.52918235502, 94407.52796235975, 95948.0, 8707.390482666153, 8718.346124040596, 8720.345974052098, 8722.563871806511, 8877.511736854534, 49843.50156498534, 53033.73483132714, 56133.0, 57175.42824571701, 58267.125993109286, 53985.822410189576, 55884.72057639686, 61565.0, 65651.34348656452, 66729.33270667231, 12229.388530573258, 12281.263124212575, 12385.752284954431, 12489.932285296632, 12628.621141365773, 11425.885741142481, 13961.232132232824, 14027.50903718365, 14314.570562883131, 14534.491292804702, 12620.55828046014, 12958.416871240628, 12996.346886912506, 13104.279264640536, 13556.93221533886, 12640.620781376574, 12915.0, 12992.48030078824, 13011.21932684042, 13073.0, 11197.216194866282, 11716.355600442057, 12463.0, 12540.686482838002, 12552.560660376843, 9038.231750301298, 9514.571844266886, 10436.0, 10663.040326370354, 11147.163962702667, 9461.905380946102, 10878.075363594184, 10994.615188468686, 11162.441877906234, 11231.0], "value": 7116.94371693215, "unit": "score"}], "name": "dromaeo_css", "value": 6943.424792913008, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 112.0, 112.0, 114.0, 113.0, 113.0, 112.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 111.0, 153.0, 140.0, 140.0, 113.0, 114.0, 140.0, 111.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 181.0, 185.0, 189.0, 182.0, 177.0, 185.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 76.0, 114.0, 85.0, 58.0, 59.0, 58.0, 81.0, 57.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 92.0, 69.0, 67.0, 68.0, 88.0, 67.0, 67.0, 92.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 84.0, 87.0, 84.0, 87.0, 85.0, 87.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 102.0, 147.0, 123.0, 91.0, 94.0, 92.0, 92.0, 92.0, 92.0], "value": 101.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 77.0, 78.0, 84.0, 84.0, 86.0, 83.0, 82.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 56.0, 54.0, 55.0, 54.0, 53.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 71.0, 75.0, 72.0, 70.0, 69.0, 70.0, 70.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 101.0, 79.0, 87.0, 97.0, 109.0, 106.0, 86.0, 89.0, 85.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 148.0, 159.0, 163.0, 150.0, 150.0, 149.0, 147.0, 188.0, 155.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 176.0, 175.0, 228.0, 220.0, 172.0, 172.0, 175.0, 173.0, 178.0], "value": 188.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 68.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1466.6000000000001, "alertThreshold": 2.0}]} 06:15:05 INFO - Return code: 0 06:15:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:15:05 INFO - # TBPL SUCCESS # 06:15:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:05 INFO - 2016-11-15 06:15:05.852 screenresolution[1653:9695] starting screenresolution argv=screenresolution get 06:15:05 INFO - 2016-11-15 06:15:05.855 screenresolution[1653:9695] Display 0: 1600x1200x32@60 06:15:05 INFO - 2016-11-15 06:15:05.861 screenresolution[1654:9698] starting screenresolution argv=screenresolution list 06:15:05 INFO - Available Modes on Display 0 06:15:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:06 INFO - Intel Iris: 06:15:06 INFO - Chipset Model: Intel Iris 06:15:06 INFO - Type: GPU 06:15:06 INFO - Bus: Built-In 06:15:06 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:06 INFO - Vendor: Intel (0x8086) 06:15:06 INFO - Device ID: 0x0a2e 06:15:06 INFO - Revision ID: 0x0009 06:15:06 INFO - Displays: 06:15:06 INFO - Display: 06:15:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:06 INFO - Display Serial Number: 1600x1200 60 06:15:06 INFO - Main Display: Yes 06:15:06 INFO - Mirror: Off 06:15:06 INFO - Online: Yes 06:15:06 INFO - Rotation: Supported 06:15:06 INFO - Return code: 0 06:15:06 INFO - Running post-action listener: _resource_record_post_action 06:15:06 INFO - [mozharness: 2016-11-15 14:15:06.094449Z] Finished run-tests step (success) 06:15:06 INFO - Running post-run listener: _resource_record_post_run 06:15:06 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 36680704; Write bytes: 698250240; Read time: 237; Write time: 1999 06:15:06 INFO - TinderboxPrint: CPU usage
53.7% 06:15:06 INFO - TinderboxPrint: I/O read bytes / time
36,680,704 / 237 06:15:06 INFO - TinderboxPrint: I/O write bytes / time
698,250,240 / 1,999 06:15:06 INFO - TinderboxPrint: CPU idle
2,247.9 (46.3%) 06:15:06 INFO - TinderboxPrint: CPU system
973.9 (20.0%) 06:15:06 INFO - TinderboxPrint: CPU user
1,635.9 (33.7%) 06:15:06 INFO - TinderboxPrint: Swap in / out
435,408,896 / 0 06:15:06 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377281024; Write bytes: 371087360; Read time: 28096; Write time: 799 06:15:06 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35730432; Write bytes: 297208832; Read time: 225; Write time: 1129 06:15:06 INFO - Running post-run listener: _upload_blobber_files 06:15:06 INFO - Blob upload gear active. 06:15:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:15:06 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 06:15:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:15:06 INFO - (blobuploader) - INFO - Open directory for files ... 06:15:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:15:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:15:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:08 INFO - (blobuploader) - INFO - Done attempting. 06:15:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:15:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:15:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:08 INFO - (blobuploader) - INFO - Done attempting. 06:15:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:15:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:15:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:11 INFO - (blobuploader) - INFO - Done attempting. 06:15:11 INFO - (blobuploader) - INFO - Iteration through files over. 06:15:11 INFO - Return code: 0 06:15:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:15:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:15:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e05d6c00ae4cff0d5b694363295bfdb14bce5d70ac18f6af41b58092e033ec98ed4270a2157e2d98ddff480a4271ccc950eae2b23ac51ebdcab94d41f91156e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55e24962b419f8181cdc9deae1756091876bcd809c5cb1d59a7d22994559aee5226c8042ba23bc0cf0a269407c70d86edca9c572d60dc35d9775d8aebb47378b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/314d297236023424230970f210339112c1632fdfd4b96d8422bf4e6f64660201dfc981d249ef10e7f25f259596cee6c9e24bbfc88e3f2080029ce05b8e363667"} 06:15:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:15:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:15:11 INFO - Contents: 06:15:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e05d6c00ae4cff0d5b694363295bfdb14bce5d70ac18f6af41b58092e033ec98ed4270a2157e2d98ddff480a4271ccc950eae2b23ac51ebdcab94d41f91156e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55e24962b419f8181cdc9deae1756091876bcd809c5cb1d59a7d22994559aee5226c8042ba23bc0cf0a269407c70d86edca9c572d60dc35d9775d8aebb47378b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/314d297236023424230970f210339112c1632fdfd4b96d8422bf4e6f64660201dfc981d249ef10e7f25f259596cee6c9e24bbfc88e3f2080029ce05b8e363667"} 06:15:11 INFO - Running post-run listener: copy_logs_to_upload_dir 06:15:11 INFO - Copying logs to upload dir... 06:15:11 INFO - mkdir: /builds/slave/test/build/upload/logs 06:15:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.770709 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-15 06:15:11.236023) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 06:15:11.239215) ========= 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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e05d6c00ae4cff0d5b694363295bfdb14bce5d70ac18f6af41b58092e033ec98ed4270a2157e2d98ddff480a4271ccc950eae2b23ac51ebdcab94d41f91156e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55e24962b419f8181cdc9deae1756091876bcd809c5cb1d59a7d22994559aee5226c8042ba23bc0cf0a269407c70d86edca9c572d60dc35d9775d8aebb47378b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/314d297236023424230970f210339112c1632fdfd4b96d8422bf4e6f64660201dfc981d249ef10e7f25f259596cee6c9e24bbfc88e3f2080029ce05b8e363667"} build_url:https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010930 build_url: 'https://queue.taskcluster.net/v1/task/RkCIgE9CT8SPezp11-oFow/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e05d6c00ae4cff0d5b694363295bfdb14bce5d70ac18f6af41b58092e033ec98ed4270a2157e2d98ddff480a4271ccc950eae2b23ac51ebdcab94d41f91156e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55e24962b419f8181cdc9deae1756091876bcd809c5cb1d59a7d22994559aee5226c8042ba23bc0cf0a269407c70d86edca9c572d60dc35d9775d8aebb47378b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/314d297236023424230970f210339112c1632fdfd4b96d8422bf4e6f64660201dfc981d249ef10e7f25f259596cee6c9e24bbfc88e3f2080029ce05b8e363667"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 06:15:11.271638) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 06:15:11.271987) ========= 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.ZIMDfoS5gz/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.9trn6LjzEO/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.005021 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 06:15:11.321446) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 06:15:11.321827) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 06:15:11.322535) ========= ========= Total master_lag: 0.17 =========