builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0136 starttime: 1477954896.72 results: success (0) buildid: 20161031120204 builduid: c6d2503dd6bb4f12a2c414ab3220dcb8 revision: 1aa20bcbb80e1014e4d01057f7d52269b0c2d908 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.723596) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.724500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.725071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.749636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.749912) ========= 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.zpMbEwuZke/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.mSIkecpAvG/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-10-31 16:01:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.9M=0.001s 2016-10-31 16:01:36 (19.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117481 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.890195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.890807) ========= 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.zpMbEwuZke/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.mSIkecpAvG/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.018925 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.942627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:36.942972) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1aa20bcbb80e1014e4d01057f7d52269b0c2d908 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1aa20bcbb80e1014e4d01057f7d52269b0c2d908 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zpMbEwuZke/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.mSIkecpAvG/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-10-31 16:01:37,007 truncating revision to first 12 chars 2016-10-31 16:01:37,008 Setting DEBUG logging. 2016-10-31 16:01:37,008 attempt 1/10 2016-10-31 16:01:37,008 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1aa20bcbb80e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 16:01:37,339 unpacking tar archive at: fx-team-1aa20bcbb80e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.567456 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:37.535260) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:37.535825) ========= script_repo_revision: 1aa20bcbb80e1014e4d01057f7d52269b0c2d908 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:37.536254) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:37.536536) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 16:01:37.556053) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-31 16:01:37.556723) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zpMbEwuZke/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.mSIkecpAvG/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:01:37 INFO - MultiFileLogger online at 20161031 16:01:37 in /builds/slave/test 16:01:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:01:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:37 INFO - {'append_to_log': False, 16:01:37 INFO - 'base_work_dir': '/builds/slave/test', 16:01:37 INFO - 'blob_upload_branch': 'Fx-Team', 16:01:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:37 INFO - 'branch': 'Fx-Team', 16:01:37 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:37 INFO - 'config_files': ('talos/mac_config.py',), 16:01:37 INFO - 'default_actions': ('clobber', 16:01:37 INFO - 'read-buildbot-config', 16:01:37 INFO - 'download-and-extract', 16:01:37 INFO - 'populate-webroot', 16:01:37 INFO - 'create-virtualenv', 16:01:37 INFO - 'install', 16:01:37 INFO - 'run-tests'), 16:01:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:37 INFO - 'download_minidump_stackwalk': True, 16:01:37 INFO - 'download_symbols': 'ondemand', 16:01:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:01:37 INFO - 'tooltool.py': '/tools/tooltool.py', 16:01:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:01:37 INFO - '/tools/misc-python/virtualenv.py')}, 16:01:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:37 INFO - 'installer_path': 'installer.exe', 16:01:37 INFO - 'log_level': 'info', 16:01:37 INFO - 'log_name': 'talos', 16:01:37 INFO - 'log_to_console': True, 16:01:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:01:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:01:37 INFO - 'opt_config_files': (), 16:01:37 INFO - 'pip_index': False, 16:01:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:01:37 INFO - 'cmd': ('bash', 16:01:37 INFO - '-c', 16:01:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:01:37 INFO - 'enabled': True, 16:01:37 INFO - 'halt_on_failure': False, 16:01:37 INFO - 'name': 'check_screen_resolution'},), 16:01:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:01:37 INFO - 'cmd': ('bash', 16:01:37 INFO - '-c', 16:01:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:01:37 INFO - 'enabled': True, 16:01:37 INFO - 'halt_on_failure': False, 16:01:37 INFO - 'name': 'check_screen_resolution'},), 16:01:37 INFO - 'run_cmd_checks_enabled': True, 16:01:37 INFO - 'sps_profile': False, 16:01:37 INFO - 'sps_profile_interval': 0, 16:01:37 INFO - 'suite': 'dromaeojs-e10s', 16:01:37 INFO - 'system_bits': '32', 16:01:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:37 INFO - 'title': 't-yosemite-r7-0136', 16:01:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:37 INFO - 'use_talos_json': True, 16:01:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:37 INFO - 'work_dir': 'build'} 16:01:37 INFO - [mozharness: 2016-10-31 23:01:37.753530Z] Running clobber step. 16:01:37 INFO - Running pre-action listener: _resource_record_pre_action 16:01:37 INFO - Running main action method: clobber 16:01:37 INFO - rmtree: /builds/slave/test/build 16:01:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:39 INFO - Running post-action listener: _resource_record_post_action 16:01:39 INFO - [mozharness: 2016-10-31 23:01:39.455740Z] Finished clobber step (success) 16:01:39 INFO - [mozharness: 2016-10-31 23:01:39.455978Z] Running read-buildbot-config step. 16:01:39 INFO - Running pre-action listener: _resource_record_pre_action 16:01:39 INFO - Running main action method: read_buildbot_config 16:01:39 INFO - Using buildbot properties: 16:01:39 INFO - { 16:01:39 INFO - "project": "", 16:01:39 INFO - "product": "firefox", 16:01:39 INFO - "script_repo_revision": "production", 16:01:39 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 16:01:39 INFO - "repository": "", 16:01:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 16:01:39 INFO - "buildid": "20161031120204", 16:01:39 INFO - "pgo_build": "False", 16:01:39 INFO - "basedir": "/builds/slave/test", 16:01:39 INFO - "buildnumber": 11, 16:01:39 INFO - "slavename": "t-yosemite-r7-0136", 16:01:39 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:01:39 INFO - "platform": "yosemite_r7", 16:01:39 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 16:01:39 INFO - "branch": "fx-team", 16:01:39 INFO - "repo_path": "integration/fx-team", 16:01:39 INFO - "revision": "1aa20bcbb80e1014e4d01057f7d52269b0c2d908", 16:01:39 INFO - "stage_platform": "macosx64", 16:01:39 INFO - "builduid": "c6d2503dd6bb4f12a2c414ab3220dcb8", 16:01:39 INFO - "slavebuilddir": "test" 16:01:39 INFO - } 16:01:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:01:39 INFO - Running post-action listener: _resource_record_post_action 16:01:39 INFO - [mozharness: 2016-10-31 23:01:39.461517Z] Finished read-buildbot-config step (success) 16:01:39 INFO - [mozharness: 2016-10-31 23:01:39.461696Z] Running download-and-extract step. 16:01:39 INFO - Running pre-action listener: _resource_record_pre_action 16:01:39 INFO - Running main action method: download_and_extract 16:01:39 INFO - mkdir: /builds/slave/test/build/tests 16:01:39 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')]} 16:01:39 INFO - trying https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:01:39 INFO - Downloading https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/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 16:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/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 16:01:40 INFO - Downloaded 1199 bytes. 16:01:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:01:40 INFO - Using the following test package requirements: 16:01:40 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:01:40 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:01:40 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:01:40 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:01:40 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:01:40 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:01:40 INFO - u'jsshell-mac.zip'], 16:01:40 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:01:40 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:01:40 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:01:40 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:01:40 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:01:40 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:01:40 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:01:40 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:01:40 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:01:40 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:01:40 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:01:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:01:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:01:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:01:40 INFO - Fetch https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:01:40 INFO - Http code: 200 16:01:40 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 16:01:40 INFO - x-amz-cf-id: Eqq-szOt0DtIT20Wn93dd6te34Or25EAOxUDFSpsT6bKkx7YVjQ1xw== 16:01:40 INFO - x-amz-version-id: Pb7Jspka__mIDeXUjGUpBiaE6EFhkDe1 16:01:41 INFO - Expected file size: 16404303 16:01:41 INFO - Obtained file size: 16404303 16:01:48 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 16:01:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:01:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:01:48 INFO - Fetch https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:01:48 INFO - Http code: 200 16:01:48 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 16:01:48 INFO - x-amz-cf-id: 46emx1oXURND4Z983xLsAxjqE401O_CO1q8BsawQNYMDTqMA5sbzoQ== 16:01:48 INFO - x-amz-version-id: Pb7Jspka__mIDeXUjGUpBiaE6EFhkDe1 16:01:48 INFO - Expected file size: 16404303 16:01:48 INFO - Obtained file size: 16404303 16:01:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:01:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:01:55 INFO - Fetch https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:01:55 INFO - Http code: 200 16:01:55 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 16:01:55 INFO - x-amz-cf-id: pxbS1CA36hrSXJ1CpB_y0dUMj4UoArXSBF6yrHEVs6lbI8c85MKTOA== 16:01:55 INFO - x-amz-version-id: udzeXVnTO510HhivQO.PGKLRnYmgwjbq 16:01:56 INFO - Expected file size: 14409038 16:01:56 INFO - Obtained file size: 14409038 16:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:56 INFO - trying https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:01:56 INFO - Downloading https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:01:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/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 16:01:59 INFO - Downloaded 112068671 bytes. 16:01:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:01:59 INFO - mkdir: /builds/slave/test/properties 16:01:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:59 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:59 INFO - Contents: 16:01:59 INFO - build_url:https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:02:00 INFO - Running post-action listener: _resource_record_post_action 16:02:00 INFO - Running post-action listener: set_extra_try_arguments 16:02:00 INFO - [mozharness: 2016-10-31 23:02:00.025740Z] Finished download-and-extract step (success) 16:02:00 INFO - [mozharness: 2016-10-31 23:02:00.025892Z] Running populate-webroot step. 16:02:00 INFO - Running pre-action listener: _resource_record_pre_action 16:02:00 INFO - Running main action method: populate_webroot 16:02:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:02:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:02:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:02:00 INFO - '--tppagecycles', 16:02:00 INFO - '7'], 16:02:00 INFO - 'tests': ['tsvgm']}}, 16:02:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:02:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:02:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:02:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:02:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:00 INFO - 'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:00 INFO - 'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['damp', 'tps']}, 16:02:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:00 INFO - 'tests': ['damp', 'tps']}, 16:02:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['dromaeo_dom']}, 16:02:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:02:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['basic_compositor_video']}, 16:02:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:02:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['a11yr', 16:02:00 INFO - 'ts_paint', 16:02:00 INFO - 'tpaint', 16:02:00 INFO - 'sessionrestore', 16:02:00 INFO - 'sessionrestore_no_auto_restore', 16:02:00 INFO - 'tabpaint']}, 16:02:00 INFO - 'other-e10s': {'tests': ['a11yr', 16:02:00 INFO - 'ts_paint', 16:02:00 INFO - 'tpaint', 16:02:00 INFO - 'sessionrestore', 16:02:00 INFO - 'sessionrestore_no_auto_restore', 16:02:00 INFO - 'tabpaint']}, 16:02:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:02:00 INFO - 'ts_paint', 16:02:00 INFO - 'tpaint', 16:02:00 INFO - 'sessionrestore', 16:02:00 INFO - 'sessionrestore_no_auto_restore', 16:02:00 INFO - 'tabpaint']}, 16:02:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:02:00 INFO - 'ts_paint', 16:02:00 INFO - 'tpaint', 16:02:00 INFO - 'sessionrestore', 16:02:00 INFO - 'sessionrestore_no_auto_restore', 16:02:00 INFO - 'tabpaint']}, 16:02:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['a11yr', 16:02:00 INFO - 'ts_paint', 16:02:00 INFO - 'tpaint', 16:02:00 INFO - 'sessionrestore', 16:02:00 INFO - 'sessionrestore_no_auto_restore', 16:02:00 INFO - 'tabpaint']}, 16:02:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['a11yr', 16:02:00 INFO - 'ts_paint', 16:02:00 INFO - 'tpaint', 16:02:00 INFO - 'sessionrestore', 16:02:00 INFO - 'sessionrestore_no_auto_restore', 16:02:00 INFO - 'tabpaint']}, 16:02:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['tsvgx', 16:02:00 INFO - 'tsvgr_opacity', 16:02:00 INFO - 'tart', 16:02:00 INFO - 'tscrollx', 16:02:00 INFO - 'cart']}, 16:02:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:02:00 INFO - 'tsvgr_opacity', 16:02:00 INFO - 'tart', 16:02:00 INFO - 'tscrollx', 16:02:00 INFO - 'cart']}, 16:02:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:00 INFO - 'talos_options': ['--disable-e10s'], 16:02:00 INFO - 'tests': ['tp5o']}, 16:02:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:00 INFO - 'tests': ['tp5o']}, 16:02:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:00 INFO - 'talos_options': ['--disable-e10s', 16:02:00 INFO - '--xperf_path', 16:02:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:00 INFO - 'tests': ['tp5n']}, 16:02:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:00 INFO - 'talos_options': ['--xperf_path', 16:02:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:00 INFO - 'tests': ['tp5n']}}, 16:02:00 INFO - 'talos.zip': {'path': '', 16:02:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:02:00 INFO - Running post-action listener: _resource_record_post_action 16:02:00 INFO - [mozharness: 2016-10-31 23:02:00.045119Z] Finished populate-webroot step (success) 16:02:00 INFO - [mozharness: 2016-10-31 23:02:00.045253Z] Running create-virtualenv step. 16:02:00 INFO - Running pre-action listener: _resource_record_pre_action 16:02:00 INFO - Running main action method: create_virtualenv 16:02:00 INFO - Creating virtualenv /builds/slave/test/build/venv 16:02:00 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 16:02:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:02:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:02:00 INFO - Using real prefix '/tools/python27' 16:02:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:02:00 INFO - Installing distribute.............................................................................................................................................................................................done. 16:02:03 INFO - Installing pip.................done. 16:02:03 INFO - Return code: 0 16:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:02:03 INFO - Reading from file tmpfile_stdout 16:02:03 INFO - Output received: 16:02:03 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) 16:02:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:02:03 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')]} 16:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:03 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')]} 16:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:03 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:03 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 16:02:03 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 16:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:04 INFO - 'HOME': '/Users/cltbld', 16:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:04 INFO - 'LOGNAME': 'cltbld', 16:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:04 INFO - 'MOZ_NO_REMOTE': '1', 16:02:04 INFO - 'NO_EM_RESTART': '1', 16:02:04 INFO - 'PAGER': '/bin/cat', 16:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:04 INFO - 'PWD': '/builds/slave/test', 16:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:04 INFO - 'SHELL': '/bin/bash', 16:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:04 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:04 INFO - 'USER': 'cltbld', 16:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:04 INFO - 'XPC_FLAGS': '0x0', 16:02:04 INFO - 'XPC_SERVICE_NAME': '0', 16:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:04 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 16:02:04 INFO - Cleaning up... 16:02:04 INFO - Return code: 0 16:02:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:04 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:04 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 16:02:04 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 16:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:04 INFO - 'HOME': '/Users/cltbld', 16:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:04 INFO - 'LOGNAME': 'cltbld', 16:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:04 INFO - 'MOZ_NO_REMOTE': '1', 16:02:04 INFO - 'NO_EM_RESTART': '1', 16:02:04 INFO - 'PAGER': '/bin/cat', 16:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:04 INFO - 'PWD': '/builds/slave/test', 16:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:04 INFO - 'SHELL': '/bin/bash', 16:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:04 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:04 INFO - 'USER': 'cltbld', 16:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:04 INFO - 'XPC_FLAGS': '0x0', 16:02:04 INFO - 'XPC_SERVICE_NAME': '0', 16:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:04 INFO - Downloading/unpacking psutil>=3.1.1 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:02:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:07 INFO - Installing collected packages: psutil 16:02:07 INFO - Running setup.py install for psutil 16:02:08 INFO - building 'psutil._psutil_osx' extension 16:02:08 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 16:02:08 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 16:02:08 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 16:02:08 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 16:02:08 INFO - building 'psutil._psutil_posix' extension 16:02:08 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 16:02:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:02:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:02:08 INFO - ^ 16:02:08 INFO - 1 warning generated. 16:02:08 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 16:02:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:08 INFO - Successfully installed psutil 16:02:08 INFO - Cleaning up... 16:02:08 INFO - Return code: 0 16:02:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:02:08 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')]} 16:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:08 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')]} 16:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:08 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:08 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 16:02:08 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 16:02:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:08 INFO - 'HOME': '/Users/cltbld', 16:02:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:08 INFO - 'LOGNAME': 'cltbld', 16:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:08 INFO - 'MOZ_NO_REMOTE': '1', 16:02:08 INFO - 'NO_EM_RESTART': '1', 16:02:08 INFO - 'PAGER': '/bin/cat', 16:02:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:08 INFO - 'PWD': '/builds/slave/test', 16:02:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:08 INFO - 'SHELL': '/bin/bash', 16:02:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:08 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:08 INFO - 'USER': 'cltbld', 16:02:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:08 INFO - 'XPC_FLAGS': '0x0', 16:02:08 INFO - 'XPC_SERVICE_NAME': '0', 16:02:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:02:09 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 16:02:09 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 16:02:09 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 16:02:09 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 16:02:09 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 16:02:09 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 16:02:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:02:11 INFO - Installing collected packages: mozsystemmonitor 16:02:11 INFO - Running setup.py install for mozsystemmonitor 16:02:11 INFO - Successfully installed mozsystemmonitor 16:02:11 INFO - Cleaning up... 16:02:11 INFO - Return code: 0 16:02:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:02:11 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')]} 16:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:11 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')]} 16:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:11 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:11 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 16:02:11 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 16:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:11 INFO - 'HOME': '/Users/cltbld', 16:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:11 INFO - 'LOGNAME': 'cltbld', 16:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:11 INFO - 'MOZ_NO_REMOTE': '1', 16:02:11 INFO - 'NO_EM_RESTART': '1', 16:02:11 INFO - 'PAGER': '/bin/cat', 16:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:11 INFO - 'PWD': '/builds/slave/test', 16:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:11 INFO - 'SHELL': '/bin/bash', 16:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:11 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:11 INFO - 'USER': 'cltbld', 16:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:11 INFO - 'XPC_FLAGS': '0x0', 16:02:11 INFO - 'XPC_SERVICE_NAME': '0', 16:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:12 INFO - Downloading/unpacking jsonschema==2.5.1 16:02:12 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 16:02:12 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 16:02:12 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 16:02:12 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 16:02:12 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 16:02:12 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 16:02:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:02:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:02:14 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 16:02:14 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 16:02:14 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 16:02:14 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 16:02:14 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 16:02:14 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 16:02:15 INFO - Downloading functools32-3.2.3-2.tar.gz 16:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:02:15 INFO - warning: no files found matching '*.txt' 16:02:15 INFO - no previously-included directories found matching 'build' 16:02:15 INFO - no previously-included directories found matching 'dist' 16:02:15 INFO - no previously-included directories found matching '.git*' 16:02:15 INFO - Installing collected packages: jsonschema, functools32 16:02:15 INFO - Running setup.py install for functools32 16:02:15 INFO - warning: no files found matching '*.txt' 16:02:15 INFO - no previously-included directories found matching 'build' 16:02:15 INFO - no previously-included directories found matching 'dist' 16:02:15 INFO - no previously-included directories found matching '.git*' 16:02:15 INFO - Successfully installed jsonschema functools32 16:02:15 INFO - Cleaning up... 16:02:15 INFO - Return code: 0 16:02:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:02:15 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')]} 16:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:15 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')]} 16:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:15 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:15 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 16:02:15 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 16:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:15 INFO - 'HOME': '/Users/cltbld', 16:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:15 INFO - 'LOGNAME': 'cltbld', 16:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:15 INFO - 'MOZ_NO_REMOTE': '1', 16:02:15 INFO - 'NO_EM_RESTART': '1', 16:02:15 INFO - 'PAGER': '/bin/cat', 16:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:15 INFO - 'PWD': '/builds/slave/test', 16:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:15 INFO - 'SHELL': '/bin/bash', 16:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:15 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:15 INFO - 'USER': 'cltbld', 16:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:15 INFO - 'XPC_FLAGS': '0x0', 16:02:15 INFO - 'XPC_SERVICE_NAME': '0', 16:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:02:15 INFO - Cleaning up... 16:02:15 INFO - Return code: 0 16:02:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:02:15 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')]} 16:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:15 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')]} 16:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:15 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:15 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 16:02:15 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 16:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:15 INFO - 'HOME': '/Users/cltbld', 16:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:15 INFO - 'LOGNAME': 'cltbld', 16:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:15 INFO - 'MOZ_NO_REMOTE': '1', 16:02:15 INFO - 'NO_EM_RESTART': '1', 16:02:15 INFO - 'PAGER': '/bin/cat', 16:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:15 INFO - 'PWD': '/builds/slave/test', 16:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:15 INFO - 'SHELL': '/bin/bash', 16:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:15 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:15 INFO - 'USER': 'cltbld', 16:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:15 INFO - 'XPC_FLAGS': '0x0', 16:02:15 INFO - 'XPC_SERVICE_NAME': '0', 16:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:15 INFO - Downloading/unpacking blobuploader==1.2.4 16:02:15 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 16:02:15 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 16:02:15 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 16:02:15 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 16:02:15 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 16:02:15 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 16:02:18 INFO - Downloading blobuploader-1.2.4.tar.gz 16:02:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:02:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:02:18 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 16:02:18 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 16:02:18 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 16:02:18 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 16:02:18 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 16:02:18 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 16:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:02:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:19 INFO - Downloading docopt-0.6.1.tar.gz 16:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:02:19 INFO - Installing collected packages: blobuploader, requests, docopt 16:02:19 INFO - Running setup.py install for blobuploader 16:02:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:02:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:02:19 INFO - Running setup.py install for requests 16:02:20 INFO - Running setup.py install for docopt 16:02:20 INFO - Successfully installed blobuploader requests docopt 16:02:20 INFO - Cleaning up... 16:02:20 INFO - Return code: 0 16:02:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:20 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')]} 16:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:20 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')]} 16:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:20 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:20 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 16:02:20 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 16:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:20 INFO - 'HOME': '/Users/cltbld', 16:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:20 INFO - 'LOGNAME': 'cltbld', 16:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:20 INFO - 'MOZ_NO_REMOTE': '1', 16:02:20 INFO - 'NO_EM_RESTART': '1', 16:02:20 INFO - 'PAGER': '/bin/cat', 16:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:20 INFO - 'PWD': '/builds/slave/test', 16:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:20 INFO - 'SHELL': '/bin/bash', 16:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:20 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:20 INFO - 'USER': 'cltbld', 16:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:20 INFO - 'XPC_FLAGS': '0x0', 16:02:20 INFO - 'XPC_SERVICE_NAME': '0', 16:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:20 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-s5_Q46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-dsslEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-iwtPGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-CwUqjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-JrFWfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-k3aQqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ZYLsHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-4kaS4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-KYi1wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-8K5EH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:21 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-JOwsnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:22 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-e9n1ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:22 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-lXYkcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:22 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-t6T9YQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:22 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ssPS68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:22 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-VI7t2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:22 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-hSPozg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:02:22 INFO - Running setup.py install for manifestparser 16:02:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:02:22 INFO - Running setup.py install for mozcrash 16:02:22 INFO - Running setup.py install for mozdebug 16:02:23 INFO - Running setup.py install for mozdevice 16:02:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:02:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:02:23 INFO - Running setup.py install for mozfile 16:02:23 INFO - Running setup.py install for mozhttpd 16:02:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:02:23 INFO - Running setup.py install for mozinfo 16:02:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:02:23 INFO - Running setup.py install for mozInstall 16:02:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:02:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:02:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:02:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:02:23 INFO - Running setup.py install for mozleak 16:02:23 INFO - Running setup.py install for mozlog 16:02:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:02:24 INFO - Running setup.py install for moznetwork 16:02:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:02:24 INFO - Running setup.py install for mozprocess 16:02:24 INFO - Running setup.py install for mozprofile 16:02:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:02:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:02:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:02:24 INFO - Running setup.py install for mozrunner 16:02:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:02:24 INFO - Running setup.py install for mozscreenshot 16:02:24 INFO - Running setup.py install for moztest 16:02:25 INFO - Running setup.py install for mozversion 16:02:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:02:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:02:25 INFO - Cleaning up... 16:02:25 INFO - Return code: 0 16:02:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:25 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:25 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 16:02:25 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 16:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:25 INFO - 'HOME': '/Users/cltbld', 16:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:25 INFO - 'LOGNAME': 'cltbld', 16:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:25 INFO - 'MOZ_NO_REMOTE': '1', 16:02:25 INFO - 'NO_EM_RESTART': '1', 16:02:25 INFO - 'PAGER': '/bin/cat', 16:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:25 INFO - 'PWD': '/builds/slave/test', 16:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:25 INFO - 'SHELL': '/bin/bash', 16:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:25 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:25 INFO - 'USER': 'cltbld', 16:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:25 INFO - 'XPC_FLAGS': '0x0', 16:02:25 INFO - 'XPC_SERVICE_NAME': '0', 16:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:25 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-5dhZws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:25 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)) 16:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:25 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-b5daUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:25 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-lmkHiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:25 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)) 16:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:25 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-BOnEGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-AstjAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-SrnRr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-gRp8D_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-7qIHL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-HTvdWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Zq6ZUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-jpjctp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-K6gygt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:26 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-DYCVrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:26 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)) 16:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-MpZ7cG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-1y_OEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:27 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)) 16:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-qf17EO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:27 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)) 16:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-g9nHKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:27 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)) 16:02:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:27 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)) 16:02:27 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)) 16:02:27 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)) 16:02:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:29 INFO - Downloading blessings-1.6.tar.gz 16:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:02:29 INFO - Installing collected packages: blessings 16:02:29 INFO - Running setup.py install for blessings 16:02:30 INFO - Successfully installed blessings 16:02:30 INFO - Cleaning up... 16:02:30 INFO - Return code: 0 16:02:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:02:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:30 INFO - Reading from file tmpfile_stdout 16:02:30 INFO - Current package versions: 16:02:30 INFO - blessings == 1.6 16:02:30 INFO - blobuploader == 1.2.4 16:02:30 INFO - docopt == 0.6.1 16:02:30 INFO - functools32 == 3.2.3-2 16:02:30 INFO - jsonschema == 2.5.1 16:02:30 INFO - manifestparser == 1.1 16:02:30 INFO - mozInstall == 1.12 16:02:30 INFO - mozcrash == 0.17 16:02:30 INFO - mozdebug == 0.1 16:02:30 INFO - mozdevice == 0.48 16:02:30 INFO - mozfile == 1.2 16:02:30 INFO - mozhttpd == 0.7 16:02:30 INFO - mozinfo == 0.9 16:02:30 INFO - mozleak == 0.1 16:02:30 INFO - mozlog == 3.4 16:02:30 INFO - moznetwork == 0.27 16:02:30 INFO - mozprocess == 0.23 16:02:30 INFO - mozprofile == 0.28 16:02:30 INFO - mozrunner == 6.12 16:02:30 INFO - mozscreenshot == 0.1 16:02:30 INFO - mozsystemmonitor == 0.3 16:02:30 INFO - moztest == 0.7 16:02:30 INFO - mozversion == 1.4 16:02:30 INFO - psutil == 3.1.1 16:02:30 INFO - requests == 1.2.3 16:02:30 INFO - wsgiref == 0.1.2 16:02:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:30 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 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:30 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 16:02:30 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 16:02:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:30 INFO - 'HOME': '/Users/cltbld', 16:02:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:30 INFO - 'LOGNAME': 'cltbld', 16:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:30 INFO - 'MOZ_NO_REMOTE': '1', 16:02:30 INFO - 'NO_EM_RESTART': '1', 16:02:30 INFO - 'PAGER': '/bin/cat', 16:02:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:30 INFO - 'PWD': '/builds/slave/test', 16:02:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:30 INFO - 'SHELL': '/bin/bash', 16:02:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:30 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:30 INFO - 'USER': 'cltbld', 16:02:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:30 INFO - 'XPC_FLAGS': '0x0', 16:02:30 INFO - 'XPC_SERVICE_NAME': '0', 16:02:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 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)) 16:02:30 INFO - Cleaning up... 16:02:30 INFO - Return code: 0 16:02:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102c5b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8419c894f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, '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.zpMbEwuZke/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.mSIkecpAvG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 16:02:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:02:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:02:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:30 INFO - 'HOME': '/Users/cltbld', 16:02:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:30 INFO - 'LOGNAME': 'cltbld', 16:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:30 INFO - 'MOZ_NO_REMOTE': '1', 16:02:30 INFO - 'NO_EM_RESTART': '1', 16:02:30 INFO - 'PAGER': '/bin/cat', 16:02:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:30 INFO - 'PWD': '/builds/slave/test', 16:02:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:30 INFO - 'SHELL': '/bin/bash', 16:02:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:02:30 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:02:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:30 INFO - 'USER': 'cltbld', 16:02:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:30 INFO - 'XPC_FLAGS': '0x0', 16:02:30 INFO - 'XPC_SERVICE_NAME': '0', 16:02:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:02:31 INFO - Cleaning up... 16:02:31 INFO - Return code: 0 16:02:31 INFO - Running post-action listener: _resource_record_post_action 16:02:31 INFO - Running post-action listener: _start_resource_monitoring 16:02:31 INFO - Starting resource monitoring. 16:02:31 INFO - [mozharness: 2016-10-31 23:02:31.083440Z] Finished create-virtualenv step (success) 16:02:31 INFO - [mozharness: 2016-10-31 23:02:31.084414Z] Running install step. 16:02:31 INFO - Running pre-action listener: _resource_record_pre_action 16:02:31 INFO - Running main action method: install 16:02:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:31 INFO - Reading from file tmpfile_stdout 16:02:31 INFO - Detecting whether we're running mozinstall >=1.0... 16:02:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:02:31 INFO - Reading from file tmpfile_stdout 16:02:31 INFO - Output received: 16:02:31 INFO - Usage: mozinstall [options] installer 16:02:31 INFO - Options: 16:02:31 INFO - -h, --help show this help message and exit 16:02:31 INFO - -d DEST, --destination=DEST 16:02:31 INFO - Directory to install application into. [default: 16:02:31 INFO - "/builds/slave/test"] 16:02:31 INFO - --app=APP Application being installed. [default: firefox] 16:02:31 INFO - mkdir: /builds/slave/test/build/application 16:02:31 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'] 16:02:31 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 16:03:05 INFO - Reading from file tmpfile_stdout 16:03:05 INFO - Output received: 16:03:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:03:05 INFO - Running post-action listener: _resource_record_post_action 16:03:05 INFO - [mozharness: 2016-10-31 23:03:05.887025Z] Finished install step (success) 16:03:05 INFO - [mozharness: 2016-10-31 23:03:05.887164Z] Running run-tests step. 16:03:05 INFO - Running pre-action listener: _resource_record_pre_action 16:03:05 INFO - Running main action method: run_tests 16:03:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:03:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:03:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:03:05 INFO - 2016-10-31 16:03:05.901 screenresolution[1925:14357] starting screenresolution argv=screenresolution get 16:03:05 INFO - 2016-10-31 16:03:05.904 screenresolution[1925:14357] Display 0: 1600x1200x32@60 16:03:05 INFO - 2016-10-31 16:03:05.910 screenresolution[1926:14359] starting screenresolution argv=screenresolution list 16:03:05 INFO - Available Modes on Display 0 16:03:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:03:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:03:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:03:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:03:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:03:06 INFO - Intel Iris: 16:03:06 INFO - Chipset Model: Intel Iris 16:03:06 INFO - Type: GPU 16:03:06 INFO - Bus: Built-In 16:03:06 INFO - VRAM (Dynamic, Max): 1536 MB 16:03:06 INFO - Vendor: Intel (0x8086) 16:03:06 INFO - Device ID: 0x0a2e 16:03:06 INFO - Revision ID: 0x0009 16:03:06 INFO - Displays: 16:03:06 INFO - Display: 16:03:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:03:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:03:06 INFO - Display Serial Number: 1600x1200 60 16:03:06 INFO - Main Display: Yes 16:03:06 INFO - Mirror: Off 16:03:06 INFO - Online: Yes 16:03:06 INFO - Rotation: Supported 16:03:06 INFO - Return code: 0 16:03:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:03:06 INFO - Python 2.7.3 16:03:06 INFO - Return code: 0 16:03:06 INFO - grabbing minidump binary from tooltool 16:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:06 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 0x102a6fd30>, '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 0x102895db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a67b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:03:06 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 16:03:06 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 16:03:06 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 16:03:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:03:06 INFO - Return code: 0 16:03:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:03:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:03:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:03:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:03:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:03:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--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 16:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0136 --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 16:03:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zpMbEwuZke/Render', 16:03:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:03:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:03:06 INFO - 'HOME': '/Users/cltbld', 16:03:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:03:06 INFO - 'LOGNAME': 'cltbld', 16:03:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:06 INFO - 'MOZ_NO_REMOTE': '1', 16:03:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:06 INFO - 'NO_EM_RESTART': '1', 16:03:06 INFO - 'PAGER': '/bin/cat', 16:03:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:06 INFO - 'PWD': '/builds/slave/test', 16:03:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:03:06 INFO - 'SHELL': '/bin/bash', 16:03:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mSIkecpAvG/Listeners', 16:03:06 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:03:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:03:06 INFO - 'USER': 'cltbld', 16:03:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:03:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:06 INFO - 'XPC_FLAGS': '0x0', 16:03:06 INFO - 'XPC_SERVICE_NAME': '0', 16:03:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:03:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--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 16:03:06 INFO - mozversion application_buildid: 20161031120204 16:03:06 INFO - mozversion application_changeset: 1aa20bcbb80e1014e4d01057f7d52269b0c2d908 16:03:06 INFO - mozversion application_display_name: Nightly 16:03:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:03:06 INFO - mozversion application_name: Firefox 16:03:06 INFO - mozversion application_remotingname: firefox 16:03:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:03:06 INFO - mozversion application_vendor: Mozilla 16:03:06 INFO - mozversion application_version: 52.0a1 16:03:06 INFO - mozversion platform_buildid: 20161031120204 16:03:06 INFO - mozversion platform_changeset: 1aa20bcbb80e1014e4d01057f7d52269b0c2d908 16:03:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:03:06 INFO - mozversion platform_version: 52.0a1 16:03:06 INFO - using testdate: 1477954986 16:03:06 INFO - actual date: 1477954986 16:03:06 INFO - starting webserver on 'localhost:50915' 16:03:06 INFO - SUITE-START | Running 2 tests 16:03:06 INFO - TEST-START | dromaeo_css 16:03:06 INFO - operating with platform_type : mac_ 16:03:06 INFO - Initialising browser for dromaeo_css test... 16:03:06 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmp13DAFq/profile http://localhost:50915/getInfo.html) 16:03:09 INFO - PROCESS | 1936 | 2016-10-31 16:03:09.329 plugin-container[1938:14518] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 16:03:09 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:03:09 INFO - PROCESS | 1936 | 2016-10-31 16:03:09.331 plugin-container[1938:14518] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 16:03:09 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:03:09 INFO - PROCESS | 1936 | 2016-10-31 16:03:09.331 plugin-container[1938:14518] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:03:09 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 16:03:09 INFO - PROCESS | 1936 | colorDepth:24 16:03:09 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 16:03:09 INFO - PROCESS | 1936 | __metrics 16:03:09 ERROR - PROCESS | 1936 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 16:03:10 INFO - TEST-INFO | 1936: exit 0 16:03:10 INFO - Browser initialized. 16:03:10 INFO - Running cycle 1/1 for dromaeo_css test... 16:03:10 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmp13DAFq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:12 INFO - PROCESS | 1939 | 2016-10-31 16:03:12.950 plugin-container[1940:14639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 16:03:12 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:03:12 INFO - PROCESS | 1939 | 2016-10-31 16:03:12.950 plugin-container[1940:14639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:03:12 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:03:12 INFO - PROCESS | 1939 | 2016-10-31 16:03:12.950 plugin-container[1940:14639] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:03:22 INFO - PROCESS | 1939 | RSS: Main: 239783936 16:03:22 INFO - PROCESS | 1939 | 16:04:12 INFO - PROCESS | 1939 | 1477955052162 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] 16:06:24 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50915/tests/dromaeo/cssquery-ext.html) 16:06:28 INFO - PROCESS | 1939 | RSS: Main: 253730816 16:06:28 INFO - PROCESS | 1939 | 16:09:30 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-ext.html (next: http://localhost:50915/tests/dromaeo/cssquery-jquery.html) 16:09:31 INFO - PROCESS | 1939 | RSS: Main: 264679424 16:09:31 INFO - PROCESS | 1939 | 16:12:33 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50915/tests/dromaeo/cssquery-mootools.html) 16:12:33 INFO - PROCESS | 1939 | RSS: Main: 268988416 16:12:33 INFO - PROCESS | 1939 | 16:15:35 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50915/tests/dromaeo/cssquery-prototype.html) 16:15:36 INFO - PROCESS | 1939 | RSS: Main: 267567104 16:15:36 INFO - PROCESS | 1939 | 16:18:39 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50915/tests/dromaeo/cssquery-yui.html) 16:18:39 INFO - PROCESS | 1939 | RSS: Main: 272371712 16:18:39 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-yui.html (next: http://localhost:50915/tests/dromaeo/cssquery-dojo.html) 16:21:42 INFO - PROCESS | 1939 | RSS: Main: 275062784 16:21:42 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | __start_tp_report 16:21:42 INFO - PROCESS | 1939 | _x_x_mozilla_page_load 16:21:42 INFO - PROCESS | 1939 | _x_x_mozilla_page_load_details 16:21:42 INFO - PROCESS | 1939 | |i|pagename|runs| 16:21:42 INFO - PROCESS | 1939 | |0;dojo.html;2797.8041537092404;2954.512505436603;3349.916252093699;3355.228297491577;3357.5635167428222;4496.752678602679;5111.386633603964;5597.188407680884;5671.3761486236535;5673.517750991171;5425.159100339444;5531.308586426697;5636.520895723868;5685.800996965107;5694.145878118274;5123.103456895038;5204.219367094928;5228.268042474037;5230.73846307685;5247.737613119348;5196.350456192976;5223.164293713006;5230.633855630107;5236.502532259429;5262.973685131569;4912.828051018215;5030.471800460972;5241.397239317489;5250.186221135711;5253.9737301313635;5055.216441451581;5087.771050302746;5148.91872706732;5170.560502357304;5204.42751297357;3944.1717239379764;5061.974690126526;5083.3391659084455;5091.949080509185;5184.870378240536;4486.551344865487;5583.35791383992;5728.513076388512;5730.828075157752;5749.971250143764;5842.941570584282;6024.728887200086;6090.177825993478;6118.418750218722;6121.357257487989;5537.031019571599;5548.72256387179;5614.624417017857;6149.754009839601;6205.689715514206;5576.86057848557;5600.85997850057;5699.344575373885;5712.600117991742;5721.599488035839;6155.169052177898;6174.8147555573405;6221.875562488724;6311.842203944846;6329.68351582419;6117.785877494266;6125.724342404603;6130.846728831815;6153.938460615426;6167.629942203482;5977.342492325841;6184.752609895644;6188.44304012641;6196.628202307876;6211.875762484816;5961.165436838846;5965.164876917235;6062.727177277033;6070.453659170607;6084.269887613426;6055.909161362583;6083.361247069082;6106.2061931948565;6108.450239478469;6122.20411346531;4244.769016985101;4818.060478206716;4859.635527335464;4862.586680132157;4889.951100489021;3731.36566783642;4326.740395576276;4391.341298805205;4419.11617676466;4429.402030725875;5136.767175877837;5587.776488940397;5630.662160270397;5688.175214593944;5696.11710184922;5770.88458230833;6088.5243303217485;6126.479249263773;6128.044828652003;6129.693515324216;5805.36141024487;5809.448102430262;5831.416858314219;5883.17635531026;5885.440883116097;5375.408705042442;5407.486288802557;5415.350157980989;5415.48552887475;5423.566114710814;6111.561351782736;6155.415235552616;6219.906701399392;6234.657093859791;6317.526185536103;6154.138420621027;6186.041163619646;6198.194234749634;6205.81382558524;6224.968875155595;6297.2128483939505;6333.050042493663;6362.936370636419;6368.076628888878;6438.227412710505;5946.554008449297;5985.910211346746;6000;6093.847653808691;6127.417895299939;5196.324477818011;6100.145979562776;6100.450959413496;6101.115338275837;6111.052786818051;5954.404559544011;5957.314908785545;5958.374370691015;5961.523078153824;5967.9556077685365;5861.501772349398;5895.823125306248;5937.050071988461;5949.167116603766;5991.430814072656;4251.256030194641;4548.5224051474115;4568.70303430276;5466.551363888652;5832.94167058324;5887.8233652990475;5905.52755779528;6009.038553831366;6014.518838492998;6130.846728831636;4695.272232803921;5757.453041961007;5890.4993075589055;5893.410658934244;5910.083936989773 16:21:42 INFO - PROCESS | 1939 | |1;ext.html;427.9208346455906;436.63104676548323;456.49329244538535;492.07244344410776;495.8817865712818;27890;32408.1897952551;35035.47446788294;35087.64912350879;35212.82393588028;35228.295434091306;35787.463188052134;35845.641543584556;35859.462108068336;35894;8038.9196108038905;9358.578863951105;9384.8592271116;9394.812103757955;9417.952910235439;9355.391899526508;9414.670486532972;9451.149396554309;9458.716238512821;9459.24326053914;9435.575399107021;9437.056294370577;9452.952735236315;9478.146966772989;9479.336446448717;9379.624815007392;9403.388779729295;9410.764730881714;9411.058894110569;9420.576074076684;9261.583228754722;9299.535023248878;9314.906850931471;9341.11259430353;9395.62417503299;33100.51746119051;33139.3372132556;33276.6672333276;33378.49932251018;33578.32843343144;2958.955615665767;3031;3152.448321543721;3258.3157536917274;3274.5743053403144;18793.624127517374;27186;27371;27817;28011.299717507023;2448.2362249391667;3106.456370135217;3145.51244557094;3249.9350012999607;3307.9007629771145;346888;374372;428462.8576857096;431365;432301.8384908087;47695.28457073147;47917.04165916662;48181.03637927222;48208.03583928302;48245.45177304585;49553.50446495561;49661.75169124131;49875.50124498781;49891;50052.749736251084;13061.934690326487;13279;13285.003624728184;13289.66775830612;13322.467101315839;10357;10392.428416437016;10403.5838566458;10412.791744165073;10415.583376665;13427.530036448677;13833.962452816078;14030.789538156936;14054.929725351309;14278.07192532482;2914.5919571259615;5447.700376479253;5650.463205995424;5670.971645141831;5724.1413787931315;773.6711897443564;856.6873091321622;876.2639382918379;923.4182465047112;1025.5846382215216;25648.61527077095;26333.52330103887;27697.446051078867;27739.583906241423;28278.575821362698;785.9782283032035;812.3825892321878;923.7459698582943;968.6464440479315;984.7587341101475;14157.929210354088;14214.573562793134;14375.712485750226;14517.274136293143;14517.637059073397;658021;660097;667786;667919;669004;47456.7627161862;47895.521044789806;47943.520564794606;47991.28013079806;47996.760016200395;1121.6803211084964;1172.4372301295425;1175.1656324010048;1181.539199712113;1188.9464974076188;27153.04964326239;27357.075726369443;27528;27749.890004399596;27837.88648454039;42312.576874230865;45713.77143114263;47036.059278814915;47081.76459117683;48678;645508;648151;650579;651502;653246;36214;36682.63317366899;36936;37054;37135.628643713215;40281.99295017648;40318.79840600778;40600.79699601483;40990.130690852544;41339.7933010333;26566.216101673726;30534.84732576323;31935.36129277355;32295.35409291848;34275.828620857734;34053.82973085119;34211.828940855135;34966.776162834176;35041.29917401689;35240.59037638466;33348.832790852204;33613;34311.79908703183;34343;35190;26515.60226596564;27854.935957044305;28067.859660701564;28752.85623571869;29607.85196074006;19407.514812129808;20855.270065547633;21701;24037.51924961526;24263.878680606485 16:21:42 INFO - PROCESS | 1939 | |2;jquery.html;338.6410404970731;356.810890228179;358.2709186804852;362.430983356131;382.96981120785085;16967.83032169678;17763.644727105468;18419;18712.625747485043;18752.06239688017;16319.347226110942;16981.15094245286;17000.979941203506;17107.486775396726;17114.82885171151;5513.862153446176;5815.040518314494;5857.41425857413;5861.325947516048;5863.706814659271;5556.833295001156;5662.971685141589;5680.5455563554815;5740.655560666352;5817.447342502455;5693.686847223401;5707.6290041147195;5712.857178570527;5713.571482138836;5717.857053573653;5299.999999999981;5596.888062238773;5625.774969001235;5724.7996320128805;5734.082546792514;5498.560115190775;5511.448855114497;5523.806666766684;5529.115341545334;5531.834044978657;11184.38485883276;14879.925600372037;19479.831210127297;21970.670439943413;22146.446338841495;2105.8947052647304;2132.338974917765;2180.4300903349563;2243.0467051503047;2337.4857531343246;8798;9423.811523769486;9790.706278811647;9828.950855245677;9857.605695772161;1687.4347093723618;1752.255291501107;1801.8558515318744;1864.813518648138;1897.3264491105735;113092.4345378276;116789;118670.40664796621;118985;123417.76582234242;10878.836817447745;10953.890461095447;11020.50407731654;11068.446577671084;11198.808339623169;11415.714607134722;13753.174809511462;13761.38073786682;13785.51750688721;13789.448422063006;6083;6353.682315884187;7074.71701131949;7370.189279179286;7506;5981.79063732776;7112.715491380391;7127.82180445493;7331.523450975669;7334.963325183447;3525.3125640499857;4225.72532785368;4306.440162778818;4384.693071484965;4398.23030969579;1800.5318617159633;1827.259959716317;1846.9933886032145;1852.129499135404;1855.3413538194018;727.5016613619688;790.2650535002502;790.3124281874691;811.8660421030464;852.799592095857;15704.607384815243;18440.262389504536;18656.626867462575;18941.33705320307;19112.52218694516;686.5914780705572;743.5798773692846;806.105223202245;833.1836596220298;895.1317222294364;7481;9538.475383853816;9799;9814.460204688668;9837.360571562825;553582;554728;556402;585131.3789793613;588395;115397.42301288439;118814.62370752536;120848;131788;135852;933.5845548730886;942.3827393057559;996.4121168510613;1000.4047591682937;1004.2819384140374;18071.91568505894;18173.54566135857;18427.341539260993;18652;19046.95464987054;52758.2086268714;54779.452205477435;55577.44422555723;56456.435435645115;56573.71713141408;63013;63537.046944296795;64514.354856452715;73151.63424182845;82037.58981205056;7207.243239459781;7683.961580192287;7839.84320313602;7853.136155023057;7938.483998540077;9900.455474948947;9954.402735835873;10596.258261921592;12559;12740.745185096432;10858;11073.114150868072;11194.720131996764;11208.887911120784;11210.439478025903;7457.888131677921;10712.625058122929;10792.892071079503;10936.78126437451;11164.720881978015;8846.911530884609;10789.56841726322;11165.330080195214;11209;11334.433278335886;6683.329167708073;6968.930310696828;7076.575405475688;7256.383207427217;7653.808654783675;8513.318934485102;10505;10796.838047429137;10956.233063685466;11132.610358637408 16:21:42 INFO - PROCESS | 1939 | |3;mootools.html;1148.1561052626319;1230.0774419185611;1239.3679223595964;1439.4745917740029;1444.8338441079272;7245.601491917942;7542.849143017136;7887.290143887049;8331.208535189155;8372.246497815195;6844.212915514718;7876.606169691521;8003.279704826565;8212.219839115289;8251.504909705422;2248.9437764055947;2278.567072256266;2280.8289378296695;2280.8403411761183;2291.5531471363092;2273.8521996070203;2308.4575124845646;2320.721513418392;2325.5232677301196;2328.7205535335784;2246.359787460574;2252.9211477598287;2281.269993602048;2282.8972696228625;2283.862968221904;2222.799948004671;2236.8769717665523;2261.2989973108224;2267.637178051512;2271.0234599122373;2264.8980795864063;2283.349245465051;2284.23478134825;2289.828262880291;2289.8969546370454;8057.315128214109;8173.386995975326;8228.917710822874;8235.8352832943;8253;4895.7062576245535;4920.384951881015;5078.21287700407;5081.949180508184;5101.69389836611;3272.672732726708;3410.795352278871;3513.6135025147214;3513.7364697647777;3523.3834079036064;1045.5086109528509;1077;1086.32104934416;1087.396494945305;1094.6168840905698;10541.73645658857;10855.240133190759;10866.67399978002;10875.673729788119;10879.891201087967;4150.522689890641;4267.871963841089;4304.870853874389;4354.869353919387;4371.344298355209;8274.793130171673;8422.031466381322;8426.620802063922;8447.915520844836;8451.746447606582;4917.28699338601;4923.679960802537;4924.9507504925205;4933.28467372229;4945.332380128709;4040.8585699500377;4189.832406703758;4502.5047244803045;4624.982503849148;4647.18674232008;3434.9313013739584;3479.408500554912;3515.1212196950764;3550.55618047744;3561.62602926689;1090.6128324444867;1098.6374496416163;1099.79103970247;1106.4744246483017;1107.8061339265596;700.852820907605;778.7391223940043;797.9082405523322;806.7176488229073;809.8783185288418;4307.827686892552;4329.480462344476;5319.601029922771;5477.287952566141;5550.250716153349;398.94215338776297;406.8006676728382;408.85076946914756;409.0958980652791;824.4023083264705;3534.540509733718;3572.696320812708;3583.7133029357587;3672.8163591820303;3701.481792549045;9857.55640996157;10175.28772985884;10216.948915255376;10230.28388012832;10275.948620257;10148.695539133838;10197.643082492226;10199.031092046243;10204.795904081877;10233.437160956219;6835.829104272333;7611.505252158592;7796.064472263251;7799.727009554638;7841.803954901173;4515.683902126951;5217.269582258411;5279.366476022902;5313.459096862021;5449.5640348772795;9154;9209.309301802527;9215.170634642915;9295.721128365889;9386.014468480984;7635.5800430974605;9282.443053416546;9379.202767764544;9413.52932353366;9446.574904129193;6103.145559621567;6304.401081897213;6443.226812782496;6539.901901471577;6687.230968438692;7681.347085497795;7803.648835802289;7840.960795195988;7854.4894581851995;7861.174576669596;6898.344657257552;6987.895181572178;6989.231184569795;7079.07971963662;7121.038659780863;6865.862682746218;6894.448444124559;6910.44716422695;6937.340952609494;6965.268646792014;6289.779857704958;6371.203599550056;6409.102725618511;6427.260865000398;6435;4645.953540464688;4676.134915040729;4705.55297246761;4722.787474563722;4793.400824896888;4609.400777898852;4638.316440883052;4689.320048593003;4781.378420805273;4885.145099607484 16:21:42 INFO - PROCESS | 1939 | |4;prototype.html;256.4204896932932;280.9240608469562;303.42198112595514;309.79398699864583;316.3546365414554;4687.601553867921;5203.609729270301;5293.6294459387855;5342.545883599893;5434.755436005385;3759.586445490994;3914.902127446815;4550;4894.696080145896;5298.6555873868265;4036.1120553478186;4080.9795951020205;4082.4896887889013;4088.713790034691;4105.979470102645;3460.4290292101773;3596.964030359702;3811.5807261201244;4128.772917489537;4145.253854306224;4013.4782478277784;4021.919561608781;4029.6574791142643;4031.4926014367543;4043.939340909889;3523.3481805865995;3880.7477513961785;4005.83976640934;4017.150757957675;4048.838046478137;3054.5796204764893;3492.1967947372245;3510.7191424685966;3522.489239060322;3531.858725650971;4375.584319489643;4907.312976183337;5197.180986654678;5279.683219006872;5279.788808447696;4583.72497650142;4590.163934426223;4594.862154135381;4599.413202445151;4617.630589552828;4877.975610121926;5299.470052994709;5390.353157621111;5392.379876314235;5660.943390566083;3128.092853072629;3243.6918492743152;3686.4838922550866;3841.2509560635685;3862.903427414309;4908.484609116027;5893.263342082239;5910.911336329958;5931.551336497627;5932.228810254639;3162.3042930555703;3285.7021476516643;3717.3494638438165;3869;3874.3607304794955;3307.8015319080932;3350.396928552835;3546.361654902142;3551.2897420516;3956.2680904032272;3655.5613326401003;4103.974006498375;4323;4562.771861406916;4592.680696248373;4665.5101214372;5529.4470552944385;5610.990021796116;5653.840962602657;5667.744951477111;1240.4232032105115;1264.9494020239088;1311.7669390772644;1325.4830616059744;1337.5452346202337;798.5767543202112;869.1959937058195;887.4852585500523;917.1058218237299;919.1497864475333;5286.524212820865;5329.467053294717;5330.413654498002;5348.870910517391;5354.063038968165;5050.939302746465;5093.516115968977;5829.679367634736;5830.192640281526;5834.27071616048;5026.145555255615;5346.893062138814;5354.732263386815;5377.919331210035;5380.488853558905;2577.69067711872;2667.6794986481814;2819.7039310872065;2821.520341541902;3110.6733792951413;5021.698698078127;5057.494250574913;5588;5952.951992645043;5979.342272350038;5863.5309175265875;5870.647761134345;5880.647161170344;5903.35063143054;5906.881862362815;4627.722336659811;5923.259592550931;5947.851303717528;5948.494377977834;5948.613340132964;4907.233395977374;4995.600351971761;5756.625819321731;5894.084422562599;5899.823005309848;5634.492895639412;5804.303483581997;5805.490572451277;5832.387599302182;5867.471927526543;5101.795928162832;5178.844634660966;5666.405027472056;5937.940620593911;6065.332813390435;3115.971729329361;3421.674940880612;3630.636936306348;3863.9227215456094;3910.608939106067;4582.633389328779;5907.793227237026;5919.904817608756;5928.051511758098;5964.433378829004;4867.464578896389;5444.785276073689;5514.834554963358;5688.63023903445;5785.611453251274;4673.439187297546;4974.502549744997;5647.576431767716;5689.914651280152;5708.514776244066;4470.441194850644;5204.427512973494;5267.735743421628;5511.779528818963;5561;5081.898362032813;5635.690037048002;5675.659460432387;5702.144678298288;5726.255586773693;5239.47605239473;5430.592705547021;5551.944480555304;5565.916511252415;5577.5537956962535 16:21:42 INFO - PROCESS | 1939 | |5;yui.html;585.7042193692184;657.4214691071857;658.476511173617;662.7746566167502;678.8167194857392;13719.725605487896;14207.71584568308;14384.42462301508;14953;15717.764233536505;13917.164970101776;13951.372188251527;14055.437782488714;14204.786928196085;14227.146371217734;620.9316975132732;635.8601107756286;642.5951650460204;644.954853160279;647.4787795824383;520.4405264340829;533.2747463449705;618.2426527503794;622.850515876191;623.0747340199799;665.3114026982074;671.8757029949451;673.276228054843;676.3169199108886;676.4115219758792;650.2944305428616;655.5574986883859;666.5634009723639;667.9799606011827;668.8227619680788;493.2010143567408;493.39805437366357;493.6125141763698;494.4141192686678;496.4092729651703;13495.865041349658;13592.524261650795;13613.863861361358;13660.726785464334;13661.795073073912;12184.81722774159;12295.200811947285;12433.129680922339;12650.367481625883;12819.807702884464;10929.836052459219;11037.889621103766;11040.889591104067;11078.279911805706;11359.943200284028;3572.4462708280257;3605.280048561318;3618.330608837347;3674.6876515496215;3758.32350176968;88518.55740721384;88954.11045889523;89086.5545672274;89247;89939;9239.676611318571;9249.815003700023;9251.213646840093;9311.208547273558;9426.245900327956;18007.819921800878;18051.729224061648;18097;18122.637547249244;18266.725999110025;7343.596102214323;7362.153352364439;7429.182789893108;7506.136794268729;7563.432742544332;3316.2704205074847;3914.8842579865172;4018.6182312680835;4133.3412916318275;4284.89287767802;2792.4833905727505;3056.2359410147465;3075.108218616621;3126.2965832687464;3172.6034245719284;513.7174553995287;544.6003770310265;560.3583896438555;583.4981915552621;606.4057223920495;423.85377044919454;519.2807960974078;551.5918220516788;606.2270604978687;621.5214285000525;7750.961245193851;8391.454555453876;8605.225529702357;8796.824063518694;8957.283417326598;606.1544145916422;612.0146564031905;617.4566381584177;620.4694985787178;620.7485968182893;4239.215745087169;4249.915001699948;4399.14216727735;4488.753118578445;4559.316102584638;115935;117237;119501;120451.39774301072;120460;75662.48675026468;85860;101334.4933275329;103828.40428989335;109403.45298273458;84785;85379.57310213409;85524.71712924312;85984.57007715046;87123.69314460288;7216.7834964951135;8477.558815001588;8489.320854331516;9115.498647574446;9263.305252106255;36538.8173059133;39330;47653.523464766295;51056.978860423325;54057.459425406814;81322;81723.18276817394;82077;82425.58787206025;83328.58335708526;11633.767324653629;11708.180427370002;11728;11734.647960561197;11959.342236177094;13422.194668319931;13567.639125285139;13594.11638243511;13868.93065534666;13888.444462221398;10671.893281067401;12312.938435307766;12472.314022728837;12658.731738386256;12786.168899021533;12559.787861514915;12679.175853569488;12802.423890925116;12821.358932053174;13017.284049376996;12293.385330733248;12582.18215815969;13038.022148338723;13063.542776002794;13066.412011459697;9765.424148483158;9801.9019809801;10595.888058847451;10858;11254.94372528132;8055.798605034921;8761.649534018568;8896.822063558822;10720.303180293256;10929.945350273198 16:21:42 INFO - PROCESS | 1939 | __end_tp_report 16:21:42 INFO - PROCESS | 1939 | __start_cc_report 16:21:42 INFO - PROCESS | 1939 | _x_x_mozilla_cycle_collect,381 16:21:42 INFO - PROCESS | 1939 | __end_cc_report 16:21:42 INFO - PROCESS | 1939 | __startTimestamp1477956102666__endTimestamp 16:21:42 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | ------- Summary: start ------- 16:21:42 INFO - PROCESS | 1939 | Number of tests: 6 16:21:42 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | [#0] dojo.html Cycles:165 Average:5583.83 Median:5809.45 stddev:699.44 (12.0%) stddev-sans-first:666.56 16:21:42 INFO - PROCESS | 1939 | Values: 2797.8 2954.5 3349.9 3355.2 3357.6 4496.8 5111.4 5597.2 5671.4 5673.5 5425.2 5531.3 5636.5 5685.8 5694.1 5123.1 5204.2 5228.3 5230.7 5247.7 5196.4 5223.2 5230.6 5236.5 5263.0 4912.8 5030.5 5241.4 5250.2 5254.0 5055.2 5087.8 5148.9 5170.6 5204.4 3944.2 5062.0 5083.3 5091.9 5184.9 4486.6 5583.4 5728.5 5730.8 5750.0 5842.9 6024.7 6090.2 6118.4 6121.4 5537.0 5548.7 5614.6 6149.8 6205.7 5576.9 5600.9 5699.3 5712.6 5721.6 6155.2 6174.8 6221.9 6311.8 6329.7 6117.8 6125.7 6130.8 6153.9 6167.6 5977.3 6184.8 6188.4 6196.6 6211.9 5961.2 5965.2 6062.7 6070.5 6084.3 6055.9 6083.4 6106.2 6108.5 6122.2 4244.8 4818.1 4859.6 4862.6 4890.0 3731.4 4326.7 4391.3 4419.1 4429.4 5136.8 5587.8 5630.7 5688.2 5696.1 5770.9 6088.5 6126.5 6128.0 6129.7 5805.4 5809.4 5831.4 5883.2 5885.4 5375.4 5407.5 5415.4 5415.5 5423.6 6111.6 6155.4 6219.9 6234.7 6317.5 6154.1 6186.0 6198.2 6205.8 6225.0 6297.2 6333.1 6362.9 6368.1 6438.2 5946.6 5985.9 6000.0 6093.8 6127.4 5196.3 6100.1 6100.5 6101.1 6111.1 5954.4 5957.3 5958.4 5961.5 5968.0 5861.5 5895.8 5937.1 5949.2 5991.4 4251.3 4548.5 4568.7 5466.6 5832.9 5887.8 5905.5 6009.0 6014.5 6130.8 4695.3 5757.5 5890.5 5893.4 5910.1 16:21:42 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | [#1] ext.html Cycles:180 Average:67572.59 Median:35852.55 stddev:157373.08 (438.9%) stddev-sans-first:157733.80 16:21:42 INFO - PROCESS | 1939 | Values: 427.9 436.6 456.5 492.1 495.9 27890.0 32408.2 35035.5 35087.6 35212.8 35228.3 35787.5 35845.6 35859.5 35894.0 8038.9 9358.6 9384.9 9394.8 9418.0 9355.4 9414.7 9451.1 9458.7 9459.2 9435.6 9437.1 9453.0 9478.1 9479.3 9379.6 9403.4 9410.8 9411.1 9420.6 9261.6 9299.5 9314.9 9341.1 9395.6 33100.5 33139.3 33276.7 33378.5 33578.3 2959.0 3031.0 3152.4 3258.3 3274.6 18793.6 27186.0 27371.0 27817.0 28011.3 2448.2 3106.5 3145.5 3249.9 3307.9 346888.0 374372.0 428462.9 431365.0 432301.8 47695.3 47917.0 48181.0 48208.0 48245.5 49553.5 49661.8 49875.5 49891.0 50052.7 13061.9 13279.0 13285.0 13289.7 13322.5 10357.0 10392.4 10403.6 10412.8 10415.6 13427.5 13834.0 14030.8 14054.9 14278.1 2914.6 5447.7 5650.5 5671.0 5724.1 773.7 856.7 876.3 923.4 1025.6 25648.6 26333.5 27697.4 27739.6 28278.6 786.0 812.4 923.7 968.6 984.8 14157.9 14214.6 14375.7 14517.3 14517.6 658021.0 660097.0 667786.0 667919.0 669004.0 47456.8 47895.5 47943.5 47991.3 47996.8 1121.7 1172.4 1175.2 1181.5 1188.9 27153.0 27357.1 27528.0 27749.9 27837.9 42312.6 45713.8 47036.1 47081.8 48678.0 645508.0 648151.0 650579.0 651502.0 653246.0 36214.0 36682.6 36936.0 37054.0 37135.6 40282.0 40318.8 40600.8 40990.1 41339.8 26566.2 30534.8 31935.4 32295.4 34275.8 34053.8 34211.8 34966.8 35041.3 35240.6 33348.8 33613.0 34311.8 34343.0 35190.0 26515.6 27854.9 28067.9 28752.9 29607.9 19407.5 20855.3 21701.0 24037.5 24263.9 16:21:42 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | [#2] jquery.html Cycles:180 Average:33084.96 Median:4391.46 stddev:95074.38 (2165.0%) stddev-sans-first:95309.29 16:21:42 INFO - PROCESS | 1939 | Values: 338.6 356.8 358.3 362.4 383.0 16967.8 17763.6 18419.0 18712.6 18752.1 16319.3 16981.2 17001.0 17107.5 17114.8 5513.9 5815.0 5857.4 5861.3 5863.7 5556.8 5663.0 5680.5 5740.7 5817.4 5693.7 5707.6 5712.9 5713.6 5717.9 5300.0 5596.9 5625.8 5724.8 5734.1 5498.6 5511.4 5523.8 5529.1 5531.8 11184.4 14879.9 19479.8 21970.7 22146.4 2105.9 2132.3 2180.4 2243.0 2337.5 8798.0 9423.8 9790.7 9829.0 9857.6 1687.4 1752.3 1801.9 1864.8 1897.3 113092.4 116789.0 118670.4 118985.0 123417.8 10878.8 10953.9 11020.5 11068.4 11198.8 11415.7 13753.2 13761.4 13785.5 13789.4 6083.0 6353.7 7074.7 7370.2 7506.0 5981.8 7112.7 7127.8 7331.5 7335.0 3525.3 4225.7 4306.4 4384.7 4398.2 1800.5 1827.3 1847.0 1852.1 1855.3 727.5 790.3 790.3 811.9 852.8 15704.6 18440.3 18656.6 18941.3 19112.5 686.6 743.6 806.1 833.2 895.1 7481.0 9538.5 9799.0 9814.5 9837.4 553582.0 554728.0 556402.0 585131.4 588395.0 115397.4 118814.6 120848.0 131788.0 135852.0 933.6 942.4 996.4 1000.4 1004.3 18071.9 18173.5 18427.3 18652.0 19047.0 52758.2 54779.5 55577.4 56456.4 56573.7 63013.0 63537.0 64514.4 73151.6 82037.6 7207.2 7684.0 7839.8 7853.1 7938.5 9900.5 9954.4 10596.3 12559.0 12740.7 10858.0 11073.1 11194.7 11208.9 11210.4 7457.9 10712.6 10792.9 10936.8 11164.7 8846.9 10789.6 11165.3 11209.0 11334.4 6683.3 6968.9 7076.6 7256.4 7653.8 8513.3 10505.0 10796.8 10956.2 11132.6 16:21:42 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | [#3] mootools.html Cycles:180 Average:5244.01 Median:4697.44 stddev:2954.71 (62.9%) stddev-sans-first:2946.96 16:21:42 INFO - PROCESS | 1939 | Values: 1148.2 1230.1 1239.4 1439.5 1444.8 7245.6 7542.8 7887.3 8331.2 8372.2 6844.2 7876.6 8003.3 8212.2 8251.5 2248.9 2278.6 2280.8 2280.8 2291.6 2273.9 2308.5 2320.7 2325.5 2328.7 2246.4 2252.9 2281.3 2282.9 2283.9 2222.8 2236.9 2261.3 2267.6 2271.0 2264.9 2283.3 2284.2 2289.8 2289.9 8057.3 8173.4 8228.9 8235.8 8253.0 4895.7 4920.4 5078.2 5081.9 5101.7 3272.7 3410.8 3513.6 3513.7 3523.4 1045.5 1077.0 1086.3 1087.4 1094.6 10541.7 10855.2 10866.7 10875.7 10879.9 4150.5 4267.9 4304.9 4354.9 4371.3 8274.8 8422.0 8426.6 8447.9 8451.7 4917.3 4923.7 4925.0 4933.3 4945.3 4040.9 4189.8 4502.5 4625.0 4647.2 3434.9 3479.4 3515.1 3550.6 3561.6 1090.6 1098.6 1099.8 1106.5 1107.8 700.9 778.7 797.9 806.7 809.9 4307.8 4329.5 5319.6 5477.3 5550.3 398.9 406.8 408.9 409.1 824.4 3534.5 3572.7 3583.7 3672.8 3701.5 9857.6 10175.3 10216.9 10230.3 10275.9 10148.7 10197.6 10199.0 10204.8 10233.4 6835.8 7611.5 7796.1 7799.7 7841.8 4515.7 5217.3 5279.4 5313.5 5449.6 9154.0 9209.3 9215.2 9295.7 9386.0 7635.6 9282.4 9379.2 9413.5 9446.6 6103.1 6304.4 6443.2 6539.9 6687.2 7681.3 7803.6 7841.0 7854.5 7861.2 6898.3 6987.9 6989.2 7079.1 7121.0 6865.9 6894.4 6910.4 6937.3 6965.3 6289.8 6371.2 6409.1 6427.3 6435.0 4646.0 4676.1 4705.6 4722.8 4793.4 4609.4 4638.3 4689.3 4781.4 4885.1 16:21:42 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | [#4] prototype.html Cycles:180 Average:4488.55 Median:5054.22 stddev:1428.56 (28.3%) stddev-sans-first:1396.81 16:21:42 INFO - PROCESS | 1939 | Values: 256.4 280.9 303.4 309.8 316.4 4687.6 5203.6 5293.6 5342.5 5434.8 3759.6 3914.9 4550.0 4894.7 5298.7 4036.1 4081.0 4082.5 4088.7 4106.0 3460.4 3597.0 3811.6 4128.8 4145.3 4013.5 4021.9 4029.7 4031.5 4043.9 3523.3 3880.7 4005.8 4017.2 4048.8 3054.6 3492.2 3510.7 3522.5 3531.9 4375.6 4907.3 5197.2 5279.7 5279.8 4583.7 4590.2 4594.9 4599.4 4617.6 4878.0 5299.5 5390.4 5392.4 5660.9 3128.1 3243.7 3686.5 3841.3 3862.9 4908.5 5893.3 5910.9 5931.6 5932.2 3162.3 3285.7 3717.3 3869.0 3874.4 3307.8 3350.4 3546.4 3551.3 3956.3 3655.6 4104.0 4323.0 4562.8 4592.7 4665.5 5529.4 5611.0 5653.8 5667.7 1240.4 1264.9 1311.8 1325.5 1337.5 798.6 869.2 887.5 917.1 919.1 5286.5 5329.5 5330.4 5348.9 5354.1 5050.9 5093.5 5829.7 5830.2 5834.3 5026.1 5346.9 5354.7 5377.9 5380.5 2577.7 2667.7 2819.7 2821.5 3110.7 5021.7 5057.5 5588.0 5953.0 5979.3 5863.5 5870.6 5880.6 5903.4 5906.9 4627.7 5923.3 5947.9 5948.5 5948.6 4907.2 4995.6 5756.6 5894.1 5899.8 5634.5 5804.3 5805.5 5832.4 5867.5 5101.8 5178.8 5666.4 5937.9 6065.3 3116.0 3421.7 3630.6 3863.9 3910.6 4582.6 5907.8 5919.9 5928.1 5964.4 4867.5 5444.8 5514.8 5688.6 5785.6 4673.4 4974.5 5647.6 5689.9 5708.5 4470.4 5204.4 5267.7 5511.8 5561.0 5081.9 5635.7 5675.7 5702.1 5726.3 5239.5 5430.6 5551.9 5565.9 5577.6 16:21:42 INFO - PROCESS | 1939 | 16:21:42 INFO - PROCESS | 1939 | [#5] yui.html Cycles:180 Average:20499.71 Median:26106.42 stddev:31239.48 (119.7%) stddev-sans-first:31291.33 16:21:42 INFO - PROCESS | 1939 | Values: 585.7 657.4 658.5 662.8 678.8 13719.7 14207.7 14384.4 14953.0 15717.8 13917.2 13951.4 14055.4 14204.8 14227.1 620.9 635.9 642.6 645.0 647.5 520.4 533.3 618.2 622.9 623.1 665.3 671.9 673.3 676.3 676.4 650.3 655.6 666.6 668.0 668.8 493.2 493.4 493.6 494.4 496.4 13495.9 13592.5 13613.9 13660.7 13661.8 12184.8 12295.2 12433.1 12650.4 12819.8 10929.8 11037.9 11040.9 11078.3 11359.9 3572.4 3605.3 3618.3 3674.7 3758.3 88518.6 88954.1 89086.6 89247.0 89939.0 9239.7 9249.8 9251.2 9311.2 9426.2 18007.8 18051.7 18097.0 18122.6 18266.7 7343.6 7362.2 7429.2 7506.1 7563.4 3316.3 3914.9 4018.6 4133.3 4284.9 2792.5 3056.2 3075.1 3126.3 3172.6 513.7 544.6 560.4 583.5 606.4 423.9 519.3 551.6 606.2 621.5 7751.0 8391.5 8605.2 8796.8 8957.3 606.2 612.0 617.5 620.5 620.7 4239.2 4249.9 4399.1 4488.8 4559.3 115935.0 117237.0 119501.0 120451.4 120460.0 75662.5 85860.0 101334.5 103828.4 109403.5 84785.0 85379.6 85524.7 85984.6 87123.7 7216.8 8477.6 8489.3 9115.5 9263.3 36538.8 39330.0 47653.5 51057.0 54057.5 81322.0 81723.2 82077.0 82425.6 83328.6 11633.8 11708.2 11728.0 11734.6 11959.3 13422.2 13567.6 13594.1 13868.9 13888.4 10671.9 12312.9 12472.3 12658.7 12786.2 12559.8 12679.2 12802.4 12821.4 13017.3 12293.4 12582.2 13038.0 13063.5 13066.4 9765.4 9801.9 10595.9 10858.0 11254.9 8055.8 8761.6 8896.8 10720.3 10929.9 16:21:42 INFO - PROCESS | 1939 | -------- Summary: end -------- 16:21:42 INFO - PROCESS | 1939 | 16:21:43 INFO - TEST-INFO | 1939: exit 0 16:21:43 INFO - TEST-OK | dromaeo_css | took 1116867ms 16:21:43 INFO - TEST-START | kraken 16:21:43 INFO - operating with platform_type : mac_ 16:21:43 INFO - Initialising browser for kraken test... 16:21:43 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpWgLU7C/profile http://localhost:50915/getInfo.html) 16:21:45 INFO - PROCESS | 1944 | 2016-10-31 16:21:45.912 plugin-container[1945:16475] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 16:21:45 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:21:45 INFO - PROCESS | 1944 | 2016-10-31 16:21:45.915 plugin-container[1945:16475] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.CFPasteboardClient' 16:21:45 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:21:45 INFO - PROCESS | 1944 | 2016-10-31 16:21:45.915 plugin-container[1945:16475] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:21:45 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 16:21:45 INFO - PROCESS | 1944 | colorDepth:24 16:21:45 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 16:21:45 INFO - PROCESS | 1944 | __metrics 16:21:46 ERROR - PROCESS | 1944 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 16:21:46 INFO - TEST-INFO | 1944: exit 0 16:21:46 INFO - Browser initialized. 16:21:46 INFO - Running cycle 1/1 for kraken test... 16:21:46 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpWgLU7C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:49 INFO - PROCESS | 1946 | 2016-10-31 16:21:49.187 plugin-container[1947:16588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x862b, name = 'com.apple.tsm.portname' 16:21:49 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:21:49 INFO - PROCESS | 1946 | 2016-10-31 16:21:49.187 plugin-container[1947:16588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:21:49 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:21:49 INFO - PROCESS | 1946 | 2016-10-31 16:21:49.187 plugin-container[1947:16588] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:21:58 INFO - PROCESS | 1946 | RSS: Main: 239931392 16:21:58 INFO - PROCESS | 1946 | 16:22:01 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50915/tests/kraken/driver.html?testName=audio-beat-detection) 16:22:02 INFO - PROCESS | 1946 | RSS: Main: 252420096 16:22:02 INFO - PROCESS | 1946 | 16:22:05 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50915/tests/kraken/driver.html?testName=audio-dft) 16:22:05 INFO - PROCESS | 1946 | RSS: Main: 259821568 16:22:05 INFO - PROCESS | 1946 | 16:22:09 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50915/tests/kraken/driver.html?testName=audio-fft) 16:22:10 INFO - PROCESS | 1946 | RSS: Main: 262905856 16:22:10 INFO - PROCESS | 1946 | 16:22:11 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50915/tests/kraken/driver.html?testName=audio-oscillator) 16:22:12 INFO - PROCESS | 1946 | RSS: Main: 265117696 16:22:12 INFO - PROCESS | 1946 | 16:22:14 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50915/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:22:15 INFO - PROCESS | 1946 | RSS: Main: 265756672 16:22:15 INFO - PROCESS | 1946 | 16:22:18 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50915/tests/kraken/driver.html?testName=imaging-darkroom) 16:22:18 INFO - PROCESS | 1946 | RSS: Main: 265859072 16:22:18 INFO - PROCESS | 1946 | 16:22:21 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50915/tests/kraken/driver.html?testName=imaging-desaturate) 16:22:22 INFO - PROCESS | 1946 | RSS: Main: 266010624 16:22:22 INFO - PROCESS | 1946 | 16:22:25 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50915/tests/kraken/driver.html?testName=json-parse-financial) 16:22:26 INFO - PROCESS | 1946 | RSS: Main: 266317824 16:22:26 INFO - PROCESS | 1946 | 16:22:27 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50915/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:22:28 INFO - PROCESS | 1946 | RSS: Main: 265932800 16:22:28 INFO - PROCESS | 1946 | 16:22:30 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:22:31 INFO - PROCESS | 1946 | RSS: Main: 265953280 16:22:31 INFO - PROCESS | 1946 | 16:22:33 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:22:34 INFO - PROCESS | 1946 | RSS: Main: 266055680 16:22:34 INFO - PROCESS | 1946 | 16:22:37 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:22:37 INFO - PROCESS | 1946 | RSS: Main: 266371072 16:22:37 INFO - PROCESS | 1946 | 16:22:40 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:22:41 INFO - PROCESS | 1946 | RSS: Main: 266215424 16:22:41 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50915/tests/kraken/driver.html?testName=ai-astar) 16:22:43 INFO - PROCESS | 1946 | RSS: Main: 267862016 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | __start_tp_report 16:22:43 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 16:22:43 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 16:22:43 INFO - PROCESS | 1946 | |i|pagename|runs| 16:22:43 INFO - PROCESS | 1946 | |0;ai-astar;112;152;172;153;118;116;119;117;117;116 16:22:43 INFO - PROCESS | 1946 | |1;audio-beat-detection;150;104;135;151;114;122;150;101;177;147 16:22:43 INFO - PROCESS | 1946 | |2;audio-dft;215;232;233;190;186;237;276;236;206;197 16:22:43 INFO - PROCESS | 1946 | |3;audio-fft;76;58;59;87;61;58;60;60;78;57 16:22:43 INFO - PROCESS | 1946 | |4;audio-oscillator;86;70;89;73;76;87;114;70;72;87 16:22:43 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;90;87;89;85;87;115;137;133;88;93 16:22:43 INFO - PROCESS | 1946 | |6;imaging-darkroom;107;174;112;171;172;100;100;98;102;98 16:22:43 INFO - PROCESS | 1946 | |7;imaging-desaturate;94;90;90;85;92;104;132;77;139;134 16:22:43 INFO - PROCESS | 1946 | |8;json-parse-financial;57;57;57;56;57;58;58;58;57;57 16:22:43 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;66;84;65;82;102;102;102;106;73;71 16:22:43 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;90;89;90;90;94;108;108;113;84;91 16:22:43 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;175;165;153;149;154;151;150;152;215;148 16:22:43 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;176;180;175;175;178;175;176;176;174;230 16:22:43 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;69;68;66;68;70;66;69;67;68;67 16:22:43 INFO - PROCESS | 1946 | __end_tp_report 16:22:43 INFO - PROCESS | 1946 | __start_cc_report 16:22:43 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,979 16:22:43 INFO - PROCESS | 1946 | __end_cc_report 16:22:43 INFO - PROCESS | 1946 | __startTimestamp1477956163479__endTimestamp 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | ------- Summary: start ------- 16:22:43 INFO - PROCESS | 1946 | Number of tests: 14 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:129.20 Median:118.50 stddev:21.32 (18.0%) stddev-sans-first:21.68 16:22:43 INFO - PROCESS | 1946 | Values: 112.0 152.0 172.0 153.0 118.0 116.0 119.0 117.0 117.0 116.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:148.50 stddev:24.36 (16.4%) stddev-sans-first:25.23 16:22:43 INFO - PROCESS | 1946 | Values: 150.0 104.0 135.0 151.0 114.0 122.0 150.0 101.0 177.0 147.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:220.80 Median:232.50 stddev:27.47 (11.8%) stddev-sans-first:29.06 16:22:43 INFO - PROCESS | 1946 | Values: 215.0 232.0 233.0 190.0 186.0 237.0 276.0 236.0 206.0 197.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.50 stddev:10.73 (17.7%) stddev-sans-first:10.67 16:22:43 INFO - PROCESS | 1946 | Values: 76.0 58.0 59.0 87.0 61.0 58.0 60.0 60.0 78.0 57.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:81.00 stddev:13.51 (16.7%) stddev-sans-first:14.27 16:22:43 INFO - PROCESS | 1946 | Values: 86.0 70.0 89.0 73.0 76.0 87.0 114.0 70.0 72.0 87.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:100.40 Median:87.50 stddev:20.16 (23.0%) stddev-sans-first:21.03 16:22:43 INFO - PROCESS | 1946 | Values: 90.0 87.0 89.0 85.0 87.0 115.0 137.0 133.0 88.0 93.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:123.40 Median:171.50 stddev:34.04 (19.9%) stddev-sans-first:35.59 16:22:43 INFO - PROCESS | 1946 | Values: 107.0 174.0 112.0 171.0 172.0 100.0 100.0 98.0 102.0 98.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:103.70 Median:87.50 stddev:22.69 (25.9%) stddev-sans-first:23.79 16:22:43 INFO - PROCESS | 1946 | Values: 94.0 90.0 90.0 85.0 92.0 104.0 132.0 77.0 139.0 134.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 16:22:43 INFO - PROCESS | 1946 | Values: 57.0 57.0 57.0 56.0 57.0 58.0 58.0 58.0 57.0 57.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:85.30 Median:68.50 stddev:16.39 (23.9%) stddev-sans-first:15.83 16:22:43 INFO - PROCESS | 1946 | Values: 66.0 84.0 65.0 82.0 102.0 102.0 102.0 106.0 73.0 71.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:95.70 Median:90.00 stddev:10.03 (11.1%) stddev-sans-first:10.43 16:22:43 INFO - PROCESS | 1946 | Values: 90.0 89.0 90.0 90.0 94.0 108.0 108.0 113.0 84.0 91.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:161.20 Median:153.50 stddev:20.70 (13.5%) stddev-sans-first:21.34 16:22:43 INFO - PROCESS | 1946 | Values: 175.0 165.0 153.0 149.0 154.0 151.0 150.0 152.0 215.0 148.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.50 Median:176.00 stddev:17.13 (9.7%) stddev-sans-first:18.05 16:22:43 INFO - PROCESS | 1946 | Values: 176.0 180.0 175.0 175.0 178.0 175.0 176.0 176.0 174.0 230.0 16:22:43 INFO - PROCESS | 1946 | 16:22:43 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.32 (1.9%) stddev-sans-first:1.32 16:22:43 INFO - PROCESS | 1946 | Values: 69.0 68.0 66.0 68.0 70.0 66.0 69.0 67.0 68.0 67.0 16:22:43 INFO - PROCESS | 1946 | -------- Summary: end -------- 16:22:43 INFO - PROCESS | 1946 | 16:22:44 INFO - TEST-INFO | 1946: exit 0 16:22:44 INFO - TEST-OK | kraken | took 61068ms 16:22:44 INFO - SUITE-END | took 1177s 16:22:44 INFO - Completed test suite (00:19:38) 16:22:44 INFO - javascript benchmark 16:22:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6794.733761917866, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2797.8041537092404, 2954.512505436603, 3349.916252093699, 3355.228297491577, 3357.5635167428222, 4496.752678602679, 5111.386633603964, 5597.188407680884, 5671.3761486236535, 5673.517750991171, 5425.159100339444, 5531.308586426697, 5636.520895723868, 5685.800996965107, 5694.145878118274, 5123.103456895038, 5204.219367094928, 5228.268042474037, 5230.73846307685, 5247.737613119348, 5196.350456192976, 5223.164293713006, 5230.633855630107, 5236.502532259429, 5262.973685131569, 4912.828051018215, 5030.471800460972, 5241.397239317489, 5250.186221135711, 5253.9737301313635, 5055.216441451581, 5087.771050302746, 5148.91872706732, 5170.560502357304, 5204.42751297357, 3944.1717239379764, 5061.974690126526, 5083.3391659084455, 5091.949080509185, 5184.870378240536, 4486.551344865487, 5583.35791383992, 5728.513076388512, 5730.828075157752, 5749.971250143764, 5842.941570584282, 6024.728887200086, 6090.177825993478, 6118.418750218722, 6121.357257487989, 5537.031019571599, 5548.72256387179, 5614.624417017857, 6149.754009839601, 6205.689715514206, 5576.86057848557, 5600.85997850057, 5699.344575373885, 5712.600117991742, 5721.599488035839, 6155.169052177898, 6174.8147555573405, 6221.875562488724, 6311.842203944846, 6329.68351582419, 6117.785877494266, 6125.724342404603, 6130.846728831815, 6153.938460615426, 6167.629942203482, 5977.342492325841, 6184.752609895644, 6188.44304012641, 6196.628202307876, 6211.875762484816, 5961.165436838846, 5965.164876917235, 6062.727177277033, 6070.453659170607, 6084.269887613426, 6055.909161362583, 6083.361247069082, 6106.2061931948565, 6108.450239478469, 6122.20411346531, 4244.769016985101, 4818.060478206716, 4859.635527335464, 4862.586680132157, 4889.951100489021, 3731.36566783642, 4326.740395576276, 4391.341298805205, 4419.11617676466, 4429.402030725875, 5136.767175877837, 5587.776488940397, 5630.662160270397, 5688.175214593944, 5696.11710184922, 5770.88458230833, 6088.5243303217485, 6126.479249263773, 6128.044828652003, 6129.693515324216, 5805.36141024487, 5809.448102430262, 5831.416858314219, 5883.17635531026, 5885.440883116097, 5375.408705042442, 5407.486288802557, 5415.350157980989, 5415.48552887475, 5423.566114710814, 6111.561351782736, 6155.415235552616, 6219.906701399392, 6234.657093859791, 6317.526185536103, 6154.138420621027, 6186.041163619646, 6198.194234749634, 6205.81382558524, 6224.968875155595, 6297.2128483939505, 6333.050042493663, 6362.936370636419, 6368.076628888878, 6438.227412710505, 5946.554008449297, 5985.910211346746, 6000.0, 6093.847653808691, 6127.417895299939, 5196.324477818011, 6100.145979562776, 6100.450959413496, 6101.115338275837, 6111.052786818051, 5954.404559544011, 5957.314908785545, 5958.374370691015, 5961.523078153824, 5967.9556077685365, 5861.501772349398, 5895.823125306248, 5937.050071988461, 5949.167116603766, 5991.430814072656, 4251.256030194641, 4548.5224051474115, 4568.70303430276, 5466.551363888652, 5832.94167058324, 5887.8233652990475, 5905.52755779528, 6009.038553831366, 6014.518838492998, 6130.846728831636, 4695.272232803921, 5757.453041961007, 5890.4993075589055, 5893.410658934244, 5910.083936989773], "value": 5537.738452214584, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.9208346455906, 436.63104676548323, 456.49329244538535, 492.07244344410776, 495.8817865712818, 27890.0, 32408.1897952551, 35035.47446788294, 35087.64912350879, 35212.82393588028, 35228.295434091306, 35787.463188052134, 35845.641543584556, 35859.462108068336, 35894.0, 8038.9196108038905, 9358.578863951105, 9384.8592271116, 9394.812103757955, 9417.952910235439, 9355.391899526508, 9414.670486532972, 9451.149396554309, 9458.716238512821, 9459.24326053914, 9435.575399107021, 9437.056294370577, 9452.952735236315, 9478.146966772989, 9479.336446448717, 9379.624815007392, 9403.388779729295, 9410.764730881714, 9411.058894110569, 9420.576074076684, 9261.583228754722, 9299.535023248878, 9314.906850931471, 9341.11259430353, 9395.62417503299, 33100.51746119051, 33139.3372132556, 33276.6672333276, 33378.49932251018, 33578.32843343144, 2958.955615665767, 3031.0, 3152.448321543721, 3258.3157536917274, 3274.5743053403144, 18793.624127517374, 27186.0, 27371.0, 27817.0, 28011.299717507023, 2448.2362249391667, 3106.456370135217, 3145.51244557094, 3249.9350012999607, 3307.9007629771145, 346888.0, 374372.0, 428462.8576857096, 431365.0, 432301.8384908087, 47695.28457073147, 47917.04165916662, 48181.03637927222, 48208.03583928302, 48245.45177304585, 49553.50446495561, 49661.75169124131, 49875.50124498781, 49891.0, 50052.749736251084, 13061.934690326487, 13279.0, 13285.003624728184, 13289.66775830612, 13322.467101315839, 10357.0, 10392.428416437016, 10403.5838566458, 10412.791744165073, 10415.583376665, 13427.530036448677, 13833.962452816078, 14030.789538156936, 14054.929725351309, 14278.07192532482, 2914.5919571259615, 5447.700376479253, 5650.463205995424, 5670.971645141831, 5724.1413787931315, 773.6711897443564, 856.6873091321622, 876.2639382918379, 923.4182465047112, 1025.5846382215216, 25648.61527077095, 26333.52330103887, 27697.446051078867, 27739.583906241423, 28278.575821362698, 785.9782283032035, 812.3825892321878, 923.7459698582943, 968.6464440479315, 984.7587341101475, 14157.929210354088, 14214.573562793134, 14375.712485750226, 14517.274136293143, 14517.637059073397, 658021.0, 660097.0, 667786.0, 667919.0, 669004.0, 47456.7627161862, 47895.521044789806, 47943.520564794606, 47991.28013079806, 47996.760016200395, 1121.6803211084964, 1172.4372301295425, 1175.1656324010048, 1181.539199712113, 1188.9464974076188, 27153.04964326239, 27357.075726369443, 27528.0, 27749.890004399596, 27837.88648454039, 42312.576874230865, 45713.77143114263, 47036.059278814915, 47081.76459117683, 48678.0, 645508.0, 648151.0, 650579.0, 651502.0, 653246.0, 36214.0, 36682.63317366899, 36936.0, 37054.0, 37135.628643713215, 40281.99295017648, 40318.79840600778, 40600.79699601483, 40990.130690852544, 41339.7933010333, 26566.216101673726, 30534.84732576323, 31935.36129277355, 32295.35409291848, 34275.828620857734, 34053.82973085119, 34211.828940855135, 34966.776162834176, 35041.29917401689, 35240.59037638466, 33348.832790852204, 33613.0, 34311.79908703183, 34343.0, 35190.0, 26515.60226596564, 27854.935957044305, 28067.859660701564, 28752.85623571869, 29607.85196074006, 19407.514812129808, 20855.270065547633, 21701.0, 24037.51924961526, 24263.878680606485], "value": 17778.087883314154, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.6410404970731, 356.810890228179, 358.2709186804852, 362.430983356131, 382.96981120785085, 16967.83032169678, 17763.644727105468, 18419.0, 18712.625747485043, 18752.06239688017, 16319.347226110942, 16981.15094245286, 17000.979941203506, 17107.486775396726, 17114.82885171151, 5513.862153446176, 5815.040518314494, 5857.41425857413, 5861.325947516048, 5863.706814659271, 5556.833295001156, 5662.971685141589, 5680.5455563554815, 5740.655560666352, 5817.447342502455, 5693.686847223401, 5707.6290041147195, 5712.857178570527, 5713.571482138836, 5717.857053573653, 5299.999999999981, 5596.888062238773, 5625.774969001235, 5724.7996320128805, 5734.082546792514, 5498.560115190775, 5511.448855114497, 5523.806666766684, 5529.115341545334, 5531.834044978657, 11184.38485883276, 14879.925600372037, 19479.831210127297, 21970.670439943413, 22146.446338841495, 2105.8947052647304, 2132.338974917765, 2180.4300903349563, 2243.0467051503047, 2337.4857531343246, 8798.0, 9423.811523769486, 9790.706278811647, 9828.950855245677, 9857.605695772161, 1687.4347093723618, 1752.255291501107, 1801.8558515318744, 1864.813518648138, 1897.3264491105735, 113092.4345378276, 116789.0, 118670.40664796621, 118985.0, 123417.76582234242, 10878.836817447745, 10953.890461095447, 11020.50407731654, 11068.446577671084, 11198.808339623169, 11415.714607134722, 13753.174809511462, 13761.38073786682, 13785.51750688721, 13789.448422063006, 6083.0, 6353.682315884187, 7074.71701131949, 7370.189279179286, 7506.0, 5981.79063732776, 7112.715491380391, 7127.82180445493, 7331.523450975669, 7334.963325183447, 3525.3125640499857, 4225.72532785368, 4306.440162778818, 4384.693071484965, 4398.23030969579, 1800.5318617159633, 1827.259959716317, 1846.9933886032145, 1852.129499135404, 1855.3413538194018, 727.5016613619688, 790.2650535002502, 790.3124281874691, 811.8660421030464, 852.799592095857, 15704.607384815243, 18440.262389504536, 18656.626867462575, 18941.33705320307, 19112.52218694516, 686.5914780705572, 743.5798773692846, 806.105223202245, 833.1836596220298, 895.1317222294364, 7481.0, 9538.475383853816, 9799.0, 9814.460204688668, 9837.360571562825, 553582.0, 554728.0, 556402.0, 585131.3789793613, 588395.0, 115397.42301288439, 118814.62370752536, 120848.0, 131788.0, 135852.0, 933.5845548730886, 942.3827393057559, 996.4121168510613, 1000.4047591682937, 1004.2819384140374, 18071.91568505894, 18173.54566135857, 18427.341539260993, 18652.0, 19046.95464987054, 52758.2086268714, 54779.452205477435, 55577.44422555723, 56456.435435645115, 56573.71713141408, 63013.0, 63537.046944296795, 64514.354856452715, 73151.63424182845, 82037.58981205056, 7207.243239459781, 7683.961580192287, 7839.84320313602, 7853.136155023057, 7938.483998540077, 9900.455474948947, 9954.402735835873, 10596.258261921592, 12559.0, 12740.745185096432, 10858.0, 11073.114150868072, 11194.720131996764, 11208.887911120784, 11210.439478025903, 7457.888131677921, 10712.625058122929, 10792.892071079503, 10936.78126437451, 11164.720881978015, 8846.911530884609, 10789.56841726322, 11165.330080195214, 11209.0, 11334.433278335886, 6683.329167708073, 6968.930310696828, 7076.575405475688, 7256.383207427217, 7653.808654783675, 8513.318934485102, 10505.0, 10796.838047429137, 10956.233063685466, 11132.610358637408], "value": 8824.374050181787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1148.1561052626319, 1230.0774419185611, 1239.3679223595964, 1439.4745917740029, 1444.8338441079272, 7245.601491917942, 7542.849143017136, 7887.290143887049, 8331.208535189155, 8372.246497815195, 6844.212915514718, 7876.606169691521, 8003.279704826565, 8212.219839115289, 8251.504909705422, 2248.9437764055947, 2278.567072256266, 2280.8289378296695, 2280.8403411761183, 2291.5531471363092, 2273.8521996070203, 2308.4575124845646, 2320.721513418392, 2325.5232677301196, 2328.7205535335784, 2246.359787460574, 2252.9211477598287, 2281.269993602048, 2282.8972696228625, 2283.862968221904, 2222.799948004671, 2236.8769717665523, 2261.2989973108224, 2267.637178051512, 2271.0234599122373, 2264.8980795864063, 2283.349245465051, 2284.23478134825, 2289.828262880291, 2289.8969546370454, 8057.315128214109, 8173.386995975326, 8228.917710822874, 8235.8352832943, 8253.0, 4895.7062576245535, 4920.384951881015, 5078.21287700407, 5081.949180508184, 5101.69389836611, 3272.672732726708, 3410.795352278871, 3513.6135025147214, 3513.7364697647777, 3523.3834079036064, 1045.5086109528509, 1077.0, 1086.32104934416, 1087.396494945305, 1094.6168840905698, 10541.73645658857, 10855.240133190759, 10866.67399978002, 10875.673729788119, 10879.891201087967, 4150.522689890641, 4267.871963841089, 4304.870853874389, 4354.869353919387, 4371.344298355209, 8274.793130171673, 8422.031466381322, 8426.620802063922, 8447.915520844836, 8451.746447606582, 4917.28699338601, 4923.679960802537, 4924.9507504925205, 4933.28467372229, 4945.332380128709, 4040.8585699500377, 4189.832406703758, 4502.5047244803045, 4624.982503849148, 4647.18674232008, 3434.9313013739584, 3479.408500554912, 3515.1212196950764, 3550.55618047744, 3561.62602926689, 1090.6128324444867, 1098.6374496416163, 1099.79103970247, 1106.4744246483017, 1107.8061339265596, 700.852820907605, 778.7391223940043, 797.9082405523322, 806.7176488229073, 809.8783185288418, 4307.827686892552, 4329.480462344476, 5319.601029922771, 5477.287952566141, 5550.250716153349, 398.94215338776297, 406.8006676728382, 408.85076946914756, 409.0958980652791, 824.4023083264705, 3534.540509733718, 3572.696320812708, 3583.7133029357587, 3672.8163591820303, 3701.481792549045, 9857.55640996157, 10175.28772985884, 10216.948915255376, 10230.28388012832, 10275.948620257, 10148.695539133838, 10197.643082492226, 10199.031092046243, 10204.795904081877, 10233.437160956219, 6835.829104272333, 7611.505252158592, 7796.064472263251, 7799.727009554638, 7841.803954901173, 4515.683902126951, 5217.269582258411, 5279.366476022902, 5313.459096862021, 5449.5640348772795, 9154.0, 9209.309301802527, 9215.170634642915, 9295.721128365889, 9386.014468480984, 7635.5800430974605, 9282.443053416546, 9379.202767764544, 9413.52932353366, 9446.574904129193, 6103.145559621567, 6304.401081897213, 6443.226812782496, 6539.901901471577, 6687.230968438692, 7681.347085497795, 7803.648835802289, 7840.960795195988, 7854.4894581851995, 7861.174576669596, 6898.344657257552, 6987.895181572178, 6989.231184569795, 7079.07971963662, 7121.038659780863, 6865.862682746218, 6894.448444124559, 6910.44716422695, 6937.340952609494, 6965.268646792014, 6289.779857704958, 6371.203599550056, 6409.102725618511, 6427.260865000398, 6435.0, 4645.953540464688, 4676.134915040729, 4705.55297246761, 4722.787474563722, 4793.400824896888, 4609.400777898852, 4638.316440883052, 4689.320048593003, 4781.378420805273, 4885.145099607484], "value": 4162.258518979326, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.4204896932932, 280.9240608469562, 303.42198112595514, 309.79398699864583, 316.3546365414554, 4687.601553867921, 5203.609729270301, 5293.6294459387855, 5342.545883599893, 5434.755436005385, 3759.586445490994, 3914.902127446815, 4550.0, 4894.696080145896, 5298.6555873868265, 4036.1120553478186, 4080.9795951020205, 4082.4896887889013, 4088.713790034691, 4105.979470102645, 3460.4290292101773, 3596.964030359702, 3811.5807261201244, 4128.772917489537, 4145.253854306224, 4013.4782478277784, 4021.919561608781, 4029.6574791142643, 4031.4926014367543, 4043.939340909889, 3523.3481805865995, 3880.7477513961785, 4005.83976640934, 4017.150757957675, 4048.838046478137, 3054.5796204764893, 3492.1967947372245, 3510.7191424685966, 3522.489239060322, 3531.858725650971, 4375.584319489643, 4907.312976183337, 5197.180986654678, 5279.683219006872, 5279.788808447696, 4583.72497650142, 4590.163934426223, 4594.862154135381, 4599.413202445151, 4617.630589552828, 4877.975610121926, 5299.470052994709, 5390.353157621111, 5392.379876314235, 5660.943390566083, 3128.092853072629, 3243.6918492743152, 3686.4838922550866, 3841.2509560635685, 3862.903427414309, 4908.484609116027, 5893.263342082239, 5910.911336329958, 5931.551336497627, 5932.228810254639, 3162.3042930555703, 3285.7021476516643, 3717.3494638438165, 3869.0, 3874.3607304794955, 3307.8015319080932, 3350.396928552835, 3546.361654902142, 3551.2897420516, 3956.2680904032272, 3655.5613326401003, 4103.974006498375, 4323.0, 4562.771861406916, 4592.680696248373, 4665.5101214372, 5529.4470552944385, 5610.990021796116, 5653.840962602657, 5667.744951477111, 1240.4232032105115, 1264.9494020239088, 1311.7669390772644, 1325.4830616059744, 1337.5452346202337, 798.5767543202112, 869.1959937058195, 887.4852585500523, 917.1058218237299, 919.1497864475333, 5286.524212820865, 5329.467053294717, 5330.413654498002, 5348.870910517391, 5354.063038968165, 5050.939302746465, 5093.516115968977, 5829.679367634736, 5830.192640281526, 5834.27071616048, 5026.145555255615, 5346.893062138814, 5354.732263386815, 5377.919331210035, 5380.488853558905, 2577.69067711872, 2667.6794986481814, 2819.7039310872065, 2821.520341541902, 3110.6733792951413, 5021.698698078127, 5057.494250574913, 5588.0, 5952.951992645043, 5979.342272350038, 5863.5309175265875, 5870.647761134345, 5880.647161170344, 5903.35063143054, 5906.881862362815, 4627.722336659811, 5923.259592550931, 5947.851303717528, 5948.494377977834, 5948.613340132964, 4907.233395977374, 4995.600351971761, 5756.625819321731, 5894.084422562599, 5899.823005309848, 5634.492895639412, 5804.303483581997, 5805.490572451277, 5832.387599302182, 5867.471927526543, 5101.795928162832, 5178.844634660966, 5666.405027472056, 5937.940620593911, 6065.332813390435, 3115.971729329361, 3421.674940880612, 3630.636936306348, 3863.9227215456094, 3910.608939106067, 4582.633389328779, 5907.793227237026, 5919.904817608756, 5928.051511758098, 5964.433378829004, 4867.464578896389, 5444.785276073689, 5514.834554963358, 5688.63023903445, 5785.611453251274, 4673.439187297546, 4974.502549744997, 5647.576431767716, 5689.914651280152, 5708.514776244066, 4470.441194850644, 5204.427512973494, 5267.735743421628, 5511.779528818963, 5561.0, 5081.898362032813, 5635.690037048002, 5675.659460432387, 5702.144678298288, 5726.255586773693, 5239.47605239473, 5430.592705547021, 5551.944480555304, 5565.916511252415, 5577.5537956962535], "value": 4033.8787720143646, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.7042193692184, 657.4214691071857, 658.476511173617, 662.7746566167502, 678.8167194857392, 13719.725605487896, 14207.71584568308, 14384.42462301508, 14953.0, 15717.764233536505, 13917.164970101776, 13951.372188251527, 14055.437782488714, 14204.786928196085, 14227.146371217734, 620.9316975132732, 635.8601107756286, 642.5951650460204, 644.954853160279, 647.4787795824383, 520.4405264340829, 533.2747463449705, 618.2426527503794, 622.850515876191, 623.0747340199799, 665.3114026982074, 671.8757029949451, 673.276228054843, 676.3169199108886, 676.4115219758792, 650.2944305428616, 655.5574986883859, 666.5634009723639, 667.9799606011827, 668.8227619680788, 493.2010143567408, 493.39805437366357, 493.6125141763698, 494.4141192686678, 496.4092729651703, 13495.865041349658, 13592.524261650795, 13613.863861361358, 13660.726785464334, 13661.795073073912, 12184.81722774159, 12295.200811947285, 12433.129680922339, 12650.367481625883, 12819.807702884464, 10929.836052459219, 11037.889621103766, 11040.889591104067, 11078.279911805706, 11359.943200284028, 3572.4462708280257, 3605.280048561318, 3618.330608837347, 3674.6876515496215, 3758.32350176968, 88518.55740721384, 88954.11045889523, 89086.5545672274, 89247.0, 89939.0, 9239.676611318571, 9249.815003700023, 9251.213646840093, 9311.208547273558, 9426.245900327956, 18007.819921800878, 18051.729224061648, 18097.0, 18122.637547249244, 18266.725999110025, 7343.596102214323, 7362.153352364439, 7429.182789893108, 7506.136794268729, 7563.432742544332, 3316.2704205074847, 3914.8842579865172, 4018.6182312680835, 4133.3412916318275, 4284.89287767802, 2792.4833905727505, 3056.2359410147465, 3075.108218616621, 3126.2965832687464, 3172.6034245719284, 513.7174553995287, 544.6003770310265, 560.3583896438555, 583.4981915552621, 606.4057223920495, 423.85377044919454, 519.2807960974078, 551.5918220516788, 606.2270604978687, 621.5214285000525, 7750.961245193851, 8391.454555453876, 8605.225529702357, 8796.824063518694, 8957.283417326598, 606.1544145916422, 612.0146564031905, 617.4566381584177, 620.4694985787178, 620.7485968182893, 4239.215745087169, 4249.915001699948, 4399.14216727735, 4488.753118578445, 4559.316102584638, 115935.0, 117237.0, 119501.0, 120451.39774301072, 120460.0, 75662.48675026468, 85860.0, 101334.4933275329, 103828.40428989335, 109403.45298273458, 84785.0, 85379.57310213409, 85524.71712924312, 85984.57007715046, 87123.69314460288, 7216.7834964951135, 8477.558815001588, 8489.320854331516, 9115.498647574446, 9263.305252106255, 36538.8173059133, 39330.0, 47653.523464766295, 51056.978860423325, 54057.459425406814, 81322.0, 81723.18276817394, 82077.0, 82425.58787206025, 83328.58335708526, 11633.767324653629, 11708.180427370002, 11728.0, 11734.647960561197, 11959.342236177094, 13422.194668319931, 13567.639125285139, 13594.11638243511, 13868.93065534666, 13888.444462221398, 10671.893281067401, 12312.938435307766, 12472.314022728837, 12658.731738386256, 12786.168899021533, 12559.787861514915, 12679.175853569488, 12802.423890925116, 12821.358932053174, 13017.284049376996, 12293.385330733248, 12582.18215815969, 13038.022148338723, 13063.542776002794, 13066.412011459697, 9765.424148483158, 9801.9019809801, 10595.888058847451, 10858.0, 11254.94372528132, 8055.798605034921, 8761.649534018568, 8896.822063558822, 10720.303180293256, 10929.945350273198], "value": 6745.846205833839, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 152.0, 172.0, 153.0, 118.0, 116.0, 119.0, 117.0, 117.0, 116.0], "value": 129.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 104.0, 135.0, 151.0, 114.0, 122.0, 150.0, 101.0, 177.0, 147.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 232.0, 233.0, 190.0, 186.0, 237.0, 276.0, 236.0, 206.0, 197.0], "value": 220.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 59.0, 87.0, 61.0, 58.0, 60.0, 60.0, 78.0, 57.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 89.0, 73.0, 76.0, 87.0, 114.0, 70.0, 72.0, 87.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 85.0, 87.0, 115.0, 137.0, 133.0, 88.0, 93.0], "value": 100.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 174.0, 112.0, 171.0, 172.0, 100.0, 100.0, 98.0, 102.0, 98.0], "value": 123.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 90.0, 90.0, 85.0, 92.0, 104.0, 132.0, 77.0, 139.0, 134.0], "value": 103.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 56.0, 57.0, 58.0, 58.0, 58.0, 57.0, 57.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 84.0, 65.0, 82.0, 102.0, 102.0, 102.0, 106.0, 73.0, 71.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 90.0, 94.0, 108.0, 108.0, 113.0, 84.0, 91.0], "value": 95.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 165.0, 153.0, 149.0, 154.0, 151.0, 150.0, 152.0, 215.0, 148.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 180.0, 175.0, 175.0, 178.0, 175.0, 176.0, 176.0, 174.0, 230.0], "value": 181.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 66.0, 68.0, 70.0, 66.0, 69.0, 67.0, 68.0, 67.0], "value": 67.8, "unit": "score"}]}]} 16:22:44 INFO - Return code: 0 16:22:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:22:44 ERROR - # TBPL SUCCESS # 16:22:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:22:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:22:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:22:44 INFO - 2016-10-31 16:22:44.816 screenresolution[1951:16964] starting screenresolution argv=screenresolution get 16:22:44 INFO - 2016-10-31 16:22:44.819 screenresolution[1951:16964] Display 0: 1600x1200x32@60 16:22:44 INFO - 2016-10-31 16:22:44.825 screenresolution[1952:16967] starting screenresolution argv=screenresolution list 16:22:44 INFO - Available Modes on Display 0 16:22:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:22:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:22:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:22:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:22:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:22:45 INFO - Intel Iris: 16:22:45 INFO - Chipset Model: Intel Iris 16:22:45 INFO - Type: GPU 16:22:45 INFO - Bus: Built-In 16:22:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:22:45 INFO - Vendor: Intel (0x8086) 16:22:45 INFO - Device ID: 0x0a2e 16:22:45 INFO - Revision ID: 0x0009 16:22:45 INFO - Displays: 16:22:45 INFO - Display: 16:22:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:22:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:22:45 INFO - Display Serial Number: 1600x1200 60 16:22:45 INFO - Main Display: Yes 16:22:45 INFO - Mirror: Off 16:22:45 INFO - Online: Yes 16:22:45 INFO - Rotation: Supported 16:22:45 INFO - Return code: 0 16:22:45 INFO - Running post-action listener: _resource_record_post_action 16:22:45 INFO - [mozharness: 2016-10-31 23:22:45.064810Z] Finished run-tests step (success) 16:22:45 INFO - Running post-run listener: _resource_record_post_run 16:22:45 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1787392; Write bytes: 710486528; Read time: 18; Write time: 1972 16:22:45 INFO - TinderboxPrint: CPU usage
54.3% 16:22:45 INFO - TinderboxPrint: I/O read bytes / time
1,787,392 / 18 16:22:45 INFO - TinderboxPrint: I/O write bytes / time
710,486,528 / 1,972 16:22:45 INFO - TinderboxPrint: CPU idle
2,217.7 (45.7%) 16:22:45 INFO - TinderboxPrint: CPU system
981.8 (20.2%) 16:22:45 INFO - TinderboxPrint: CPU user
1,654.2 (34.1%) 16:22:45 INFO - TinderboxPrint: Swap in / out
409,079,808 / 0 16:22:45 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391719424; Write bytes: 385324544; Read time: 29578; Write time: 683 16:22:45 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1787392; Write bytes: 301347840; Read time: 18; Write time: 1238 16:22:45 INFO - Running post-run listener: _upload_blobber_files 16:22:45 INFO - Blob upload gear active. 16:22:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:22:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:22:45 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:22:45 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:22:45 INFO - (blobuploader) - INFO - Open directory for files ... 16:22:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:22:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:22:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:46 INFO - (blobuploader) - INFO - Done attempting. 16:22:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:22:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:22:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:47 INFO - (blobuploader) - INFO - Done attempting. 16:22:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:22:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:22:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:50 INFO - (blobuploader) - INFO - Done attempting. 16:22:50 INFO - (blobuploader) - INFO - Iteration through files over. 16:22:50 INFO - Return code: 0 16:22:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:22:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:22:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40cc557773a794a23e0a2d2bb8213cb9b18b3e03074404183aeff50415bfb7632d726202e1087f10c6d6c6b7799061abab560a6f55a682f2dbfb79bdafbb2615", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f784f2c7acb6212bca1670f151f85f292e1242f7547303ff9fd67464ea9d8eb802bfcaa172de753533ad3439137331bae78700f91233031bd364215efa32addd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3a8032f556f6636ce9c5d6b9bddd8f7aa54a0d954c353f1eba9e82988b3781e7c4bd27c26ff36178c93b221a77c5e706c8f0330c28feed6d255be020cc061e4"} 16:22:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:22:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:22:50 INFO - Contents: 16:22:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40cc557773a794a23e0a2d2bb8213cb9b18b3e03074404183aeff50415bfb7632d726202e1087f10c6d6c6b7799061abab560a6f55a682f2dbfb79bdafbb2615", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f784f2c7acb6212bca1670f151f85f292e1242f7547303ff9fd67464ea9d8eb802bfcaa172de753533ad3439137331bae78700f91233031bd364215efa32addd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3a8032f556f6636ce9c5d6b9bddd8f7aa54a0d954c353f1eba9e82988b3781e7c4bd27c26ff36178c93b221a77c5e706c8f0330c28feed6d255be020cc061e4"} 16:22:50 INFO - Running post-run listener: copy_logs_to_upload_dir 16:22:50 INFO - Copying logs to upload dir... 16:22:50 INFO - mkdir: /builds/slave/test/build/upload/logs 16:22:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.514682 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-31 16:22:50.111918) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 16:22:50.115574) ========= 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.zpMbEwuZke/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.mSIkecpAvG/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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/Fx-Team/sha512/40cc557773a794a23e0a2d2bb8213cb9b18b3e03074404183aeff50415bfb7632d726202e1087f10c6d6c6b7799061abab560a6f55a682f2dbfb79bdafbb2615", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f784f2c7acb6212bca1670f151f85f292e1242f7547303ff9fd67464ea9d8eb802bfcaa172de753533ad3439137331bae78700f91233031bd364215efa32addd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3a8032f556f6636ce9c5d6b9bddd8f7aa54a0d954c353f1eba9e82988b3781e7c4bd27c26ff36178c93b221a77c5e706c8f0330c28feed6d255be020cc061e4"} build_url:https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011036 build_url: 'https://queue.taskcluster.net/v1/task/fhHzzYibQrikLUFBWcj0Hw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40cc557773a794a23e0a2d2bb8213cb9b18b3e03074404183aeff50415bfb7632d726202e1087f10c6d6c6b7799061abab560a6f55a682f2dbfb79bdafbb2615", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f784f2c7acb6212bca1670f151f85f292e1242f7547303ff9fd67464ea9d8eb802bfcaa172de753533ad3439137331bae78700f91233031bd364215efa32addd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3a8032f556f6636ce9c5d6b9bddd8f7aa54a0d954c353f1eba9e82988b3781e7c4bd27c26ff36178c93b221a77c5e706c8f0330c28feed6d255be020cc061e4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 16:22:50.150773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 16:22:50.151250) ========= 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.zpMbEwuZke/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.mSIkecpAvG/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.005266 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 16:22:50.201883) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 16:22:50.202270) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 16:22:50.202630) ========= ========= Total master_lag: 0.19 =========