builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0309 starttime: 1478885169.55 results: success (0) buildid: 20161111073320 builduid: 6040fd0114eb4b08a9086e42f87aaffb revision: d284cdb3ad6e0609a53a275df6efcff4255c30ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:09.555053) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:09.555550) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:09.555839) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:09.581044) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:09.581440) ========= 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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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-11 09:26:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-11-11 09:26:09 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081476 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:09.687194) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:09.687547) ========= 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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.025090 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:09.738452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-11 09:26:09.739024) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d284cdb3ad6e0609a53a275df6efcff4255c30ec --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 d284cdb3ad6e0609a53a275df6efcff4255c30ec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Adv6OaaYOY/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.qYqPgAgq5M/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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-11 09:26:09,826 truncating revision to first 12 chars 2016-11-11 09:26:09,826 Setting DEBUG logging. 2016-11-11 09:26:09,826 attempt 1/10 2016-11-11 09:26:09,826 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d284cdb3ad6e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-11 09:26:10,895 unpacking tar archive at: mozilla-central-d284cdb3ad6e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330225 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-11 09:26:11.094089) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:11.094636) ========= script_repo_revision: d284cdb3ad6e0609a53a275df6efcff4255c30ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:11.095277) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:11.095681) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:11.114236) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-11 09:26:11.114966) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:26:11 INFO - MultiFileLogger online at 20161111 09:26:11 in /builds/slave/test 09:26:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:26:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:11 INFO - {'append_to_log': False, 09:26:11 INFO - 'base_work_dir': '/builds/slave/test', 09:26:11 INFO - 'blob_upload_branch': 'Firefox', 09:26:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:11 INFO - 'branch': 'Firefox', 09:26:11 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:11 INFO - 'config_files': ('talos/mac_config.py',), 09:26:11 INFO - 'default_actions': ('clobber', 09:26:11 INFO - 'read-buildbot-config', 09:26:11 INFO - 'download-and-extract', 09:26:11 INFO - 'populate-webroot', 09:26:11 INFO - 'create-virtualenv', 09:26:11 INFO - 'install', 09:26:11 INFO - 'run-tests'), 09:26:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:11 INFO - 'download_minidump_stackwalk': True, 09:26:11 INFO - 'download_symbols': 'ondemand', 09:26:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:11 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:11 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:11 INFO - 'installer_path': 'installer.exe', 09:26:11 INFO - 'log_level': 'info', 09:26:11 INFO - 'log_name': 'talos', 09:26:11 INFO - 'log_to_console': True, 09:26:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:11 INFO - 'opt_config_files': (), 09:26:11 INFO - 'pip_index': False, 09:26:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:11 INFO - 'cmd': ('bash', 09:26:11 INFO - '-c', 09:26:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:11 INFO - 'enabled': True, 09:26:11 INFO - 'halt_on_failure': False, 09:26:11 INFO - 'name': 'check_screen_resolution'},), 09:26:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:11 INFO - 'cmd': ('bash', 09:26:11 INFO - '-c', 09:26:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:11 INFO - 'enabled': True, 09:26:11 INFO - 'halt_on_failure': False, 09:26:11 INFO - 'name': 'check_screen_resolution'},), 09:26:11 INFO - 'run_cmd_checks_enabled': True, 09:26:11 INFO - 'sps_profile': False, 09:26:11 INFO - 'sps_profile_interval': 0, 09:26:11 INFO - 'suite': 'dromaeojs-e10s', 09:26:11 INFO - 'system_bits': '32', 09:26:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:11 INFO - 'title': 't-yosemite-r7-0309', 09:26:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:11 INFO - 'use_talos_json': True, 09:26:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:11 INFO - 'work_dir': 'build'} 09:26:11 INFO - [mozharness: 2016-11-11 17:26:11.326022Z] Running clobber step. 09:26:11 INFO - Running pre-action listener: _resource_record_pre_action 09:26:11 INFO - Running main action method: clobber 09:26:11 INFO - rmtree: /builds/slave/test/build 09:26:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:13 INFO - Running post-action listener: _resource_record_post_action 09:26:13 INFO - [mozharness: 2016-11-11 17:26:13.469085Z] Finished clobber step (success) 09:26:13 INFO - [mozharness: 2016-11-11 17:26:13.469217Z] Running read-buildbot-config step. 09:26:13 INFO - Running pre-action listener: _resource_record_pre_action 09:26:13 INFO - Running main action method: read_buildbot_config 09:26:13 INFO - Using buildbot properties: 09:26:13 INFO - { 09:26:13 INFO - "project": "", 09:26:13 INFO - "product": "firefox", 09:26:13 INFO - "script_repo_revision": "production", 09:26:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:26:13 INFO - "repository": "", 09:26:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:26:13 INFO - "buildid": "20161111073320", 09:26:13 INFO - "pgo_build": "False", 09:26:13 INFO - "basedir": "/builds/slave/test", 09:26:13 INFO - "buildnumber": 91, 09:26:13 INFO - "slavename": "t-yosemite-r7-0309", 09:26:13 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:26:13 INFO - "platform": "yosemite_r7", 09:26:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:26:13 INFO - "branch": "mozilla-central", 09:26:13 INFO - "repo_path": "mozilla-central", 09:26:13 INFO - "revision": "d284cdb3ad6e0609a53a275df6efcff4255c30ec", 09:26:13 INFO - "stage_platform": "macosx64", 09:26:13 INFO - "builduid": "6040fd0114eb4b08a9086e42f87aaffb", 09:26:13 INFO - "slavebuilddir": "test" 09:26:13 INFO - } 09:26:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:26:13 INFO - Running post-action listener: _resource_record_post_action 09:26:13 INFO - [mozharness: 2016-11-11 17:26:13.472364Z] Finished read-buildbot-config step (success) 09:26:13 INFO - [mozharness: 2016-11-11 17:26:13.472459Z] Running download-and-extract step. 09:26:13 INFO - Running pre-action listener: _resource_record_pre_action 09:26:13 INFO - Running main action method: download_and_extract 09:26:13 INFO - mkdir: /builds/slave/test/build/tests 09:26:13 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')]} 09:26:13 INFO - trying https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:26:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/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 09:26:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/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 09:26:14 INFO - Downloaded 1199 bytes. 09:26:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:26:14 INFO - Using the following test package requirements: 09:26:14 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:26:14 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:26:14 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:26:14 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:26:14 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:26:14 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:26:14 INFO - u'jsshell-mac.zip'], 09:26:14 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:26:14 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:26:14 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:26:14 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:26:14 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:26:14 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:26:14 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:26:14 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:26:14 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:26:14 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:26:14 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:26:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:26:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:26:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:26:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:26:15 INFO - Http code: 200 09:26:15 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 09:26:15 INFO - x-amz-cf-id: c-0jPw5wnSTpQw-ooZbAH1f5TkYHPXO2CGS4xr6dOBwRLI3T0CCEOw== 09:26:15 INFO - x-amz-version-id: vXROQJYJuXB.KGH0mRw2YYG4ohYDhr4e 09:26:15 INFO - Expected file size: 16328452 09:26:15 INFO - Obtained file size: 16328452 09:26:22 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 09:26:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:26:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:26:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:26:22 INFO - Http code: 200 09:26:22 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 09:26:22 INFO - x-amz-cf-id: kCIVzTAOFhAh_IN9w3bJ7nLVllzr9FdSFOpkX0rA70Pc3_MY-UK0gQ== 09:26:22 INFO - x-amz-version-id: vXROQJYJuXB.KGH0mRw2YYG4ohYDhr4e 09:26:23 INFO - Expected file size: 16328452 09:26:23 INFO - Obtained file size: 16328452 09:26:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:26:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:26:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:26:30 INFO - Http code: 200 09:26:30 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 09:26:30 INFO - x-amz-cf-id: fmXGIWHvgCvzlpjhBBy8MEO8a2sWVOaZ-mF-WioJZjnOhktrtHt6WA== 09:26:30 INFO - x-amz-version-id: EwZ68B4deYmF.J6FNrwolsz4LpybQd66 09:26:30 INFO - Expected file size: 14409038 09:26:30 INFO - Obtained file size: 14409038 09:26:31 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')]} 09:26:31 INFO - trying https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:26:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/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 09:26:33 INFO - Downloaded 110901539 bytes. 09:26:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:26:33 INFO - mkdir: /builds/slave/test/properties 09:26:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:33 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:33 INFO - Contents: 09:26:33 INFO - build_url:https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:26:34 INFO - Running post-action listener: _resource_record_post_action 09:26:34 INFO - Running post-action listener: set_extra_try_arguments 09:26:34 INFO - [mozharness: 2016-11-11 17:26:34.573774Z] Finished download-and-extract step (success) 09:26:34 INFO - [mozharness: 2016-11-11 17:26:34.573930Z] Running populate-webroot step. 09:26:34 INFO - Running pre-action listener: _resource_record_pre_action 09:26:34 INFO - Running main action method: populate_webroot 09:26:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:34 INFO - '--tppagecycles', 09:26:34 INFO - '7'], 09:26:34 INFO - 'tests': ['tsvgm']}}, 09:26:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:26:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:34 INFO - 'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:34 INFO - 'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['damp', 'tps']}, 09:26:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:34 INFO - 'tests': ['damp', 'tps']}, 09:26:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['dromaeo_dom']}, 09:26:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['basic_compositor_video']}, 09:26:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:26:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['a11yr', 09:26:34 INFO - 'ts_paint', 09:26:34 INFO - 'tpaint', 09:26:34 INFO - 'sessionrestore', 09:26:34 INFO - 'sessionrestore_no_auto_restore', 09:26:34 INFO - 'tabpaint']}, 09:26:34 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:34 INFO - 'ts_paint', 09:26:34 INFO - 'tpaint', 09:26:34 INFO - 'sessionrestore', 09:26:34 INFO - 'sessionrestore_no_auto_restore', 09:26:34 INFO - 'tabpaint']}, 09:26:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:34 INFO - 'ts_paint', 09:26:34 INFO - 'tpaint', 09:26:34 INFO - 'sessionrestore', 09:26:34 INFO - 'sessionrestore_no_auto_restore', 09:26:34 INFO - 'tabpaint']}, 09:26:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:34 INFO - 'ts_paint', 09:26:34 INFO - 'tpaint', 09:26:34 INFO - 'sessionrestore', 09:26:34 INFO - 'sessionrestore_no_auto_restore', 09:26:34 INFO - 'tabpaint']}, 09:26:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['a11yr', 09:26:34 INFO - 'ts_paint', 09:26:34 INFO - 'tpaint', 09:26:34 INFO - 'sessionrestore', 09:26:34 INFO - 'sessionrestore_no_auto_restore', 09:26:34 INFO - 'tabpaint']}, 09:26:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['a11yr', 09:26:34 INFO - 'ts_paint', 09:26:34 INFO - 'tpaint', 09:26:34 INFO - 'sessionrestore', 09:26:34 INFO - 'sessionrestore_no_auto_restore', 09:26:34 INFO - 'tabpaint']}, 09:26:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['tsvgx', 09:26:34 INFO - 'tsvgr_opacity', 09:26:34 INFO - 'tart', 09:26:34 INFO - 'tscrollx', 09:26:34 INFO - 'cart']}, 09:26:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:34 INFO - 'tsvgr_opacity', 09:26:34 INFO - 'tart', 09:26:34 INFO - 'tscrollx', 09:26:34 INFO - 'cart']}, 09:26:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:34 INFO - 'talos_options': ['--disable-e10s'], 09:26:34 INFO - 'tests': ['tp5o']}, 09:26:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:34 INFO - 'tests': ['tp5o']}, 09:26:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:34 INFO - 'talos_options': ['--disable-e10s', 09:26:34 INFO - '--xperf_path', 09:26:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:34 INFO - 'tests': ['tp5n']}, 09:26:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:34 INFO - 'talos_options': ['--xperf_path', 09:26:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:34 INFO - 'tests': ['tp5n']}}, 09:26:34 INFO - 'talos.zip': {'path': '', 09:26:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:34 INFO - Running post-action listener: _resource_record_post_action 09:26:34 INFO - [mozharness: 2016-11-11 17:26:34.592523Z] Finished populate-webroot step (success) 09:26:34 INFO - [mozharness: 2016-11-11 17:26:34.592613Z] Running create-virtualenv step. 09:26:34 INFO - Running pre-action listener: _resource_record_pre_action 09:26:34 INFO - Running main action method: create_virtualenv 09:26:34 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:34 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 09:26:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:34 INFO - Using real prefix '/tools/python27' 09:26:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:35 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:38 INFO - Installing pip.................done. 09:26:38 INFO - Return code: 0 09:26:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:26:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:26:38 INFO - Reading from file tmpfile_stdout 09:26:38 INFO - Output received: 09:26:38 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) 09:26:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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')]} 09:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:26: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', 'pip>=1.5'] in /builds/slave/test/build 09:26: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 pip>=1.5 09:26:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:26:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:38 INFO - 'HOME': '/Users/cltbld', 09:26:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:38 INFO - 'LOGNAME': 'cltbld', 09:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:38 INFO - 'MOZ_NO_REMOTE': '1', 09:26:38 INFO - 'NO_EM_RESTART': '1', 09:26:38 INFO - 'PAGER': '/bin/cat', 09:26:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:38 INFO - 'PWD': '/builds/slave/test', 09:26:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:38 INFO - 'SHELL': '/bin/bash', 09:26:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:26:38 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:26:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:38 INFO - 'USER': 'cltbld', 09:26:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:38 INFO - 'XPC_FLAGS': '0x0', 09:26:38 INFO - 'XPC_SERVICE_NAME': '0', 09:26:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:38 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 09:26:38 INFO - Cleaning up... 09:26:38 INFO - Return code: 0 09:26:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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')]} 09:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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', '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 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:26: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:26: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 psutil>=3.1.1 09:26:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:26:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:38 INFO - 'HOME': '/Users/cltbld', 09:26:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:38 INFO - 'LOGNAME': 'cltbld', 09:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:38 INFO - 'MOZ_NO_REMOTE': '1', 09:26:38 INFO - 'NO_EM_RESTART': '1', 09:26:38 INFO - 'PAGER': '/bin/cat', 09:26:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:38 INFO - 'PWD': '/builds/slave/test', 09:26:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:38 INFO - 'SHELL': '/bin/bash', 09:26:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:26:38 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:26:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:38 INFO - 'USER': 'cltbld', 09:26:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:38 INFO - 'XPC_FLAGS': '0x0', 09:26:38 INFO - 'XPC_SERVICE_NAME': '0', 09:26:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:39 INFO - Downloading/unpacking psutil>=3.1.1 09:26:39 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 09:26:39 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 09:26:39 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 09:26:39 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 09:26:39 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 09:26:39 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 09:26:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:42 INFO - Installing collected packages: psutil 09:26:42 INFO - Running setup.py install for psutil 09:26:42 INFO - building 'psutil._psutil_osx' extension 09:26:42 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 09:26:43 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 09:26:43 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 09:26:43 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 09:26:43 INFO - building 'psutil._psutil_posix' extension 09:26:43 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 09:26:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:26:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:26:43 INFO - ^ 09:26:43 INFO - 1 warning generated. 09:26:43 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 09:26:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:43 INFO - Successfully installed psutil 09:26:43 INFO - Cleaning up... 09:26:43 INFO - Return code: 0 09:26:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:43 INFO - 'HOME': '/Users/cltbld', 09:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:43 INFO - 'LOGNAME': 'cltbld', 09:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:43 INFO - 'MOZ_NO_REMOTE': '1', 09:26:43 INFO - 'NO_EM_RESTART': '1', 09:26:43 INFO - 'PAGER': '/bin/cat', 09:26:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:43 INFO - 'PWD': '/builds/slave/test', 09:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:43 INFO - 'SHELL': '/bin/bash', 09:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:26:43 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:43 INFO - 'USER': 'cltbld', 09:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:43 INFO - 'XPC_FLAGS': '0x0', 09:26:43 INFO - 'XPC_SERVICE_NAME': '0', 09:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:26:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:26:46 INFO - Installing collected packages: mozsystemmonitor 09:26:46 INFO - Running setup.py install for mozsystemmonitor 09:26:46 INFO - Successfully installed mozsystemmonitor 09:26:46 INFO - Cleaning up... 09:26:46 INFO - Return code: 0 09:26:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:26:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:26:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:26:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:26:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:26:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:46 INFO - 'HOME': '/Users/cltbld', 09:26:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:46 INFO - 'LOGNAME': 'cltbld', 09:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:46 INFO - 'MOZ_NO_REMOTE': '1', 09:26:46 INFO - 'NO_EM_RESTART': '1', 09:26:46 INFO - 'PAGER': '/bin/cat', 09:26:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:46 INFO - 'PWD': '/builds/slave/test', 09:26:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:46 INFO - 'SHELL': '/bin/bash', 09:26:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:26:46 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:26:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:46 INFO - 'USER': 'cltbld', 09:26:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:46 INFO - 'XPC_FLAGS': '0x0', 09:26:46 INFO - 'XPC_SERVICE_NAME': '0', 09:26:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:47 INFO - Downloading/unpacking jsonschema==2.5.1 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:26:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 INFO - Downloading functools32-3.2.3-2.tar.gz 09:26:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:26:50 INFO - warning: no files found matching '*.txt' 09:26:50 INFO - no previously-included directories found matching 'build' 09:26:50 INFO - no previously-included directories found matching 'dist' 09:26:50 INFO - no previously-included directories found matching '.git*' 09:26:50 INFO - Installing collected packages: jsonschema, functools32 09:26:50 INFO - Running setup.py install for functools32 09:26:50 INFO - warning: no files found matching '*.txt' 09:26:50 INFO - no previously-included directories found matching 'build' 09:26:50 INFO - no previously-included directories found matching 'dist' 09:26:50 INFO - no previously-included directories found matching '.git*' 09:26:50 INFO - Successfully installed jsonschema functools32 09:26:50 INFO - Cleaning up... 09:26:50 INFO - Return code: 0 09:26:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:50 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 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:26:50 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 09:26:50 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 09:26:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:26:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:50 INFO - 'HOME': '/Users/cltbld', 09:26:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:50 INFO - 'LOGNAME': 'cltbld', 09:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:50 INFO - 'MOZ_NO_REMOTE': '1', 09:26:50 INFO - 'NO_EM_RESTART': '1', 09:26:50 INFO - 'PAGER': '/bin/cat', 09:26:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:50 INFO - 'PWD': '/builds/slave/test', 09:26:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:50 INFO - 'SHELL': '/bin/bash', 09:26:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:26:50 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:26:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:50 INFO - 'USER': 'cltbld', 09:26:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:50 INFO - 'XPC_FLAGS': '0x0', 09:26:50 INFO - 'XPC_SERVICE_NAME': '0', 09:26:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:26:50 INFO - Cleaning up... 09:26:50 INFO - Return code: 0 09:26:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:50 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 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:26:50 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 09:26:50 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 09:26:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:26:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:50 INFO - 'HOME': '/Users/cltbld', 09:26:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:50 INFO - 'LOGNAME': 'cltbld', 09:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:50 INFO - 'MOZ_NO_REMOTE': '1', 09:26:50 INFO - 'NO_EM_RESTART': '1', 09:26:50 INFO - 'PAGER': '/bin/cat', 09:26:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:50 INFO - 'PWD': '/builds/slave/test', 09:26:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:50 INFO - 'SHELL': '/bin/bash', 09:26:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:26:50 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:26:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:50 INFO - 'USER': 'cltbld', 09:26:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:50 INFO - 'XPC_FLAGS': '0x0', 09:26:50 INFO - 'XPC_SERVICE_NAME': '0', 09:26:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:50 INFO - Downloading/unpacking blobuploader==1.2.4 09:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:53 INFO - Downloading blobuploader-1.2.4.tar.gz 09:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:26:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:26:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:26:54 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 09:26:54 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 09:26:54 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 09:26:54 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 09:26:54 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 09:26:54 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 09:26:54 INFO - Downloading docopt-0.6.1.tar.gz 09:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:26:54 INFO - Installing collected packages: blobuploader, requests, docopt 09:26:54 INFO - Running setup.py install for blobuploader 09:26:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:26:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:26:54 INFO - Running setup.py install for requests 09:26:55 INFO - Running setup.py install for docopt 09:26:55 INFO - Successfully installed blobuploader requests docopt 09:26:55 INFO - Cleaning up... 09:26:55 INFO - Return code: 0 09:26:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:55 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')]} 09:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:55 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')]} 09:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:55 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 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:26:55 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 09:26:55 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 09:26:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:26:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:55 INFO - 'HOME': '/Users/cltbld', 09:26:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:55 INFO - 'LOGNAME': 'cltbld', 09:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:55 INFO - 'MOZ_NO_REMOTE': '1', 09:26:55 INFO - 'NO_EM_RESTART': '1', 09:26:55 INFO - 'PAGER': '/bin/cat', 09:26:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:55 INFO - 'PWD': '/builds/slave/test', 09:26:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:55 INFO - 'SHELL': '/bin/bash', 09:26:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:26:55 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:26:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:55 INFO - 'USER': 'cltbld', 09:26:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:55 INFO - 'XPC_FLAGS': '0x0', 09:26:55 INFO - 'XPC_SERVICE_NAME': '0', 09:26:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:55 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-kd_zR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:55 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-S3dzQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-q6AUIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-H6Q_pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-fbGKFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-JkBtHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-4E62xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ViqSr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-BJqCxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-rVwrmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-JJ6Lq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-aLgMVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-4uHcUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-XBwc4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-SXHTLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ZzZDqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-fvUhFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:26:57 INFO - Running setup.py install for manifestparser 09:26:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:26:57 INFO - Running setup.py install for mozcrash 09:26:57 INFO - Running setup.py install for mozdebug 09:26:58 INFO - Running setup.py install for mozdevice 09:26:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:26:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:26:58 INFO - Running setup.py install for mozfile 09:26:58 INFO - Running setup.py install for mozhttpd 09:26:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:26:58 INFO - Running setup.py install for mozinfo 09:26:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:26:58 INFO - Running setup.py install for mozInstall 09:26:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:26:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:26:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:26:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:26:58 INFO - Running setup.py install for mozleak 09:26:58 INFO - Running setup.py install for mozlog 09:26:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Running setup.py install for moznetwork 09:26:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Running setup.py install for mozprocess 09:26:59 INFO - Running setup.py install for mozprofile 09:26:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Running setup.py install for mozrunner 09:26:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Running setup.py install for mozscreenshot 09:26:59 INFO - Running setup.py install for moztest 09:27:00 INFO - Running setup.py install for mozversion 09:27:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:00 INFO - Cleaning up... 09:27:00 INFO - Return code: 0 09:27:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:00 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')]} 09:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:00 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')]} 09:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:00 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 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:27:00 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 09:27:00 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 09:27:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:27:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:00 INFO - 'HOME': '/Users/cltbld', 09:27:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:00 INFO - 'LOGNAME': 'cltbld', 09:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:00 INFO - 'MOZ_NO_REMOTE': '1', 09:27:00 INFO - 'NO_EM_RESTART': '1', 09:27:00 INFO - 'PAGER': '/bin/cat', 09:27:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:00 INFO - 'PWD': '/builds/slave/test', 09:27:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:00 INFO - 'SHELL': '/bin/bash', 09:27:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:27:00 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:27:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:00 INFO - 'USER': 'cltbld', 09:27:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:00 INFO - 'XPC_FLAGS': '0x0', 09:27:00 INFO - 'XPC_SERVICE_NAME': '0', 09:27:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-LZVZKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:00 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)) 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-VuiGAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-aYS5XB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:00 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)) 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ildKNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:00 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)) 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-WVubnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:00 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)) 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-2qryim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:01 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)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-sj28nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:01 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)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-rT02fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:01 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)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-RYz1Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:01 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)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-bLQmSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:01 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)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-4OyzHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:01 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)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-d3SHOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:01 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)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-RQOgzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:01 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)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-zlMIrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-wQ7lxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:02 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)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-tRfZ8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:02 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)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-vKRMTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:02 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)) 09:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:02 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)) 09:27:02 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)) 09:27:02 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)) 09:27:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:02 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 09:27:02 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 09:27:02 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 09:27:02 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 09:27:02 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 09:27:02 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 09:27:04 INFO - Downloading blessings-1.6.tar.gz 09:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:04 INFO - Installing collected packages: blessings 09:27:04 INFO - Running setup.py install for blessings 09:27:05 INFO - Successfully installed blessings 09:27:05 INFO - Cleaning up... 09:27:05 INFO - Return code: 0 09:27:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:05 INFO - Reading from file tmpfile_stdout 09:27:05 INFO - Current package versions: 09:27:05 INFO - blessings == 1.6 09:27:05 INFO - blobuploader == 1.2.4 09:27:05 INFO - docopt == 0.6.1 09:27:05 INFO - functools32 == 3.2.3-2 09:27:05 INFO - jsonschema == 2.5.1 09:27:05 INFO - manifestparser == 1.1 09:27:05 INFO - mozInstall == 1.12 09:27:05 INFO - mozcrash == 1.0 09:27:05 INFO - mozdebug == 0.1 09:27:05 INFO - mozdevice == 0.48 09:27:05 INFO - mozfile == 1.2 09:27:05 INFO - mozhttpd == 0.7 09:27:05 INFO - mozinfo == 0.9 09:27:05 INFO - mozleak == 0.1 09:27:05 INFO - mozlog == 3.4 09:27:05 INFO - moznetwork == 0.27 09:27:05 INFO - mozprocess == 0.23 09:27:05 INFO - mozprofile == 0.28 09:27:05 INFO - mozrunner == 6.13 09:27:05 INFO - mozscreenshot == 0.1 09:27:05 INFO - mozsystemmonitor == 0.3 09:27:05 INFO - moztest == 0.7 09:27:05 INFO - mozversion == 1.4 09:27:05 INFO - psutil == 3.1.1 09:27:05 INFO - requests == 1.2.3 09:27:05 INFO - wsgiref == 0.1.2 09:27:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:05 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 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:27:05 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 09:27:05 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 09:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:05 INFO - 'HOME': '/Users/cltbld', 09:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:05 INFO - 'LOGNAME': 'cltbld', 09:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:05 INFO - 'MOZ_NO_REMOTE': '1', 09:27:05 INFO - 'NO_EM_RESTART': '1', 09:27:05 INFO - 'PAGER': '/bin/cat', 09:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:05 INFO - 'PWD': '/builds/slave/test', 09:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:05 INFO - 'SHELL': '/bin/bash', 09:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:27:05 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:05 INFO - 'USER': 'cltbld', 09:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:05 INFO - 'XPC_FLAGS': '0x0', 09:27:05 INFO - 'XPC_SERVICE_NAME': '0', 09:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 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)) 09:27:05 INFO - Cleaning up... 09:27:05 INFO - Return code: 0 09:27:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:05 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 0x102cca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97915af5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, '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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 09:27:05 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 09:27:05 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 09:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:05 INFO - 'HOME': '/Users/cltbld', 09:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:05 INFO - 'LOGNAME': 'cltbld', 09:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:05 INFO - 'MOZ_NO_REMOTE': '1', 09:27:05 INFO - 'NO_EM_RESTART': '1', 09:27:05 INFO - 'PAGER': '/bin/cat', 09:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:05 INFO - 'PWD': '/builds/slave/test', 09:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:05 INFO - 'SHELL': '/bin/bash', 09:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:27:05 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:05 INFO - 'USER': 'cltbld', 09:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:05 INFO - 'XPC_FLAGS': '0x0', 09:27:05 INFO - 'XPC_SERVICE_NAME': '0', 09:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:06 INFO - Cleaning up... 09:27:06 INFO - Return code: 0 09:27:06 INFO - Running post-action listener: _resource_record_post_action 09:27:06 INFO - Running post-action listener: _start_resource_monitoring 09:27:06 INFO - Starting resource monitoring. 09:27:06 INFO - [mozharness: 2016-11-11 17:27:06.057771Z] Finished create-virtualenv step (success) 09:27:06 INFO - [mozharness: 2016-11-11 17:27:06.058723Z] Running install step. 09:27:06 INFO - Running pre-action listener: _resource_record_pre_action 09:27:06 INFO - Running main action method: install 09:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:06 INFO - Reading from file tmpfile_stdout 09:27:06 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:06 INFO - Reading from file tmpfile_stdout 09:27:06 INFO - Output received: 09:27:06 INFO - Usage: mozinstall [options] installer 09:27:06 INFO - Options: 09:27:06 INFO - -h, --help show this help message and exit 09:27:06 INFO - -d DEST, --destination=DEST 09:27:06 INFO - Directory to install application into. [default: 09:27:06 INFO - "/builds/slave/test"] 09:27:06 INFO - --app=APP Application being installed. [default: firefox] 09:27:06 INFO - mkdir: /builds/slave/test/build/application 09:27:06 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'] 09:27:06 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 09:27:40 INFO - Reading from file tmpfile_stdout 09:27:40 INFO - Output received: 09:27:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:27:40 INFO - Running post-action listener: _resource_record_post_action 09:27:40 INFO - [mozharness: 2016-11-11 17:27:40.506564Z] Finished install step (success) 09:27:40 INFO - [mozharness: 2016-11-11 17:27:40.506704Z] Running run-tests step. 09:27:40 INFO - Running pre-action listener: _resource_record_pre_action 09:27:40 INFO - Running main action method: run_tests 09:27:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:40 INFO - 2016-11-11 09:27:40.521 screenresolution[1645:7606] starting screenresolution argv=screenresolution get 09:27:40 INFO - 2016-11-11 09:27:40.523 screenresolution[1645:7606] Display 0: 1600x1200x32@60 09:27:40 INFO - 2016-11-11 09:27:40.529 screenresolution[1646:7608] starting screenresolution argv=screenresolution list 09:27:40 INFO - Available Modes on Display 0 09:27:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:40 INFO - Intel Iris: 09:27:40 INFO - Chipset Model: Intel Iris 09:27:40 INFO - Type: GPU 09:27:40 INFO - Bus: Built-In 09:27:40 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:40 INFO - Vendor: Intel (0x8086) 09:27:40 INFO - Device ID: 0x0a2e 09:27:40 INFO - Revision ID: 0x0009 09:27:40 INFO - Displays: 09:27:40 INFO - Display: 09:27:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:40 INFO - Display Serial Number: 1600x1200 60 09:27:40 INFO - Main Display: Yes 09:27:40 INFO - Mirror: Off 09:27:40 INFO - Online: Yes 09:27:40 INFO - Rotation: Supported 09:27:40 INFO - Return code: 0 09:27:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:27:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:27:40 INFO - Python 2.7.3 09:27:40 INFO - Return code: 0 09:27:40 INFO - grabbing minidump binary from tooltool 09:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:40 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 0x102aded30>, '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 0x102904db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ad6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:27:40 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 09:27:40 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 09:27:40 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 09:27:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:27:40 INFO - Return code: 0 09:27:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:27:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:27:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:27:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:27:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:27:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:27:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0309 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Adv6OaaYOY/Render', 09:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:40 INFO - 'HOME': '/Users/cltbld', 09:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:40 INFO - 'LOGNAME': 'cltbld', 09:27:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:27:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:40 INFO - 'MOZ_NO_REMOTE': '1', 09:27:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:27:40 INFO - 'NO_EM_RESTART': '1', 09:27:40 INFO - 'PAGER': '/bin/cat', 09:27:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:40 INFO - 'PWD': '/builds/slave/test', 09:27:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:40 INFO - 'SHELL': '/bin/bash', 09:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYqPgAgq5M/Listeners', 09:27:40 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 09:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:40 INFO - 'USER': 'cltbld', 09:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:40 INFO - 'XPC_FLAGS': '0x0', 09:27:40 INFO - 'XPC_SERVICE_NAME': '0', 09:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:27:41 INFO - mozversion application_buildid: 20161111073320 09:27:41 INFO - mozversion application_changeset: d284cdb3ad6e0609a53a275df6efcff4255c30ec 09:27:41 INFO - mozversion application_display_name: Nightly 09:27:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:27:41 INFO - mozversion application_name: Firefox 09:27:41 INFO - mozversion application_remotingname: firefox 09:27:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:27:41 INFO - mozversion application_vendor: Mozilla 09:27:41 INFO - mozversion application_version: 52.0a1 09:27:41 INFO - mozversion platform_buildid: 20161111073320 09:27:41 INFO - mozversion platform_changeset: d284cdb3ad6e0609a53a275df6efcff4255c30ec 09:27:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:27:41 INFO - mozversion platform_version: 52.0a1 09:27:41 INFO - using testdate: 1478885261 09:27:41 INFO - actual date: 1478885261 09:27:41 INFO - starting webserver on 'localhost:49272' 09:27:41 INFO - SUITE-START | Running 2 tests 09:27:41 INFO - TEST-START | dromaeo_css 09:27:41 INFO - operating with platform_type : mac_ 09:27:41 INFO - Initialising browser for dromaeo_css test... 09:27:41 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpsYB8ss/profile http://localhost:49272/getInfo.html) 09:27:44 INFO - PROCESS | 1656 | 2016-11-11 09:27:44.142 plugin-container[1658:7789] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 09:27:44 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:44 INFO - PROCESS | 1656 | 2016-11-11 09:27:44.146 plugin-container[1658:7789] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1b, name = 'com.apple.CFPasteboardClient' 09:27:44 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:44 INFO - PROCESS | 1656 | 2016-11-11 09:27:44.146 plugin-container[1658:7789] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:44 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 09:27:44 INFO - PROCESS | 1656 | colorDepth:24 09:27:44 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 09:27:44 INFO - PROCESS | 1656 | __metrics 09:27:44 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3174: TypeError: this.tabs is undefined 09:27:44 INFO - TEST-INFO | 1656: exit 0 09:27:44 INFO - Browser initialized. 09:27:44 INFO - Running cycle 1/1 for dromaeo_css test... 09:27:44 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpsYB8ss/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:47 INFO - PROCESS | 1659 | 2016-11-11 09:27:47.375 plugin-container[1660:7910] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:27:47 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:47 INFO - PROCESS | 1659 | 2016-11-11 09:27:47.376 plugin-container[1660:7910] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 09:27:47 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:47 INFO - PROCESS | 1659 | 2016-11-11 09:27:47.376 plugin-container[1660:7910] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:56 INFO - PROCESS | 1659 | RSS: Main: 237641728 09:27:56 INFO - PROCESS | 1659 | 09:28:46 INFO - PROCESS | 1659 | 1478885326641 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] 09:31:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 09:31:01 INFO - PROCESS | 1659 | RSS: Main: 250056704 09:31:01 INFO - PROCESS | 1659 | 09:34:04 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 09:34:04 INFO - PROCESS | 1659 | RSS: Main: 265338880 09:34:04 INFO - PROCESS | 1659 | 09:37:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 09:37:07 INFO - PROCESS | 1659 | RSS: Main: 268771328 09:37:07 INFO - PROCESS | 1659 | 09:40:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 09:40:10 INFO - PROCESS | 1659 | RSS: Main: 268353536 09:40:10 INFO - PROCESS | 1659 | 09:43:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 09:43:14 INFO - PROCESS | 1659 | RSS: Main: 272773120 09:43:14 INFO - PROCESS | 1659 | 09:46:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 09:46:17 INFO - PROCESS | 1659 | RSS: Main: 274841600 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - PROCESS | 1659 | __start_tp_report 09:46:17 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 09:46:17 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 09:46:17 INFO - PROCESS | 1659 | |i|pagename|runs| 09:46:17 INFO - PROCESS | 1659 | |0;dojo.html;3332.3168750406153;3339.01499057778;3360.697537221651;3385.2552438463545;3409.0113866978577;5506.944930550693;5529.612927095105;5534.972325138378;5579.944200557999;5599.496045355917;5519.806806761764;5534.861628459291;5615.494605485505;5616.241807356006;5667.263255776743;5013.398392192942;5044.747762611873;5097.9490205097845;5189.195674670414;5191.610629202825;5003.39959204896;5045.192769156935;5093.439721630618;5101.51535604117;5107.948920510785;5168.689878607277;5183.533481986601;5220.791168353261;5239.973800130994;5240.187770895517;5001.89996200074;5031.195008798574;5085.567726743231;5101.82143624975;5149.356330458692;4177.310743727281;4999.850004499871;5046.470120637354;5053.292539044537;5174.560162386202;4214.894627634334;4392.472903251631;4610.769461526944;4913.582345500637;5603.355614104389;5593.776248950037;6051.1830902828;6107.8167654969975;6146.385361463817;6161.876762464725;4438.533953934822;5787.710614469302;6179;6182;6214.502839772807;5641.915371269434;5697.059985102412;5701.344345400249;5704.80033198836;5715.8856822864145;6119.654885717031;6149.200603921551;6153.353897840751;6206.348333425016;6232.3144454110015;5975.193348897843;5993.670348130808;6017;6019.067044608093;6055.394460553909;6132.448079672851;6151.9692401538605;6156.168917196122;6161.630302181883;6206.06908963659;5885.529157667376;5940.821775346746;6052.273727152769;6058.363871793486;6090.390960903874;6075.33171351151;6130.979101202072;6131.0708424985305;6131.14164017038;6144.631322120687;4764.285357196448;4811.78346974387;4827.710337379768;4830.4444988826735;4838.637102217348;4241.830326786894;4273.423087883159;4350;4359.80380882861;4365.519792822787;5503.174523821459;5598.888022239533;5776;5838.211841401441;5908.4091590841435;6118.449339559461;6147.323794382614;6151.415615516519;6166.568340216142;6174.44430001293;5685.971570142123;5793.826185214366;5799.82600521985;5821.359650438449;5827.329857066492;5185.351831021122;5197.376314842243;5206.427292997767;5219.608529360313;5258.2112683097075;6248.4688801452385;6301.999999999908;6319.747210111544;6323.873522529616;6325.082862984841;6141.938580614136;6144.139820425234;6153;6156.168917196122;6157.6613286269685;6189.412005859436;6214.161088253209;6248.562600617913;6266.843328916813;6282.246130464191;6102.237220347457;6110.285381942539;6140.13616995759;6141.51669281436;6144.969275153774;5973.462388385066;6053.545984051126;6141.012512800163;6189.412005859436;6197.938020619736;5716.628419152742;5838.32859221178;5944.613600116151;6000.489958353414;6010.639361638316;5767.826965191051;5825.737841797214;5892.440218179266;5989.191459153133;5995.910061348996;5687.971560142173;5719.914201286901;5724.771009159587;5754.625949313281;5838.766449341979;5971.880562388641;5989.9700501498955;5995.820125396245;6024.126501657148;6045.78839740607;4767.523247675204;4782.569568738831;5790.363060063305;5839.824805255849;5853.853653658693 09:46:17 INFO - PROCESS | 1659 | |1;ext.html;479.0706030301216;511.3965520685592;511.6188439612489;513.2045329738904;519.1382305373077;28775.28061798456;39630;39646.00884977877;41533.37699934496;41534.58465415345;40649.39025914614;40966.59033409665;40988;41068.383974240336;41073.58926410735;9232.953835230848;9247.075292470767;9418.62325506979;9429.717108486722;9443.527823608792;9275.72172834816;9325.487098209596;9358.953205233998;9408.905910940906;9463;8353.916460835406;9245.72262832116;9299.349045566812;9436.339456238065;9497.667581634643;9308.115729005767;9396.107369799858;9431.386959847589;9456.952715236448;9469.479178645171;9170.358074934757;9286.674966376144;9332.766680833034;9364.063593640649;9371.859422108673;38825;38948.415773763416;39036.438542458265;39117.804410978046;39167.41248881269;3239.4492936200904;3323.5180898769545;3338.565986421774;3413.9487907681405;3912.393578995232;29474.263143421373;29545.409091818045;29661.555076673867;29758.85120574405;31027.53458698121;3175.5871736674085;3196.3767065422253;3210.2295449092285;3308.9007329780147;3336.282699219679;488070.79823004355;492824;493510;495883;497075;58027.41972580305;58240.4175958235;58264.12603810946;58296.417035829945;58544;60922;60967;61393;61397.38602613917;61457.07814382788;15410.383584656713;15437.382504699686;15697.68604627901;15702.528924132293;15711.057336559843;11507.539698411969;12011.939940300243;12070.75858482843;12214.877851221552;12254.938725306494;17033.574160646083;17959.73060404095;18132.546686332684;18338.266469341343;18425.34171924533;3152.479840826238;3268.624108227537;6815.490022638345;7475.215102414277;7712.807179820437;875.2691502595404;946.5456580841235;1048.9318194317345;1054.1514081164582;1056.0812093478723;26752;28795.136145915658;29446.11661650154;29924;29962;932.9084970584432;959.1367769007949;964.7781010367655;1032.6334151376304;1060.1624716474053;14759.704805904037;15346;15406.845931540765;15428.614284642974;15585.610359741097;665538;667808;673495;674455.2990230805;675696;55367;55695;55945.720271398386;55961;56021.9187944667;1273.9808902866464;1303.478608556566;1335.0587835575895;1344.5764584155959;1359.320339830085;21948.89025554862;22080.88959555192;28082.017129400374;28112.29719257035;30532.542011870282;53968.73015634897;59912.70043649928;59971.40028599833;60027.39972600218;60153;618546;619875;633677;633697;634417.7628454135;41419.1716165669;42508.14983700371;42595;42812.35781463218;43527.564724353615;47488;48038.27942580934;48817.02365952732;48825.26762098642;49349.25976110289;39250.6074939247;39378.212435751695;39723.80138099291;40887.795561022;41145.58854411417;39018.414723778595;39113.80443097766;39783.00542486461;40106.79679609616;40147.99630009273;31886;38268.80865595654;38340.041498962746;38894;39101.41347879841;26277;32550.83724581362;34944.82527587346;36645;36815.63184368122;20597.629214770892;24503.632445512976;25794.61308080343;26084;26446.471070578096 09:46:17 INFO - PROCESS | 1659 | |2;jquery.html;348.9546358973334;369.3481006024368;377.34907284933485;383.11309318926664;401.2216300377268;18667.533311667215;18789.81210187898;19425.805741942575;19569.804301956978;19751.604967900632;11547.595834145806;11801.7639647207;11930.105242106833;11936.104792140603;11949.223300485484;4881.585065269447;5167.069927413064;5233.607479439038;5273.2617433559335;5280.128778751501;3796.52543432071;4014.3978403239603;5123.5388815006645;5239.816606418776;5303.655262407931;3963.8414463421427;5460.235567020621;6342.460890824285;6385;6401.039844023388;5149.5107964743665;5837.737301821428;6001.4298641628975;6331.936680633204;6398.9040164397575;5681.744321505501;5689.516391106719;5696.971515142439;5699.316082070137;5701.458361455655;12778.23330600167;12962.80555791664;13065.216087034712;13114.147580407243;21730.89134554333;2190.59473997311;2192.484766079986;2220.4226901005854;2236.1502629000875;2320.8027317677847;9492.857607135898;10204.977307864914;10343.63797267092;12482;15251.923740381228;1821.7449557062023;1879.1543805287538;1909.2363054778061;1932.294712429967;1943.9125239364262;111324;113894.86105139008;120260;126484;127132;8919.955400222958;10910;11373.829392559119;11463.942680286713;11578.884211157949;14209.78685319721;14244.643883902985;14511.1293322401;14513.637159070897;14523.564293071226;8025.59872006409;8336.083030866721;8373.41386102979;8466.74599762008;8473.745787626258;7173.89239161413;8152.347812175011;8192.75421737349;8198.426110172231;8374.665013399517;4485.394471746337;4521.728696278234;4539.568740969603;4544.091181763661;4606.470255920545;1898.193267861181;1947.9318223862097;1960.7941166177345;1961.1956999560439;1961.4213806926991;677.4580335731396;810.3517186251094;850.3707256630049;902.7336935604012;913.7350168451075;16778.573821225087;17802.91098544499;18066.728999065024;19586.31447899317;19655.606887862163;878.6529320918329;887.8002449448823;896.3904544909524;900.8063266397756;910.517425764346;8618.870716939251;10342.224333174892;10701.83947240792;10760.730981725519;10796.67609971702;527160;554993;598314;602749;609028;95177;116667;119716.40141799235;120920;122052;951.6003278622995;960.1406740966795;993.9602415903283;995.3629677006721;1001.0540039662594;18057.909710451364;18970.336038239147;18976.810231897503;19009.90495047516;19010.714839277145;54291;55289;55706.72146639241;56269.905155215216;57018.859622806485;75807.62096189483;78796.81804773047;80069.19930800618;80638;83178;7897.854547264581;7960.800979975547;7969;8298.087210406971;8321.251087402163;11465.082793376676;12140;12721.491140354283;13108;13131.146475479063;10464.843027354747;10707.250492465453;11073;11178.720531986766;11275.87241275866;8775.341849361452;10897.019268265894;11242;11249.043831274434;11279.887201127884;10654.147668186371;11210.775784484102;11354.148438867283;11693.064554835422;11735.943765061185;8081.515109093473;8174.713885013996;8236.176382361717;8281.461704989157;8395.86655801459;9040.593173307085;11132.332060076098;11357.432128393382;11383.943080284545;11438.485268162785 09:46:17 INFO - PROCESS | 1659 | |3;mootools.html;1476.9852301476983;1510.5166346769033;1694.2121913310316;1816.1736409933483;1822.8177182281775;7060.293970602937;7065.551561929792;7957.20427957204;8441.78895527612;8493.91898984748;6094.817155485332;8224.05423376312;8238.91761082389;8336.374771892102;8353.289970352513;2286.4741109544816;2319.1651005637955;2323.7792409721133;2324.535092981402;2326.7905888470036;1958.4810025343295;1986.4040787763686;2316.5714342846545;2345.003373566236;2356.728976167737;2164.1343462614923;2236.535069529461;2241.1147596699293;2242.450599603091;2267.965980510294;2005.6289586426485;2281.361218858722;2284.5430913817304;2295.517941232343;2304.5621331946877;2307.826912981516;2323.0591610397814;2323.744388117306;2330.149495434171;2331.19573747057;7121.643917804141;8206.097329293772;8374.665013399457;8393.202645748644;8399.790005249857;4623.260278355447;5019.372578427697;5161.5870730341485;5182.559482443997;5211.583073354161;3279.4588892832653;3321.684439978198;3608.1340478285024;3609.350316942949;3620.420732682758;1018.9388636681823;1047.6699839550517;1063.0432610650398;1077.1867240233653;1079.643717573199;10725.51735171919;10818.513166907429;10879.728006799814;10894.346339219594;10921.453927303683;4446.9777651111535;4534.909301813946;4600.2869555218995;4614.007988282536;4709.788059537329;9214.815703685888;9215.677451289173;9374.296927730311;9407.90592094084;9423.528823558794;5186.2479940409185;5192.818251361185;5197.974010129925;5215.217717342429;5216.191490319006;4092.4475195848777;4265.360195970629;4272.423222864873;4673.789679464432;4791.209450440639;3684.1894783147663;3764.661180493769;3791.3933770596573;3810.8285127169343;3877.515310586177;1269.7777888869412;1271.357964228059;1271.6058022013206;1272.4337669736879;1282.9358532073359;749.2582343479916;880.097899652861;891.2112780189578;892.4957399069505;915.7985243246475;5615.887682246333;5622.775088996476;5728.312602487645;5747.57281553398;5845.240118784616;435.5861931165405;443.4634092747747;446.3777583126741;452.56101581465924;452.8075567883702;3741.326561218952;3813.866514671973;3892.2799282132896;3905.414187871842;3905.843766249375;7920.881186782203;10001.49992500372;10177.440240786827;10180.083792458714;10183.847242291371;9867;10106.747331316776;10115.59537618487;10142.949285253526;10144.442055687008;6180.536459765535;6436.35636436362;6924.965375173092;7600.441909408558;7740.26467485588;5453.590980676386;5557.055300598827;5576.470235327637;5588.6646801192055;5701.315842098975;9296.860547091934;9322.161005509537;9372.343935924693;9398.483083430478;9432.292578056537;7395.371393431618;7587.165411804586;9351.532423378667;9642.855357169508;9713.70858874235;5108.66793658411;6554.344565543598;6740.528163028541;7110.395616372666;7257.709691612276;6445.4199122079235;7034.718611255493;7597.354224890946;7963.840723185388;7967;5668.866226754748;6047.244094488189;6998.125234345707;7133.037039999534;7187.6765545551625;6956.513044087068;6987.545809522365;7001.614911179934;7041.225465198926;7047.189573199148;5620.932022915633;6667.899981500184;6710.530262881552;6730.46156307504;6752.08846805675;4740.269801522489;4806.831760888352;4808.0624278266805;4812;4943.258511223202;4807.302941073594;4887.169181239126;4923.679960802537;4928;4933.33399991011 09:46:17 INFO - PROCESS | 1659 | |4;prototype.html;297.1101550855189;302.2082144780675;308.9057837359606;311.8222613110525;312.35654551623657;4120.690948178887;4499.392582001429;5250.684958902469;5441.347038355393;5511.255980442639;4593.977030114845;4825.686330388522;5145.562627176694;5205.193195054763;5306.4958828911285;3791.601881802412;4042.3532234842432;4164.750114993095;4183.581641835823;4185.916281674365;3982.9004274893214;4008.158286759784;4158.688098392618;4169.5830416958215;4249.723767955088;3608.0799396153943;3855.9228815423676;4098.4671992640915;4131.5042194936495;4152.691902050859;4101.364288535282;4116.653507500726;4146.564610715876;4150.958490415087;4154.356074808379;3607.549056367954;3611.638836116367;3632.364336241147;3641.217138315275;3641.708663306929;4893.412790465131;5257.792746424678;5268.916589643019;5299.629025968184;5366.758495867695;4622.46841613212;4631.652626053025;4632.969316694497;4636.930446043312;4649.790759415834;4178.435911151986;4986.950130498685;5428.874055717683;5664.915026274609;5721.7711291548285;3210.4060748761553;3583.33708263972;3815.790131542764;3817.503724515795;3842.9807850960847;5318.6542874713405;5398.546199385522;5817.941820581782;5967.4629283364275;5977.551683623746;3068.3709839482854;3908.511436070491;3919.6864250859862;3926.5680775114715;3928.724989250782;3674.669279764834;3904.3752999520507;3963.544192417909;3971.980140099281;3980.721349505565;4244.936325955113;4592.5407459254475;4600.194965880957;4609;4630.791614377361;5708.942910570841;5743.59794814359;5750.769969201185;5758.945292017329;5763.106718458645;1041.880183778875;1230.274138258414;1259.5591542959892;1268.7145392286845;1281.0520215040804;779.8791187365968;863.3826813828143;865.9523726194993;873.8121303919688;874.2132081127037;4387.4954380246045;4533.433320834895;4786.994731106507;4891.804327826918;5140.280360749498;4942.975285123552;5117.364557253018;5514.255575497336;5525.834224973257;5541.418151094158;4227.936580951288;4355.586219309161;4678.906421871611;5132.127538318495;5182.5594824439595;2249.527599204153;2561.6039258604105;2608.869556522204;2809.845458499802;2910.4179164167253;5171.3535808024;5242.475752424727;5487.890242195133;5826.067829147316;5874.647521148745;4477.820887164542;4655.604273636779;4795.496472870368;5327.440618735054;5576.776928922879;4901.509849015141;5771.509421699203;5781.537477001913;5828.60113572748;5831.792514117304;4691.765411729331;5516.724163791875;5526.834194974157;5559.081482221866;5564.137558678412;4795.930604413248;5380.408155102858;5428.918566221589;5822.854428639318;5854.092615644582;4924.704517728947;5418.431064738145;5490.940897163564;5680.943190568041;6199.855213559963;3586.8027258501033;3825.6365645263654;3904.7657140571655;3948.0919388540224;3949.9012524687114;5083.212102124176;5511.732301570581;5596.524295434933;5623.465770751772;5986.6707331097205;4758.881027974328;5583.431621454177;5614.438556144353;5654.236678048575;5700.885982280414;4779.904401911873;4946.579540739078;5117.032121369522;5630.3402788188005;5755.8561035974435;3972.662323702518;5267.894642107213;5482.122860342485;5638.9436105638415;5661.830145095653;4960.90860010797;5699.857503562278;5718.771249149988;5774.740136693943;5814.592978491465;5155.53600175986;5590.357108932525;5623.268975033219;5687.232223649754;5795.536357091339 09:46:17 INFO - PROCESS | 1659 | |5;yui.html;568.8236646639544;703.7044441334639;719.9784006479807;731.6744048898236;764.7858599592113;14150.716985660281;14849.034812737165;14925.253737313145;14940.626484337898;14979.775303370445;10992.780144397128;12957.416916238768;13132.0807543472;13148.605541833736;13192.340382980836;648.4909346163263;675.9526833121683;687.5737043033326;814.0963530481162;832.7376876283981;820.5609998650723;822.5311572403732;833.4790755777639;834.1783343406739;835.1106072033265;892.9285657147412;894.8120894612139;895.1585509620935;895.230102112183;899.9460032398044;853.3642436384902;867.7960679240374;871.5424402188838;886.5655828643951;888.3648191543014;629.3077614623877;653.3270731146927;659.581165959619;661.2098542242022;662.7580932959435;11937.701557461047;13455.394507247198;14084.084534505328;14112.223827689473;14119.223442710647;13737.381817818123;13752.724945501033;13943.860561394458;14078;14256.429742810275;9839.163671087967;9958.80082398348;10419.791604167875;11465.426728663533;11742.706432339175;3651.548220567851;3682.797446140461;3732.309522738275;3766.736328457009;3795.165063686012;99461;99787;104884.37789055334;105111.47444262807;105908.47045764721;10057.346272492265;10296.588136474456;10450;10743.033127018605;11002.954719301653;18095.54761130956;19845;19883;20182.293619723532;20184.394468165978;7106.680199391039;7196.0645116134565;7243;7294.161171465349;7444.627768611548;4101.774402407897;4127.979360103181;4170;4195.160967806451;4196.328587425997;3238.530413090136;3324.036029551403;3336.332733453319;3581.534400527915;3760.435934609776;616.4390404731672;649.389573800626;680.880845851974;692.7125243024088;695.3255342317943;520.456123351099;546.9124940009659;620.271181361898;621.4655396359127;629.5844742469948;7136.429085673134;7223.674934627954;8940.955295223483;9025.781519494916;9038.367314288069;596.60325883288;637.4382762232549;650.3171669746747;658.8517583543664;670.3296703296703;4265.466816647919;4453.643708503312;4459;4478.283474644041;4516.16450956574;93206;110202.44898775616;112667;114363.42818286021;117503;72047;72785.27214727891;95827.52086239523;99644;99973;62512.06231906525;89712;91204;92501.61247581292;95646;7531.497435386771;7569.584359376078;7941.888135661127;8766.1867087817;8848.690295839871;57116.143257851996;57445.85108297727;57902;58459.707701462925;59799.10301345396;59129.11306330495;62549.06176407448;68913;81414;84229;9462.601666583614;11021.118310534977;11388.974992250738;11471.311721296748;11682.24065435744;11969.341686207343;12425.875741242471;12737.29944853042;13441;14845.703085938005;12852.807207891701;13337.733245334844;13562;13587.660308491972;13634.522791702242;13124.540641077516;13220.669483262995;13325.333733313102;13369.197848129143;13474.19154850712;12799.872001279868;13032.283224422368;13049.608511744664;13604.251766152956;13639.863601363859;9261.768455788659;11278.830817537579;11348.659540213806;11423.543058277575;11581.826272606086;8808.427452215585;9564.4739539326;10196.796064078826;11159.625914232083;11498.94250528742 09:46:17 INFO - PROCESS | 1659 | __end_tp_report 09:46:17 INFO - PROCESS | 1659 | __start_cc_report 09:46:17 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,363 09:46:17 INFO - PROCESS | 1659 | __end_cc_report 09:46:17 INFO - PROCESS | 1659 | __startTimestamp1478886377064__endTimestamp 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - PROCESS | 1659 | ------- Summary: start ------- 09:46:17 INFO - PROCESS | 1659 | Number of tests: 6 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5589.10 Median:5799.83 stddev:671.12 (11.6%) stddev-sans-first:649.41 09:46:17 INFO - PROCESS | 1659 | Values: 3332.3 3339.0 3360.7 3385.3 3409.0 5506.9 5529.6 5535.0 5579.9 5599.5 5519.8 5534.9 5615.5 5616.2 5667.3 5013.4 5044.7 5097.9 5189.2 5191.6 5003.4 5045.2 5093.4 5101.5 5107.9 5168.7 5183.5 5220.8 5240.0 5240.2 5001.9 5031.2 5085.6 5101.8 5149.4 4177.3 4999.9 5046.5 5053.3 5174.6 4214.9 4392.5 4610.8 4913.6 5603.4 5593.8 6051.2 6107.8 6146.4 6161.9 4438.5 5787.7 6179.0 6182.0 6214.5 5641.9 5697.1 5701.3 5704.8 5715.9 6119.7 6149.2 6153.4 6206.3 6232.3 5975.2 5993.7 6017.0 6019.1 6055.4 6132.4 6152.0 6156.2 6161.6 6206.1 5885.5 5940.8 6052.3 6058.4 6090.4 6075.3 6131.0 6131.1 6131.1 6144.6 4764.3 4811.8 4827.7 4830.4 4838.6 4241.8 4273.4 4350.0 4359.8 4365.5 5503.2 5598.9 5776.0 5838.2 5908.4 6118.4 6147.3 6151.4 6166.6 6174.4 5686.0 5793.8 5799.8 5821.4 5827.3 5185.4 5197.4 5206.4 5219.6 5258.2 6248.5 6302.0 6319.7 6323.9 6325.1 6141.9 6144.1 6153.0 6156.2 6157.7 6189.4 6214.2 6248.6 6266.8 6282.2 6102.2 6110.3 6140.1 6141.5 6145.0 5973.5 6053.5 6141.0 6189.4 6197.9 5716.6 5838.3 5944.6 6000.5 6010.6 5767.8 5825.7 5892.4 5989.2 5995.9 5688.0 5719.9 5724.8 5754.6 5838.8 5971.9 5990.0 5995.8 6024.1 6045.8 4767.5 4782.6 5790.4 5839.8 5853.9 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:72781.51 Median:40977.30 stddev:161251.99 (393.5%) stddev-sans-first:161612.97 09:46:17 INFO - PROCESS | 1659 | Values: 479.1 511.4 511.6 513.2 519.1 28775.3 39630.0 39646.0 41533.4 41534.6 40649.4 40966.6 40988.0 41068.4 41073.6 9233.0 9247.1 9418.6 9429.7 9443.5 9275.7 9325.5 9359.0 9408.9 9463.0 8353.9 9245.7 9299.3 9436.3 9497.7 9308.1 9396.1 9431.4 9457.0 9469.5 9170.4 9286.7 9332.8 9364.1 9371.9 38825.0 38948.4 39036.4 39117.8 39167.4 3239.4 3323.5 3338.6 3413.9 3912.4 29474.3 29545.4 29661.6 29758.9 31027.5 3175.6 3196.4 3210.2 3308.9 3336.3 488070.8 492824.0 493510.0 495883.0 497075.0 58027.4 58240.4 58264.1 58296.4 58544.0 60922.0 60967.0 61393.0 61397.4 61457.1 15410.4 15437.4 15697.7 15702.5 15711.1 11507.5 12011.9 12070.8 12214.9 12254.9 17033.6 17959.7 18132.5 18338.3 18425.3 3152.5 3268.6 6815.5 7475.2 7712.8 875.3 946.5 1048.9 1054.2 1056.1 26752.0 28795.1 29446.1 29924.0 29962.0 932.9 959.1 964.8 1032.6 1060.2 14759.7 15346.0 15406.8 15428.6 15585.6 665538.0 667808.0 673495.0 674455.3 675696.0 55367.0 55695.0 55945.7 55961.0 56021.9 1274.0 1303.5 1335.1 1344.6 1359.3 21948.9 22080.9 28082.0 28112.3 30532.5 53968.7 59912.7 59971.4 60027.4 60153.0 618546.0 619875.0 633677.0 633697.0 634417.8 41419.2 42508.1 42595.0 42812.4 43527.6 47488.0 48038.3 48817.0 48825.3 49349.3 39250.6 39378.2 39723.8 40887.8 41145.6 39018.4 39113.8 39783.0 40106.8 40148.0 31886.0 38268.8 38340.0 38894.0 39101.4 26277.0 32550.8 34944.8 36645.0 36815.6 20597.6 24503.6 25794.6 26084.0 26446.5 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33570.84 Median:359.15 stddev:96779.20 (26946.6%) stddev-sans-first:97018.55 09:46:17 INFO - PROCESS | 1659 | Values: 349.0 369.3 377.3 383.1 401.2 18667.5 18789.8 19425.8 19569.8 19751.6 11547.6 11801.8 11930.1 11936.1 11949.2 4881.6 5167.1 5233.6 5273.3 5280.1 3796.5 4014.4 5123.5 5239.8 5303.7 3963.8 5460.2 6342.5 6385.0 6401.0 5149.5 5837.7 6001.4 6331.9 6398.9 5681.7 5689.5 5697.0 5699.3 5701.5 12778.2 12962.8 13065.2 13114.1 21730.9 2190.6 2192.5 2220.4 2236.2 2320.8 9492.9 10205.0 10343.6 12482.0 15251.9 1821.7 1879.2 1909.2 1932.3 1943.9 111324.0 113894.9 120260.0 126484.0 127132.0 8920.0 10910.0 11373.8 11463.9 11578.9 14209.8 14244.6 14511.1 14513.6 14523.6 8025.6 8336.1 8373.4 8466.7 8473.7 7173.9 8152.3 8192.8 8198.4 8374.7 4485.4 4521.7 4539.6 4544.1 4606.5 1898.2 1947.9 1960.8 1961.2 1961.4 677.5 810.4 850.4 902.7 913.7 16778.6 17802.9 18066.7 19586.3 19655.6 878.7 887.8 896.4 900.8 910.5 8618.9 10342.2 10701.8 10760.7 10796.7 527160.0 554993.0 598314.0 602749.0 609028.0 95177.0 116667.0 119716.4 120920.0 122052.0 951.6 960.1 994.0 995.4 1001.1 18057.9 18970.3 18976.8 19009.9 19010.7 54291.0 55289.0 55706.7 56269.9 57018.9 75807.6 78796.8 80069.2 80638.0 83178.0 7897.9 7960.8 7969.0 8298.1 8321.3 11465.1 12140.0 12721.5 13108.0 13131.1 10464.8 10707.3 11073.0 11178.7 11275.9 8775.3 10897.0 11242.0 11249.0 11279.9 10654.1 11210.8 11354.1 11693.1 11735.9 8081.5 8174.7 8236.2 8281.5 8395.9 9040.6 11132.3 11357.4 11383.9 11438.5 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5300.47 Median:4925.84 stddev:2918.39 (59.2%) stddev-sans-first:2912.43 09:46:17 INFO - PROCESS | 1659 | Values: 1477.0 1510.5 1694.2 1816.2 1822.8 7060.3 7065.6 7957.2 8441.8 8493.9 6094.8 8224.1 8238.9 8336.4 8353.3 2286.5 2319.2 2323.8 2324.5 2326.8 1958.5 1986.4 2316.6 2345.0 2356.7 2164.1 2236.5 2241.1 2242.5 2268.0 2005.6 2281.4 2284.5 2295.5 2304.6 2307.8 2323.1 2323.7 2330.1 2331.2 7121.6 8206.1 8374.7 8393.2 8399.8 4623.3 5019.4 5161.6 5182.6 5211.6 3279.5 3321.7 3608.1 3609.4 3620.4 1018.9 1047.7 1063.0 1077.2 1079.6 10725.5 10818.5 10879.7 10894.3 10921.5 4447.0 4534.9 4600.3 4614.0 4709.8 9214.8 9215.7 9374.3 9407.9 9423.5 5186.2 5192.8 5198.0 5215.2 5216.2 4092.4 4265.4 4272.4 4673.8 4791.2 3684.2 3764.7 3791.4 3810.8 3877.5 1269.8 1271.4 1271.6 1272.4 1282.9 749.3 880.1 891.2 892.5 915.8 5615.9 5622.8 5728.3 5747.6 5845.2 435.6 443.5 446.4 452.6 452.8 3741.3 3813.9 3892.3 3905.4 3905.8 7920.9 10001.5 10177.4 10180.1 10183.8 9867.0 10106.7 10115.6 10142.9 10144.4 6180.5 6436.4 6925.0 7600.4 7740.3 5453.6 5557.1 5576.5 5588.7 5701.3 9296.9 9322.2 9372.3 9398.5 9432.3 7395.4 7587.2 9351.5 9642.9 9713.7 5108.7 6554.3 6740.5 7110.4 7257.7 6445.4 7034.7 7597.4 7963.8 7967.0 5668.9 6047.2 6998.1 7133.0 7187.7 6956.5 6987.5 7001.6 7041.2 7047.2 5620.9 6667.9 6710.5 6730.5 6752.1 4740.3 4806.8 4808.1 4812.0 4943.3 4807.3 4887.2 4923.7 4928.0 4933.3 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4471.72 Median:4897.46 stddev:1377.45 (28.1%) stddev-sans-first:1345.21 09:46:17 INFO - PROCESS | 1659 | Values: 297.1 302.2 308.9 311.8 312.4 4120.7 4499.4 5250.7 5441.3 5511.3 4594.0 4825.7 5145.6 5205.2 5306.5 3791.6 4042.4 4164.8 4183.6 4185.9 3982.9 4008.2 4158.7 4169.6 4249.7 3608.1 3855.9 4098.5 4131.5 4152.7 4101.4 4116.7 4146.6 4151.0 4154.4 3607.5 3611.6 3632.4 3641.2 3641.7 4893.4 5257.8 5268.9 5299.6 5366.8 4622.5 4631.7 4633.0 4636.9 4649.8 4178.4 4987.0 5428.9 5664.9 5721.8 3210.4 3583.3 3815.8 3817.5 3843.0 5318.7 5398.5 5817.9 5967.5 5977.6 3068.4 3908.5 3919.7 3926.6 3928.7 3674.7 3904.4 3963.5 3972.0 3980.7 4244.9 4592.5 4600.2 4609.0 4630.8 5708.9 5743.6 5750.8 5758.9 5763.1 1041.9 1230.3 1259.6 1268.7 1281.1 779.9 863.4 866.0 873.8 874.2 4387.5 4533.4 4787.0 4891.8 5140.3 4943.0 5117.4 5514.3 5525.8 5541.4 4227.9 4355.6 4678.9 5132.1 5182.6 2249.5 2561.6 2608.9 2809.8 2910.4 5171.4 5242.5 5487.9 5826.1 5874.6 4477.8 4655.6 4795.5 5327.4 5576.8 4901.5 5771.5 5781.5 5828.6 5831.8 4691.8 5516.7 5526.8 5559.1 5564.1 4795.9 5380.4 5428.9 5822.9 5854.1 4924.7 5418.4 5490.9 5680.9 6199.9 3586.8 3825.6 3904.8 3948.1 3949.9 5083.2 5511.7 5596.5 5623.5 5986.7 4758.9 5583.4 5614.4 5654.2 5700.9 4779.9 4946.6 5117.0 5630.3 5755.9 3972.7 5267.9 5482.1 5638.9 5661.8 4960.9 5699.9 5718.8 5774.7 5814.6 5155.5 5590.4 5623.3 5687.2 5795.5 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:20650.18 Median:2518.31 stddev:30865.35 (1225.6%) stddev-sans-first:30915.11 09:46:17 INFO - PROCESS | 1659 | Values: 568.8 703.7 720.0 731.7 764.8 14150.7 14849.0 14925.3 14940.6 14979.8 10992.8 12957.4 13132.1 13148.6 13192.3 648.5 676.0 687.6 814.1 832.7 820.6 822.5 833.5 834.2 835.1 892.9 894.8 895.2 895.2 899.9 853.4 867.8 871.5 886.6 888.4 629.3 653.3 659.6 661.2 662.8 11937.7 13455.4 14084.1 14112.2 14119.2 13737.4 13752.7 13943.9 14078.0 14256.4 9839.2 9958.8 10419.8 11465.4 11742.7 3651.5 3682.8 3732.3 3766.7 3795.2 99461.0 99787.0 104884.4 105111.5 105908.5 10057.3 10296.6 10450.0 10743.0 11003.0 18095.5 19845.0 19883.0 20182.3 20184.4 7106.7 7196.1 7243.0 7294.2 7444.6 4101.8 4128.0 4170.0 4195.2 4196.3 3238.5 3324.0 3336.3 3581.5 3760.4 616.4 649.4 680.9 692.7 695.3 520.5 546.9 620.3 621.5 629.6 7136.4 7223.7 8941.0 9025.8 9038.4 596.6 637.4 650.3 658.9 670.3 4265.5 4453.6 4459.0 4478.3 4516.2 93206.0 110202.4 112667.0 114363.4 117503.0 72047.0 72785.3 95827.5 99644.0 99973.0 62512.1 89712.0 91204.0 92501.6 95646.0 7531.5 7569.6 7941.9 8766.2 8848.7 57116.1 57445.9 57902.0 58459.7 59799.1 59129.1 62549.1 68913.0 81414.0 84229.0 9462.6 11021.1 11389.0 11471.3 11682.2 11969.3 12425.9 12737.3 13441.0 14845.7 12852.8 13337.7 13562.0 13587.7 13634.5 13124.5 13220.7 13325.3 13369.2 13474.2 12799.9 13032.3 13049.6 13604.3 13639.9 9261.8 11278.8 11348.7 11423.5 11581.8 8808.4 9564.5 10196.8 11159.6 11498.9 09:46:17 INFO - PROCESS | 1659 | -------- Summary: end -------- 09:46:17 INFO - PROCESS | 1659 | 09:46:17 INFO - TEST-INFO | 1659: exit 0 09:46:17 INFO - TEST-OK | dromaeo_css | took 1116586ms 09:46:17 INFO - TEST-START | kraken 09:46:17 INFO - operating with platform_type : mac_ 09:46:17 INFO - Initialising browser for kraken test... 09:46:17 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpR2uEw9/profile http://localhost:49272/getInfo.html) 09:46:20 INFO - PROCESS | 1671 | 2016-11-11 09:46:20.573 plugin-container[1672:10430] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 09:46:20 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:20 INFO - PROCESS | 1671 | 2016-11-11 09:46:20.578 plugin-container[1672:10430] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 09:46:20 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:20 INFO - PROCESS | 1671 | 2016-11-11 09:46:20.578 plugin-container[1672:10430] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:20 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 09:46:20 INFO - PROCESS | 1671 | colorDepth:24 09:46:20 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 09:46:20 INFO - PROCESS | 1671 | __metrics 09:46:20 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3174: TypeError: this.tabs is undefined 09:46:21 INFO - TEST-INFO | 1671: exit 0 09:46:21 INFO - Browser initialized. 09:46:21 INFO - Running cycle 1/1 for kraken test... 09:46:21 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpR2uEw9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:23 INFO - PROCESS | 1673 | 2016-11-11 09:46:23.562 plugin-container[1674:10543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:46:23 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:23 INFO - PROCESS | 1673 | 2016-11-11 09:46:23.563 plugin-container[1674:10543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:46:23 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:23 INFO - PROCESS | 1673 | 2016-11-11 09:46:23.563 plugin-container[1674:10543] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:33 INFO - PROCESS | 1673 | RSS: Main: 237363200 09:46:33 INFO - PROCESS | 1673 | 09:46:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 09:46:37 INFO - PROCESS | 1673 | RSS: Main: 249774080 09:46:37 INFO - PROCESS | 1673 | 09:46:40 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 09:46:40 INFO - PROCESS | 1673 | RSS: Main: 257376256 09:46:40 INFO - PROCESS | 1673 | 09:46:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 09:46:44 INFO - PROCESS | 1673 | RSS: Main: 260182016 09:46:44 INFO - PROCESS | 1673 | 09:46:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 09:46:47 INFO - PROCESS | 1673 | RSS: Main: 261279744 09:46:47 INFO - PROCESS | 1673 | 09:46:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:46:49 INFO - PROCESS | 1673 | RSS: Main: 262860800 09:46:49 INFO - PROCESS | 1673 | 09:46:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 09:46:53 INFO - PROCESS | 1673 | RSS: Main: 262983680 09:46:53 INFO - PROCESS | 1673 | 09:46:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 09:46:56 INFO - PROCESS | 1673 | RSS: Main: 263102464 09:46:56 INFO - PROCESS | 1673 | 09:47:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 09:47:00 INFO - PROCESS | 1673 | RSS: Main: 263409664 09:47:00 INFO - PROCESS | 1673 | 09:47:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:03 INFO - PROCESS | 1673 | RSS: Main: 263143424 09:47:03 INFO - PROCESS | 1673 | 09:47:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:05 INFO - PROCESS | 1673 | RSS: Main: 263147520 09:47:05 INFO - PROCESS | 1673 | 09:47:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:08 INFO - PROCESS | 1673 | RSS: Main: 263286784 09:47:08 INFO - PROCESS | 1673 | 09:47:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:12 INFO - PROCESS | 1673 | RSS: Main: 263548928 09:47:12 INFO - PROCESS | 1673 | 09:47:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:15 INFO - PROCESS | 1673 | RSS: Main: 263438336 09:47:15 INFO - PROCESS | 1673 | 09:47:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 09:47:18 INFO - PROCESS | 1673 | RSS: Main: 265023488 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | __start_tp_report 09:47:18 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 09:47:18 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 09:47:18 INFO - PROCESS | 1673 | |i|pagename|runs| 09:47:18 INFO - PROCESS | 1673 | |0;ai-astar;105;118;119;120;128;175;161;118;119;120 09:47:18 INFO - PROCESS | 1673 | |1;audio-beat-detection;148;120;115;150;113;105;131;145;157;152 09:47:18 INFO - PROCESS | 1673 | |2;audio-dft;192;208;223;202;195;194;196;195;197;195 09:47:18 INFO - PROCESS | 1673 | |3;audio-fft;81;61;78;75;64;50;64;61;76;74 09:47:18 INFO - PROCESS | 1673 | |4;audio-oscillator;89;84;122;70;68;72;86;70;71;90 09:47:18 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;120;132;90;87;96;87;92;88;108;90 09:47:18 INFO - PROCESS | 1673 | |6;imaging-darkroom;123;96;98;96;97;95;92;152;95;93 09:47:18 INFO - PROCESS | 1673 | |7;imaging-desaturate;98;109;130;89;135;74;150;147;114;91 09:47:18 INFO - PROCESS | 1673 | |8;json-parse-financial;50;50;91;90;91;50;52;83;49;55 09:47:18 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;70;74;80;79;77;68;71;70;79;63 09:47:18 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;88;90;90;89;89;109;112;76;114;111 09:47:18 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;150;153;149;149;176;155;167;167;272;166 09:47:18 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;172;177;174;173;169;176;177;178;185;176 09:47:18 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;67;79;68;65;76;85;92;91;94 09:47:18 INFO - PROCESS | 1673 | __end_tp_report 09:47:18 INFO - PROCESS | 1673 | __start_cc_report 09:47:18 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,941 09:47:18 INFO - PROCESS | 1673 | __end_cc_report 09:47:18 INFO - PROCESS | 1673 | __startTimestamp1478886438009__endTimestamp 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | ------- Summary: start ------- 09:47:18 INFO - PROCESS | 1673 | Number of tests: 14 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:128.30 Median:120.00 stddev:21.90 (18.2%) stddev-sans-first:21.54 09:47:18 INFO - PROCESS | 1673 | Values: 105.0 118.0 119.0 120.0 128.0 175.0 161.0 118.0 119.0 120.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:146.50 stddev:19.07 (13.0%) stddev-sans-first:19.50 09:47:18 INFO - PROCESS | 1673 | Values: 148.0 120.0 115.0 150.0 113.0 105.0 131.0 145.0 157.0 152.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:199.70 Median:196.50 stddev:9.41 (4.8%) stddev-sans-first:9.55 09:47:18 INFO - PROCESS | 1673 | Values: 192.0 208.0 223.0 202.0 195.0 194.0 196.0 195.0 197.0 195.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:68.40 Median:74.50 stddev:9.83 (13.2%) stddev-sans-first:9.31 09:47:18 INFO - PROCESS | 1673 | Values: 81.0 61.0 78.0 75.0 64.0 50.0 64.0 61.0 76.0 74.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:78.00 stddev:16.46 (21.1%) stddev-sans-first:17.27 09:47:18 INFO - PROCESS | 1673 | Values: 89.0 84.0 122.0 70.0 68.0 72.0 86.0 70.0 71.0 90.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:89.00 stddev:15.78 (17.7%) stddev-sans-first:14.79 09:47:18 INFO - PROCESS | 1673 | Values: 120.0 132.0 90.0 87.0 96.0 87.0 92.0 88.0 108.0 90.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:103.70 Median:95.50 stddev:19.16 (20.1%) stddev-sans-first:19.01 09:47:18 INFO - PROCESS | 1673 | Values: 123.0 96.0 98.0 96.0 97.0 95.0 92.0 152.0 95.0 93.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:113.70 Median:112.00 stddev:26.07 (23.3%) stddev-sans-first:27.02 09:47:18 INFO - PROCESS | 1673 | Values: 98.0 109.0 130.0 89.0 135.0 74.0 150.0 147.0 114.0 91.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:69.00 stddev:19.69 (28.5%) stddev-sans-first:20.00 09:47:18 INFO - PROCESS | 1673 | Values: 50.0 50.0 91.0 90.0 91.0 50.0 52.0 83.0 49.0 55.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:75.50 stddev:5.63 (7.5%) stddev-sans-first:5.85 09:47:18 INFO - PROCESS | 1673 | Values: 70.0 74.0 80.0 79.0 77.0 68.0 71.0 70.0 79.0 63.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:96.80 Median:88.50 stddev:13.34 (15.1%) stddev-sans-first:13.76 09:47:18 INFO - PROCESS | 1673 | Values: 88.0 90.0 90.0 89.0 89.0 109.0 112.0 76.0 114.0 111.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:170.40 Median:166.50 stddev:36.92 (22.2%) stddev-sans-first:38.42 09:47:18 INFO - PROCESS | 1673 | Values: 150.0 153.0 149.0 149.0 176.0 155.0 167.0 167.0 272.0 166.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:176.50 stddev:4.27 (2.4%) stddev-sans-first:4.31 09:47:18 INFO - PROCESS | 1673 | Values: 172.0 177.0 174.0 173.0 169.0 176.0 177.0 178.0 185.0 176.0 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.40 Median:82.00 stddev:11.47 (14.0%) stddev-sans-first:11.40 09:47:18 INFO - PROCESS | 1673 | Values: 67.0 67.0 79.0 68.0 65.0 76.0 85.0 92.0 91.0 94.0 09:47:18 INFO - PROCESS | 1673 | -------- Summary: end -------- 09:47:18 INFO - PROCESS | 1673 | 09:47:18 INFO - TEST-INFO | 1673: exit 0 09:47:18 INFO - TEST-OK | kraken | took 61144ms 09:47:18 INFO - SUITE-END | took 1177s 09:47:19 INFO - Completed test suite (00:19:38) 09:47:19 INFO - javascript benchmark 09:47:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7050.611981946894, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.3168750406153, 3339.01499057778, 3360.697537221651, 3385.2552438463545, 3409.0113866978577, 5506.944930550693, 5529.612927095105, 5534.972325138378, 5579.944200557999, 5599.496045355917, 5519.806806761764, 5534.861628459291, 5615.494605485505, 5616.241807356006, 5667.263255776743, 5013.398392192942, 5044.747762611873, 5097.9490205097845, 5189.195674670414, 5191.610629202825, 5003.39959204896, 5045.192769156935, 5093.439721630618, 5101.51535604117, 5107.948920510785, 5168.689878607277, 5183.533481986601, 5220.791168353261, 5239.973800130994, 5240.187770895517, 5001.89996200074, 5031.195008798574, 5085.567726743231, 5101.82143624975, 5149.356330458692, 4177.310743727281, 4999.850004499871, 5046.470120637354, 5053.292539044537, 5174.560162386202, 4214.894627634334, 4392.472903251631, 4610.769461526944, 4913.582345500637, 5603.355614104389, 5593.776248950037, 6051.1830902828, 6107.8167654969975, 6146.385361463817, 6161.876762464725, 4438.533953934822, 5787.710614469302, 6179.0, 6182.0, 6214.502839772807, 5641.915371269434, 5697.059985102412, 5701.344345400249, 5704.80033198836, 5715.8856822864145, 6119.654885717031, 6149.200603921551, 6153.353897840751, 6206.348333425016, 6232.3144454110015, 5975.193348897843, 5993.670348130808, 6017.0, 6019.067044608093, 6055.394460553909, 6132.448079672851, 6151.9692401538605, 6156.168917196122, 6161.630302181883, 6206.06908963659, 5885.529157667376, 5940.821775346746, 6052.273727152769, 6058.363871793486, 6090.390960903874, 6075.33171351151, 6130.979101202072, 6131.0708424985305, 6131.14164017038, 6144.631322120687, 4764.285357196448, 4811.78346974387, 4827.710337379768, 4830.4444988826735, 4838.637102217348, 4241.830326786894, 4273.423087883159, 4350.0, 4359.80380882861, 4365.519792822787, 5503.174523821459, 5598.888022239533, 5776.0, 5838.211841401441, 5908.4091590841435, 6118.449339559461, 6147.323794382614, 6151.415615516519, 6166.568340216142, 6174.44430001293, 5685.971570142123, 5793.826185214366, 5799.82600521985, 5821.359650438449, 5827.329857066492, 5185.351831021122, 5197.376314842243, 5206.427292997767, 5219.608529360313, 5258.2112683097075, 6248.4688801452385, 6301.999999999908, 6319.747210111544, 6323.873522529616, 6325.082862984841, 6141.938580614136, 6144.139820425234, 6153.0, 6156.168917196122, 6157.6613286269685, 6189.412005859436, 6214.161088253209, 6248.562600617913, 6266.843328916813, 6282.246130464191, 6102.237220347457, 6110.285381942539, 6140.13616995759, 6141.51669281436, 6144.969275153774, 5973.462388385066, 6053.545984051126, 6141.012512800163, 6189.412005859436, 6197.938020619736, 5716.628419152742, 5838.32859221178, 5944.613600116151, 6000.489958353414, 6010.639361638316, 5767.826965191051, 5825.737841797214, 5892.440218179266, 5989.191459153133, 5995.910061348996, 5687.971560142173, 5719.914201286901, 5724.771009159587, 5754.625949313281, 5838.766449341979, 5971.880562388641, 5989.9700501498955, 5995.820125396245, 6024.126501657148, 6045.78839740607, 4767.523247675204, 4782.569568738831, 5790.363060063305, 5839.824805255849, 5853.853653658693], "value": 5546.613837111095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.0706030301216, 511.3965520685592, 511.6188439612489, 513.2045329738904, 519.1382305373077, 28775.28061798456, 39630.0, 39646.00884977877, 41533.37699934496, 41534.58465415345, 40649.39025914614, 40966.59033409665, 40988.0, 41068.383974240336, 41073.58926410735, 9232.953835230848, 9247.075292470767, 9418.62325506979, 9429.717108486722, 9443.527823608792, 9275.72172834816, 9325.487098209596, 9358.953205233998, 9408.905910940906, 9463.0, 8353.916460835406, 9245.72262832116, 9299.349045566812, 9436.339456238065, 9497.667581634643, 9308.115729005767, 9396.107369799858, 9431.386959847589, 9456.952715236448, 9469.479178645171, 9170.358074934757, 9286.674966376144, 9332.766680833034, 9364.063593640649, 9371.859422108673, 38825.0, 38948.415773763416, 39036.438542458265, 39117.804410978046, 39167.41248881269, 3239.4492936200904, 3323.5180898769545, 3338.565986421774, 3413.9487907681405, 3912.393578995232, 29474.263143421373, 29545.409091818045, 29661.555076673867, 29758.85120574405, 31027.53458698121, 3175.5871736674085, 3196.3767065422253, 3210.2295449092285, 3308.9007329780147, 3336.282699219679, 488070.79823004355, 492824.0, 493510.0, 495883.0, 497075.0, 58027.41972580305, 58240.4175958235, 58264.12603810946, 58296.417035829945, 58544.0, 60922.0, 60967.0, 61393.0, 61397.38602613917, 61457.07814382788, 15410.383584656713, 15437.382504699686, 15697.68604627901, 15702.528924132293, 15711.057336559843, 11507.539698411969, 12011.939940300243, 12070.75858482843, 12214.877851221552, 12254.938725306494, 17033.574160646083, 17959.73060404095, 18132.546686332684, 18338.266469341343, 18425.34171924533, 3152.479840826238, 3268.624108227537, 6815.490022638345, 7475.215102414277, 7712.807179820437, 875.2691502595404, 946.5456580841235, 1048.9318194317345, 1054.1514081164582, 1056.0812093478723, 26752.0, 28795.136145915658, 29446.11661650154, 29924.0, 29962.0, 932.9084970584432, 959.1367769007949, 964.7781010367655, 1032.6334151376304, 1060.1624716474053, 14759.704805904037, 15346.0, 15406.845931540765, 15428.614284642974, 15585.610359741097, 665538.0, 667808.0, 673495.0, 674455.2990230805, 675696.0, 55367.0, 55695.0, 55945.720271398386, 55961.0, 56021.9187944667, 1273.9808902866464, 1303.478608556566, 1335.0587835575895, 1344.5764584155959, 1359.320339830085, 21948.89025554862, 22080.88959555192, 28082.017129400374, 28112.29719257035, 30532.542011870282, 53968.73015634897, 59912.70043649928, 59971.40028599833, 60027.39972600218, 60153.0, 618546.0, 619875.0, 633677.0, 633697.0, 634417.7628454135, 41419.1716165669, 42508.14983700371, 42595.0, 42812.35781463218, 43527.564724353615, 47488.0, 48038.27942580934, 48817.02365952732, 48825.26762098642, 49349.25976110289, 39250.6074939247, 39378.212435751695, 39723.80138099291, 40887.795561022, 41145.58854411417, 39018.414723778595, 39113.80443097766, 39783.00542486461, 40106.79679609616, 40147.99630009273, 31886.0, 38268.80865595654, 38340.041498962746, 38894.0, 39101.41347879841, 26277.0, 32550.83724581362, 34944.82527587346, 36645.0, 36815.63184368122, 20597.629214770892, 24503.632445512976, 25794.61308080343, 26084.0, 26446.471070578096], "value": 19847.296284176075, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.9546358973334, 369.3481006024368, 377.34907284933485, 383.11309318926664, 401.2216300377268, 18667.533311667215, 18789.81210187898, 19425.805741942575, 19569.804301956978, 19751.604967900632, 11547.595834145806, 11801.7639647207, 11930.105242106833, 11936.104792140603, 11949.223300485484, 4881.585065269447, 5167.069927413064, 5233.607479439038, 5273.2617433559335, 5280.128778751501, 3796.52543432071, 4014.3978403239603, 5123.5388815006645, 5239.816606418776, 5303.655262407931, 3963.8414463421427, 5460.235567020621, 6342.460890824285, 6385.0, 6401.039844023388, 5149.5107964743665, 5837.737301821428, 6001.4298641628975, 6331.936680633204, 6398.9040164397575, 5681.744321505501, 5689.516391106719, 5696.971515142439, 5699.316082070137, 5701.458361455655, 12778.23330600167, 12962.80555791664, 13065.216087034712, 13114.147580407243, 21730.89134554333, 2190.59473997311, 2192.484766079986, 2220.4226901005854, 2236.1502629000875, 2320.8027317677847, 9492.857607135898, 10204.977307864914, 10343.63797267092, 12482.0, 15251.923740381228, 1821.7449557062023, 1879.1543805287538, 1909.2363054778061, 1932.294712429967, 1943.9125239364262, 111324.0, 113894.86105139008, 120260.0, 126484.0, 127132.0, 8919.955400222958, 10910.0, 11373.829392559119, 11463.942680286713, 11578.884211157949, 14209.78685319721, 14244.643883902985, 14511.1293322401, 14513.637159070897, 14523.564293071226, 8025.59872006409, 8336.083030866721, 8373.41386102979, 8466.74599762008, 8473.745787626258, 7173.89239161413, 8152.347812175011, 8192.75421737349, 8198.426110172231, 8374.665013399517, 4485.394471746337, 4521.728696278234, 4539.568740969603, 4544.091181763661, 4606.470255920545, 1898.193267861181, 1947.9318223862097, 1960.7941166177345, 1961.1956999560439, 1961.4213806926991, 677.4580335731396, 810.3517186251094, 850.3707256630049, 902.7336935604012, 913.7350168451075, 16778.573821225087, 17802.91098544499, 18066.728999065024, 19586.31447899317, 19655.606887862163, 878.6529320918329, 887.8002449448823, 896.3904544909524, 900.8063266397756, 910.517425764346, 8618.870716939251, 10342.224333174892, 10701.83947240792, 10760.730981725519, 10796.67609971702, 527160.0, 554993.0, 598314.0, 602749.0, 609028.0, 95177.0, 116667.0, 119716.40141799235, 120920.0, 122052.0, 951.6003278622995, 960.1406740966795, 993.9602415903283, 995.3629677006721, 1001.0540039662594, 18057.909710451364, 18970.336038239147, 18976.810231897503, 19009.90495047516, 19010.714839277145, 54291.0, 55289.0, 55706.72146639241, 56269.905155215216, 57018.859622806485, 75807.62096189483, 78796.81804773047, 80069.19930800618, 80638.0, 83178.0, 7897.854547264581, 7960.800979975547, 7969.0, 8298.087210406971, 8321.251087402163, 11465.082793376676, 12140.0, 12721.491140354283, 13108.0, 13131.146475479063, 10464.843027354747, 10707.250492465453, 11073.0, 11178.720531986766, 11275.87241275866, 8775.341849361452, 10897.019268265894, 11242.0, 11249.043831274434, 11279.887201127884, 10654.147668186371, 11210.775784484102, 11354.148438867283, 11693.064554835422, 11735.943765061185, 8081.515109093473, 8174.713885013996, 8236.176382361717, 8281.461704989157, 8395.86655801459, 9040.593173307085, 11132.332060076098, 11357.432128393382, 11383.943080284545, 11438.485268162785], "value": 9066.993774423496, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.9852301476983, 1510.5166346769033, 1694.2121913310316, 1816.1736409933483, 1822.8177182281775, 7060.293970602937, 7065.551561929792, 7957.20427957204, 8441.78895527612, 8493.91898984748, 6094.817155485332, 8224.05423376312, 8238.91761082389, 8336.374771892102, 8353.289970352513, 2286.4741109544816, 2319.1651005637955, 2323.7792409721133, 2324.535092981402, 2326.7905888470036, 1958.4810025343295, 1986.4040787763686, 2316.5714342846545, 2345.003373566236, 2356.728976167737, 2164.1343462614923, 2236.535069529461, 2241.1147596699293, 2242.450599603091, 2267.965980510294, 2005.6289586426485, 2281.361218858722, 2284.5430913817304, 2295.517941232343, 2304.5621331946877, 2307.826912981516, 2323.0591610397814, 2323.744388117306, 2330.149495434171, 2331.19573747057, 7121.643917804141, 8206.097329293772, 8374.665013399457, 8393.202645748644, 8399.790005249857, 4623.260278355447, 5019.372578427697, 5161.5870730341485, 5182.559482443997, 5211.583073354161, 3279.4588892832653, 3321.684439978198, 3608.1340478285024, 3609.350316942949, 3620.420732682758, 1018.9388636681823, 1047.6699839550517, 1063.0432610650398, 1077.1867240233653, 1079.643717573199, 10725.51735171919, 10818.513166907429, 10879.728006799814, 10894.346339219594, 10921.453927303683, 4446.9777651111535, 4534.909301813946, 4600.2869555218995, 4614.007988282536, 4709.788059537329, 9214.815703685888, 9215.677451289173, 9374.296927730311, 9407.90592094084, 9423.528823558794, 5186.2479940409185, 5192.818251361185, 5197.974010129925, 5215.217717342429, 5216.191490319006, 4092.4475195848777, 4265.360195970629, 4272.423222864873, 4673.789679464432, 4791.209450440639, 3684.1894783147663, 3764.661180493769, 3791.3933770596573, 3810.8285127169343, 3877.515310586177, 1269.7777888869412, 1271.357964228059, 1271.6058022013206, 1272.4337669736879, 1282.9358532073359, 749.2582343479916, 880.097899652861, 891.2112780189578, 892.4957399069505, 915.7985243246475, 5615.887682246333, 5622.775088996476, 5728.312602487645, 5747.57281553398, 5845.240118784616, 435.5861931165405, 443.4634092747747, 446.3777583126741, 452.56101581465924, 452.8075567883702, 3741.326561218952, 3813.866514671973, 3892.2799282132896, 3905.414187871842, 3905.843766249375, 7920.881186782203, 10001.49992500372, 10177.440240786827, 10180.083792458714, 10183.847242291371, 9867.0, 10106.747331316776, 10115.59537618487, 10142.949285253526, 10144.442055687008, 6180.536459765535, 6436.35636436362, 6924.965375173092, 7600.441909408558, 7740.26467485588, 5453.590980676386, 5557.055300598827, 5576.470235327637, 5588.6646801192055, 5701.315842098975, 9296.860547091934, 9322.161005509537, 9372.343935924693, 9398.483083430478, 9432.292578056537, 7395.371393431618, 7587.165411804586, 9351.532423378667, 9642.855357169508, 9713.70858874235, 5108.66793658411, 6554.344565543598, 6740.528163028541, 7110.395616372666, 7257.709691612276, 6445.4199122079235, 7034.718611255493, 7597.354224890946, 7963.840723185388, 7967.0, 5668.866226754748, 6047.244094488189, 6998.125234345707, 7133.037039999534, 7187.6765545551625, 6956.513044087068, 6987.545809522365, 7001.614911179934, 7041.225465198926, 7047.189573199148, 5620.932022915633, 6667.899981500184, 6710.530262881552, 6730.46156307504, 6752.08846805675, 4740.269801522489, 4806.831760888352, 4808.0624278266805, 4812.0, 4943.258511223202, 4807.302941073594, 4887.169181239126, 4923.679960802537, 4928.0, 4933.33399991011], "value": 4251.258975270925, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.1101550855189, 302.2082144780675, 308.9057837359606, 311.8222613110525, 312.35654551623657, 4120.690948178887, 4499.392582001429, 5250.684958902469, 5441.347038355393, 5511.255980442639, 4593.977030114845, 4825.686330388522, 5145.562627176694, 5205.193195054763, 5306.4958828911285, 3791.601881802412, 4042.3532234842432, 4164.750114993095, 4183.581641835823, 4185.916281674365, 3982.9004274893214, 4008.158286759784, 4158.688098392618, 4169.5830416958215, 4249.723767955088, 3608.0799396153943, 3855.9228815423676, 4098.4671992640915, 4131.5042194936495, 4152.691902050859, 4101.364288535282, 4116.653507500726, 4146.564610715876, 4150.958490415087, 4154.356074808379, 3607.549056367954, 3611.638836116367, 3632.364336241147, 3641.217138315275, 3641.708663306929, 4893.412790465131, 5257.792746424678, 5268.916589643019, 5299.629025968184, 5366.758495867695, 4622.46841613212, 4631.652626053025, 4632.969316694497, 4636.930446043312, 4649.790759415834, 4178.435911151986, 4986.950130498685, 5428.874055717683, 5664.915026274609, 5721.7711291548285, 3210.4060748761553, 3583.33708263972, 3815.790131542764, 3817.503724515795, 3842.9807850960847, 5318.6542874713405, 5398.546199385522, 5817.941820581782, 5967.4629283364275, 5977.551683623746, 3068.3709839482854, 3908.511436070491, 3919.6864250859862, 3926.5680775114715, 3928.724989250782, 3674.669279764834, 3904.3752999520507, 3963.544192417909, 3971.980140099281, 3980.721349505565, 4244.936325955113, 4592.5407459254475, 4600.194965880957, 4609.0, 4630.791614377361, 5708.942910570841, 5743.59794814359, 5750.769969201185, 5758.945292017329, 5763.106718458645, 1041.880183778875, 1230.274138258414, 1259.5591542959892, 1268.7145392286845, 1281.0520215040804, 779.8791187365968, 863.3826813828143, 865.9523726194993, 873.8121303919688, 874.2132081127037, 4387.4954380246045, 4533.433320834895, 4786.994731106507, 4891.804327826918, 5140.280360749498, 4942.975285123552, 5117.364557253018, 5514.255575497336, 5525.834224973257, 5541.418151094158, 4227.936580951288, 4355.586219309161, 4678.906421871611, 5132.127538318495, 5182.5594824439595, 2249.527599204153, 2561.6039258604105, 2608.869556522204, 2809.845458499802, 2910.4179164167253, 5171.3535808024, 5242.475752424727, 5487.890242195133, 5826.067829147316, 5874.647521148745, 4477.820887164542, 4655.604273636779, 4795.496472870368, 5327.440618735054, 5576.776928922879, 4901.509849015141, 5771.509421699203, 5781.537477001913, 5828.60113572748, 5831.792514117304, 4691.765411729331, 5516.724163791875, 5526.834194974157, 5559.081482221866, 5564.137558678412, 4795.930604413248, 5380.408155102858, 5428.918566221589, 5822.854428639318, 5854.092615644582, 4924.704517728947, 5418.431064738145, 5490.940897163564, 5680.943190568041, 6199.855213559963, 3586.8027258501033, 3825.6365645263654, 3904.7657140571655, 3948.0919388540224, 3949.9012524687114, 5083.212102124176, 5511.732301570581, 5596.524295434933, 5623.465770751772, 5986.6707331097205, 4758.881027974328, 5583.431621454177, 5614.438556144353, 5654.236678048575, 5700.885982280414, 4779.904401911873, 4946.579540739078, 5117.032121369522, 5630.3402788188005, 5755.8561035974435, 3972.662323702518, 5267.894642107213, 5482.122860342485, 5638.9436105638415, 5661.830145095653, 4960.90860010797, 5699.857503562278, 5718.771249149988, 5774.740136693943, 5814.592978491465, 5155.53600175986, 5590.357108932525, 5623.268975033219, 5687.232223649754, 5795.536357091339], "value": 4029.37942835757, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.8236646639544, 703.7044441334639, 719.9784006479807, 731.6744048898236, 764.7858599592113, 14150.716985660281, 14849.034812737165, 14925.253737313145, 14940.626484337898, 14979.775303370445, 10992.780144397128, 12957.416916238768, 13132.0807543472, 13148.605541833736, 13192.340382980836, 648.4909346163263, 675.9526833121683, 687.5737043033326, 814.0963530481162, 832.7376876283981, 820.5609998650723, 822.5311572403732, 833.4790755777639, 834.1783343406739, 835.1106072033265, 892.9285657147412, 894.8120894612139, 895.1585509620935, 895.230102112183, 899.9460032398044, 853.3642436384902, 867.7960679240374, 871.5424402188838, 886.5655828643951, 888.3648191543014, 629.3077614623877, 653.3270731146927, 659.581165959619, 661.2098542242022, 662.7580932959435, 11937.701557461047, 13455.394507247198, 14084.084534505328, 14112.223827689473, 14119.223442710647, 13737.381817818123, 13752.724945501033, 13943.860561394458, 14078.0, 14256.429742810275, 9839.163671087967, 9958.80082398348, 10419.791604167875, 11465.426728663533, 11742.706432339175, 3651.548220567851, 3682.797446140461, 3732.309522738275, 3766.736328457009, 3795.165063686012, 99461.0, 99787.0, 104884.37789055334, 105111.47444262807, 105908.47045764721, 10057.346272492265, 10296.588136474456, 10450.0, 10743.033127018605, 11002.954719301653, 18095.54761130956, 19845.0, 19883.0, 20182.293619723532, 20184.394468165978, 7106.680199391039, 7196.0645116134565, 7243.0, 7294.161171465349, 7444.627768611548, 4101.774402407897, 4127.979360103181, 4170.0, 4195.160967806451, 4196.328587425997, 3238.530413090136, 3324.036029551403, 3336.332733453319, 3581.534400527915, 3760.435934609776, 616.4390404731672, 649.389573800626, 680.880845851974, 692.7125243024088, 695.3255342317943, 520.456123351099, 546.9124940009659, 620.271181361898, 621.4655396359127, 629.5844742469948, 7136.429085673134, 7223.674934627954, 8940.955295223483, 9025.781519494916, 9038.367314288069, 596.60325883288, 637.4382762232549, 650.3171669746747, 658.8517583543664, 670.3296703296703, 4265.466816647919, 4453.643708503312, 4459.0, 4478.283474644041, 4516.16450956574, 93206.0, 110202.44898775616, 112667.0, 114363.42818286021, 117503.0, 72047.0, 72785.27214727891, 95827.52086239523, 99644.0, 99973.0, 62512.06231906525, 89712.0, 91204.0, 92501.61247581292, 95646.0, 7531.497435386771, 7569.584359376078, 7941.888135661127, 8766.1867087817, 8848.690295839871, 57116.143257851996, 57445.85108297727, 57902.0, 58459.707701462925, 59799.10301345396, 59129.11306330495, 62549.06176407448, 68913.0, 81414.0, 84229.0, 9462.601666583614, 11021.118310534977, 11388.974992250738, 11471.311721296748, 11682.24065435744, 11969.341686207343, 12425.875741242471, 12737.29944853042, 13441.0, 14845.703085938005, 12852.807207891701, 13337.733245334844, 13562.0, 13587.660308491972, 13634.522791702242, 13124.540641077516, 13220.669483262995, 13325.333733313102, 13369.197848129143, 13474.19154850712, 12799.872001279868, 13032.283224422368, 13049.608511744664, 13604.251766152956, 13639.863601363859, 9261.768455788659, 11278.830817537579, 11348.659540213806, 11423.543058277575, 11581.826272606086, 8808.427452215585, 9564.4739539326, 10196.796064078826, 11159.625914232083, 11498.94250528742], "value": 7183.956317005589, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 119.0, 120.0, 128.0, 175.0, 161.0, 118.0, 119.0, 120.0], "value": 128.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 115.0, 150.0, 113.0, 105.0, 131.0, 145.0, 157.0, 152.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 208.0, 223.0, 202.0, 195.0, 194.0, 196.0, 195.0, 197.0, 195.0], "value": 199.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 78.0, 75.0, 64.0, 50.0, 64.0, 61.0, 76.0, 74.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 122.0, 70.0, 68.0, 72.0, 86.0, 70.0, 71.0, 90.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 132.0, 90.0, 87.0, 96.0, 87.0, 92.0, 88.0, 108.0, 90.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 96.0, 98.0, 96.0, 97.0, 95.0, 92.0, 152.0, 95.0, 93.0], "value": 103.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 130.0, 89.0, 135.0, 74.0, 150.0, 147.0, 114.0, 91.0], "value": 113.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 91.0, 90.0, 91.0, 50.0, 52.0, 83.0, 49.0, 55.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 80.0, 79.0, 77.0, 68.0, 71.0, 70.0, 79.0, 63.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 90.0, 89.0, 89.0, 109.0, 112.0, 76.0, 114.0, 111.0], "value": 96.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 149.0, 149.0, 176.0, 155.0, 167.0, 167.0, 272.0, 166.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 177.0, 174.0, 173.0, 169.0, 176.0, 177.0, 178.0, 185.0, 176.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 79.0, 68.0, 65.0, 76.0, 85.0, 92.0, 91.0, 94.0], "value": 78.4, "unit": "score"}]}]} 09:47:19 INFO - Return code: 0 09:47:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:19 ERROR - # TBPL SUCCESS # 09:47:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:19 INFO - 2016-11-11 09:47:19.328 screenresolution[1678:10931] starting screenresolution argv=screenresolution get 09:47:19 INFO - 2016-11-11 09:47:19.331 screenresolution[1678:10931] Display 0: 1600x1200x32@60 09:47:19 INFO - 2016-11-11 09:47:19.337 screenresolution[1679:10934] starting screenresolution argv=screenresolution list 09:47:19 INFO - Available Modes on Display 0 09:47:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:19 INFO - Intel Iris: 09:47:19 INFO - Chipset Model: Intel Iris 09:47:19 INFO - Type: GPU 09:47:19 INFO - Bus: Built-In 09:47:19 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:19 INFO - Vendor: Intel (0x8086) 09:47:19 INFO - Device ID: 0x0a2e 09:47:19 INFO - Revision ID: 0x0009 09:47:19 INFO - Displays: 09:47:19 INFO - Display: 09:47:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:19 INFO - Display Serial Number: 1600x1200 60 09:47:19 INFO - Main Display: Yes 09:47:19 INFO - Mirror: Off 09:47:19 INFO - Online: Yes 09:47:19 INFO - Rotation: Supported 09:47:19 INFO - Return code: 0 09:47:19 INFO - Running post-action listener: _resource_record_post_action 09:47:19 INFO - [mozharness: 2016-11-11 17:47:19.580171Z] Finished run-tests step (success) 09:47:19 INFO - Running post-run listener: _resource_record_post_run 09:47:19 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38324736; Write bytes: 702235648; Read time: 244; Write time: 1972 09:47:19 INFO - TinderboxPrint: CPU usage
54.3% 09:47:19 INFO - TinderboxPrint: I/O read bytes / time
38,324,736 / 244 09:47:19 INFO - TinderboxPrint: I/O write bytes / time
702,235,648 / 1,972 09:47:19 INFO - TinderboxPrint: CPU idle
2,216.7 (45.7%) 09:47:19 INFO - TinderboxPrint: CPU system
980.6 (20.2%) 09:47:19 INFO - TinderboxPrint: CPU user
1,656.3 (34.1%) 09:47:19 INFO - TinderboxPrint: Swap in / out
436,457,472 / 0 09:47:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 391485952; Write bytes: 386557440; Read time: 29624; Write time: 783 09:47:19 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36256256; Write bytes: 299617792; Read time: 224; Write time: 1140 09:47:20 INFO - Running post-run listener: _upload_blobber_files 09:47:20 INFO - Blob upload gear active. 09:47:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:47:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:47:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:47:20 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:47:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:47:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:21 INFO - (blobuploader) - INFO - Done attempting. 09:47:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:47:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:47:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:24 INFO - (blobuploader) - INFO - Done attempting. 09:47:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:26 INFO - (blobuploader) - INFO - Done attempting. 09:47:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:26 INFO - Return code: 0 09:47:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89b31160aef0722aed38d34744ec4434e09c44e052e5b3ee16397afaa1c8c0196518bf2e21a3f4947b6d95b660c24e4bc23bac494c838fe3d228b94d69fa646f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6af2e4d1572a32fbe7d0f7aadd858c9888e23ccc1014a533f4a200f14d8c10ed123b6f4f74d44538a950e6cb88cc08809b5d88fc73ee3d52664ae274ab61a665", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3991fe572a9c51085077f62f4d106334f1ddb8efd4524d42959ce775db2befc71edeb5f83663ca957b1b46e6c0df524fb47aa0d7c3d9c56ae3f5a580e7737a92"} 09:47:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:26 INFO - Contents: 09:47:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89b31160aef0722aed38d34744ec4434e09c44e052e5b3ee16397afaa1c8c0196518bf2e21a3f4947b6d95b660c24e4bc23bac494c838fe3d228b94d69fa646f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6af2e4d1572a32fbe7d0f7aadd858c9888e23ccc1014a533f4a200f14d8c10ed123b6f4f74d44538a950e6cb88cc08809b5d88fc73ee3d52664ae274ab61a665", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3991fe572a9c51085077f62f4d106334f1ddb8efd4524d42959ce775db2befc71edeb5f83663ca957b1b46e6c0df524fb47aa0d7c3d9c56ae3f5a580e7737a92"} 09:47:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:26 INFO - Copying logs to upload dir... 09:47:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.484832 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-11 09:47:26.640200) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 09:47:26.644036) ========= 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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89b31160aef0722aed38d34744ec4434e09c44e052e5b3ee16397afaa1c8c0196518bf2e21a3f4947b6d95b660c24e4bc23bac494c838fe3d228b94d69fa646f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6af2e4d1572a32fbe7d0f7aadd858c9888e23ccc1014a533f4a200f14d8c10ed123b6f4f74d44538a950e6cb88cc08809b5d88fc73ee3d52664ae274ab61a665", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3991fe572a9c51085077f62f4d106334f1ddb8efd4524d42959ce775db2befc71edeb5f83663ca957b1b46e6c0df524fb47aa0d7c3d9c56ae3f5a580e7737a92"} build_url:https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010880 build_url: 'https://queue.taskcluster.net/v1/task/Uhzv9Xi3THa7D379_98PBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89b31160aef0722aed38d34744ec4434e09c44e052e5b3ee16397afaa1c8c0196518bf2e21a3f4947b6d95b660c24e4bc23bac494c838fe3d228b94d69fa646f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6af2e4d1572a32fbe7d0f7aadd858c9888e23ccc1014a533f4a200f14d8c10ed123b6f4f74d44538a950e6cb88cc08809b5d88fc73ee3d52664ae274ab61a665", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3991fe572a9c51085077f62f4d106334f1ddb8efd4524d42959ce775db2befc71edeb5f83663ca957b1b46e6c0df524fb47aa0d7c3d9c56ae3f5a580e7737a92"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 09:47:26.676465) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:47:26.676841) ========= 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.Adv6OaaYOY/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.qYqPgAgq5M/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.005042 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:47:26.725957) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 09:47:26.726308) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 09:47:26.726758) ========= ========= Total master_lag: 0.18 =========