builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0096 starttime: 1478003703.23 results: success (0) buildid: 20161101030207 builduid: 41ea543753304764ae96fc9ec016de46 revision: 2c773b97167252cedcba0be0c7af9d4cab192ef5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:03.226745) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:03.227281) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:03.227601) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:03.253786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:03.254087) ========= 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.7MiSZ47KjF/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.YddoHksh2e/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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-01 05:35:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 140M=0s 2016-11-01 05:35:03 (140 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118880 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:03.396430) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:03.396761) ========= 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.7MiSZ47KjF/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.YddoHksh2e/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.026603 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:03.446196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-01 05:35:03.446892) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2c773b97167252cedcba0be0c7af9d4cab192ef5 --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 2c773b97167252cedcba0be0c7af9d4cab192ef5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7MiSZ47KjF/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.YddoHksh2e/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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-01 05:35:03,524 truncating revision to first 12 chars 2016-11-01 05:35:03,524 Setting DEBUG logging. 2016-11-01 05:35:03,524 attempt 1/10 2016-11-01 05:35:03,524 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2c773b971672?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-01 05:35:04,337 unpacking tar archive at: mozilla-central-2c773b971672/testing/mozharness/ program finished with exit code 0 elapsedTime=1.147989 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-01 05:35:04.618533) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:04.618889) ========= script_repo_revision: 2c773b97167252cedcba0be0c7af9d4cab192ef5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:04.619380) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:04.619727) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-01 05:35:04.639722) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-01 05:35:04.640115) ========= /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.7MiSZ47KjF/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.YddoHksh2e/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:04 INFO - MultiFileLogger online at 20161101 05:35:04 in /builds/slave/test 05:35:04 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:35:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:35:04 INFO - {'append_to_log': False, 05:35:04 INFO - 'base_work_dir': '/builds/slave/test', 05:35:04 INFO - 'blob_upload_branch': 'Firefox', 05:35:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:35:04 INFO - 'branch': 'Firefox', 05:35:04 INFO - 'buildbot_json_path': 'buildprops.json', 05:35:04 INFO - 'config_files': ('talos/mac_config.py',), 05:35:04 INFO - 'default_actions': ('clobber', 05:35:04 INFO - 'read-buildbot-config', 05:35:04 INFO - 'download-and-extract', 05:35:04 INFO - 'populate-webroot', 05:35:04 INFO - 'create-virtualenv', 05:35:04 INFO - 'install', 05:35:04 INFO - 'run-tests'), 05:35:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:35:04 INFO - 'download_minidump_stackwalk': True, 05:35:04 INFO - 'download_symbols': 'ondemand', 05:35:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:35:04 INFO - 'tooltool.py': '/tools/tooltool.py', 05:35:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:35:04 INFO - '/tools/misc-python/virtualenv.py')}, 05:35:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:35:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:35:04 INFO - 'installer_path': 'installer.exe', 05:35:04 INFO - 'log_level': 'info', 05:35:04 INFO - 'log_name': 'talos', 05:35:04 INFO - 'log_to_console': True, 05:35:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:35:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:35:04 INFO - 'opt_config_files': (), 05:35:04 INFO - 'pip_index': False, 05:35:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:35:04 INFO - 'cmd': ('bash', 05:35:04 INFO - '-c', 05:35:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:35:04 INFO - 'enabled': True, 05:35:04 INFO - 'halt_on_failure': False, 05:35:04 INFO - 'name': 'check_screen_resolution'},), 05:35:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:35:04 INFO - 'cmd': ('bash', 05:35:04 INFO - '-c', 05:35:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:35:04 INFO - 'enabled': True, 05:35:04 INFO - 'halt_on_failure': False, 05:35:04 INFO - 'name': 'check_screen_resolution'},), 05:35:04 INFO - 'run_cmd_checks_enabled': True, 05:35:04 INFO - 'sps_profile': False, 05:35:04 INFO - 'sps_profile_interval': 0, 05:35:04 INFO - 'suite': 'dromaeojs', 05:35:04 INFO - 'system_bits': '32', 05:35:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:35:04 INFO - 'title': 't-yosemite-r7-0096', 05:35:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:35:04 INFO - 'use_talos_json': True, 05:35:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:35:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:35:04 INFO - 'work_dir': 'build'} 05:35:04 INFO - [mozharness: 2016-11-01 12:35:04.846269Z] Running clobber step. 05:35:04 INFO - Running pre-action listener: _resource_record_pre_action 05:35:04 INFO - Running main action method: clobber 05:35:04 INFO - rmtree: /builds/slave/test/build 05:35:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:35:07 INFO - Running post-action listener: _resource_record_post_action 05:35:07 INFO - [mozharness: 2016-11-01 12:35:07.054959Z] Finished clobber step (success) 05:35:07 INFO - [mozharness: 2016-11-01 12:35:07.055083Z] Running read-buildbot-config step. 05:35:07 INFO - Running pre-action listener: _resource_record_pre_action 05:35:07 INFO - Running main action method: read_buildbot_config 05:35:07 INFO - Using buildbot properties: 05:35:07 INFO - { 05:35:07 INFO - "project": "", 05:35:07 INFO - "product": "firefox", 05:35:07 INFO - "script_repo_revision": "production", 05:35:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:35:07 INFO - "repository": "", 05:35:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:35:07 INFO - "buildid": "20161101030207", 05:35:07 INFO - "stage_platform": "macosx64", 05:35:07 INFO - "basedir": "/builds/slave/test", 05:35:07 INFO - "nightly_build": "True", 05:35:07 INFO - "buildnumber": 17, 05:35:07 INFO - "slavename": "t-yosemite-r7-0096", 05:35:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:35:07 INFO - "platform": "yosemite_r7", 05:35:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:35:07 INFO - "branch": "mozilla-central", 05:35:07 INFO - "repo_path": "mozilla-central", 05:35:07 INFO - "revision": "2c773b97167252cedcba0be0c7af9d4cab192ef5", 05:35:07 INFO - "pgo_build": "False", 05:35:07 INFO - "builduid": "41ea543753304764ae96fc9ec016de46", 05:35:07 INFO - "slavebuilddir": "test" 05:35:07 INFO - } 05:35:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:35:07 INFO - Running post-action listener: _resource_record_post_action 05:35:07 INFO - [mozharness: 2016-11-01 12:35:07.058564Z] Finished read-buildbot-config step (success) 05:35:07 INFO - [mozharness: 2016-11-01 12:35:07.058693Z] Running download-and-extract step. 05:35:07 INFO - Running pre-action listener: _resource_record_pre_action 05:35:07 INFO - Running main action method: download_and_extract 05:35:07 INFO - mkdir: /builds/slave/test/build/tests 05:35:07 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:35:07 INFO - trying https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:35:07 INFO - Downloading https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:35:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:35:08 INFO - Downloaded 1199 bytes. 05:35:08 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:35:08 INFO - Using the following test package requirements: 05:35:08 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:35:08 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:35:08 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:35:08 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:35:08 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:35:08 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:35:08 INFO - u'jsshell-mac.zip'], 05:35:08 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:35:08 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:35:08 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:35:08 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:35:08 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:35:08 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:35:08 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:35:08 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:35:08 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:35:08 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:35:08 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:35:08 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:35:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:35:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:35:08 INFO - Fetch https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:35:09 INFO - Http code: 200 05:35:09 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 05:35:09 INFO - x-amz-cf-id: pzUj5ePSkkESz7kC6xf3rDXScABj4F1Szny2a8To_QX14iZUZdtO2g== 05:35:09 INFO - x-amz-version-id: oksBMcuBhKKdVbGml8occvVs4s9gSb.U 05:35:09 INFO - Expected file size: 16405022 05:35:09 INFO - Obtained file size: 16405022 05:35:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:35:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:35:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:35:16 INFO - Fetch https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:35:16 INFO - Http code: 200 05:35:16 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 05:35:16 INFO - x-amz-cf-id: dgZr6W0D46lxoxxhPXLkjpixB__NsOmkg6Nd03QsXbavKDh-QKZ7TQ== 05:35:16 INFO - x-amz-version-id: oksBMcuBhKKdVbGml8occvVs4s9gSb.U 05:35:17 INFO - Expected file size: 16405022 05:35:17 INFO - Obtained file size: 16405022 05:35:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:35:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:35:23 INFO - Fetch https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:35:24 INFO - Http code: 200 05:35:24 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 05:35:24 INFO - x-amz-cf-id: RBtz0Tuvfn784Sem-EAU3MmuFd44pu0ECXCtBx-lANFOInfyLaqwqg== 05:35:24 INFO - x-amz-version-id: M7.2h5luwDKeRy1vq7GitH7KxqtdcdBC 05:35:24 INFO - Expected file size: 14409038 05:35:24 INFO - Obtained file size: 14409038 05:35:24 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:35:24 INFO - trying https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:35:24 INFO - Downloading https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:35:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:35:27 INFO - Downloaded 112245108 bytes. 05:35:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:35:27 INFO - mkdir: /builds/slave/test/properties 05:35:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:35:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:35:27 INFO - Contents: 05:35:27 INFO - build_url:https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:35:28 INFO - Running post-action listener: _resource_record_post_action 05:35:28 INFO - Running post-action listener: set_extra_try_arguments 05:35:28 INFO - [mozharness: 2016-11-01 12:35:28.184992Z] Finished download-and-extract step (success) 05:35:28 INFO - [mozharness: 2016-11-01 12:35:28.185138Z] Running populate-webroot step. 05:35:28 INFO - Running pre-action listener: _resource_record_pre_action 05:35:28 INFO - Running main action method: populate_webroot 05:35:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:35:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:35:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:35:28 INFO - '--tppagecycles', 05:35:28 INFO - '7'], 05:35:28 INFO - 'tests': ['tsvgm']}}, 05:35:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:35:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:35:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:35:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:35:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:28 INFO - 'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:28 INFO - 'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['damp', 'tps']}, 05:35:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:28 INFO - 'tests': ['damp', 'tps']}, 05:35:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['dromaeo_dom']}, 05:35:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:35:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['basic_compositor_video']}, 05:35:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:35:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['a11yr', 05:35:28 INFO - 'ts_paint', 05:35:28 INFO - 'tpaint', 05:35:28 INFO - 'sessionrestore', 05:35:28 INFO - 'sessionrestore_no_auto_restore', 05:35:28 INFO - 'tabpaint']}, 05:35:28 INFO - 'other-e10s': {'tests': ['a11yr', 05:35:28 INFO - 'ts_paint', 05:35:28 INFO - 'tpaint', 05:35:28 INFO - 'sessionrestore', 05:35:28 INFO - 'sessionrestore_no_auto_restore', 05:35:28 INFO - 'tabpaint']}, 05:35:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:35:28 INFO - 'ts_paint', 05:35:28 INFO - 'tpaint', 05:35:28 INFO - 'sessionrestore', 05:35:28 INFO - 'sessionrestore_no_auto_restore', 05:35:28 INFO - 'tabpaint']}, 05:35:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:35:28 INFO - 'ts_paint', 05:35:28 INFO - 'tpaint', 05:35:28 INFO - 'sessionrestore', 05:35:28 INFO - 'sessionrestore_no_auto_restore', 05:35:28 INFO - 'tabpaint']}, 05:35:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['a11yr', 05:35:28 INFO - 'ts_paint', 05:35:28 INFO - 'tpaint', 05:35:28 INFO - 'sessionrestore', 05:35:28 INFO - 'sessionrestore_no_auto_restore', 05:35:28 INFO - 'tabpaint']}, 05:35:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['a11yr', 05:35:28 INFO - 'ts_paint', 05:35:28 INFO - 'tpaint', 05:35:28 INFO - 'sessionrestore', 05:35:28 INFO - 'sessionrestore_no_auto_restore', 05:35:28 INFO - 'tabpaint']}, 05:35:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['tsvgx', 05:35:28 INFO - 'tsvgr_opacity', 05:35:28 INFO - 'tart', 05:35:28 INFO - 'tscrollx', 05:35:28 INFO - 'cart']}, 05:35:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:35:28 INFO - 'tsvgr_opacity', 05:35:28 INFO - 'tart', 05:35:28 INFO - 'tscrollx', 05:35:28 INFO - 'cart']}, 05:35:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:28 INFO - 'talos_options': ['--disable-e10s'], 05:35:28 INFO - 'tests': ['tp5o']}, 05:35:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:28 INFO - 'tests': ['tp5o']}, 05:35:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:28 INFO - 'talos_options': ['--disable-e10s', 05:35:28 INFO - '--xperf_path', 05:35:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:28 INFO - 'tests': ['tp5n']}, 05:35:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:28 INFO - 'talos_options': ['--xperf_path', 05:35:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:28 INFO - 'tests': ['tp5n']}}, 05:35:28 INFO - 'talos.zip': {'path': '', 05:35:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:35:28 INFO - Running post-action listener: _resource_record_post_action 05:35:28 INFO - [mozharness: 2016-11-01 12:35:28.204012Z] Finished populate-webroot step (success) 05:35:28 INFO - [mozharness: 2016-11-01 12:35:28.204155Z] Running create-virtualenv step. 05:35:28 INFO - Running pre-action listener: _resource_record_pre_action 05:35:28 INFO - Running main action method: create_virtualenv 05:35:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:35:28 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:35:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:35:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:35:28 INFO - Using real prefix '/tools/python27' 05:35:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:35:29 INFO - Installing distribute.............................................................................................................................................................................................done. 05:35:31 INFO - Installing pip.................done. 05:35:31 INFO - Return code: 0 05:35:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:35:32 INFO - Reading from file tmpfile_stdout 05:35:32 INFO - Output received: 05:35:32 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:35:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:32 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:32 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:35:32 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:35:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:32 INFO - 'HOME': '/Users/cltbld', 05:35:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:32 INFO - 'LOGNAME': 'cltbld', 05:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:32 INFO - 'MOZ_NO_REMOTE': '1', 05:35:32 INFO - 'NO_EM_RESTART': '1', 05:35:32 INFO - 'PAGER': '/bin/cat', 05:35:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:32 INFO - 'PWD': '/builds/slave/test', 05:35:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:32 INFO - 'SHELL': '/bin/bash', 05:35:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:32 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:32 INFO - 'USER': 'cltbld', 05:35:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:32 INFO - 'XPC_FLAGS': '0x0', 05:35:32 INFO - 'XPC_SERVICE_NAME': '0', 05:35:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:32 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:35:32 INFO - Cleaning up... 05:35:32 INFO - Return code: 0 05:35:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:32 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:32 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:35:32 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:35:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:32 INFO - 'HOME': '/Users/cltbld', 05:35:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:32 INFO - 'LOGNAME': 'cltbld', 05:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:32 INFO - 'MOZ_NO_REMOTE': '1', 05:35:32 INFO - 'NO_EM_RESTART': '1', 05:35:32 INFO - 'PAGER': '/bin/cat', 05:35:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:32 INFO - 'PWD': '/builds/slave/test', 05:35:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:32 INFO - 'SHELL': '/bin/bash', 05:35:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:32 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:32 INFO - 'USER': 'cltbld', 05:35:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:32 INFO - 'XPC_FLAGS': '0x0', 05:35:32 INFO - 'XPC_SERVICE_NAME': '0', 05:35:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:32 INFO - Downloading/unpacking psutil>=3.1.1 05:35: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:35: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:35: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:35: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:35: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:35: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:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:35:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:35:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:35:36 INFO - Installing collected packages: psutil 05:35:36 INFO - Running setup.py install for psutil 05:35:36 INFO - building 'psutil._psutil_osx' extension 05:35:36 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:35:37 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:35:37 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:35:37 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:35:37 INFO - building 'psutil._psutil_posix' extension 05:35:37 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:35:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:35:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:35:37 INFO - ^ 05:35:37 INFO - 1 warning generated. 05:35:37 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:35:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:35:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:35:37 INFO - Successfully installed psutil 05:35:37 INFO - Cleaning up... 05:35:38 INFO - Return code: 0 05:35:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:35: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:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35: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:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:38 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:38 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:35:38 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:35:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:38 INFO - 'HOME': '/Users/cltbld', 05:35:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:38 INFO - 'LOGNAME': 'cltbld', 05:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:38 INFO - 'MOZ_NO_REMOTE': '1', 05:35:38 INFO - 'NO_EM_RESTART': '1', 05:35:38 INFO - 'PAGER': '/bin/cat', 05:35:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:38 INFO - 'PWD': '/builds/slave/test', 05:35:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:38 INFO - 'SHELL': '/bin/bash', 05:35:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:38 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:38 INFO - 'USER': 'cltbld', 05:35:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:38 INFO - 'XPC_FLAGS': '0x0', 05:35:38 INFO - 'XPC_SERVICE_NAME': '0', 05:35:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:35:38 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:35:38 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:35:38 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:35:38 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:35:38 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:35:38 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:35:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:35:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:35:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:35:40 INFO - Installing collected packages: mozsystemmonitor 05:35:40 INFO - Running setup.py install for mozsystemmonitor 05:35:41 INFO - Successfully installed mozsystemmonitor 05:35:41 INFO - Cleaning up... 05:35:41 INFO - Return code: 0 05:35:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:41 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:41 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:35:41 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:35:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:41 INFO - 'HOME': '/Users/cltbld', 05:35:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:41 INFO - 'LOGNAME': 'cltbld', 05:35:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:41 INFO - 'MOZ_NO_REMOTE': '1', 05:35:41 INFO - 'NO_EM_RESTART': '1', 05:35:41 INFO - 'PAGER': '/bin/cat', 05:35:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:41 INFO - 'PWD': '/builds/slave/test', 05:35:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:41 INFO - 'SHELL': '/bin/bash', 05:35:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:41 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:41 INFO - 'USER': 'cltbld', 05:35:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:41 INFO - 'XPC_FLAGS': '0x0', 05:35:41 INFO - 'XPC_SERVICE_NAME': '0', 05:35:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:41 INFO - Downloading/unpacking jsonschema==2.5.1 05:35:41 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:35:41 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:35:41 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:35:41 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:35:41 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:35:41 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:35:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:35:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:44 INFO - Downloading functools32-3.2.3-2.tar.gz 05:35:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:35:44 INFO - warning: no files found matching '*.txt' 05:35:44 INFO - no previously-included directories found matching 'build' 05:35:44 INFO - no previously-included directories found matching 'dist' 05:35:44 INFO - no previously-included directories found matching '.git*' 05:35:44 INFO - Installing collected packages: jsonschema, functools32 05:35:44 INFO - Running setup.py install for functools32 05:35:44 INFO - warning: no files found matching '*.txt' 05:35:44 INFO - no previously-included directories found matching 'build' 05:35:44 INFO - no previously-included directories found matching 'dist' 05:35:44 INFO - no previously-included directories found matching '.git*' 05:35:44 INFO - Successfully installed jsonschema functools32 05:35:44 INFO - Cleaning up... 05:35:44 INFO - Return code: 0 05:35:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:44 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:44 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:35:44 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:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:44 INFO - 'HOME': '/Users/cltbld', 05:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:44 INFO - 'LOGNAME': 'cltbld', 05:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:44 INFO - 'MOZ_NO_REMOTE': '1', 05:35:44 INFO - 'NO_EM_RESTART': '1', 05:35:44 INFO - 'PAGER': '/bin/cat', 05:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:44 INFO - 'PWD': '/builds/slave/test', 05:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:44 INFO - 'SHELL': '/bin/bash', 05:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:44 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:44 INFO - 'USER': 'cltbld', 05:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:44 INFO - 'XPC_FLAGS': '0x0', 05:35:44 INFO - 'XPC_SERVICE_NAME': '0', 05:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:35:44 INFO - Cleaning up... 05:35:44 INFO - Return code: 0 05:35:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:44 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:44 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:35:44 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:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:44 INFO - 'HOME': '/Users/cltbld', 05:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:44 INFO - 'LOGNAME': 'cltbld', 05:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:44 INFO - 'MOZ_NO_REMOTE': '1', 05:35:44 INFO - 'NO_EM_RESTART': '1', 05:35:44 INFO - 'PAGER': '/bin/cat', 05:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:44 INFO - 'PWD': '/builds/slave/test', 05:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:44 INFO - 'SHELL': '/bin/bash', 05:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:44 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:44 INFO - 'USER': 'cltbld', 05:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:44 INFO - 'XPC_FLAGS': '0x0', 05:35:44 INFO - 'XPC_SERVICE_NAME': '0', 05:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:45 INFO - Downloading/unpacking blobuploader==1.2.4 05:35: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:35: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:35: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:35: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:35: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:35: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:35:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:35:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:35:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:35:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:35:48 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:35:48 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:35:48 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:35:48 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:35:48 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:35:48 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:35:48 INFO - Downloading docopt-0.6.1.tar.gz 05:35:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:35:48 INFO - Installing collected packages: blobuploader, requests, docopt 05:35:48 INFO - Running setup.py install for blobuploader 05:35:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:35:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:35:49 INFO - Running setup.py install for requests 05:35:49 INFO - Running setup.py install for docopt 05:35:49 INFO - Successfully installed blobuploader requests docopt 05:35:49 INFO - Cleaning up... 05:35:49 INFO - Return code: 0 05:35:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35: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:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35: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:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:49 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:49 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:35:49 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:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:49 INFO - 'HOME': '/Users/cltbld', 05:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:49 INFO - 'LOGNAME': 'cltbld', 05:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:49 INFO - 'MOZ_NO_REMOTE': '1', 05:35:49 INFO - 'NO_EM_RESTART': '1', 05:35:49 INFO - 'PAGER': '/bin/cat', 05:35:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:49 INFO - 'PWD': '/builds/slave/test', 05:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:49 INFO - 'SHELL': '/bin/bash', 05:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:49 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:49 INFO - 'USER': 'cltbld', 05:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:49 INFO - 'XPC_FLAGS': '0x0', 05:35:49 INFO - 'XPC_SERVICE_NAME': '0', 05:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-hcgbm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-p1VE9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-sEfs0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-LkmBG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-JRIvCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ycZ4PV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-UprriM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-mWijDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-QcrzFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-3riPBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-KkNbEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NdaYMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-hKzj3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Oeo0ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-l8hDUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-oi_sgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-kXjokU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:35:51 INFO - Running setup.py install for manifestparser 05:35:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:35:52 INFO - Running setup.py install for mozcrash 05:35:52 INFO - Running setup.py install for mozdebug 05:35:52 INFO - Running setup.py install for mozdevice 05:35:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:35:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:35:52 INFO - Running setup.py install for mozfile 05:35:52 INFO - Running setup.py install for mozhttpd 05:35:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:35:52 INFO - Running setup.py install for mozinfo 05:35:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:35:52 INFO - Running setup.py install for mozInstall 05:35:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Running setup.py install for mozleak 05:35:53 INFO - Running setup.py install for mozlog 05:35:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Running setup.py install for moznetwork 05:35:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Running setup.py install for mozprocess 05:35:53 INFO - Running setup.py install for mozprofile 05:35:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Running setup.py install for mozrunner 05:35:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:35:53 INFO - Running setup.py install for mozscreenshot 05:35:54 INFO - Running setup.py install for moztest 05:35:54 INFO - Running setup.py install for mozversion 05:35:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:35:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:35:54 INFO - Cleaning up... 05:35:54 INFO - Return code: 0 05:35:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:54 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:54 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:35:54 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:35:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:54 INFO - 'HOME': '/Users/cltbld', 05:35:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:54 INFO - 'LOGNAME': 'cltbld', 05:35:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:54 INFO - 'MOZ_NO_REMOTE': '1', 05:35:54 INFO - 'NO_EM_RESTART': '1', 05:35:54 INFO - 'PAGER': '/bin/cat', 05:35:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:54 INFO - 'PWD': '/builds/slave/test', 05:35:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:54 INFO - 'SHELL': '/bin/bash', 05:35:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:54 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:54 INFO - 'USER': 'cltbld', 05:35:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:54 INFO - 'XPC_FLAGS': '0x0', 05:35:54 INFO - 'XPC_SERVICE_NAME': '0', 05:35:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:54 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-VkOTuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:54 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:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:54 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-LyCc7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:54 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-6laaWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-wZfUBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-kqyLGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-VysGxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-yQj78N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Zaiteu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-HFo9lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ThJ5Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ZKyuuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-c0GIOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:55 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:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:55 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-sFVmbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:56 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:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:56 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-2cdPfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:56 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-BzWtWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:56 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:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:56 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-0bgyIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:56 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:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:56 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-mREMl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:56 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:35:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:35:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:35:56 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:35:56 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:35:56 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:35:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:35:56 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:35:56 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:35:56 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:35:56 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:35:56 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:35:56 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:35:58 INFO - Downloading blessings-1.6.tar.gz 05:35:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:35:59 INFO - Installing collected packages: blessings 05:35:59 INFO - Running setup.py install for blessings 05:35:59 INFO - Successfully installed blessings 05:35:59 INFO - Cleaning up... 05:35:59 INFO - Return code: 0 05:35:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:35:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:59 INFO - Reading from file tmpfile_stdout 05:35:59 INFO - Current package versions: 05:35:59 INFO - blessings == 1.6 05:35:59 INFO - blobuploader == 1.2.4 05:35:59 INFO - docopt == 0.6.1 05:35:59 INFO - functools32 == 3.2.3-2 05:35:59 INFO - jsonschema == 2.5.1 05:35:59 INFO - manifestparser == 1.1 05:35:59 INFO - mozInstall == 1.12 05:35:59 INFO - mozcrash == 0.17 05:35:59 INFO - mozdebug == 0.1 05:35:59 INFO - mozdevice == 0.48 05:35:59 INFO - mozfile == 1.2 05:35:59 INFO - mozhttpd == 0.7 05:35:59 INFO - mozinfo == 0.9 05:35:59 INFO - mozleak == 0.1 05:35:59 INFO - mozlog == 3.4 05:35:59 INFO - moznetwork == 0.27 05:35:59 INFO - mozprocess == 0.23 05:35:59 INFO - mozprofile == 0.28 05:35:59 INFO - mozrunner == 6.12 05:35:59 INFO - mozscreenshot == 0.1 05:35:59 INFO - mozsystemmonitor == 0.3 05:35:59 INFO - moztest == 0.7 05:35:59 INFO - mozversion == 1.4 05:35:59 INFO - psutil == 3.1.1 05:35:59 INFO - requests == 1.2.3 05:35:59 INFO - wsgiref == 0.1.2 05:35:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:59 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 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:59 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:35:59 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:35:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:59 INFO - 'HOME': '/Users/cltbld', 05:35:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:59 INFO - 'LOGNAME': 'cltbld', 05:35:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:59 INFO - 'MOZ_NO_REMOTE': '1', 05:35:59 INFO - 'NO_EM_RESTART': '1', 05:35:59 INFO - 'PAGER': '/bin/cat', 05:35:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:59 INFO - 'PWD': '/builds/slave/test', 05:35:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:59 INFO - 'SHELL': '/bin/bash', 05:35:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:59 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:59 INFO - 'USER': 'cltbld', 05:35:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:59 INFO - 'XPC_FLAGS': '0x0', 05:35:59 INFO - 'XPC_SERVICE_NAME': '0', 05:35:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:59 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:35:59 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:35:59 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:35:59 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:35:59 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:35:59 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:35:59 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:35:59 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:35:59 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:35:59 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:35:59 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:35:59 INFO - Cleaning up... 05:35:59 INFO - Return code: 0 05:35:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:35:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bad4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0c8c7e0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, '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.7MiSZ47KjF/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.YddoHksh2e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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:35:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:35:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:35:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:35:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:59 INFO - 'HOME': '/Users/cltbld', 05:35:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:59 INFO - 'LOGNAME': 'cltbld', 05:35:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:59 INFO - 'MOZ_NO_REMOTE': '1', 05:35:59 INFO - 'NO_EM_RESTART': '1', 05:35:59 INFO - 'PAGER': '/bin/cat', 05:35:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:59 INFO - 'PWD': '/builds/slave/test', 05:35:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:59 INFO - 'SHELL': '/bin/bash', 05:35:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:35:59 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:35:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:59 INFO - 'USER': 'cltbld', 05:35:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:59 INFO - 'XPC_FLAGS': '0x0', 05:35:59 INFO - 'XPC_SERVICE_NAME': '0', 05:35:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:36:00 INFO - Cleaning up... 05:36:00 INFO - Return code: 0 05:36:00 INFO - Running post-action listener: _resource_record_post_action 05:36:00 INFO - Running post-action listener: _start_resource_monitoring 05:36:00 INFO - Starting resource monitoring. 05:36:00 INFO - [mozharness: 2016-11-01 12:36:00.223244Z] Finished create-virtualenv step (success) 05:36:00 INFO - [mozharness: 2016-11-01 12:36:00.224233Z] Running install step. 05:36:00 INFO - Running pre-action listener: _resource_record_pre_action 05:36:00 INFO - Running main action method: install 05:36:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:36:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:36:00 INFO - Reading from file tmpfile_stdout 05:36:00 INFO - Detecting whether we're running mozinstall >=1.0... 05:36:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:36:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:36:00 INFO - Reading from file tmpfile_stdout 05:36:00 INFO - Output received: 05:36:00 INFO - Usage: mozinstall [options] installer 05:36:00 INFO - Options: 05:36:00 INFO - -h, --help show this help message and exit 05:36:00 INFO - -d DEST, --destination=DEST 05:36:00 INFO - Directory to install application into. [default: 05:36:00 INFO - "/builds/slave/test"] 05:36:00 INFO - --app=APP Application being installed. [default: firefox] 05:36:00 INFO - mkdir: /builds/slave/test/build/application 05:36:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:36:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:36:35 INFO - Reading from file tmpfile_stdout 05:36:35 INFO - Output received: 05:36:35 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:36:35 INFO - Running post-action listener: _resource_record_post_action 05:36:35 INFO - [mozharness: 2016-11-01 12:36:35.412178Z] Finished install step (success) 05:36:35 INFO - [mozharness: 2016-11-01 12:36:35.412316Z] Running run-tests step. 05:36:35 INFO - Running pre-action listener: _resource_record_pre_action 05:36:35 INFO - Running main action method: run_tests 05:36:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:36:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:36:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:36:35 INFO - 2016-11-01 05:36:35.426 screenresolution[1644:8184] starting screenresolution argv=screenresolution get 05:36:35 INFO - 2016-11-01 05:36:35.429 screenresolution[1644:8184] Display 0: 1600x1200x32@60 05:36:35 INFO - 2016-11-01 05:36:35.435 screenresolution[1645:8186] starting screenresolution argv=screenresolution list 05:36:35 INFO - Available Modes on Display 0 05:36:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:36:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:36:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:36:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:36:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:36:35 INFO - Intel Iris: 05:36:35 INFO - Chipset Model: Intel Iris 05:36:35 INFO - Type: GPU 05:36:35 INFO - Bus: Built-In 05:36:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:36:35 INFO - Vendor: Intel (0x8086) 05:36:35 INFO - Device ID: 0x0a2e 05:36:35 INFO - Revision ID: 0x0009 05:36:35 INFO - Displays: 05:36:35 INFO - Display: 05:36:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:36:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:36:35 INFO - Display Serial Number: 1600x1200 60 05:36:35 INFO - Main Display: Yes 05:36:35 INFO - Mirror: Off 05:36:35 INFO - Online: Yes 05:36:35 INFO - Rotation: Supported 05:36:35 INFO - Return code: 0 05:36:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:36:35 INFO - Python 2.7.3 05:36:35 INFO - Return code: 0 05:36:35 INFO - grabbing minidump binary from tooltool 05:36:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:35 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e8d30>, '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 0x10b70edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:36:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:36:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:36:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:36:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:36:35 INFO - Return code: 0 05:36:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:36:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:36:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:36:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:36:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:36:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0096 --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:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MiSZ47KjF/Render', 05:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:35 INFO - 'HOME': '/Users/cltbld', 05:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:35 INFO - 'LOGNAME': 'cltbld', 05:36:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:36:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:35 INFO - 'MOZ_NO_REMOTE': '1', 05:36:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:36:35 INFO - 'NO_EM_RESTART': '1', 05:36:35 INFO - 'PAGER': '/bin/cat', 05:36:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:35 INFO - 'PWD': '/builds/slave/test', 05:36:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:35 INFO - 'SHELL': '/bin/bash', 05:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YddoHksh2e/Listeners', 05:36:35 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 05:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:35 INFO - 'USER': 'cltbld', 05:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:35 INFO - 'XPC_FLAGS': '0x0', 05:36:35 INFO - 'XPC_SERVICE_NAME': '0', 05:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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:36:36 INFO - mozversion application_buildid: 20161101030207 05:36:36 INFO - mozversion application_changeset: 2c773b97167252cedcba0be0c7af9d4cab192ef5 05:36:36 INFO - mozversion application_display_name: Nightly 05:36:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:36:36 INFO - mozversion application_name: Firefox 05:36:36 INFO - mozversion application_remotingname: firefox 05:36:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:36:36 INFO - mozversion application_vendor: Mozilla 05:36:36 INFO - mozversion application_version: 52.0a1 05:36:36 INFO - mozversion platform_buildid: 20161101030207 05:36:36 INFO - mozversion platform_changeset: 2c773b97167252cedcba0be0c7af9d4cab192ef5 05:36:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:36:36 INFO - mozversion platform_version: 52.0a1 05:36:36 INFO - using testdate: 1478003796 05:36:36 INFO - actual date: 1478003796 05:36:36 INFO - starting webserver on 'localhost:49274' 05:36:36 INFO - SUITE-START | Running 2 tests 05:36:36 INFO - TEST-START | dromaeo_css 05:36:36 INFO - operating with platform_type : mac_ 05:36:36 INFO - Initialising browser for dromaeo_css test... 05:36:36 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpgItAZP/profile http://localhost:49274/getInfo.html) 05:36:38 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 05:36:38 INFO - PROCESS | 1655 | colorDepth:24 05:36:38 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 05:36:38 INFO - PROCESS | 1655 | __metrics 05:36:38 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:36:38 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:36:39 INFO - TEST-INFO | 1655: exit 0 05:36:39 INFO - Browser initialized. 05:36:39 INFO - Running cycle 1/1 for dromaeo_css test... 05:36:39 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpgItAZP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:51 INFO - PROCESS | 1659 | RSS: Main: 239620096 05:36:51 INFO - PROCESS | 1659 | 05:37:42 INFO - PROCESS | 1659 | 1478003862713 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:39:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 05:39:58 INFO - PROCESS | 1659 | RSS: Main: 415494144 05:39:58 INFO - PROCESS | 1659 | 05:43:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 05:43:01 INFO - PROCESS | 1659 | RSS: Main: 375521280 05:43:01 INFO - PROCESS | 1659 | 05:46:03 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 05:46:04 INFO - PROCESS | 1659 | RSS: Main: 345030656 05:46:04 INFO - PROCESS | 1659 | 05:49:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 05:49:07 INFO - PROCESS | 1659 | RSS: Main: 336900096 05:49:07 INFO - PROCESS | 1659 | 05:52:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 05:52:11 INFO - PROCESS | 1659 | RSS: Main: 334016512 05:52:11 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 05:55:14 INFO - PROCESS | 1659 | RSS: Main: 449863680 05:55:14 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | __start_tp_report 05:55:14 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 05:55:14 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 05:55:14 INFO - PROCESS | 1659 | |i|pagename|runs| 05:55:14 INFO - PROCESS | 1659 | |0;dojo.html;3279.2457734721015;3283.3269179818153;3291.9506207406894;3326.1352048467393;3346.748993825462;5524.917126243109;5588.273524441822;5602.887942241153;5644.8024319148835;5651.9152212716735;5620.1288800235925;5641.830745077655;5649.265595472593;5651.40660230676;5668.376478587363;5082.517160869721;5123;5128.71792051437;5144.09978253806;5170.043541944733;5132.23016547519;5186.377634683843;5199.844004679866;5215.713135777531;5216.921746173811;5219.71291578963;5234.947650523484;5245.475452454743;5297.205419187114;5297.364316282052;386.8549666954608;5211.088059589576;5236.423993360727;5251.711155886424;5287.629865909388;3300.8149894990347;5071.974640126776;5126.077306084903;5135.717535535544;5219.42586315505;4856.490068542787;5247.737613119367;5590.161475778625;5681.772729090872;5699.173619825144;6126.29547602027;6155.876882462326;6166.167567378391;6167.321594624587;6174.382561743834;6010.729517171694;6069.757209711606;6085.300190478063;6097.101247991131;6145.402195429176;5663.1505274208375;5663.8867222656145;5667.348254950651;5672.234248376416;5673.744681489343;5377.4084850666395;6144.78493252734;6265.937340626535;6266.68666566679;6273.341299163613;5902.2032025676845;5935.970320148372;5948.910766338508;5990.101484777319;6064.423879731418;6146.0473626587955;6169.629822210682;6198.535109866688;6206.565540412128;6213.409726076015;6130.478909292671;6138.508919286446;6140.600860944024;6141.83695396871;6143.665407145256;5995.970020149872;6002.819915402545;6058.818235452944;6127.842042281593;6136.163923949498;4829.372181616438;4836.709797412166;4842.854714358575;4843.07981483517;4845.98234370779;4391.363252328394;4445.51099379068;4448.132614140276;4449.2658711312915;4452.376667266585;5643.322801263875;5706.28671416073;5714.228579141846;5719.828405147852;5737.913931291034;6102.368068311607;6114.145302971568;6140.596275277816;6144.187464697836;6146.815595532141;5741.62679425836;5770.076787713946;5817.418258174149;5818.679972601463;5819.418058194146;5320.441353657888;5321.73391330432;5325.866853328698;5330.9733451332495;5331.866703332447;5167.974160129175;6212.937870621236;6239.313675495692;6277.843053923689;6278.46633036197;4843.660943733905;6121.632702037892;6134.8159555213415;6154.59995100335;6159.076138579249;6330.968345158429;6335.239771227482;6340.524460665561;6341.619502829845;6352.142460767922;6119.663418512025;6132;6136.570440069331;6140.508759299156;6161.661108639068;6059.848503787264;6073.453389194994;6086.269647642312;6213.65824879636;6228.159198508143;5971.28344598651;5989.311229208697;6013.398660134127;6067.453929146398;6141.570090093651;5011.7494125294315;6028.698565071817;6030.185924900083;6035.818925432244;6039.184710064085;4199.580041995776;5813.128030795415;5833;5884.676342801187;5885.499732522612;5933.406659334032;5953.613015154001;5993.550483713651;5995.790147344822;6004.639721616717;5859.94140058611;5873.03094989339;5886.323072846678;5898.970505147447;5920.259967504062 05:55:14 INFO - PROCESS | 1659 | |1;ext.html;457.96336293096533;510.869728219304;511.08260672093587;530.1755769777993;541.5396912624267;29779;31922.042338729818;32036.35927281453;40080.59919400805;40797.59202407975;41510.792446037805;42082.57917420825;42145.57854421455;42149.36775948363;42457.78771106148;9353.345265831378;9537.856932146024;9541.618335266581;9560.856587151198;9573.521323933775;9347.813043739121;9458.905410945872;9465.053494650549;9469.621215151386;9498.240140788721;9431.292653051049;9488.288378371615;9498.572564234626;9503.382280151767;9505.00197479265;9253.768655783659;9409.95295023524;9444.669436569688;9469.384490008128;9489.620415183419;9258.490783006931;9331.953340233324;9347.719568412891;9352.485613291266;9364.204042656382;39230.41154382686;39532.20935581301;39631.20737585261;39636.80181599102;39674.20651586952;3214.164317277501;3268.2619959282747;3478.7564870459078;3859.3053250414914;3993.4209539616595;29496.492798111973;30121.397572048438;30629.23426914345;31429.84285078583;31439.52840707391;3091.6444608870042;3124.3438877835683;3274.4433446313947;3316.403047451458;3320.3857286402094;414896;419229.80770192214;487038;495165.52417238045;504530;61523.6923815378;62723;63545.682271588346;63615;63662.045069324;29784.40431191364;34792.82603586966;34932.47601285983;35406.64593354085;65849;15802.841971580367;15962.840371596136;15966.840331596768;15976.600584985468;15996.440124595583;12151.027917766558;12262.754744905053;12276.877231227574;12279.815802762965;12334.259944403186;16092.66656334825;19414.819253045847;19537.706934395996;19562;19596.902015490115;6949.957506374084;8127.59362031896;8270.37972152091;8303.377246706521;8377.983925786712;956.293688860523;960.6061514778908;1000.1798525209404;1012.731626119079;1037.0666400239845;29718;29831.311334519112;29900.434207855506;30254.697453025627;30445.23886902801;969.3795970578835;984.3700031979539;1010.3432768700403;1013.2096964367805;1054.2093429927554;12852.48590056406;13694.452221911211;14616.415343386145;15560.299786509635;15821.762673559906;654608;655628;659363;660933;662251;56582.717086414305;57127;57521.71239143777;57546.7122664384;57566.7121664389;1305.092960392518;1331.9134256273312;1338.3508998136092;1350.1089281074444;1351.5607427586272;25467.74532254728;28861.422771544872;29754.851225743732;29900.401991959603;30120.849395753758;53107.73446132899;56276.31171064875;56399.43600563942;56521;56611.71694141503;603372;608962;609867.3586216882;610426;611861;26843.32891677724;27023.45953080888;27194;40276.993075172184;40516.39225411562;44997;45457.5444348923;45672.77163614161;45741.313880291156;46277.76861115673;38406.23187536289;38700;39090;39210.80394598123;40171.598284016785;35507.289854203285;38726.940848209364;39666.206675866895;39801;39959.80020099881;36442.453363200104;36603;36613;36691.08272293214;37118.88643340704;32071;33308.50037249492;34584.82707586446;34694.82652586721;34836.477452837724;24784.25647230586;25099.247022589352;25657.12514622216;25711;25836.224913252634 05:55:14 INFO - PROCESS | 1659 | |2;jquery.html;319.3262216722714;350.84738138909563;365.90669379308287;372.3799873211104;402.27389561840886;13685.93157034216;14286.357113929873;15113.471028514005;20001.699974500396;20502;15242.695146097072;18939.33712320069;19968.900155499203;20165.899170504163;20202.888841113734;5241.18761591952;5523.226748255248;5536.2249285100115;5538.446155384469;5558.583106267046;4621.768911554425;5393.894251678396;5484.725763711798;6034.094885767148;6103.23709536308;6049.455549000589;6060.787872424466;6062.363451837559;6062.818115456521;6065.909011364833;5436.646617969819;5479.260299859548;5483.314585676791;5499.285092937933;5686.09022556391;5321.122014867542;5330.600204984642;5335.653182543122;5340.225667278223;5352.732363381815;19390.127444265043;23079.53840923189;23971.760282397303;24041.87979060111;25060.874695626586;2029.289748587997;2108.68369744538;2112.545802652437;2322.001539338084;2721.523733346676;9468.242540596735;9671.419714817062;9805.509724513746;9998.500074996295;10357.171426285879;1682.9495115146565;1802.774653168354;1813.2565648084221;1823.717323814811;1866.2814816295688;115507;135832;138446.30776846153;138997.61002389947;147633.99999999892;18799.248030078645;20121.698174527395;20852.687209691867;21411.92940352976;21550.49146559757;33187;34214.97355079352;34672.133196670286;34741.13147171341;34751;10268.435236062089;10649.307794993301;10662.946685266525;10689.679309620724;10773.138148948065;8725.21473067427;8755.693550725695;8780.07809180027;8910.777230569287;8937.955310223408;7767.495112817649;8829.54312538424;9553.608468993547;9706.514674266258;9707.495338222587;3149.1812128846664;3282.3107147498813;3706.9258614827554;3728.9067773305883;3798.0884587699306;696.3245651717825;706.1710170657918;787.271773609409;827.6234313387395;863.3999370437485;15198.316075776616;19642.47468483678;21055.473613159793;21253;21318.573628527654;885.6191837509804;898.9910100899039;903.8779764731809;928.8792456980549;987.318750062469;9301.674441394518;10540.420276884692;10560.735981600521;11048.558057677763;11113.666590002313;529906;533518;540328;540617;546375;76909;93336.53307735366;120875.39562302132;122249;126398.36800815938;926.2682480840061;955.8948515663271;973.0561355485167;978.8972157923315;980.8626792249091;14652.413903443743;18085.36701215451;18131.54671133232;18477.9076104624;19567.412977610125;47385.763071184425;53133.734331328094;53703;53915.921681566935;54908.45091549193;73138;76138.61930690533;76266;78354;80377;7865.292123708665;8110.31062359706;8176.46852954556;8189.181081892001;8372.748817535483;12150.939245303716;12728.363581821057;13014.609561713163;13039.348032598522;13044.413001414769;10844.45777711127;10989.395583242998;11303.208775385645;11310.208285419942;11383.487743051417;9480.810383792148;10563.52464139131;10786.73033174177;11139.665810025712;11152.776944461228;9728.319017668695;11111.83332250033;11159.609413670483;11384.48769805344;11559.479823407799;6716.798496045127;7974.681012759424;8160.918390816016;8226.588670566567;8259.587020648823;9370.25037996945;11029.393383363991;11069.948354906272;11091.33451992883;11197.216194866607 05:55:14 INFO - PROCESS | 1659 | |3;mootools.html;1447.1389523233674;1470.2281302316285;1610.1627153879983;1762.6562820250056;1805.647898659762;7916.247956444143;8324.250817426431;8336.666533338674;8347.91652083479;8349.290310323622;7394.149672787631;7615.543067415959;7857.2535609117185;8102.351811855052;8110.351171906248;2259.593273210817;2297.6438652008965;2297.8391512594126;2300.66640337151;2303.79265866072;2283.246528645543;2331.1607821184443;2333.53329334133;2337.240396871015;2341.438054866828;2276.4536511237347;2280.372897453197;2306.411864974429;2307.9423014424606;2313.8958746856347;2279.5326957973575;2302.689136966505;2304.965425518627;2305.965410518844;2310.2260742651233;2278.1798552521077;2307.4346785037606;2310.1336998625516;2310.5609934112463;2313.826463015264;7915.683372665087;7933.365330773524;7948.8410231795615;7957.721479748181;7958.840823183562;4910.116179087763;5086.847394578169;5088.465711100318;5090.720010399426;5095.286659867622;3203.7917535360125;3280.343931213743;3496.667816557423;3515.5781306243157;3535.4166562517153;1027.255239951037;1047.5600247895902;1060.6977011551671;1075.5912753153752;1080.2114456446839;10659.253852230348;10709.25035247533;10814.891851081546;10825.67522974312;10877.83683244752;4431.7340959542535;4573.725576465423;4683.765811709401;4686.203345431285;4690.53094690535;9206.90793092074;9208.26333893287;9233;9237.722868313962;9240.3531752778;5152.227165925066;5152.768125434364;5153.123968925292;5154.433012368637;5155.32980712505;3895.2014836958356;4135.855245066408;4704.388429504143;4722.645801564897;4740.45484769249;3070.8618112185004;3649.9270014600093;3713.591504934455;3746.456763769238;3755.5493340798735;1217.0811037666683;1220.0666490135054;1230.5241674548297;1236.9443375048145;1237.975240495185;869.7043005378075;874.5758307220992;876.0626130040795;881.3698205782896;911.8677791720163;5394.460553944653;5493.450654934554;5546.112621980464;5643.548516118701;5715.371309155989;863.248973393152;953.2602999525893;954.6706386296717;977.2035790830593;1005.8483036922829;3482.199094208346;3493.9126521836647;3764.5294338207723;3782.394816829294;3782.5650050244576;9729.367591106555;10173.237007224488;10178.287519873538;10180.236482263712;10187.286889917783;10116.696499105039;10128.59485620567;10129.848052279222;10138.03688649577;10139.7465063374;7426.6632006238315;7675.232476752391;7883.408744344082;7890.092639346434;7891.250331218525;4942.4316203637045;5236.842894713165;5298.205269209495;5361.678299302054;5448.918266226089;9266.536673166449;9331.580078896603;9355.06449355059;9449.196818270524;9455.716328510156;8907.643694252158;9239.491827949527;9354.25165986733;9466.195373393064;9472.763180920532;6579.078928950046;6764.4588432924265;6782.25395206517;6873.518853680194;7126.002359669754;7615.42884283692;7749.070111586646;7866.842663146819;7870.449068565145;7924.286814186751;6857.657117144023;7037.085178926707;7070.823229419306;7185.604791736505;7246.565206087652;6879.380855723009;6913.723451061901;6937.306269373023;6957.8956315654295;7020.438364930691;6366.363363663597;6407.3592640737415;6483.837904052248;6500.447461965786;6516.771912983023;4687.671862969696;4725.810967561259;4778.354922085474;4803.015381846643;4890;4359.738415695068;4397.054633253866;4436.489803672619;4439.667024973075;4566.109608626286 05:55:14 INFO - PROCESS | 1659 | |4;prototype.html;298.2989973562129;304.9252933031408;308.80545256488415;311.02803738317755;313.86660669215576;4035.55608883023;4315.791578358061;4994.926090890458;5149.665271757333;5277.445868183843;4581.564751348625;5210.817621383252;5213.218017297408;5217.399999000116;5240.29256050434;3533.2580158166816;3708.4437334399854;3792.563855156651;3881.6700580450697;4003.699722520808;3954;3973.708544722962;3986.5614782373914;3991.261616600938;4060.127072679373;3910.178862438877;3925.764454132747;3928.921421571567;3931.8034098295116;3966.682665386762;3592.515010473579;3937.370020796659;3939.231849789278;3940.724149309564;3947.210557888405;3070.5394190871325;3417.726581873444;3469.4499542835997;3474.1488335357744;3488.5173801134415;4471.038726673781;4740.217864052427;5058.595312375001;5079.74601269935;5086.618503612207;4397.582229688206;4552.453705555321;4573.6569757268335;4586.633069354443;4588.311753237008;5454.300121439913;5459.290292262021;5469.835904922858;5486.189557446033;5493.395726470085;3824.0248736572275;3825.7321987460887;3825.827837747308;3831.233753249361;3856.570360672786;4768.451628062782;4770.8750768246855;5508.366537848158;5768.90390825746;5796.739146738407;3822.426636004566;3886.3393223152134;3896.6298201670797;3898.2983063048355;3907.824147913294;3366.9730732126936;3391.2878295558207;3935.4883865097927;3957.6438120569287;3963.4344433948427;3815.6756675682877;4201.033762234642;4523.190723710542;4525.004499010212;4570.542945705402;4533.682642215013;5194.68831870089;5513.641613295123;5538.255449533384;5542.861428464321;1206.4148887789415;1306.6929271621161;1326.5025615394227;1342.543535198018;1350.9797353039712;789.6170357376668;797.5294576199954;817.4032955942196;829.3365307753772;833.7285639399843;4956.067133817814;5069.290299358093;5073.264376665436;5114.698385726364;5195.844124676266;4668.393108895822;4975.154223781966;5483.6405057979;5657.830265092054;5760.596758226968;4251.404803327537;4945.774401519331;5210.374755029421;5236.39781425141;5257.106291930381;2439.42673471734;2921.5617657351568;2961.5784423476853;2965.8961936332125;2975.497373826204;5856.619319744288;5875.707344384228;5877.265341832271;5890.646561206341;5923.526117910643;5478.315625343553;5778.521645724597;5812.709364531756;5855.619384739978;5928.407159284124;5882.794102206402;5908.379620139909;5919.763209471573;5942.741758131882;5944;4917.655764096479;5663.32040155184;5714.885702285847;5716.714164291686;5765.711714414347;5612.579056570692;5647.887042259214;5655.208270842168;5666.9716651416475;5669.688167150846;5213.426523082382;5916.437938395846;5927.251460818982;5942.870854537624;5951.226340575698;3721.9255614888093;3809.6761775249415;3821.350370436977;3826.483424737625;3839.846406143723;5708.343540492897;5711.200971693903;5735.4551317624755;5736.799212027559;5850.23946886902;4449.466064072332;4930.211166213388;5511.114664161676;5579.637323573955;5579.944200558105;4622.838200662961;4701.858944231679;5158.252053452304;5304.469553044664;5524.83978364548;5009.269947878488;5419.2413062170535;5468.26178465902;5471.987682278791;5499.1476321170285;4440.511543730252;4998.500149985118;5450.337647017697;5492.917606235989;5514.420985796594;4600.516945720785;4781.976090119527;5411.756470958895;5424.457554244702;5444.183372494158 05:55:14 INFO - PROCESS | 1659 | |5;yui.html;708.5429897715974;736.4476642518111;760.1600231743923;780.4575819805231;793.6190628498323;14998.650121489063;15181.544553663394;15183.392664293442;15208.467703630375;15627;13360;13508.324583770822;13510.527131550398;13525.323733813295;13533.932330338335;662.3773652766408;682.8327059870338;807.7738233294657;817.9182081791832;836.1011912194385;821.9917800821975;827.9130691277419;830.2942498875967;835.1356346181703;835.6532039203723;894.2354287084546;897.8024834536392;897.9191872731453;897.964081436745;901.535709109809;695.6834640238679;890.6081324217356;892.482360231071;892.8749975003504;893.4237416866085;528.5375296615462;632.2886752403546;652.797632733854;653.8463461086641;655;14607.269636518131;14656.560303190814;14657.853421465756;14673.486427974969;14678;11953.40232988347;12807.807882881763;13114.278714670689;13485.258310792902;13662.385192666354;12156.696082597917;12255.93872030643;12281.57014504497;12309;12377.566785162477;4375.343698445226;4429.977850110761;4450.666200035011;4454.710443821174;4655.767211639439;89476.6578501323;89698.16961897456;90591;91735;92442;10627.787444251226;10852.782944341227;10903.890961090447;10946;11021.008109270044;15645.921770391304;20780.688289675665;20828.687569686466;20829;21197.470063248235;7271.054762880897;7597.126330471956;7639.885401718979;7672.0409948756405;7692.653830577638;4062.532808727034;4094.959050409517;4122.505299364036;4201.663866890703;4212.389203565466;3293.357795229907;3365.1082463146795;3382.9323413531592;3398.558187435667;3406.4379377402947;614.6158650843223;620.770314983459;620.9441150296495;625.956183067181;635.9682015899186;524.538406202547;603.6740160313382;614.4316507230793;615.3846153846154;627.8156089662349;7254.854902902018;8396.748097557083;8751.693690720922;8843.425177363579;8890.955545222232;578.6701580099387;598.5839841310247;619.9008158694677;626.7931582577738;643.7521554201666;4047.3928910663635;4092.324766413568;4310.956890431118;4373.6282415994365;4434.558768400283;70620;92971;93665;108653;117230.82769172199;96031;96827;97893;98417;101353.49323253483;88801.55599221833;89573;89924;90706;91068.6339704905;7042;8227.588620569068;8264.669413223644;8307.335413166811;8487.55330754721;55918.720406399334;57293.140602890155;57625;57885.421145788;59043.87871055195;65599.68800623865;75648.62175689086;76354.61822690851;80670.19329806627;81544.59227703823;10986.670399888017;11000.724981875517;11058.834117488404;11395.202335836744;11530.135239857213;11806.937375636235;11814.763704725685;11859.70350741204;11925.522979080739;12400.62798116058;11287.661370158909;11317.886821132013;11348;11642.185047046625;11853.992410645193;9348.485833279236;10980.945095274472;11191.944040280072;11280.717982050515;11385.259958103028;9165.762622046204;11105.278156919774;11125.44372781374;11131.888681113409;11333.716657083638;9445.433274003582;9484;9609.759756006155;9629.855552166582;9682.080202380763;8851.64840642418;9549.681743372177;9578.760530986781;9751.073648003428;9773.85339219898 05:55:14 INFO - PROCESS | 1659 | __end_tp_report 05:55:14 INFO - PROCESS | 1659 | __start_cc_report 05:55:14 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1949 05:55:14 INFO - PROCESS | 1659 | __end_cc_report 05:55:14 INFO - PROCESS | 1659 | __startTimestamp1478004914870__endTimestamp 05:55:14 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | ------- Summary: start ------- 05:55:14 INFO - PROCESS | 1659 | Number of tests: 6 05:55:14 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5604.16 Median:5859.94 stddev:777.72 (13.3%) stddev-sans-first:758.41 05:55:14 INFO - PROCESS | 1659 | Values: 3279.2 3283.3 3292.0 3326.1 3346.7 5524.9 5588.3 5602.9 5644.8 5651.9 5620.1 5641.8 5649.3 5651.4 5668.4 5082.5 5123.0 5128.7 5144.1 5170.0 5132.2 5186.4 5199.8 5215.7 5216.9 5219.7 5234.9 5245.5 5297.2 5297.4 386.9 5211.1 5236.4 5251.7 5287.6 3300.8 5072.0 5126.1 5135.7 5219.4 4856.5 5247.7 5590.2 5681.8 5699.2 6126.3 6155.9 6166.2 6167.3 6174.4 6010.7 6069.8 6085.3 6097.1 6145.4 5663.2 5663.9 5667.3 5672.2 5673.7 5377.4 6144.8 6265.9 6266.7 6273.3 5902.2 5936.0 5948.9 5990.1 6064.4 6146.0 6169.6 6198.5 6206.6 6213.4 6130.5 6138.5 6140.6 6141.8 6143.7 5996.0 6002.8 6058.8 6127.8 6136.2 4829.4 4836.7 4842.9 4843.1 4846.0 4391.4 4445.5 4448.1 4449.3 4452.4 5643.3 5706.3 5714.2 5719.8 5737.9 6102.4 6114.1 6140.6 6144.2 6146.8 5741.6 5770.1 5817.4 5818.7 5819.4 5320.4 5321.7 5325.9 5331.0 5331.9 5168.0 6212.9 6239.3 6277.8 6278.5 4843.7 6121.6 6134.8 6154.6 6159.1 6331.0 6335.2 6340.5 6341.6 6352.1 6119.7 6132.0 6136.6 6140.5 6161.7 6059.8 6073.5 6086.3 6213.7 6228.2 5971.3 5989.3 6013.4 6067.5 6141.6 5011.7 6028.7 6030.2 6035.8 6039.2 4199.6 5813.1 5833.0 5884.7 5885.5 5933.4 5953.6 5993.6 5995.8 6004.6 5859.9 5873.0 5886.3 5899.0 5920.3 05:55:14 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:70363.72 Median:39670.21 stddev:156199.09 (393.7%) stddev-sans-first:156549.09 05:55:14 INFO - PROCESS | 1659 | Values: 458.0 510.9 511.1 530.2 541.5 29779.0 31922.0 32036.4 40080.6 40797.6 41510.8 42082.6 42145.6 42149.4 42457.8 9353.3 9537.9 9541.6 9560.9 9573.5 9347.8 9458.9 9465.1 9469.6 9498.2 9431.3 9488.3 9498.6 9503.4 9505.0 9253.8 9410.0 9444.7 9469.4 9489.6 9258.5 9332.0 9347.7 9352.5 9364.2 39230.4 39532.2 39631.2 39636.8 39674.2 3214.2 3268.3 3478.8 3859.3 3993.4 29496.5 30121.4 30629.2 31429.8 31439.5 3091.6 3124.3 3274.4 3316.4 3320.4 414896.0 419229.8 487038.0 495165.5 504530.0 61523.7 62723.0 63545.7 63615.0 63662.0 29784.4 34792.8 34932.5 35406.6 65849.0 15802.8 15962.8 15966.8 15976.6 15996.4 12151.0 12262.8 12276.9 12279.8 12334.3 16092.7 19414.8 19537.7 19562.0 19596.9 6950.0 8127.6 8270.4 8303.4 8378.0 956.3 960.6 1000.2 1012.7 1037.1 29718.0 29831.3 29900.4 30254.7 30445.2 969.4 984.4 1010.3 1013.2 1054.2 12852.5 13694.5 14616.4 15560.3 15821.8 654608.0 655628.0 659363.0 660933.0 662251.0 56582.7 57127.0 57521.7 57546.7 57566.7 1305.1 1331.9 1338.4 1350.1 1351.6 25467.7 28861.4 29754.9 29900.4 30120.8 53107.7 56276.3 56399.4 56521.0 56611.7 603372.0 608962.0 609867.4 610426.0 611861.0 26843.3 27023.5 27194.0 40277.0 40516.4 44997.0 45457.5 45672.8 45741.3 46277.8 38406.2 38700.0 39090.0 39210.8 40171.6 35507.3 38726.9 39666.2 39801.0 39959.8 36442.5 36603.0 36613.0 36691.1 37118.9 32071.0 33308.5 34584.8 34694.8 34836.5 24784.3 25099.2 25657.1 25711.0 25836.2 05:55:14 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33936.22 Median:2039.65 stddev:90350.34 (4429.7%) stddev-sans-first:90568.54 05:55:14 INFO - PROCESS | 1659 | Values: 319.3 350.8 365.9 372.4 402.3 13685.9 14286.4 15113.5 20001.7 20502.0 15242.7 18939.3 19968.9 20165.9 20202.9 5241.2 5523.2 5536.2 5538.4 5558.6 4621.8 5393.9 5484.7 6034.1 6103.2 6049.5 6060.8 6062.4 6062.8 6065.9 5436.6 5479.3 5483.3 5499.3 5686.1 5321.1 5330.6 5335.7 5340.2 5352.7 19390.1 23079.5 23971.8 24041.9 25060.9 2029.3 2108.7 2112.5 2322.0 2721.5 9468.2 9671.4 9805.5 9998.5 10357.2 1682.9 1802.8 1813.3 1823.7 1866.3 115507.0 135832.0 138446.3 138997.6 147634.0 18799.2 20121.7 20852.7 21411.9 21550.5 33187.0 34215.0 34672.1 34741.1 34751.0 10268.4 10649.3 10662.9 10689.7 10773.1 8725.2 8755.7 8780.1 8910.8 8938.0 7767.5 8829.5 9553.6 9706.5 9707.5 3149.2 3282.3 3706.9 3728.9 3798.1 696.3 706.2 787.3 827.6 863.4 15198.3 19642.5 21055.5 21253.0 21318.6 885.6 899.0 903.9 928.9 987.3 9301.7 10540.4 10560.7 11048.6 11113.7 529906.0 533518.0 540328.0 540617.0 546375.0 76909.0 93336.5 120875.4 122249.0 126398.4 926.3 955.9 973.1 978.9 980.9 14652.4 18085.4 18131.5 18477.9 19567.4 47385.8 53133.7 53703.0 53915.9 54908.5 73138.0 76138.6 76266.0 78354.0 80377.0 7865.3 8110.3 8176.5 8189.2 8372.7 12150.9 12728.4 13014.6 13039.3 13044.4 10844.5 10989.4 11303.2 11310.2 11383.5 9480.8 10563.5 10786.7 11139.7 11152.8 9728.3 11111.8 11159.6 11384.5 11559.5 6716.8 7974.7 8160.9 8226.6 8259.6 9370.3 11029.4 11069.9 11091.3 11197.2 05:55:14 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5360.09 Median:4926.27 stddev:2949.93 (59.9%) stddev-sans-first:2943.55 05:55:14 INFO - PROCESS | 1659 | Values: 1447.1 1470.2 1610.2 1762.7 1805.6 7916.2 8324.3 8336.7 8347.9 8349.3 7394.1 7615.5 7857.3 8102.4 8110.4 2259.6 2297.6 2297.8 2300.7 2303.8 2283.2 2331.2 2333.5 2337.2 2341.4 2276.5 2280.4 2306.4 2307.9 2313.9 2279.5 2302.7 2305.0 2306.0 2310.2 2278.2 2307.4 2310.1 2310.6 2313.8 7915.7 7933.4 7948.8 7957.7 7958.8 4910.1 5086.8 5088.5 5090.7 5095.3 3203.8 3280.3 3496.7 3515.6 3535.4 1027.3 1047.6 1060.7 1075.6 1080.2 10659.3 10709.3 10814.9 10825.7 10877.8 4431.7 4573.7 4683.8 4686.2 4690.5 9206.9 9208.3 9233.0 9237.7 9240.4 5152.2 5152.8 5153.1 5154.4 5155.3 3895.2 4135.9 4704.4 4722.6 4740.5 3070.9 3649.9 3713.6 3746.5 3755.5 1217.1 1220.1 1230.5 1236.9 1238.0 869.7 874.6 876.1 881.4 911.9 5394.5 5493.5 5546.1 5643.5 5715.4 863.2 953.3 954.7 977.2 1005.8 3482.2 3493.9 3764.5 3782.4 3782.6 9729.4 10173.2 10178.3 10180.2 10187.3 10116.7 10128.6 10129.8 10138.0 10139.7 7426.7 7675.2 7883.4 7890.1 7891.3 4942.4 5236.8 5298.2 5361.7 5448.9 9266.5 9331.6 9355.1 9449.2 9455.7 8907.6 9239.5 9354.3 9466.2 9472.8 6579.1 6764.5 6782.3 6873.5 7126.0 7615.4 7749.1 7866.8 7870.4 7924.3 6857.7 7037.1 7070.8 7185.6 7246.6 6879.4 6913.7 6937.3 6957.9 7020.4 6366.4 6407.4 6483.8 6500.4 6516.8 4687.7 4725.8 4778.4 4803.0 4890.0 4359.7 4397.1 4436.5 4439.7 4566.1 05:55:14 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4466.60 Median:4985.04 stddev:1384.19 (27.8%) stddev-sans-first:1352.26 05:55:14 INFO - PROCESS | 1659 | Values: 298.3 304.9 308.8 311.0 313.9 4035.6 4315.8 4994.9 5149.7 5277.4 4581.6 5210.8 5213.2 5217.4 5240.3 3533.3 3708.4 3792.6 3881.7 4003.7 3954.0 3973.7 3986.6 3991.3 4060.1 3910.2 3925.8 3928.9 3931.8 3966.7 3592.5 3937.4 3939.2 3940.7 3947.2 3070.5 3417.7 3469.4 3474.1 3488.5 4471.0 4740.2 5058.6 5079.7 5086.6 4397.6 4552.5 4573.7 4586.6 4588.3 5454.3 5459.3 5469.8 5486.2 5493.4 3824.0 3825.7 3825.8 3831.2 3856.6 4768.5 4770.9 5508.4 5768.9 5796.7 3822.4 3886.3 3896.6 3898.3 3907.8 3367.0 3391.3 3935.5 3957.6 3963.4 3815.7 4201.0 4523.2 4525.0 4570.5 4533.7 5194.7 5513.6 5538.3 5542.9 1206.4 1306.7 1326.5 1342.5 1351.0 789.6 797.5 817.4 829.3 833.7 4956.1 5069.3 5073.3 5114.7 5195.8 4668.4 4975.2 5483.6 5657.8 5760.6 4251.4 4945.8 5210.4 5236.4 5257.1 2439.4 2921.6 2961.6 2965.9 2975.5 5856.6 5875.7 5877.3 5890.6 5923.5 5478.3 5778.5 5812.7 5855.6 5928.4 5882.8 5908.4 5919.8 5942.7 5944.0 4917.7 5663.3 5714.9 5716.7 5765.7 5612.6 5647.9 5655.2 5667.0 5669.7 5213.4 5916.4 5927.3 5942.9 5951.2 3721.9 3809.7 3821.4 3826.5 3839.8 5708.3 5711.2 5735.5 5736.8 5850.2 4449.5 4930.2 5511.1 5579.6 5579.9 4622.8 4701.9 5158.3 5304.5 5524.8 5009.3 5419.2 5468.3 5472.0 5499.1 4440.5 4998.5 5450.3 5492.9 5514.4 4600.5 4782.0 5411.8 5424.5 5444.2 05:55:14 INFO - PROCESS | 1659 | 05:55:14 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:20295.19 Median:609.05 stddev:30084.72 (4939.6%) stddev-sans-first:30133.17 05:55:14 INFO - PROCESS | 1659 | Values: 708.5 736.4 760.2 780.5 793.6 14998.7 15181.5 15183.4 15208.5 15627.0 13360.0 13508.3 13510.5 13525.3 13533.9 662.4 682.8 807.8 817.9 836.1 822.0 827.9 830.3 835.1 835.7 894.2 897.8 897.9 898.0 901.5 695.7 890.6 892.5 892.9 893.4 528.5 632.3 652.8 653.8 655.0 14607.3 14656.6 14657.9 14673.5 14678.0 11953.4 12807.8 13114.3 13485.3 13662.4 12156.7 12255.9 12281.6 12309.0 12377.6 4375.3 4430.0 4450.7 4454.7 4655.8 89476.7 89698.2 90591.0 91735.0 92442.0 10627.8 10852.8 10903.9 10946.0 11021.0 15645.9 20780.7 20828.7 20829.0 21197.5 7271.1 7597.1 7639.9 7672.0 7692.7 4062.5 4095.0 4122.5 4201.7 4212.4 3293.4 3365.1 3382.9 3398.6 3406.4 614.6 620.8 620.9 626.0 636.0 524.5 603.7 614.4 615.4 627.8 7254.9 8396.7 8751.7 8843.4 8891.0 578.7 598.6 619.9 626.8 643.8 4047.4 4092.3 4311.0 4373.6 4434.6 70620.0 92971.0 93665.0 108653.0 117230.8 96031.0 96827.0 97893.0 98417.0 101353.5 88801.6 89573.0 89924.0 90706.0 91068.6 7042.0 8227.6 8264.7 8307.3 8487.6 55918.7 57293.1 57625.0 57885.4 59043.9 65599.7 75648.6 76354.6 80670.2 81544.6 10986.7 11000.7 11058.8 11395.2 11530.1 11806.9 11814.8 11859.7 11925.5 12400.6 11287.7 11317.9 11348.0 11642.2 11854.0 9348.5 10980.9 11191.9 11280.7 11385.3 9165.8 11105.3 11125.4 11131.9 11333.7 9445.4 9484.0 9609.8 9629.9 9682.1 8851.6 9549.7 9578.8 9751.1 9773.9 05:55:14 INFO - PROCESS | 1659 | -------- Summary: end -------- 05:55:14 INFO - PROCESS | 1659 | 05:55:15 INFO - TEST-INFO | 1659: exit 0 05:55:15 INFO - TEST-OK | dromaeo_css | took 1119552ms 05:55:15 INFO - TEST-START | kraken 05:55:15 INFO - operating with platform_type : mac_ 05:55:15 INFO - Initialising browser for kraken test... 05:55:15 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp6kKNxL/profile http://localhost:49274/getInfo.html) 05:55:17 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 05:55:17 INFO - PROCESS | 1665 | colorDepth:24 05:55:17 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 05:55:17 INFO - PROCESS | 1665 | __metrics 05:55:17 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:55:17 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:55:18 INFO - TEST-INFO | 1665: exit 0 05:55:18 INFO - Browser initialized. 05:55:18 INFO - Running cycle 1/1 for kraken test... 05:55:18 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp6kKNxL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:30 INFO - PROCESS | 1667 | RSS: Main: 239837184 05:55:30 INFO - PROCESS | 1667 | 05:55:33 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 05:55:34 INFO - PROCESS | 1667 | RSS: Main: 291168256 05:55:34 INFO - PROCESS | 1667 | 05:55:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 05:55:37 INFO - PROCESS | 1667 | RSS: Main: 289869824 05:55:37 INFO - PROCESS | 1667 | 05:55:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 05:55:41 INFO - PROCESS | 1667 | RSS: Main: 384192512 05:55:41 INFO - PROCESS | 1667 | 05:55:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 05:55:44 INFO - PROCESS | 1667 | RSS: Main: 287055872 05:55:44 INFO - PROCESS | 1667 | 05:55:46 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:55:47 INFO - PROCESS | 1667 | RSS: Main: 289087488 05:55:47 INFO - PROCESS | 1667 | 05:55:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 05:55:50 INFO - PROCESS | 1667 | RSS: Main: 327540736 05:55:50 INFO - PROCESS | 1667 | 05:55:53 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 05:55:54 INFO - PROCESS | 1667 | RSS: Main: 327335936 05:55:54 INFO - PROCESS | 1667 | 05:55:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 05:55:57 INFO - PROCESS | 1667 | RSS: Main: 325464064 05:55:57 INFO - PROCESS | 1667 | 05:55:58 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:55:59 INFO - PROCESS | 1667 | RSS: Main: 303374336 05:55:59 INFO - PROCESS | 1667 | 05:56:01 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:56:02 INFO - PROCESS | 1667 | RSS: Main: 302850048 05:56:02 INFO - PROCESS | 1667 | 05:56:04 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:56:04 INFO - PROCESS | 1667 | RSS: Main: 312299520 05:56:04 INFO - PROCESS | 1667 | 05:56:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:56:08 INFO - PROCESS | 1667 | RSS: Main: 326516736 05:56:08 INFO - PROCESS | 1667 | 05:56:11 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:56:12 INFO - PROCESS | 1667 | RSS: Main: 319942656 05:56:12 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 05:56:14 INFO - PROCESS | 1667 | RSS: Main: 363991040 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | __start_tp_report 05:56:14 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 05:56:14 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 05:56:14 INFO - PROCESS | 1667 | |i|pagename|runs| 05:56:14 INFO - PROCESS | 1667 | |0;ai-astar;112;112;113;112;112;113;113;112;113;111 05:56:14 INFO - PROCESS | 1667 | |1;audio-beat-detection;150;106;119;178;142;144;114;116;144;113 05:56:14 INFO - PROCESS | 1667 | |2;audio-dft;184;182;182;183;186;185;182;182;183;182 05:56:14 INFO - PROCESS | 1667 | |3;audio-fft;78;60;58;87;62;60;59;59;85;57 05:56:14 INFO - PROCESS | 1667 | |4;audio-oscillator;87;71;95;84;96;98;112;67;69;90 05:56:14 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;88;85;88;87;89;85;89;86;88;85 05:56:14 INFO - PROCESS | 1667 | |6;imaging-darkroom;93;93;119;149;94;93;106;150;119;93 05:56:14 INFO - PROCESS | 1667 | |7;imaging-desaturate;81;82;80;79;82;85;86;85;83;81 05:56:14 INFO - PROCESS | 1667 | |8;json-parse-financial;55;55;54;54;56;58;55;55;55;56 05:56:14 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;72;66;65;76;70;69;69;71;70;70 05:56:14 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;91;89;86;87;86;89;90;89;92;91 05:56:14 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;150;148;150;148;146;146;148;147;194;187 05:56:14 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;175;173;176;175;176;177;178;176;234;232 05:56:14 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;67;67;66;67;69 05:56:14 INFO - PROCESS | 1667 | __end_tp_report 05:56:14 INFO - PROCESS | 1667 | __start_cc_report 05:56:14 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1998 05:56:14 INFO - PROCESS | 1667 | __end_cc_report 05:56:14 INFO - PROCESS | 1667 | __startTimestamp1478004974288__endTimestamp 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | ------- Summary: start ------- 05:56:14 INFO - PROCESS | 1667 | Number of tests: 14 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:56:14 INFO - PROCESS | 1667 | Values: 112.0 112.0 113.0 112.0 112.0 113.0 113.0 112.0 113.0 111.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:143.00 stddev:22.63 (15.8%) stddev-sans-first:23.11 05:56:14 INFO - PROCESS | 1667 | Values: 150.0 106.0 119.0 178.0 142.0 144.0 114.0 116.0 144.0 113.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.00 stddev:1.45 (0.8%) stddev-sans-first:1.50 05:56:14 INFO - PROCESS | 1667 | Values: 184.0 182.0 182.0 183.0 186.0 185.0 182.0 182.0 183.0 182.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:66.50 Median:61.00 stddev:11.90 (19.5%) stddev-sans-first:11.87 05:56:14 INFO - PROCESS | 1667 | Values: 78.0 60.0 58.0 87.0 62.0 60.0 59.0 59.0 85.0 57.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:88.50 stddev:14.49 (16.4%) stddev-sans-first:15.37 05:56:14 INFO - PROCESS | 1667 | Values: 87.0 71.0 95.0 84.0 96.0 98.0 112.0 67.0 69.0 90.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.63 (1.9%) stddev-sans-first:1.69 05:56:14 INFO - PROCESS | 1667 | Values: 88.0 85.0 88.0 87.0 89.0 85.0 89.0 86.0 88.0 85.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:93.00 stddev:22.86 (24.6%) stddev-sans-first:23.31 05:56:14 INFO - PROCESS | 1667 | Values: 93.0 93.0 119.0 149.0 94.0 93.0 106.0 150.0 119.0 93.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:82.40 Median:82.50 stddev:2.32 (2.8%) stddev-sans-first:2.40 05:56:14 INFO - PROCESS | 1667 | Values: 81.0 82.0 80.0 79.0 82.0 85.0 86.0 85.0 83.0 81.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:1.16 (2.1%) stddev-sans-first:1.22 05:56:14 INFO - PROCESS | 1667 | Values: 55.0 55.0 54.0 54.0 56.0 58.0 55.0 55.0 55.0 56.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.05 (4.4%) stddev-sans-first:3.13 05:56:14 INFO - PROCESS | 1667 | Values: 72.0 66.0 65.0 76.0 70.0 69.0 69.0 71.0 70.0 70.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:89.50 stddev:2.11 (2.4%) stddev-sans-first:2.11 05:56:14 INFO - PROCESS | 1667 | Values: 91.0 89.0 86.0 87.0 86.0 89.0 90.0 89.0 92.0 91.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:156.40 Median:149.00 stddev:18.10 (12.1%) stddev-sans-first:19.05 05:56:14 INFO - PROCESS | 1667 | Values: 150.0 148.0 150.0 148.0 146.0 146.0 148.0 147.0 194.0 187.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.20 Median:176.50 stddev:24.18 (13.7%) stddev-sans-first:25.24 05:56:14 INFO - PROCESS | 1667 | Values: 175.0 173.0 176.0 175.0 176.0 177.0 178.0 176.0 234.0 232.0 05:56:14 INFO - PROCESS | 1667 | 05:56:14 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 05:56:14 INFO - PROCESS | 1667 | Values: 67.0 67.0 68.0 67.0 67.0 67.0 67.0 66.0 67.0 69.0 05:56:14 INFO - PROCESS | 1667 | -------- Summary: end -------- 05:56:14 INFO - PROCESS | 1667 | 05:56:15 INFO - TEST-INFO | 1667: exit 0 05:56:15 INFO - TEST-OK | kraken | took 59622ms 05:56:15 INFO - SUITE-END | took 1179s 05:56:15 INFO - Completed test suite (00:19:39) 05:56:15 INFO - javascript benchmark 05:56:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3279.2457734721015, 3283.3269179818153, 3291.9506207406894, 3326.1352048467393, 3346.748993825462, 5524.917126243109, 5588.273524441822, 5602.887942241153, 5644.8024319148835, 5651.9152212716735, 5620.1288800235925, 5641.830745077655, 5649.265595472593, 5651.40660230676, 5668.376478587363, 5082.517160869721, 5123.0, 5128.71792051437, 5144.09978253806, 5170.043541944733, 5132.23016547519, 5186.377634683843, 5199.844004679866, 5215.713135777531, 5216.921746173811, 5219.71291578963, 5234.947650523484, 5245.475452454743, 5297.205419187114, 5297.364316282052, 386.8549666954608, 5211.088059589576, 5236.423993360727, 5251.711155886424, 5287.629865909388, 3300.8149894990347, 5071.974640126776, 5126.077306084903, 5135.717535535544, 5219.42586315505, 4856.490068542787, 5247.737613119367, 5590.161475778625, 5681.772729090872, 5699.173619825144, 6126.29547602027, 6155.876882462326, 6166.167567378391, 6167.321594624587, 6174.382561743834, 6010.729517171694, 6069.757209711606, 6085.300190478063, 6097.101247991131, 6145.402195429176, 5663.1505274208375, 5663.8867222656145, 5667.348254950651, 5672.234248376416, 5673.744681489343, 5377.4084850666395, 6144.78493252734, 6265.937340626535, 6266.68666566679, 6273.341299163613, 5902.2032025676845, 5935.970320148372, 5948.910766338508, 5990.101484777319, 6064.423879731418, 6146.0473626587955, 6169.629822210682, 6198.535109866688, 6206.565540412128, 6213.409726076015, 6130.478909292671, 6138.508919286446, 6140.600860944024, 6141.83695396871, 6143.665407145256, 5995.970020149872, 6002.819915402545, 6058.818235452944, 6127.842042281593, 6136.163923949498, 4829.372181616438, 4836.709797412166, 4842.854714358575, 4843.07981483517, 4845.98234370779, 4391.363252328394, 4445.51099379068, 4448.132614140276, 4449.2658711312915, 4452.376667266585, 5643.322801263875, 5706.28671416073, 5714.228579141846, 5719.828405147852, 5737.913931291034, 6102.368068311607, 6114.145302971568, 6140.596275277816, 6144.187464697836, 6146.815595532141, 5741.62679425836, 5770.076787713946, 5817.418258174149, 5818.679972601463, 5819.418058194146, 5320.441353657888, 5321.73391330432, 5325.866853328698, 5330.9733451332495, 5331.866703332447, 5167.974160129175, 6212.937870621236, 6239.313675495692, 6277.843053923689, 6278.46633036197, 4843.660943733905, 6121.632702037892, 6134.8159555213415, 6154.59995100335, 6159.076138579249, 6330.968345158429, 6335.239771227482, 6340.524460665561, 6341.619502829845, 6352.142460767922, 6119.663418512025, 6132.0, 6136.570440069331, 6140.508759299156, 6161.661108639068, 6059.848503787264, 6073.453389194994, 6086.269647642312, 6213.65824879636, 6228.159198508143, 5971.28344598651, 5989.311229208697, 6013.398660134127, 6067.453929146398, 6141.570090093651, 5011.7494125294315, 6028.698565071817, 6030.185924900083, 6035.818925432244, 6039.184710064085, 4199.580041995776, 5813.128030795415, 5833.0, 5884.676342801187, 5885.499732522612, 5933.406659334032, 5953.613015154001, 5993.550483713651, 5995.790147344822, 6004.639721616717, 5859.94140058611, 5873.03094989339, 5886.323072846678, 5898.970505147447, 5920.259967504062], "value": 5558.874098043403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.96336293096533, 510.869728219304, 511.08260672093587, 530.1755769777993, 541.5396912624267, 29779.0, 31922.042338729818, 32036.35927281453, 40080.59919400805, 40797.59202407975, 41510.792446037805, 42082.57917420825, 42145.57854421455, 42149.36775948363, 42457.78771106148, 9353.345265831378, 9537.856932146024, 9541.618335266581, 9560.856587151198, 9573.521323933775, 9347.813043739121, 9458.905410945872, 9465.053494650549, 9469.621215151386, 9498.240140788721, 9431.292653051049, 9488.288378371615, 9498.572564234626, 9503.382280151767, 9505.00197479265, 9253.768655783659, 9409.95295023524, 9444.669436569688, 9469.384490008128, 9489.620415183419, 9258.490783006931, 9331.953340233324, 9347.719568412891, 9352.485613291266, 9364.204042656382, 39230.41154382686, 39532.20935581301, 39631.20737585261, 39636.80181599102, 39674.20651586952, 3214.164317277501, 3268.2619959282747, 3478.7564870459078, 3859.3053250414914, 3993.4209539616595, 29496.492798111973, 30121.397572048438, 30629.23426914345, 31429.84285078583, 31439.52840707391, 3091.6444608870042, 3124.3438877835683, 3274.4433446313947, 3316.403047451458, 3320.3857286402094, 414896.0, 419229.80770192214, 487038.0, 495165.52417238045, 504530.0, 61523.6923815378, 62723.0, 63545.682271588346, 63615.0, 63662.045069324, 29784.40431191364, 34792.82603586966, 34932.47601285983, 35406.64593354085, 65849.0, 15802.841971580367, 15962.840371596136, 15966.840331596768, 15976.600584985468, 15996.440124595583, 12151.027917766558, 12262.754744905053, 12276.877231227574, 12279.815802762965, 12334.259944403186, 16092.66656334825, 19414.819253045847, 19537.706934395996, 19562.0, 19596.902015490115, 6949.957506374084, 8127.59362031896, 8270.37972152091, 8303.377246706521, 8377.983925786712, 956.293688860523, 960.6061514778908, 1000.1798525209404, 1012.731626119079, 1037.0666400239845, 29718.0, 29831.311334519112, 29900.434207855506, 30254.697453025627, 30445.23886902801, 969.3795970578835, 984.3700031979539, 1010.3432768700403, 1013.2096964367805, 1054.2093429927554, 12852.48590056406, 13694.452221911211, 14616.415343386145, 15560.299786509635, 15821.762673559906, 654608.0, 655628.0, 659363.0, 660933.0, 662251.0, 56582.717086414305, 57127.0, 57521.71239143777, 57546.7122664384, 57566.7121664389, 1305.092960392518, 1331.9134256273312, 1338.3508998136092, 1350.1089281074444, 1351.5607427586272, 25467.74532254728, 28861.422771544872, 29754.851225743732, 29900.401991959603, 30120.849395753758, 53107.73446132899, 56276.31171064875, 56399.43600563942, 56521.0, 56611.71694141503, 603372.0, 608962.0, 609867.3586216882, 610426.0, 611861.0, 26843.32891677724, 27023.45953080888, 27194.0, 40276.993075172184, 40516.39225411562, 44997.0, 45457.5444348923, 45672.77163614161, 45741.313880291156, 46277.76861115673, 38406.23187536289, 38700.0, 39090.0, 39210.80394598123, 40171.598284016785, 35507.289854203285, 38726.940848209364, 39666.206675866895, 39801.0, 39959.80020099881, 36442.453363200104, 36603.0, 36613.0, 36691.08272293214, 37118.88643340704, 32071.0, 33308.50037249492, 34584.82707586446, 34694.82652586721, 34836.477452837724, 24784.25647230586, 25099.247022589352, 25657.12514622216, 25711.0, 25836.224913252634], "value": 19719.121701724092, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.3262216722714, 350.84738138909563, 365.90669379308287, 372.3799873211104, 402.27389561840886, 13685.93157034216, 14286.357113929873, 15113.471028514005, 20001.699974500396, 20502.0, 15242.695146097072, 18939.33712320069, 19968.900155499203, 20165.899170504163, 20202.888841113734, 5241.18761591952, 5523.226748255248, 5536.2249285100115, 5538.446155384469, 5558.583106267046, 4621.768911554425, 5393.894251678396, 5484.725763711798, 6034.094885767148, 6103.23709536308, 6049.455549000589, 6060.787872424466, 6062.363451837559, 6062.818115456521, 6065.909011364833, 5436.646617969819, 5479.260299859548, 5483.314585676791, 5499.285092937933, 5686.09022556391, 5321.122014867542, 5330.600204984642, 5335.653182543122, 5340.225667278223, 5352.732363381815, 19390.127444265043, 23079.53840923189, 23971.760282397303, 24041.87979060111, 25060.874695626586, 2029.289748587997, 2108.68369744538, 2112.545802652437, 2322.001539338084, 2721.523733346676, 9468.242540596735, 9671.419714817062, 9805.509724513746, 9998.500074996295, 10357.171426285879, 1682.9495115146565, 1802.774653168354, 1813.2565648084221, 1823.717323814811, 1866.2814816295688, 115507.0, 135832.0, 138446.30776846153, 138997.61002389947, 147633.99999999892, 18799.248030078645, 20121.698174527395, 20852.687209691867, 21411.92940352976, 21550.49146559757, 33187.0, 34214.97355079352, 34672.133196670286, 34741.13147171341, 34751.0, 10268.435236062089, 10649.307794993301, 10662.946685266525, 10689.679309620724, 10773.138148948065, 8725.21473067427, 8755.693550725695, 8780.07809180027, 8910.777230569287, 8937.955310223408, 7767.495112817649, 8829.54312538424, 9553.608468993547, 9706.514674266258, 9707.495338222587, 3149.1812128846664, 3282.3107147498813, 3706.9258614827554, 3728.9067773305883, 3798.0884587699306, 696.3245651717825, 706.1710170657918, 787.271773609409, 827.6234313387395, 863.3999370437485, 15198.316075776616, 19642.47468483678, 21055.473613159793, 21253.0, 21318.573628527654, 885.6191837509804, 898.9910100899039, 903.8779764731809, 928.8792456980549, 987.318750062469, 9301.674441394518, 10540.420276884692, 10560.735981600521, 11048.558057677763, 11113.666590002313, 529906.0, 533518.0, 540328.0, 540617.0, 546375.0, 76909.0, 93336.53307735366, 120875.39562302132, 122249.0, 126398.36800815938, 926.2682480840061, 955.8948515663271, 973.0561355485167, 978.8972157923315, 980.8626792249091, 14652.413903443743, 18085.36701215451, 18131.54671133232, 18477.9076104624, 19567.412977610125, 47385.763071184425, 53133.734331328094, 53703.0, 53915.921681566935, 54908.45091549193, 73138.0, 76138.61930690533, 76266.0, 78354.0, 80377.0, 7865.292123708665, 8110.31062359706, 8176.46852954556, 8189.181081892001, 8372.748817535483, 12150.939245303716, 12728.363581821057, 13014.609561713163, 13039.348032598522, 13044.413001414769, 10844.45777711127, 10989.395583242998, 11303.208775385645, 11310.208285419942, 11383.487743051417, 9480.810383792148, 10563.52464139131, 10786.73033174177, 11139.665810025712, 11152.776944461228, 9728.319017668695, 11111.83332250033, 11159.609413670483, 11384.48769805344, 11559.479823407799, 6716.798496045127, 7974.681012759424, 8160.918390816016, 8226.588670566567, 8259.587020648823, 9370.25037996945, 11029.393383363991, 11069.948354906272, 11091.33451992883, 11197.216194866607], "value": 10034.535998983447, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.1389523233674, 1470.2281302316285, 1610.1627153879983, 1762.6562820250056, 1805.647898659762, 7916.247956444143, 8324.250817426431, 8336.666533338674, 8347.91652083479, 8349.290310323622, 7394.149672787631, 7615.543067415959, 7857.2535609117185, 8102.351811855052, 8110.351171906248, 2259.593273210817, 2297.6438652008965, 2297.8391512594126, 2300.66640337151, 2303.79265866072, 2283.246528645543, 2331.1607821184443, 2333.53329334133, 2337.240396871015, 2341.438054866828, 2276.4536511237347, 2280.372897453197, 2306.411864974429, 2307.9423014424606, 2313.8958746856347, 2279.5326957973575, 2302.689136966505, 2304.965425518627, 2305.965410518844, 2310.2260742651233, 2278.1798552521077, 2307.4346785037606, 2310.1336998625516, 2310.5609934112463, 2313.826463015264, 7915.683372665087, 7933.365330773524, 7948.8410231795615, 7957.721479748181, 7958.840823183562, 4910.116179087763, 5086.847394578169, 5088.465711100318, 5090.720010399426, 5095.286659867622, 3203.7917535360125, 3280.343931213743, 3496.667816557423, 3515.5781306243157, 3535.4166562517153, 1027.255239951037, 1047.5600247895902, 1060.6977011551671, 1075.5912753153752, 1080.2114456446839, 10659.253852230348, 10709.25035247533, 10814.891851081546, 10825.67522974312, 10877.83683244752, 4431.7340959542535, 4573.725576465423, 4683.765811709401, 4686.203345431285, 4690.53094690535, 9206.90793092074, 9208.26333893287, 9233.0, 9237.722868313962, 9240.3531752778, 5152.227165925066, 5152.768125434364, 5153.123968925292, 5154.433012368637, 5155.32980712505, 3895.2014836958356, 4135.855245066408, 4704.388429504143, 4722.645801564897, 4740.45484769249, 3070.8618112185004, 3649.9270014600093, 3713.591504934455, 3746.456763769238, 3755.5493340798735, 1217.0811037666683, 1220.0666490135054, 1230.5241674548297, 1236.9443375048145, 1237.975240495185, 869.7043005378075, 874.5758307220992, 876.0626130040795, 881.3698205782896, 911.8677791720163, 5394.460553944653, 5493.450654934554, 5546.112621980464, 5643.548516118701, 5715.371309155989, 863.248973393152, 953.2602999525893, 954.6706386296717, 977.2035790830593, 1005.8483036922829, 3482.199094208346, 3493.9126521836647, 3764.5294338207723, 3782.394816829294, 3782.5650050244576, 9729.367591106555, 10173.237007224488, 10178.287519873538, 10180.236482263712, 10187.286889917783, 10116.696499105039, 10128.59485620567, 10129.848052279222, 10138.03688649577, 10139.7465063374, 7426.6632006238315, 7675.232476752391, 7883.408744344082, 7890.092639346434, 7891.250331218525, 4942.4316203637045, 5236.842894713165, 5298.205269209495, 5361.678299302054, 5448.918266226089, 9266.536673166449, 9331.580078896603, 9355.06449355059, 9449.196818270524, 9455.716328510156, 8907.643694252158, 9239.491827949527, 9354.25165986733, 9466.195373393064, 9472.763180920532, 6579.078928950046, 6764.4588432924265, 6782.25395206517, 6873.518853680194, 7126.002359669754, 7615.42884283692, 7749.070111586646, 7866.842663146819, 7870.449068565145, 7924.286814186751, 6857.657117144023, 7037.085178926707, 7070.823229419306, 7185.604791736505, 7246.565206087652, 6879.380855723009, 6913.723451061901, 6937.306269373023, 6957.8956315654295, 7020.438364930691, 6366.363363663597, 6407.3592640737415, 6483.837904052248, 6500.447461965786, 6516.771912983023, 4687.671862969696, 4725.810967561259, 4778.354922085474, 4803.015381846643, 4890.0, 4359.738415695068, 4397.054633253866, 4436.489803672619, 4439.667024973075, 4566.109608626286], "value": 4355.235186427074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.2989973562129, 304.9252933031408, 308.80545256488415, 311.02803738317755, 313.86660669215576, 4035.55608883023, 4315.791578358061, 4994.926090890458, 5149.665271757333, 5277.445868183843, 4581.564751348625, 5210.817621383252, 5213.218017297408, 5217.399999000116, 5240.29256050434, 3533.2580158166816, 3708.4437334399854, 3792.563855156651, 3881.6700580450697, 4003.699722520808, 3954.0, 3973.708544722962, 3986.5614782373914, 3991.261616600938, 4060.127072679373, 3910.178862438877, 3925.764454132747, 3928.921421571567, 3931.8034098295116, 3966.682665386762, 3592.515010473579, 3937.370020796659, 3939.231849789278, 3940.724149309564, 3947.210557888405, 3070.5394190871325, 3417.726581873444, 3469.4499542835997, 3474.1488335357744, 3488.5173801134415, 4471.038726673781, 4740.217864052427, 5058.595312375001, 5079.74601269935, 5086.618503612207, 4397.582229688206, 4552.453705555321, 4573.6569757268335, 4586.633069354443, 4588.311753237008, 5454.300121439913, 5459.290292262021, 5469.835904922858, 5486.189557446033, 5493.395726470085, 3824.0248736572275, 3825.7321987460887, 3825.827837747308, 3831.233753249361, 3856.570360672786, 4768.451628062782, 4770.8750768246855, 5508.366537848158, 5768.90390825746, 5796.739146738407, 3822.426636004566, 3886.3393223152134, 3896.6298201670797, 3898.2983063048355, 3907.824147913294, 3366.9730732126936, 3391.2878295558207, 3935.4883865097927, 3957.6438120569287, 3963.4344433948427, 3815.6756675682877, 4201.033762234642, 4523.190723710542, 4525.004499010212, 4570.542945705402, 4533.682642215013, 5194.68831870089, 5513.641613295123, 5538.255449533384, 5542.861428464321, 1206.4148887789415, 1306.6929271621161, 1326.5025615394227, 1342.543535198018, 1350.9797353039712, 789.6170357376668, 797.5294576199954, 817.4032955942196, 829.3365307753772, 833.7285639399843, 4956.067133817814, 5069.290299358093, 5073.264376665436, 5114.698385726364, 5195.844124676266, 4668.393108895822, 4975.154223781966, 5483.6405057979, 5657.830265092054, 5760.596758226968, 4251.404803327537, 4945.774401519331, 5210.374755029421, 5236.39781425141, 5257.106291930381, 2439.42673471734, 2921.5617657351568, 2961.5784423476853, 2965.8961936332125, 2975.497373826204, 5856.619319744288, 5875.707344384228, 5877.265341832271, 5890.646561206341, 5923.526117910643, 5478.315625343553, 5778.521645724597, 5812.709364531756, 5855.619384739978, 5928.407159284124, 5882.794102206402, 5908.379620139909, 5919.763209471573, 5942.741758131882, 5944.0, 4917.655764096479, 5663.32040155184, 5714.885702285847, 5716.714164291686, 5765.711714414347, 5612.579056570692, 5647.887042259214, 5655.208270842168, 5666.9716651416475, 5669.688167150846, 5213.426523082382, 5916.437938395846, 5927.251460818982, 5942.870854537624, 5951.226340575698, 3721.9255614888093, 3809.6761775249415, 3821.350370436977, 3826.483424737625, 3839.846406143723, 5708.343540492897, 5711.200971693903, 5735.4551317624755, 5736.799212027559, 5850.23946886902, 4449.466064072332, 4930.211166213388, 5511.114664161676, 5579.637323573955, 5579.944200558105, 4622.838200662961, 4701.858944231679, 5158.252053452304, 5304.469553044664, 5524.83978364548, 5009.269947878488, 5419.2413062170535, 5468.26178465902, 5471.987682278791, 5499.1476321170285, 4440.511543730252, 4998.500149985118, 5450.337647017697, 5492.917606235989, 5514.420985796594, 4600.516945720785, 4781.976090119527, 5411.756470958895, 5424.457554244702, 5444.183372494158], "value": 4023.2148557920113, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.5429897715974, 736.4476642518111, 760.1600231743923, 780.4575819805231, 793.6190628498323, 14998.650121489063, 15181.544553663394, 15183.392664293442, 15208.467703630375, 15627.0, 13360.0, 13508.324583770822, 13510.527131550398, 13525.323733813295, 13533.932330338335, 662.3773652766408, 682.8327059870338, 807.7738233294657, 817.9182081791832, 836.1011912194385, 821.9917800821975, 827.9130691277419, 830.2942498875967, 835.1356346181703, 835.6532039203723, 894.2354287084546, 897.8024834536392, 897.9191872731453, 897.964081436745, 901.535709109809, 695.6834640238679, 890.6081324217356, 892.482360231071, 892.8749975003504, 893.4237416866085, 528.5375296615462, 632.2886752403546, 652.797632733854, 653.8463461086641, 655.0, 14607.269636518131, 14656.560303190814, 14657.853421465756, 14673.486427974969, 14678.0, 11953.40232988347, 12807.807882881763, 13114.278714670689, 13485.258310792902, 13662.385192666354, 12156.696082597917, 12255.93872030643, 12281.57014504497, 12309.0, 12377.566785162477, 4375.343698445226, 4429.977850110761, 4450.666200035011, 4454.710443821174, 4655.767211639439, 89476.6578501323, 89698.16961897456, 90591.0, 91735.0, 92442.0, 10627.787444251226, 10852.782944341227, 10903.890961090447, 10946.0, 11021.008109270044, 15645.921770391304, 20780.688289675665, 20828.687569686466, 20829.0, 21197.470063248235, 7271.054762880897, 7597.126330471956, 7639.885401718979, 7672.0409948756405, 7692.653830577638, 4062.532808727034, 4094.959050409517, 4122.505299364036, 4201.663866890703, 4212.389203565466, 3293.357795229907, 3365.1082463146795, 3382.9323413531592, 3398.558187435667, 3406.4379377402947, 614.6158650843223, 620.770314983459, 620.9441150296495, 625.956183067181, 635.9682015899186, 524.538406202547, 603.6740160313382, 614.4316507230793, 615.3846153846154, 627.8156089662349, 7254.854902902018, 8396.748097557083, 8751.693690720922, 8843.425177363579, 8890.955545222232, 578.6701580099387, 598.5839841310247, 619.9008158694677, 626.7931582577738, 643.7521554201666, 4047.3928910663635, 4092.324766413568, 4310.956890431118, 4373.6282415994365, 4434.558768400283, 70620.0, 92971.0, 93665.0, 108653.0, 117230.82769172199, 96031.0, 96827.0, 97893.0, 98417.0, 101353.49323253483, 88801.55599221833, 89573.0, 89924.0, 90706.0, 91068.6339704905, 7042.0, 8227.588620569068, 8264.669413223644, 8307.335413166811, 8487.55330754721, 55918.720406399334, 57293.140602890155, 57625.0, 57885.421145788, 59043.87871055195, 65599.68800623865, 75648.62175689086, 76354.61822690851, 80670.19329806627, 81544.59227703823, 10986.670399888017, 11000.724981875517, 11058.834117488404, 11395.202335836744, 11530.135239857213, 11806.937375636235, 11814.763704725685, 11859.70350741204, 11925.522979080739, 12400.62798116058, 11287.661370158909, 11317.886821132013, 11348.0, 11642.185047046625, 11853.992410645193, 9348.485833279236, 10980.945095274472, 11191.944040280072, 11280.717982050515, 11385.259958103028, 9165.762622046204, 11105.278156919774, 11125.44372781374, 11131.888681113409, 11333.716657083638, 9445.433274003582, 9484.0, 9609.759756006155, 9629.855552166582, 9682.080202380763, 8851.64840642418, 9549.681743372177, 9578.760530986781, 9751.073648003428, 9773.85339219898], "value": 7086.969507847001, "unit": "score"}], "name": "dromaeo_css", "value": 7176.473098071752, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 113.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0, 111.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 106.0, 119.0, 178.0, 142.0, 144.0, 114.0, 116.0, 144.0, 113.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 182.0, 183.0, 186.0, 185.0, 182.0, 182.0, 183.0, 182.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 60.0, 58.0, 87.0, 62.0, 60.0, 59.0, 59.0, 85.0, 57.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 95.0, 84.0, 96.0, 98.0, 112.0, 67.0, 69.0, 90.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 87.0, 89.0, 85.0, 89.0, 86.0, 88.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 119.0, 149.0, 94.0, 93.0, 106.0, 150.0, 119.0, 93.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 79.0, 82.0, 85.0, 86.0, 85.0, 83.0, 81.0], "value": 82.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 54.0, 56.0, 58.0, 55.0, 55.0, 55.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 66.0, 65.0, 76.0, 70.0, 69.0, 69.0, 71.0, 70.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 86.0, 87.0, 86.0, 89.0, 90.0, 89.0, 92.0, 91.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 150.0, 148.0, 146.0, 146.0, 148.0, 147.0, 194.0, 187.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 173.0, 176.0, 175.0, 176.0, 177.0, 178.0, 176.0, 234.0, 232.0], "value": 187.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0, 69.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1486.6, "alertThreshold": 2.0}]} 05:56:15 INFO - Return code: 0 05:56:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:56:15 INFO - # TBPL SUCCESS # 05:56:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:56:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:56:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:56:15 INFO - 2016-11-01 05:56:15.603 screenresolution[1671:10529] starting screenresolution argv=screenresolution get 05:56:15 INFO - 2016-11-01 05:56:15.606 screenresolution[1671:10529] Display 0: 1600x1200x32@60 05:56:15 INFO - 2016-11-01 05:56:15.612 screenresolution[1672:10532] starting screenresolution argv=screenresolution list 05:56:15 INFO - Available Modes on Display 0 05:56:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:56:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:56:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:56:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:56:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:56:15 INFO - Intel Iris: 05:56:15 INFO - Chipset Model: Intel Iris 05:56:15 INFO - Type: GPU 05:56:15 INFO - Bus: Built-In 05:56:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:56:15 INFO - Vendor: Intel (0x8086) 05:56:15 INFO - Device ID: 0x0a2e 05:56:15 INFO - Revision ID: 0x0009 05:56:15 INFO - Displays: 05:56:15 INFO - Display: 05:56:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:56:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:56:15 INFO - Display Serial Number: 1600x1200 60 05:56:15 INFO - Main Display: Yes 05:56:15 INFO - Mirror: Off 05:56:15 INFO - Online: Yes 05:56:15 INFO - Rotation: Supported 05:56:15 INFO - Return code: 0 05:56:15 INFO - Running post-action listener: _resource_record_post_action 05:56:15 INFO - [mozharness: 2016-11-01 12:56:15.847677Z] Finished run-tests step (success) 05:56:15 INFO - Running post-run listener: _resource_record_post_run 05:56:15 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 33139712; Write bytes: 712723456; Read time: 378; Write time: 2398 05:56:15 INFO - TinderboxPrint: CPU usage
53.5% 05:56:15 INFO - TinderboxPrint: I/O read bytes / time
33,139,712 / 378 05:56:15 INFO - TinderboxPrint: I/O write bytes / time
712,723,456 / 2,398 05:56:15 INFO - TinderboxPrint: CPU idle
2,262.5 (46.5%) 05:56:15 INFO - TinderboxPrint: CPU system
972.1 (20.0%) 05:56:15 INFO - TinderboxPrint: CPU user
1,627.1 (33.5%) 05:56:15 INFO - TinderboxPrint: Swap in / out
436,453,376 / 0 05:56:15 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 406342144; Write bytes: 401352704; Read time: 30929; Write time: 792 05:56:15 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31022080; Write bytes: 300958720; Read time: 354; Write time: 1570 05:56:16 INFO - Running post-run listener: _upload_blobber_files 05:56:16 INFO - Blob upload gear active. 05:56:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:56:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:56:16 INFO - (blobuploader) - INFO - Open directory for files ... 05:56:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:56:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:56:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:56:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:56:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:56:17 INFO - (blobuploader) - INFO - Done attempting. 05:56:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:56:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:56:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:56:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:56:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:56:18 INFO - (blobuploader) - INFO - Done attempting. 05:56:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:56:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:56:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:56:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:56:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:56:20 INFO - (blobuploader) - INFO - Done attempting. 05:56:20 INFO - (blobuploader) - INFO - Iteration through files over. 05:56:20 INFO - Return code: 0 05:56:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:56:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:56:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/729a05d56ad8b2d41372ca01cdb31ab7dc1d48d1f0a9d379a11ec6530373554d942c0c0ca3d7d821e91ebceea56a8a0891eb25a5f0dcfbcf10bff60f87df1995", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b36ea16b0cfb1de210f7ae4c624897fccaac75c7500781099704258951763c05c1513000f2096a965cf3e0af9e513953a26a5a0a5465e3cc03290a958627d35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f7993077609050c35dba59dac9b000f3499f8d4a98f3ccb0c266e29edb757f95237c23dab644c6e2037ca8c68121b9220ef12e73c36a4ab9c95bc9317b60b94"} 05:56:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:56:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:56:20 INFO - Contents: 05:56:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/729a05d56ad8b2d41372ca01cdb31ab7dc1d48d1f0a9d379a11ec6530373554d942c0c0ca3d7d821e91ebceea56a8a0891eb25a5f0dcfbcf10bff60f87df1995", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b36ea16b0cfb1de210f7ae4c624897fccaac75c7500781099704258951763c05c1513000f2096a965cf3e0af9e513953a26a5a0a5465e3cc03290a958627d35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f7993077609050c35dba59dac9b000f3499f8d4a98f3ccb0c266e29edb757f95237c23dab644c6e2037ca8c68121b9220ef12e73c36a4ab9c95bc9317b60b94"} 05:56:20 INFO - Running post-run listener: copy_logs_to_upload_dir 05:56:20 INFO - Copying logs to upload dir... 05:56:20 INFO - mkdir: /builds/slave/test/build/upload/logs 05:56:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.335896 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-01 05:56:21.018379) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-01 05:56:21.021656) ========= 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.7MiSZ47KjF/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.YddoHksh2e/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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/729a05d56ad8b2d41372ca01cdb31ab7dc1d48d1f0a9d379a11ec6530373554d942c0c0ca3d7d821e91ebceea56a8a0891eb25a5f0dcfbcf10bff60f87df1995", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b36ea16b0cfb1de210f7ae4c624897fccaac75c7500781099704258951763c05c1513000f2096a965cf3e0af9e513953a26a5a0a5465e3cc03290a958627d35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f7993077609050c35dba59dac9b000f3499f8d4a98f3ccb0c266e29edb757f95237c23dab644c6e2037ca8c68121b9220ef12e73c36a4ab9c95bc9317b60b94"} build_url:https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010107 build_url: 'https://queue.taskcluster.net/v1/task/BztcTcezRRWryVyyvqVBAg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/729a05d56ad8b2d41372ca01cdb31ab7dc1d48d1f0a9d379a11ec6530373554d942c0c0ca3d7d821e91ebceea56a8a0891eb25a5f0dcfbcf10bff60f87df1995", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b36ea16b0cfb1de210f7ae4c624897fccaac75c7500781099704258951763c05c1513000f2096a965cf3e0af9e513953a26a5a0a5465e3cc03290a958627d35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f7993077609050c35dba59dac9b000f3499f8d4a98f3ccb0c266e29edb757f95237c23dab644c6e2037ca8c68121b9220ef12e73c36a4ab9c95bc9317b60b94"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-01 05:56:21.053677) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 05:56:21.054018) ========= 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.7MiSZ47KjF/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.YddoHksh2e/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.004997 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 05:56:21.103259) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-01 05:56:21.103653) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-01 05:56:21.103992) ========= ========= Total master_lag: 0.18 =========