builder: jamun_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0136 starttime: 1494631849.45 results: success (0) buildid: 20170512084822 builduid: 52a4a95607a64253b35f28a2b7e14dc6 revision: 4c2d260af74e6dbd0b0f9e406083868d335d914e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.454428) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.455112) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.455534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.484809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.485525) ========= 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.8U2MBuGlpr/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.puKQnXkOzG/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 --2017-05-12 16:30:49-- 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% 15.3M=0.001s 2017-05-12 16:30:49 (15.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125133 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.636136) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.636505) ========= 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.8U2MBuGlpr/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.puKQnXkOzG/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.044613 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.702219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:49.702606) ========= bash -c 'python archiver_client.py mozharness --repo projects/jamun --rev 4c2d260af74e6dbd0b0f9e406083868d335d914e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/jamun --rev 4c2d260af74e6dbd0b0f9e406083868d335d914e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8U2MBuGlpr/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.puKQnXkOzG/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 2017-05-12 16:30:49,805 truncating revision to first 12 chars 2017-05-12 16:30:49,805 Setting DEBUG logging. 2017-05-12 16:30:49,805 attempt 1/10 2017-05-12 16:30:49,805 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/jamun/4c2d260af74e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 16:30:50,378 unpacking tar archive at: jamun-4c2d260af74e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.841712 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:50.587770) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:50.588116) ========= script_repo_revision: 4c2d260af74e6dbd0b0f9e406083868d335d914e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:50.588520) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:50.589033) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 16:30:50.608474) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 25 secs) (at 2017-05-12 16:30:50.608858) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Jamun --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Jamun 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', 'Jamun', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Jamun'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8U2MBuGlpr/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.puKQnXkOzG/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:30:50 INFO - MultiFileLogger online at 20170512 16:30:50 in /builds/slave/test 16:30:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Jamun --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Jamun 16:30:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:30:50 INFO - {'append_to_log': False, 16:30:50 INFO - 'base_work_dir': '/builds/slave/test', 16:30:50 INFO - 'blob_upload_branch': 'Jamun', 16:30:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:30:50 INFO - 'branch': 'Jamun', 16:30:50 INFO - 'buildbot_json_path': 'buildprops.json', 16:30:50 INFO - 'config_files': ('talos/mac_config.py',), 16:30:50 INFO - 'default_actions': ('clobber', 16:30:50 INFO - 'read-buildbot-config', 16:30:50 INFO - 'download-and-extract', 16:30:50 INFO - 'populate-webroot', 16:30:50 INFO - 'create-virtualenv', 16:30:50 INFO - 'install', 16:30:50 INFO - 'run-tests'), 16:30:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:30:50 INFO - 'download_minidump_stackwalk': True, 16:30:50 INFO - 'download_symbols': 'ondemand', 16:30:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:30:50 INFO - 'tooltool.py': '/tools/tooltool.py', 16:30:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:30:50 INFO - '/tools/misc-python/virtualenv.py')}, 16:30:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:30:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:30:50 INFO - 'gecko_profile': False, 16:30:50 INFO - 'gecko_profile_interval': 0, 16:30:50 INFO - 'installer_path': 'installer.exe', 16:30:50 INFO - 'log_level': 'info', 16:30:50 INFO - 'log_name': 'talos', 16:30:50 INFO - 'log_to_console': True, 16:30:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:30:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:30:50 INFO - 'opt_config_files': (), 16:30:50 INFO - 'pip_index': False, 16:30:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:30:50 INFO - 'cmd': ('bash', 16:30:50 INFO - '-c', 16:30:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:30:50 INFO - 'enabled': True, 16:30:50 INFO - 'halt_on_failure': False, 16:30:50 INFO - 'name': 'check_screen_resolution'},), 16:30:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:30:50 INFO - 'cmd': ('bash', 16:30:50 INFO - '-c', 16:30:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:30:50 INFO - 'enabled': True, 16:30:50 INFO - 'halt_on_failure': False, 16:30:50 INFO - 'name': 'check_screen_resolution'},), 16:30:50 INFO - 'run_cmd_checks_enabled': True, 16:30:50 INFO - 'suite': 'dromaeojs-e10s', 16:30:50 INFO - 'system_bits': '32', 16:30:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:30:50 INFO - 'title': 't-yosemite-r7-0136', 16:30:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:30:50 INFO - 'use_talos_json': True, 16:30:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:30:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:30:50 INFO - 'work_dir': 'build'} 16:30:50 INFO - [mozharness: 2017-05-12 23:30:50.824918Z] Running clobber step. 16:30:50 INFO - Running pre-action listener: _resource_record_pre_action 16:30:50 INFO - Running main action method: clobber 16:30:50 INFO - rmtree: /builds/slave/test/build 16:30:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:30:53 INFO - Running post-action listener: _resource_record_post_action 16:30:53 INFO - [mozharness: 2017-05-12 23:30:53.463064Z] Finished clobber step (success) 16:30:53 INFO - [mozharness: 2017-05-12 23:30:53.463229Z] Running read-buildbot-config step. 16:30:53 INFO - Running pre-action listener: _resource_record_pre_action 16:30:53 INFO - Running main action method: read_buildbot_config 16:30:53 INFO - Using buildbot properties: 16:30:53 INFO - { 16:30:53 INFO - "project": "", 16:30:53 INFO - "product": "firefox", 16:30:53 INFO - "script_repo_revision": "production", 16:30:53 INFO - "builddir": "jamun_yosemite_r7_devedition_test-dromaeojs-e10s", 16:30:53 INFO - "repository": "", 16:30:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition jamun talos dromaeojs-e10s", 16:30:53 INFO - "buildid": "20170512084822", 16:30:53 INFO - "pgo_build": "False", 16:30:53 INFO - "basedir": "/builds/slave/test", 16:30:53 INFO - "buildnumber": 0, 16:30:53 INFO - "slavename": "t-yosemite-r7-0136", 16:30:53 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:30:53 INFO - "platform": "yosemite_r7_devedition", 16:30:53 INFO - "scheduler": "tests-jamun-macosx64-devedition-talos", 16:30:53 INFO - "branch": "jamun", 16:30:53 INFO - "repo_path": "projects/jamun", 16:30:53 INFO - "revision": "4c2d260af74e6dbd0b0f9e406083868d335d914e", 16:30:53 INFO - "stage_platform": "macosx64-devedition", 16:30:53 INFO - "builduid": "52a4a95607a64253b35f28a2b7e14dc6", 16:30:53 INFO - "slavebuilddir": "test" 16:30:53 INFO - } 16:30:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 16:30:53 INFO - Running post-action listener: _resource_record_post_action 16:30:53 INFO - [mozharness: 2017-05-12 23:30:53.466680Z] Finished read-buildbot-config step (success) 16:30:53 INFO - [mozharness: 2017-05-12 23:30:53.466787Z] Running download-and-extract step. 16:30:53 INFO - Running pre-action listener: _resource_record_pre_action 16:30:53 INFO - Running main action method: download_and_extract 16:30:53 INFO - mkdir: /builds/slave/test/build/tests 16:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:53 INFO - trying https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 16:30:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 16:30:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 16:30:54 INFO - Downloaded 1287 bytes. 16:30:54 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 16:30:54 INFO - Using the following test package requirements: 16:30:54 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 16:30:54 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 16:30:54 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 16:30:54 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 16:30:54 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 16:30:54 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 16:30:54 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 16:30:54 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:30:54 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 16:30:54 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 16:30:54 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 16:30:54 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 16:30:54 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 16:30:54 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 16:30:54 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 16:30:54 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 16:30:54 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 16:30:54 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 16:30:54 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 16:30:54 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 16:30:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 16:30:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 16:30:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 16:30:56 INFO - Expected file size: 51794385 16:30:56 INFO - Obtained file size: 51794385 16:31:12 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:31:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 16:31:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 16:31:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 16:31:13 INFO - Expected file size: 51794385 16:31:13 INFO - Obtained file size: 51794385 16:31:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 16:31:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 16:31:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 16:31:29 INFO - Expected file size: 14404449 16:31:29 INFO - Obtained file size: 14404449 16:31: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:31:30 INFO - trying https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 16:31:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 16:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 16:31:32 INFO - Downloaded 74772991 bytes. 16:31:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 16:31:32 INFO - mkdir: /builds/slave/test/properties 16:31:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:31:32 INFO - Writing to file /builds/slave/test/properties/build_url 16:31:32 INFO - Contents: 16:31:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 16:31:33 INFO - Running post-action listener: _resource_record_post_action 16:31:33 INFO - Running post-action listener: set_extra_try_arguments 16:31:33 INFO - [mozharness: 2017-05-12 23:31:33.171876Z] Finished download-and-extract step (success) 16:31:33 INFO - [mozharness: 2017-05-12 23:31:33.172013Z] Running populate-webroot step. 16:31:33 INFO - Running pre-action listener: _resource_record_pre_action 16:31:33 INFO - Running main action method: populate_webroot 16:31:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:31:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:31:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:31:33 INFO - '--tppagecycles', 16:31:33 INFO - '7'], 16:31:33 INFO - 'tests': ['tsvgm']}}, 16:31:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:31:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:31:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:31:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:31:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:31:33 INFO - 'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:31:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:31:33 INFO - 'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['damp', 'tps']}, 16:31:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:31:33 INFO - 'tests': ['damp', 'tps']}, 16:31:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['dromaeo_dom']}, 16:31:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:31:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['basic_compositor_video']}, 16:31:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:31:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['a11yr', 16:31:33 INFO - 'ts_paint', 16:31:33 INFO - 'tpaint', 16:31:33 INFO - 'sessionrestore', 16:31:33 INFO - 'sessionrestore_no_auto_restore', 16:31:33 INFO - 'tabpaint']}, 16:31:33 INFO - 'other-e10s': {'tests': ['a11yr', 16:31:33 INFO - 'ts_paint', 16:31:33 INFO - 'tpaint', 16:31:33 INFO - 'sessionrestore', 16:31:33 INFO - 'sessionrestore_no_auto_restore', 16:31:33 INFO - 'tabpaint']}, 16:31:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:31:33 INFO - 'ts_paint', 16:31:33 INFO - 'tpaint', 16:31:33 INFO - 'sessionrestore', 16:31:33 INFO - 'sessionrestore_no_auto_restore', 16:31:33 INFO - 'tabpaint']}, 16:31:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:31:33 INFO - 'ts_paint', 16:31:33 INFO - 'tpaint', 16:31:33 INFO - 'sessionrestore', 16:31:33 INFO - 'sessionrestore_no_auto_restore', 16:31:33 INFO - 'tabpaint']}, 16:31:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['a11yr', 16:31:33 INFO - 'ts_paint', 16:31:33 INFO - 'tpaint', 16:31:33 INFO - 'sessionrestore', 16:31:33 INFO - 'sessionrestore_no_auto_restore', 16:31:33 INFO - 'tabpaint']}, 16:31:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['a11yr', 16:31:33 INFO - 'ts_paint', 16:31:33 INFO - 'tpaint', 16:31:33 INFO - 'sessionrestore', 16:31:33 INFO - 'sessionrestore_no_auto_restore', 16:31:33 INFO - 'tabpaint']}, 16:31:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['tsvgx', 16:31:33 INFO - 'tsvgr_opacity', 16:31:33 INFO - 'tart', 16:31:33 INFO - 'tscrollx', 16:31:33 INFO - 'cart', 16:31:33 INFO - 'tsvg_static']}, 16:31:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:31:33 INFO - 'tsvgr_opacity', 16:31:33 INFO - 'tart', 16:31:33 INFO - 'tscrollx', 16:31:33 INFO - 'cart', 16:31:33 INFO - 'tsvg_static']}, 16:31:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:31:33 INFO - 'talos_options': ['--disable-e10s'], 16:31:33 INFO - 'tests': ['tp5o']}, 16:31:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:31:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:31:33 INFO - 'talos_options': ['--disable-e10s', 16:31:33 INFO - '--xperf_path', 16:31:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:33 INFO - 'tests': ['tp5n']}, 16:31:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:31:33 INFO - 'talos_options': ['--xperf_path', 16:31:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:33 INFO - 'tests': ['tp5n']}}, 16:31:33 INFO - 'talos.zip': {'path': '', 16:31:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:31:33 INFO - Running post-action listener: _resource_record_post_action 16:31:33 INFO - [mozharness: 2017-05-12 23:31:33.190557Z] Finished populate-webroot step (success) 16:31:33 INFO - [mozharness: 2017-05-12 23:31:33.190699Z] Running create-virtualenv step. 16:31:33 INFO - Running pre-action listener: _resource_record_pre_action 16:31:33 INFO - Running main action method: create_virtualenv 16:31:33 INFO - Creating virtualenv /builds/slave/test/build/venv 16:31:33 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:31:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:31:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:31:33 INFO - Using real prefix '/tools/python27' 16:31:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:31:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:31:37 INFO - Installing pip.................done. 16:31:37 INFO - Return code: 0 16:31:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:31:37 INFO - Reading from file tmpfile_stdout 16:31:37 INFO - Output received: 16:31:37 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:31:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:31:37 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:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:37 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:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:37 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 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:31:37 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:31:37 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:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:37 INFO - 'HOME': '/Users/cltbld', 16:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:37 INFO - 'LOGNAME': 'cltbld', 16:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:37 INFO - 'MOZ_NO_REMOTE': '1', 16:31:37 INFO - 'NO_EM_RESTART': '1', 16:31:37 INFO - 'PAGER': '/bin/cat', 16:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:37 INFO - 'PWD': '/builds/slave/test', 16:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:37 INFO - 'SHELL': '/bin/bash', 16:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:31:37 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:37 INFO - 'USER': 'cltbld', 16:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:37 INFO - 'XPC_FLAGS': '0x0', 16:31:37 INFO - 'XPC_SERVICE_NAME': '0', 16:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:37 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:31:37 INFO - Cleaning up... 16:31:37 INFO - Return code: 0 16:31:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:31:37 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:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:37 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:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:37 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 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:31:37 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:31:37 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:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:37 INFO - 'HOME': '/Users/cltbld', 16:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:37 INFO - 'LOGNAME': 'cltbld', 16:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:37 INFO - 'MOZ_NO_REMOTE': '1', 16:31:37 INFO - 'NO_EM_RESTART': '1', 16:31:37 INFO - 'PAGER': '/bin/cat', 16:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:37 INFO - 'PWD': '/builds/slave/test', 16:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:37 INFO - 'SHELL': '/bin/bash', 16:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:31:37 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:37 INFO - 'USER': 'cltbld', 16:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:37 INFO - 'XPC_FLAGS': '0x0', 16:31:37 INFO - 'XPC_SERVICE_NAME': '0', 16:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:37 INFO - Downloading/unpacking psutil>=3.1.1 16:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:34:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:10 INFO - Installing collected packages: psutil 16:34:10 INFO - Running setup.py install for psutil 16:34:10 INFO - building 'psutil._psutil_osx' extension 16:34:10 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:34:11 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:34:11 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:34:11 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:34:11 INFO - building 'psutil._psutil_posix' extension 16:34:11 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:34:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:34:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:34:11 INFO - ^ 16:34:11 INFO - 1 warning generated. 16:34:11 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:34:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:11 INFO - Successfully installed psutil 16:34:11 INFO - Cleaning up... 16:34:11 INFO - Return code: 0 16:34:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:34: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:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34: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:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:12 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 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:34:12 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:34:12 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:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:12 INFO - 'HOME': '/Users/cltbld', 16:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:12 INFO - 'LOGNAME': 'cltbld', 16:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:12 INFO - 'MOZ_NO_REMOTE': '1', 16:34:12 INFO - 'NO_EM_RESTART': '1', 16:34:12 INFO - 'PAGER': '/bin/cat', 16:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:12 INFO - 'PWD': '/builds/slave/test', 16:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:12 INFO - 'SHELL': '/bin/bash', 16:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:34:12 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:12 INFO - 'USER': 'cltbld', 16:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:12 INFO - 'XPC_FLAGS': '0x0', 16:34:12 INFO - 'XPC_SERVICE_NAME': '0', 16:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:34: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:34: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:34: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:34: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:34: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:34: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:36:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:36:45 INFO - Installing collected packages: mozsystemmonitor 16:36:45 INFO - Running setup.py install for mozsystemmonitor 16:36:45 INFO - Successfully installed mozsystemmonitor 16:36:45 INFO - Cleaning up... 16:36:45 INFO - Return code: 0 16:36:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:36:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:36:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:36:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:36:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:36:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:36:46 INFO - 'HOME': '/Users/cltbld', 16:36:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:36:46 INFO - 'LOGNAME': 'cltbld', 16:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:46 INFO - 'MOZ_NO_REMOTE': '1', 16:36:46 INFO - 'NO_EM_RESTART': '1', 16:36:46 INFO - 'PAGER': '/bin/cat', 16:36:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:36:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:46 INFO - 'PWD': '/builds/slave/test', 16:36:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:36:46 INFO - 'SHELL': '/bin/bash', 16:36:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:36:46 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:36:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:36:46 INFO - 'USER': 'cltbld', 16:36:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:36:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:46 INFO - 'XPC_FLAGS': '0x0', 16:36:46 INFO - 'XPC_SERVICE_NAME': '0', 16:36:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:36:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:46 INFO - Downloading/unpacking jsonschema==2.5.1 16:36:46 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:36:46 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:36:46 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:36:46 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:36:46 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:36:46 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:39:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:39:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:39: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:39: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:39: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:39: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:39: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:39: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:41:49 INFO - Downloading functools32-3.2.3-2.tar.gz 16:41:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:41:49 INFO - warning: no files found matching '*.txt' 16:41:49 INFO - no previously-included directories found matching 'build' 16:41:49 INFO - no previously-included directories found matching 'dist' 16:41:49 INFO - no previously-included directories found matching '.git*' 16:41:49 INFO - Installing collected packages: jsonschema, functools32 16:41:49 INFO - Running setup.py install for functools32 16:41:49 INFO - warning: no files found matching '*.txt' 16:41:49 INFO - no previously-included directories found matching 'build' 16:41:49 INFO - no previously-included directories found matching 'dist' 16:41:49 INFO - no previously-included directories found matching '.git*' 16:41:49 INFO - Successfully installed jsonschema functools32 16:41:49 INFO - Cleaning up... 16:41:49 INFO - Return code: 0 16:41:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:41:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:51 INFO - 'HOME': '/Users/cltbld', 16:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:51 INFO - 'LOGNAME': 'cltbld', 16:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:51 INFO - 'MOZ_NO_REMOTE': '1', 16:41:51 INFO - 'NO_EM_RESTART': '1', 16:41:51 INFO - 'PAGER': '/bin/cat', 16:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:51 INFO - 'PWD': '/builds/slave/test', 16:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:51 INFO - 'SHELL': '/bin/bash', 16:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:41:51 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:51 INFO - 'USER': 'cltbld', 16:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:51 INFO - 'XPC_FLAGS': '0x0', 16:41:51 INFO - 'XPC_SERVICE_NAME': '0', 16:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:41:51 INFO - Cleaning up... 16:41:51 INFO - Return code: 0 16:41:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:41:51 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:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:51 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:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:51 INFO - 'HOME': '/Users/cltbld', 16:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:51 INFO - 'LOGNAME': 'cltbld', 16:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:51 INFO - 'MOZ_NO_REMOTE': '1', 16:41:51 INFO - 'NO_EM_RESTART': '1', 16:41:51 INFO - 'PAGER': '/bin/cat', 16:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:51 INFO - 'PWD': '/builds/slave/test', 16:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:51 INFO - 'SHELL': '/bin/bash', 16:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:41:51 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:51 INFO - 'USER': 'cltbld', 16:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:51 INFO - 'XPC_FLAGS': '0x0', 16:41:51 INFO - 'XPC_SERVICE_NAME': '0', 16:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:52 INFO - Downloading/unpacking blobuploader==1.2.4 16:41:52 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:41:52 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:41:52 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:41:52 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:41:52 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:41:52 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:44:24 INFO - Downloading blobuploader-1.2.4.tar.gz 16:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:44:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:44:24 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:44:24 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:44:24 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:44:24 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:44:24 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:44:24 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:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:46:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:25 INFO - Downloading docopt-0.6.1.tar.gz 16:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:49:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:49:25 INFO - Running setup.py install for blobuploader 16:49:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:49:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:49:25 INFO - Running setup.py install for requests 16:49:26 INFO - Running setup.py install for docopt 16:49:26 INFO - Successfully installed blobuploader requests docopt 16:49:26 INFO - Cleaning up... 16:49:26 INFO - Return code: 0 16:49:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:49:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:27 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 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:49:27 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:49:27 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:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:27 INFO - 'HOME': '/Users/cltbld', 16:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:27 INFO - 'LOGNAME': 'cltbld', 16:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:27 INFO - 'MOZ_NO_REMOTE': '1', 16:49:27 INFO - 'NO_EM_RESTART': '1', 16:49:27 INFO - 'PAGER': '/bin/cat', 16:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:27 INFO - 'PWD': '/builds/slave/test', 16:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:27 INFO - 'SHELL': '/bin/bash', 16:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:49:27 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:27 INFO - 'USER': 'cltbld', 16:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:27 INFO - 'XPC_FLAGS': '0x0', 16:49:27 INFO - 'XPC_SERVICE_NAME': '0', 16:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:49:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-CoUwyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:49:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-kAOHrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:49:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-f3mayq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:49:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-brgPfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:49:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-JT3UyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:49:27 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-46vh83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Xx5ST4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Oxr8Zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-jUfOJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-UvfNoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-biUmRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-9mr3yZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Su8YtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-w0E98g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-xKuDpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:49:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ggAHRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:49:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-d7_BsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:49:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:49:29 INFO - Running setup.py install for manifestparser 16:49:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:49:29 INFO - Running setup.py install for mozcrash 16:49:29 INFO - Running setup.py install for mozdebug 16:49:29 INFO - Running setup.py install for mozdevice 16:49:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:49:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:49:29 INFO - Running setup.py install for mozfile 16:49:29 INFO - Running setup.py install for mozhttpd 16:49:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:49:30 INFO - Running setup.py install for mozinfo 16:49:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:49:30 INFO - Running setup.py install for mozInstall 16:49:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:49:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:49:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:49:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:49:30 INFO - Running setup.py install for mozleak 16:49:30 INFO - Running setup.py install for mozlog 16:49:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:49:30 INFO - Running setup.py install for moznetwork 16:49:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:49:30 INFO - Running setup.py install for mozprocess 16:49:30 INFO - Running setup.py install for mozprofile 16:49:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:49:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:49:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:49:31 INFO - Running setup.py install for mozrunner 16:49:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:49:31 INFO - Running setup.py install for mozscreenshot 16:49:31 INFO - Running setup.py install for moztest 16:49:31 INFO - Running setup.py install for mozversion 16:49:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:49:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:49:31 INFO - Cleaning up... 16:49:31 INFO - Return code: 0 16:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:31 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 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:49:31 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:49:31 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:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:31 INFO - 'HOME': '/Users/cltbld', 16:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:31 INFO - 'LOGNAME': 'cltbld', 16:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:31 INFO - 'MOZ_NO_REMOTE': '1', 16:49:31 INFO - 'NO_EM_RESTART': '1', 16:49:31 INFO - 'PAGER': '/bin/cat', 16:49:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:31 INFO - 'PWD': '/builds/slave/test', 16:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:31 INFO - 'SHELL': '/bin/bash', 16:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:49:31 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:31 INFO - 'USER': 'cltbld', 16:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:31 INFO - 'XPC_FLAGS': '0x0', 16:49:31 INFO - 'XPC_SERVICE_NAME': '0', 16:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-nJRZFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:49:32 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:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ul8Ry3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Wkl0J3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:49:32 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:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-alAkCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-VLp9Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:49:32 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:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-v61qnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:49:32 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:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-fowRFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:49:32 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:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-7ybmeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:49:32 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:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-AianuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:49:32 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:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:49:32 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-VLnxvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:49:33 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:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:49:33 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-FDRVAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:49:33 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:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:49:33 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-88HN40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:49:33 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-bguoZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:49:33 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:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:49:33 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-wymsqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:33 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Rgrg24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:33 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:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:49:33 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-I9AGqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:49:33 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-QNCgS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:49:33 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:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:49:33 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:49:33 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:49:33 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:49:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:49:33 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:49:33 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:49:33 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:49:33 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:49:33 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:49:33 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:52:06 INFO - Downloading blessings-1.6.tar.gz 16:52:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:52:06 INFO - Installing collected packages: blessings 16:52:06 INFO - Running setup.py install for blessings 16:52:06 INFO - Successfully installed blessings 16:52:06 INFO - Cleaning up... 16:52:06 INFO - Return code: 0 16:52:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:52:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:52:06 INFO - Reading from file tmpfile_stdout 16:52:06 INFO - Current package versions: 16:52:06 INFO - blessings == 1.6 16:52:06 INFO - blobuploader == 1.2.4 16:52:06 INFO - docopt == 0.6.1 16:52:06 INFO - functools32 == 3.2.3-2 16:52:06 INFO - jsonschema == 2.5.1 16:52:06 INFO - manifestparser == 1.1 16:52:06 INFO - mozInstall == 1.12 16:52:06 INFO - mozcrash == 1.0 16:52:06 INFO - mozdebug == 0.1 16:52:06 INFO - mozdevice == 0.49 16:52:06 INFO - mozfile == 1.2 16:52:06 INFO - mozhttpd == 0.7 16:52:06 INFO - mozinfo == 0.9 16:52:06 INFO - mozleak == 0.1 16:52:06 INFO - mozlog == 3.4 16:52:06 INFO - moznetwork == 0.27 16:52:06 INFO - mozprocess == 0.25 16:52:06 INFO - mozprofile == 0.28 16:52:06 INFO - mozrunner == 6.13 16:52:06 INFO - mozscreenshot == 0.1 16:52:06 INFO - mozsystemmonitor == 0.3 16:52:06 INFO - moztest == 0.8 16:52:06 INFO - mozversion == 1.4 16:52:06 INFO - psutil == 3.1.1 16:52:06 INFO - requests == 1.2.3 16:52:06 INFO - wsgiref == 0.1.2 16:52:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:52: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:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52: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:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:08 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 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:52:08 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:52:08 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:52:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:52:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:08 INFO - 'HOME': '/Users/cltbld', 16:52:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:08 INFO - 'LOGNAME': 'cltbld', 16:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:08 INFO - 'MOZ_NO_REMOTE': '1', 16:52:08 INFO - 'NO_EM_RESTART': '1', 16:52:08 INFO - 'PAGER': '/bin/cat', 16:52:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:08 INFO - 'PWD': '/builds/slave/test', 16:52:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:08 INFO - 'SHELL': '/bin/bash', 16:52:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:52:08 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:52:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:08 INFO - 'USER': 'cltbld', 16:52:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:08 INFO - 'XPC_FLAGS': '0x0', 16:52:08 INFO - 'XPC_SERVICE_NAME': '0', 16:52:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:08 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:52:08 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:52:08 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:52:08 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:52:08 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:52:08 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:52:08 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:52:08 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:52:08 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:52:08 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:52:08 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:52:08 INFO - Cleaning up... 16:52:08 INFO - Return code: 0 16:52:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:52: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:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52: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:52:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5195dc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, '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.8U2MBuGlpr/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.puKQnXkOzG/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:52: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', 'jsonschema'] in /builds/slave/test/build 16:52: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 jsonschema 16:52:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:52:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:08 INFO - 'HOME': '/Users/cltbld', 16:52:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:08 INFO - 'LOGNAME': 'cltbld', 16:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:08 INFO - 'MOZ_NO_REMOTE': '1', 16:52:08 INFO - 'NO_EM_RESTART': '1', 16:52:08 INFO - 'PAGER': '/bin/cat', 16:52:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:08 INFO - 'PWD': '/builds/slave/test', 16:52:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:08 INFO - 'SHELL': '/bin/bash', 16:52:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:52:08 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:52:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:08 INFO - 'USER': 'cltbld', 16:52:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:08 INFO - 'XPC_FLAGS': '0x0', 16:52:08 INFO - 'XPC_SERVICE_NAME': '0', 16:52:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:52:08 INFO - Cleaning up... 16:52:08 INFO - Return code: 0 16:52:08 INFO - Running post-action listener: _resource_record_post_action 16:52:08 INFO - Running post-action listener: _start_resource_monitoring 16:52:08 INFO - Starting resource monitoring. 16:52:08 INFO - [mozharness: 2017-05-12 23:52:08.886579Z] Finished create-virtualenv step (success) 16:52:08 INFO - [mozharness: 2017-05-12 23:52:08.887527Z] Running install step. 16:52:08 INFO - Running pre-action listener: _resource_record_pre_action 16:52:08 INFO - Running main action method: install 16:52:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:52:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:52:09 INFO - Reading from file tmpfile_stdout 16:52:09 INFO - Detecting whether we're running mozinstall >=1.0... 16:52:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:52:09 INFO - Reading from file tmpfile_stdout 16:52:09 INFO - Output received: 16:52:09 INFO - Usage: mozinstall [options] installer 16:52:09 INFO - Options: 16:52:09 INFO - -h, --help show this help message and exit 16:52:09 INFO - -d DEST, --destination=DEST 16:52:09 INFO - Directory to install application into. [default: 16:52:09 INFO - "/builds/slave/test"] 16:52:09 INFO - --app=APP Application being installed. [default: firefox] 16:52:09 INFO - mkdir: /builds/slave/test/build/application 16:52:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:52:29 INFO - Reading from file tmpfile_stdout 16:52:29 INFO - Output received: 16:52:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:52:29 INFO - Running post-action listener: _resource_record_post_action 16:52:29 INFO - [mozharness: 2017-05-12 23:52:29.396287Z] Finished install step (success) 16:52:29 INFO - [mozharness: 2017-05-12 23:52:29.396422Z] Running run-tests step. 16:52:29 INFO - Running pre-action listener: _resource_record_pre_action 16:52:29 INFO - Running main action method: run_tests 16:52:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:52:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:52:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:52:29 INFO - 2017-05-12 16:52:29.410 screenresolution[1632:8504] starting screenresolution argv=screenresolution get 16:52:29 INFO - 2017-05-12 16:52:29.413 screenresolution[1632:8504] Display 0: 1600x1200x32@60 16:52:29 INFO - 2017-05-12 16:52:29.419 screenresolution[1633:8506] starting screenresolution argv=screenresolution list 16:52:29 INFO - Available Modes on Display 0 16:52:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:52:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:52:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:52:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:52:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:52:29 INFO - Intel Iris: 16:52:29 INFO - Chipset Model: Intel Iris 16:52:29 INFO - Type: GPU 16:52:29 INFO - Bus: Built-In 16:52:29 INFO - VRAM (Dynamic, Max): 1536 MB 16:52:29 INFO - Vendor: Intel (0x8086) 16:52:29 INFO - Device ID: 0x0a2e 16:52:29 INFO - Revision ID: 0x0009 16:52:29 INFO - Displays: 16:52:29 INFO - Display: 16:52:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:52:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:52:29 INFO - Display Serial Number: 1600x1200 60 16:52:29 INFO - Main Display: Yes 16:52:29 INFO - Mirror: Off 16:52:29 INFO - Online: Yes 16:52:29 INFO - Rotation: Supported 16:52:29 INFO - Return code: 0 16:52:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:52:29 INFO - Python 2.7.3 16:52:29 INFO - Return code: 0 16:52:29 INFO - grabbing minidump binary from tooltool 16:52:29 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:52:29 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 0x1025c2d30>, '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 0x1024e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025b7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:52:29 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:52:29 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:52:29 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:52:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:52:29 INFO - Return code: 0 16:52:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:52:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:52:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:52:29 INFO - ENV: RUST_BACKTRACE is now 1 16:52:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:52:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:52:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Jamun', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.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:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Jamun --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.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:52:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8U2MBuGlpr/Render', 16:52:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:29 INFO - 'HOME': '/Users/cltbld', 16:52:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:29 INFO - 'LOGNAME': 'cltbld', 16:52:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:52:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:29 INFO - 'MOZ_NO_REMOTE': '1', 16:52:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:52:29 INFO - 'NO_EM_RESTART': '1', 16:52:29 INFO - 'PAGER': '/bin/cat', 16:52:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:29 INFO - 'PWD': '/builds/slave/test', 16:52:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:52:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:29 INFO - 'RUST_BACKTRACE': '1', 16:52:29 INFO - 'SHELL': '/bin/bash', 16:52:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puKQnXkOzG/Listeners', 16:52:29 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 16:52:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:29 INFO - 'USER': 'cltbld', 16:52:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:29 INFO - 'XPC_FLAGS': '0x0', 16:52:29 INFO - 'XPC_SERVICE_NAME': '0', 16:52:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Jamun', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.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:52:30 INFO - mozversion application_buildid: 20170512084822 16:52:30 INFO - mozversion application_changeset: 4c2d260af74e6dbd0b0f9e406083868d335d914e 16:52:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:52:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:52:30 INFO - mozversion application_name: Firefox 16:52:30 INFO - mozversion application_remotingname: firefox-dev 16:52:30 INFO - mozversion application_repository: https://hg.mozilla.org/projects/jamun 16:52:30 INFO - mozversion application_vendor: Mozilla 16:52:30 INFO - mozversion application_version: 54.0 16:52:30 INFO - mozversion platform_buildid: 20170512084822 16:52:30 INFO - mozversion platform_changeset: 4c2d260af74e6dbd0b0f9e406083868d335d914e 16:52:30 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/jamun 16:52:30 INFO - mozversion platform_version: 54.0 16:52:30 INFO - using testdate: 1494633150 16:52:30 INFO - actual date: 1494633150 16:52:30 INFO - starting webserver on 'localhost:49248' 16:52:30 INFO - SUITE-START | Running 2 tests 16:52:30 INFO - TEST-START | dromaeo_css 16:52:30 INFO - operating with platform_type : mac_ 16:52:30 INFO - Initialising browser for dromaeo_css test... 16:52:30 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpXHMy1Z/profile http://localhost:49248/getInfo.html) 16:52:33 INFO - PROCESS | 1643 | 2017-05-12 16:52:33.015 plugin-container[1647:8695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f47, name = 'com.apple.tsm.portname' 16:52:33 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:33 INFO - PROCESS | 1643 | 2017-05-12 16:52:33.016 plugin-container[1647:8695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 16:52:33 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:33 INFO - PROCESS | 1643 | 2017-05-12 16:52:33.016 plugin-container[1647:8695] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:52:33 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 16:52:33 INFO - PROCESS | 1643 | colorDepth:24 16:52:33 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 16:52:33 INFO - PROCESS | 1643 | __metrics 16:52:33 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 16:52:33 INFO - TEST-INFO | 1643: exit 0 16:52:33 INFO - Browser initialized. 16:52:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:52:33 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpXHMy1Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:52:36 INFO - PROCESS | 1649 | 2017-05-12 16:52:36.208 plugin-container[1650:8823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874b, name = 'com.apple.tsm.portname' 16:52:36 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:36 INFO - PROCESS | 1649 | 2017-05-12 16:52:36.209 plugin-container[1650:8823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 16:52:36 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:36 INFO - PROCESS | 1649 | 2017-05-12 16:52:36.209 plugin-container[1650:8823] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:52:45 INFO - PROCESS | 1649 | RSS: Main: 202952704 16:52:45 INFO - PROCESS | 1649 | 16:55:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 16:55:51 INFO - PROCESS | 1649 | RSS: Main: 219332608 16:55:51 INFO - PROCESS | 1649 | 16:58:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 16:58:53 INFO - PROCESS | 1649 | RSS: Main: 230825984 16:58:53 INFO - PROCESS | 1649 | 17:01:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 17:01:56 INFO - PROCESS | 1649 | RSS: Main: 232198144 17:01:56 INFO - PROCESS | 1649 | 17:04:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 17:04:58 INFO - PROCESS | 1649 | RSS: Main: 235417600 17:04:58 INFO - PROCESS | 1649 | 17:08:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 17:08:02 INFO - PROCESS | 1649 | RSS: Main: 237510656 17:08:02 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 17:11:05 INFO - PROCESS | 1649 | RSS: Main: 235237376 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | __start_tp_report 17:11:05 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 17:11:05 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 17:11:05 INFO - PROCESS | 1649 | |i|pagename|runs| 17:11:05 INFO - PROCESS | 1649 | |0;dojo.html;3281.7866838655486;3440.2087519870447;3499.072745722384;3511.4557243627232;3519.595246546648;5263.920896216276;5897.616654917428;6102.786402475915;6154.261488621371;6164.81505554833;6161.630302181861;6201.348858369863;6217.471514921237;6224.128621992913;6228.096925945746;5516.91724624131;5527.281453411051;5605.1872478490595;5643.633163844337;5645.379008309083;5660.716964151797;5677.744501497422;5683.857903552403;5692;5703.6546965860625;4437.334399840018;5450.482204190595;5539.695316757577;5650.943490565103;5666.546676265889;5608.46719561641;5608.747606357723;5613.691246981414;5643.8589035274035;5663.178839068353;5213.921791173136;5474.42518535556;5563.388027316992;5586.608937374385;5597.63615365;5053.242013697938;5905.704714764288;6029.698515074273;6061;6064.63612183266;6372.7132279047555;6413.935860641381;6429.6142231466265;6540.33221528512;6567.802965911031;6649.567778094409;6671.633060181688;6675.499337549703;6689.030090636879;6694.698738556776;5999.130126131687;6308.148399965946;6309.684515774193;6337.429631333202;6338.809835704937;6512.804615861532;6532.869342613216;6571.507136964747;6655.401013908771;6682.599044057372;6434.613923164625;6445.065465507475;6447.2263328400895;6456.903146452807;6459.806205813833;5532.695701736363;6463.353664633499;6522.249941256816;6595.0766892635065;6608.107905432801;6265.310815810257;6318.115463835067;6361.268454127742;6507.414332710079;6523.412892839668;5229.816956406584;5384.596155288369;6355.618662880242;6542.542022058506;6543.312952140052;5187.0922588546855;5190.558802501754;5211.140161873249;5245.947540524546;5246.63273570854;4615.8846028848875;4618.976905115453;4660.39414876064;4693.859184224479;4712.434507859079;5985.880282394415;5986.102084687332;6035.336113027563;6035.7283922223605;6129.48104857405;6538.901916471256;6648.069270302161;6665.833354166088;6674.632895190811;6699;6213.937860621426;6217.626942383381;6219.564630475823;6242.531810114259;6255.468285195719;4574.748388838646;5602.775888964396;5779.248697669276;5796.536277097822;5830.47525722687;6702.095217145623;6743.7639682612835;6757.3242675732035;6781.932180678327;6857.691403886937;6515.511336649865;6613.00804879272;6639.867202656016;6662.133922590032;6665.766698165541;6481.8703625928165;6812.250652428328;6838.7264509419065;6840.48696347766;6843.3840954314355;6370.203724534434;6417.197850268716;6426.967865160831;6508.837279068061;6520.869582608416;6461.61230326182;6582.045603387386;6604.801855944329;6615.702293396713;6624.867502650017;6387.808365749035;6408.166938297992;6439.935600643934;6458.160439142881;6461.483081353575;6264.968675156595;6312.652804095819;6347.111404403479;6370.840728981812;6372.203474565679;6273.466755325666;6338.683065846597;6353.96823015882;6359.141515895295;6370.872582548416;6243.282022567463;6293.559450838397;6416.358364163546;6416.647084410402;6466.644334561644;6264.373562643699;6264.436200741955;6285.905711414424;6312.400321969406;6333.240011198685 17:11:05 INFO - PROCESS | 1649 | |1;ext.html;762.039829814434;783.2285199078906;841.6507149532944;980.6175591519304;986.9309148359617;39151.80424097879;40122;41789.582104178946;41831.37252941208;41902.790486047525;40387.990300242505;40504.18991620173;40539.79730101345;40640.78077657675;40695.77912662618;8227.424080314366;8362.16378362162;11098.44507774612;11415.486303116339;11583.42082895856;9443.38617989832;11310.208285420023;11371.31772093673;11371.545138194462;11397.943010284936;11375.772484550303;11389.202755807097;11402.714932126722;11411.657650270505;11411.714707132305;11294.548218071308;11337.716557086056;11359.204855660024;11375.544978200862;11377.54489820402;11069;11091.667249982513;11168.776624467546;11171.329720216732;11179.552817887356;34762.652373476194;34922.650773492445;34959.776407825855;34962.825185874164;35061.64938350609;3881.3013657541633;3904.297226499229;3911.4915061041884;3923.019245188703;3972.2254160438724;25560.23319300424;25636.35909102269;26358.868205658848;26686;27290.5906411404;2951.911442656724;3095.8142511449387;3138.482150445174;3338.2655815720505;3343.180920674426;321478;347888;348786;348953;349475.99999999744;45292.54707452883;45352.77323613361;45454.545454545034;45457.54542454599;45459;46287.76856115765;46456;47053;47060.7646961763;47089.529104709196;14342;14388;14445.566633000817;14455.98808083424;14478.203698796668;12313.44589493475;12501.74996500083;12523.874761252453;12532.81200781989;12545.996320294355;9904.356216845881;10029.247806414549;11856.170068095324;11921.105917056255;12138.968187704144;4864.6594738368685;5672.234248376499;5785.71071446426;5794.536437085023;5796.043652797325;1136.3863513702508;1167.5855071449682;1169.818678104895;1170.777552265067;1219.231883913129;21135.154593816384;27888.442231155263;28335.858320708263;28447.431051378855;28526.572101418496;1057.360297020307;1195.9654898512724;1270.9514650413446;1276.8786965238287;1300.947962081525;11266.830997535044;14039.649008774863;14473.493427730189;14696.632584185267;14787.334569944378;438746;439215;442129;442809;443337;48267.51732482701;50753.238701419505;56932;57208;57234.42765572374;1410.4922227997913;1442.5167568864338;1450.1299220467636;1451.368654635216;1461.7076584683107;27419.04033358823;27455.588166177924;27869.303267418476;28442.71557284401;28540.714592854638;42501;42535.57464425316;42669;42696.5730342705;42770.35844462398;496538;496871;497108;497323;500372;29565;29643.1838975297;29684.109476715734;29723.108306750833;29811.571669258312;37722.81138594399;38239;39091.413628795024;39198.02004949785;39475.40786888256;27685.44629107447;27717.861410692818;28109;28202.576961346007;29027.419451611273;27565.17304480869;27947.860260698566;27999.860000699868;28889.844406223514;29101.417971640025;31562.684373155975;31654.841725791222;32314.83842580772;32736;33609.83195084009;27635.585466218425;28690;28982.710172898845;29529.704702952695;30190.54714179333;23020;23661.526769464857;23694.644580330965;24193.637095443934;24198.51602967966 17:11:05 INFO - PROCESS | 1649 | |2;jquery.html;400.4633790720434;434.85215026890853;438.3009100484727;443.7138045960356;450.95490450954907;19487.902560487215;20096.59806803863;21341;21455.463613409673;21520.923953802325;21875.343739687836;22414.318926080527;23045.654315185286;23235.419114522105;23590.410239744015;6877.65611719412;6882.311768823102;7114.395276401512;7128.108986376703;7170.426365890742;6913.239543650195;7119.110111236096;7129.287071292881;7135.714571417163;7142.500024998252;6714.3621355971345;6906.930930690704;7028.19175794785;7069.964650176742;7084.752033678822;7004.684789184499;7032.015517827508;7063.505554611179;7074.858502829966;7108.78673639789;6524.510661700344;6771.255161932233;6868.7252509899545;6920;6922.58464492132;26794.598081028802;27561.586576201375;27626.033088841796;27760.86119569389;27928.022519211932;2831.847884784018;3029.9848500757357;3037.4684430224843;3043.4065357255345;3051.0999255219763;16138.515844524683;16169.838301617068;16171.91914040434;16343.34626614934;16382.344706211738;2022.1405902491383;2076.262926661029;2156.1698745982753;2209.171560664751;2223.3885681437573;156464;158404;159037.93134337285;161974;167830;17411.216495257744;19048.047597620065;19662.508437288896;22130;22351.888240558692;27164.864175678995;31919.52120718191;32380.028599142064;32404.837975810442;32425.675743242737;8496.320294376435;10715.24993250465;10883.401412922365;10903.672889813319;11028.393438360967;8130.634121464548;9018.59416326267;9038.367314288069;9047.095290471032;9263.490508022123;9088.182063614306;9332.603938730907;9888.110070093726;9931.950340248253;10090.243231757648;3796.563395209531;4292.978535107304;4321.2005778931;4432.933505997413;4450.265706158512;801.4950581133849;851.3189448441223;899.4423457456288;908.5684299957468;958.520739630185;18218.180181891847;21177.199938005135;21889.890550547465;22199.66700499494;22396;885.968991085309;950.3632566180676;960.5341409416427;975.9414435133773;992.9900700992897;10560.471976401303;11381.943090284496;11387.202895797214;11402.657920262405;11473.713157171138;674991;789750;817795;827909.4418111604;843050;170001;171569.28430715782;173139;174164;179050;1225.8651548329676;1317.2030921292671;1336.8663133686553;1338.4445455136204;1340.4638144742182;20413.38759837207;20776.896115519325;20882.582348352644;21244.150233990465;21386.679199811704;60201;66120;68186;68468.31531684619;69500.6524967372;88169.67745483952;88925;89615.10384896067;89868.10131898597;91037;11655.125865560287;11775;11937.4031298433;11944.044476442037;12248.87751122513;13095.607131786062;13145.868541314465;13345.866541334462;13481.056326057082;13518.05373623837;10518.316309439862;10676;10782.568697252022;10866.185036122164;10969.670909872717;10783.460826958777;10826.67519974402;10986.725331866768;11142.997130258316;11204.49579768896;9304.441733496013;11820.822687659864;11989.580364687194;12086.274823510617;12118.454669539715;11777.234479758788;11804.468798904241;11877.703057423634;12078;12102;10241.385516869012;11606.593769218036;11750.882491174978;12022.519099236282;12112.7281635427 17:11:05 INFO - PROCESS | 1649 | |3;mootools.html;1592.8884978051542;1593.4980481148436;1616.6362568422098;1628.5358672778261;1628.9104099274534;9666.903330966688;9893.703188904345;11141.387223702703;11836.289822610639;11861.940690296547;10876.728081797959;11160.330380177194;11163.051140653033;11179.441027948611;11219.270747401413;2410.7950824179966;2477.492114116611;2477.6407420923956;2479.1446950801947;2482.491089326684;2389.498205376873;2410.8312418130736;2418.1657328221736;2430.7083150021936;2435.659007738918;2433.5741245282093;2444.315591634336;2451.252368027751;2453.950920981579;2464.7799339327057;1973.398113575359;1981.3263490413256;2450.424150324672;2467.592847180213;2498.4253621667117;2420.9515809683708;2429.4290841652196;2440.2069327468466;2442.072012635205;2442.4016116051503;11501.022413094897;11685.006774424184;11694.70763230926;11695.766084678258;11724.531018759239;5935.198748168985;6016.338202797689;6124.724387402578;6135.601185922946;6142.907856382158;3279.7212236959886;3415.7096646785053;3603.4234522476277;3652.8904132876055;3670.522832031819;1157.7163594919214;1160.646002969094;1193.2780667696093;1208.3958020989505;1220.4263995921901;16201;16201.189940503045;16202.946808457413;16219.675606487923;16246.675066498721;4739.834105806281;4886.706797592155;5084.618653600995;5136.280920671109;5165.560927321145;13172.209667419986;13177.407016684272;13185.736285274432;13189.736205276033;13236.13965092266;6134.9079763803575;6136.294326152461;6142.815715528542;6145.846353841101;6163.3836616338;3952.6086218771084;5267.104592219332;5339.092354299778;5488.313960754906;5489.533389661923;4094.151567121529;4199.223143718422;4290.485141783006;4314.0077782109665;4337.891552711208;1151.0963893343685;1153.7231064544449;1157.8652920138143;1164.1967042740482;1172.783035138502;811.4117264982958;817.15832692327;843.8312337532518;846.4286606540561;856.5588721808274;6475.287718350977;6692.163479565054;6732.394084532318;6845.657717114124;6979.8604027919155;1041.9010194031555;1062.0229388962173;1064.914806815453;1081.886401927802;1103.3600511703194;4260.382244574519;4290.141971605629;4322.481302243751;4380.934285985712;4394.2969124939855;12031.157818952765;13620.59138225835;14011.579652610439;14233.928830355782;14238;13082.411291491906;14103.858961410255;14121.293935303194;14126.64683382903;14130.646733831736;10346.430946297874;10605.628802991858;10609.15126789855;10626.628068017737;10637.9468102659;6616.239132499824;6716.1604799400075;6767.289434609295;6775.1531058617675;7005.579665220103;12160.209586376857;12202;12480.75038499243;12487.188332758342;12626.242425454502;12484.063695223076;12487.375631218585;12773.23360598367;12813.807792882928;12846.100772945803;8224.917750822415;8786.38495305348;9285.62857485693;9457.19613832832;9544.809103817746;10538.683839484827;10618;10629.734256643645;10722.410267435054;10749.570017199225;8678.218960293603;8721;8787.956060219658;9136.49749263797;9163.450192988443;8470.110638383128;8602.096779838026;8616.569171541523;8646.82706345882;8859.24696400813;7813.179616140451;7861.960690196513;7935.246151615587;7936.722214722457;7960.164182760726;5914.319853216935;5923.881522369615;5934.376890426443;5947.821565353046;6062.333143354315;5924.229850119457;6088.908666370096;6112.388761123852;6174.351693072163;6209.96895015522 17:11:05 INFO - PROCESS | 1649 | |4;prototype.html;333.80472423632176;378.20010677417235;389.0138498905274;391.6749098248453;400.0558681511633;4726.456457507387;5207.479252074795;5276.577873770094;5293.6294459387855;5388.632104934416;4817.373741413611;5169.224616307556;5198.8700282492955;5238.476152384759;5258.947410525903;3728.6017743346247;4191.329387298033;4221.831126754926;4329.709420840169;4353.651707863379;3964.385520244367;4349.043210493686;4383.364412160235;4384.539623339551;4398.626116780061;4340.1771256072525;4343.370211319372;4368.453943257093;4395.857077159945;4431.49037860647;3955.2880481513316;4017.337139372;4288.549702281278;4366.497852746943;4377.737335759849;3809.9713077469205;3873.5351757788962;3958.6833053355663;3973.304671682444;4004.799760012017;5121.901249743889;5156.974215128937;5178.482151784829;5192.818251361185;5200;3779.3340459753995;4266.400099962514;4579.519150489184;5106.161781758601;5134.409542902539;4801.591864691505;4913.06651736169;5206.594219560698;5588.497035266845;5651.378348381716;3871.3031654302213;4408.515063343029;4460.710053846522;4523.751193684346;4533.6146427553695;5277.947220527784;5798.650477726882;5859.6561375450165;5936.910946335808;5958.523318134538;4023.879283621496;4587.568588647955;4597.517260687647;4605.907881842411;4618.884527886829;3978.840846366113;4073.5315438724924;4425.579569940851;4677.579017888406;4748.857534273978;4301.827926882889;4564.817407303671;4921.704697718148;4983.5763960063805;5088.160453525201;4732.674851041714;5187.962407518511;5378.542823859937;5643.5203007743985;5672.262605861211;1382.3710211853584;1436.3895344478556;1441.654003039263;1541.9591775551683;1574.527641707483;1011.5144730529387;1087.8694556653095;1185.7628474305172;1192.3382522699812;1216.6593353860933;4329.978350108292;4498.830304120952;5017.974910125426;5226.529612334908;5358.758855851496;5664.54683625309;5829.387914269026;5842.883142337215;5897.2038774765715;5907.261592300963;4753.47711751707;4844.8304309349705;5029.84910452687;5184.792608295701;5215.24378965048;2894.45005448964;3076.676948920331;3084.7969291976146;3087.81473111614;3102.5966624338694;5149;5718.827640333721;5794.478496935211;6091.299500557493;6104.908426373608;5285.814996475182;5323.083689871643;5788.0739081746715;6036.607620504741;6048.60684055535;5151.716655583829;5239;5697.601167918206;5759.913601295984;5779.595428319977;5170.844874653766;5509.531689806411;5840.386759390372;5884.293884733859;5942.554308426972;5042.84871453857;5120.206368012964;5138.97430512845;5409.810656626999;5523.8895222096135;5269.762860671202;5473.808416705396;5542.473465020817;5623.915641265303;6027;3853.7475320520384;3955.307821131348;4118.8055463915125;4144.564820693784;4281.801095693211;5472.6990015547935;5522.199281104137;5699.917015766991;5721.227634269487;5774.249347584955;5145.842185508231;5245.423003469539;5431.891362172813;5483.314585676791;5726.74229659658;4761.571458568746;5091.898162036665;5403.621746477709;5638.633488823213;5655.7454914528125;4973.126336190481;5096.725818545364;5269.235960785742;5363.436839131991;5464.426235245401;5158.587313015025;5233.581313494835;5306.442823503477;5326.937011218487;5732.4204108152335;4440.492011311028;4583.931241031454;4961.6030717543235;5518.558515318685;5520.447955204448 17:11:05 INFO - PROCESS | 1649 | |5;yui.html;750.0736590310962;759.4083356465874;780.4029917113405;837.2255663511253;857.9184977427142;15643.921780391096;16284.348626054943;16422.917885410585;16515.00909945404;16584.502464926056;15455.149966751853;15724.37102515898;15737.527874163765;15885.36458541657;15890.443834465796;960.8798900137483;994.4083270454068;1000.6597756762699;1001.3991605036992;1004.6182450668736;969.4280374578997;985.6106837799065;987.027777639025;1002.959881604735;1004.7990401919645;1042.734102803784;1044.582167133149;1046.3251202974077;1047.979040419195;1050.8686414198226;876.5967654878764;1037.0044757033256;1042.197507918902;1042.2547878267037;1043.4782608695652;763.904511936008;777.3703300326754;778.5834578500532;779.4582764978343;779.5050143159078;14815.518353186992;15219.239038048165;15252.847471525365;17181.969081855128;17646.91176544109;10959.780804383867;14325.068870523382;14340.64148396288;14387.640308992255;14390.496332628412;11661.533538658443;11667.6499705009;11674.533018679243;11749.118816088827;11964.40177991097;3629.5825980012373;3729.589745128034;3759.154190307186;3772.8868133956025;3885.8834234972996;113535.43232283786;115758.42120789427;117877;117932;118661;10826.296290741077;10996.615118470974;11090.500927458284;11147.888521114848;11253.831192532118;22585.270260213892;23077.307680769605;23170.420739481648;23514.52970940606;23547.058117675442;6961.408280296231;7259.201487836333;7531.3598344141365;7624.920810896491;7678.232176782277;4288.292431748789;4305.806238719265;4333.761643109659;4404.427424434803;4553.707784681397;3459.775114617592;3509.649035096521;3523.4714792781288;3555.591107022674;3560.5193298904833;567.5941701683316;567.9233303503975;568.4116938968148;568.7128000359792;568.778176511161;564.7418464775628;659.1696444944458;705.5625512182377;713.1727196452091;719.3921136639531;8020.759377218693;10599.152067834555;10605.681829545127;10697.251192416612;10748.838767418494;688.7658196213312;706.2443185791154;707.4199156691567;714.2179313651543;714.9606771627506;4727;5068.873278168076;5075.949240507622;5082.110630639697;5082.720450375191;98500.50749746205;100771.49614251882;101021.49489252506;101618;103058;57192.71403642955;58520.414795851495;78233.60883195662;82420.58789706013;84927;63874.68062659657;64961;67012.664936675;68323;69432.30567694259;10892.564297428014;11243.831342530033;11320.150988675718;11461.598844040418;11492.36791976416;47958;50043.74978125086;50190.498095018585;50769.746151269006;51563;61222;62955.6852215736;63218.683906580176;63829.6808515973;64428.35571644411;12581.937090314856;12677.17598356104;12929.482820687443;12955.157914735511;13034.543790967271;12115.394230288626;14802.454085813082;15032.398704051715;15105.32026058808;15120.319585618894;11789.587364442203;11917.642470725892;12185.086118540967;12225.510979560719;12504.374781261082;13874.791878122038;14124.646883827576;14189.929050354682;14365.20991345486;14661.48684796027;14077.507287244896;14290.64273393132;14311.57065288043;14334.996550241383;14695.92652036733;9916.256280778825;10066.848997265193;10389.48052597352;10397.740056498647;10478;9292.58183381763;10076.596936122472;10809.44400924171;11352.262102963612;11810.350430726392 17:11:05 INFO - PROCESS | 1649 | __end_tp_report 17:11:05 INFO - PROCESS | 1649 | __start_cc_report 17:11:05 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,381 17:11:05 INFO - PROCESS | 1649 | __end_cc_report 17:11:05 INFO - PROCESS | 1649 | __startTimestamp1494634265557__endTimestamp 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | ------- Summary: start ------- 17:11:05 INFO - PROCESS | 1649 | Number of tests: 6 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:6047.44 Median:6308.15 stddev:705.88 (11.2%) stddev-sans-first:673.88 17:11:05 INFO - PROCESS | 1649 | Values: 3281.8 3440.2 3499.1 3511.5 3519.6 5263.9 5897.6 6102.8 6154.3 6164.8 6161.6 6201.3 6217.5 6224.1 6228.1 5516.9 5527.3 5605.2 5643.6 5645.4 5660.7 5677.7 5683.9 5692.0 5703.7 4437.3 5450.5 5539.7 5650.9 5666.5 5608.5 5608.7 5613.7 5643.9 5663.2 5213.9 5474.4 5563.4 5586.6 5597.6 5053.2 5905.7 6029.7 6061.0 6064.6 6372.7 6413.9 6429.6 6540.3 6567.8 6649.6 6671.6 6675.5 6689.0 6694.7 5999.1 6308.1 6309.7 6337.4 6338.8 6512.8 6532.9 6571.5 6655.4 6682.6 6434.6 6445.1 6447.2 6456.9 6459.8 5532.7 6463.4 6522.2 6595.1 6608.1 6265.3 6318.1 6361.3 6507.4 6523.4 5229.8 5384.6 6355.6 6542.5 6543.3 5187.1 5190.6 5211.1 5245.9 5246.6 4615.9 4619.0 4660.4 4693.9 4712.4 5985.9 5986.1 6035.3 6035.7 6129.5 6538.9 6648.1 6665.8 6674.6 6699.0 6213.9 6217.6 6219.6 6242.5 6255.5 4574.7 5602.8 5779.2 5796.5 5830.5 6702.1 6743.8 6757.3 6781.9 6857.7 6515.5 6613.0 6639.9 6662.1 6665.8 6481.9 6812.3 6838.7 6840.5 6843.4 6370.2 6417.2 6427.0 6508.8 6520.9 6461.6 6582.0 6604.8 6615.7 6624.9 6387.8 6408.2 6439.9 6458.2 6461.5 6265.0 6312.7 6347.1 6370.8 6372.2 6273.5 6338.7 6354.0 6359.1 6370.9 6243.3 6293.6 6416.4 6416.6 6466.6 6264.4 6264.4 6285.9 6312.4 6333.2 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:55638.96 Median:29547.35 stddev:114876.05 (388.8%) stddev-sans-first:115124.42 17:11:05 INFO - PROCESS | 1649 | Values: 762.0 783.2 841.7 980.6 986.9 39151.8 40122.0 41789.6 41831.4 41902.8 40388.0 40504.2 40539.8 40640.8 40695.8 8227.4 8362.2 11098.4 11415.5 11583.4 9443.4 11310.2 11371.3 11371.5 11397.9 11375.8 11389.2 11402.7 11411.7 11411.7 11294.5 11337.7 11359.2 11375.5 11377.5 11069.0 11091.7 11168.8 11171.3 11179.6 34762.7 34922.7 34959.8 34962.8 35061.6 3881.3 3904.3 3911.5 3923.0 3972.2 25560.2 25636.4 26358.9 26686.0 27290.6 2951.9 3095.8 3138.5 3338.3 3343.2 321478.0 347888.0 348786.0 348953.0 349476.0 45292.5 45352.8 45454.5 45457.5 45459.0 46287.8 46456.0 47053.0 47060.8 47089.5 14342.0 14388.0 14445.6 14456.0 14478.2 12313.4 12501.7 12523.9 12532.8 12546.0 9904.4 10029.2 11856.2 11921.1 12139.0 4864.7 5672.2 5785.7 5794.5 5796.0 1136.4 1167.6 1169.8 1170.8 1219.2 21135.2 27888.4 28335.9 28447.4 28526.6 1057.4 1196.0 1271.0 1276.9 1300.9 11266.8 14039.6 14473.5 14696.6 14787.3 438746.0 439215.0 442129.0 442809.0 443337.0 48267.5 50753.2 56932.0 57208.0 57234.4 1410.5 1442.5 1450.1 1451.4 1461.7 27419.0 27455.6 27869.3 28442.7 28540.7 42501.0 42535.6 42669.0 42696.6 42770.4 496538.0 496871.0 497108.0 497323.0 500372.0 29565.0 29643.2 29684.1 29723.1 29811.6 37722.8 38239.0 39091.4 39198.0 39475.4 27685.4 27717.9 28109.0 28202.6 29027.4 27565.2 27947.9 27999.9 28889.8 29101.4 31562.7 31654.8 32314.8 32736.0 33609.8 27635.6 28690.0 28982.7 29529.7 30190.5 23020.0 23661.5 23694.6 24193.6 24198.5 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:45332.76 Median:22729.99 stddev:132574.82 (583.3%) stddev-sans-first:132903.80 17:11:05 INFO - PROCESS | 1649 | Values: 400.5 434.9 438.3 443.7 451.0 19487.9 20096.6 21341.0 21455.5 21520.9 21875.3 22414.3 23045.7 23235.4 23590.4 6877.7 6882.3 7114.4 7128.1 7170.4 6913.2 7119.1 7129.3 7135.7 7142.5 6714.4 6906.9 7028.2 7070.0 7084.8 7004.7 7032.0 7063.5 7074.9 7108.8 6524.5 6771.3 6868.7 6920.0 6922.6 26794.6 27561.6 27626.0 27760.9 27928.0 2831.8 3030.0 3037.5 3043.4 3051.1 16138.5 16169.8 16171.9 16343.3 16382.3 2022.1 2076.3 2156.2 2209.2 2223.4 156464.0 158404.0 159037.9 161974.0 167830.0 17411.2 19048.0 19662.5 22130.0 22351.9 27164.9 31919.5 32380.0 32404.8 32425.7 8496.3 10715.2 10883.4 10903.7 11028.4 8130.6 9018.6 9038.4 9047.1 9263.5 9088.2 9332.6 9888.1 9932.0 10090.2 3796.6 4293.0 4321.2 4432.9 4450.3 801.5 851.3 899.4 908.6 958.5 18218.2 21177.2 21889.9 22199.7 22396.0 886.0 950.4 960.5 975.9 993.0 10560.5 11381.9 11387.2 11402.7 11473.7 674991.0 789750.0 817795.0 827909.4 843050.0 170001.0 171569.3 173139.0 174164.0 179050.0 1225.9 1317.2 1336.9 1338.4 1340.5 20413.4 20776.9 20882.6 21244.2 21386.7 60201.0 66120.0 68186.0 68468.3 69500.7 88169.7 88925.0 89615.1 89868.1 91037.0 11655.1 11775.0 11937.4 11944.0 12248.9 13095.6 13145.9 13345.9 13481.1 13518.1 10518.3 10676.0 10782.6 10866.2 10969.7 10783.5 10826.7 10986.7 11143.0 11204.5 9304.4 11820.8 11989.6 12086.3 12118.5 11777.2 11804.5 11877.7 12078.0 12102.0 10241.4 11606.6 11750.9 12022.5 12112.7 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:6925.20 Median:2459.37 stddev:4369.07 (177.7%) stddev-sans-first:4362.96 17:11:05 INFO - PROCESS | 1649 | Values: 1592.9 1593.5 1616.6 1628.5 1628.9 9666.9 9893.7 11141.4 11836.3 11861.9 10876.7 11160.3 11163.1 11179.4 11219.3 2410.8 2477.5 2477.6 2479.1 2482.5 2389.5 2410.8 2418.2 2430.7 2435.7 2433.6 2444.3 2451.3 2454.0 2464.8 1973.4 1981.3 2450.4 2467.6 2498.4 2421.0 2429.4 2440.2 2442.1 2442.4 11501.0 11685.0 11694.7 11695.8 11724.5 5935.2 6016.3 6124.7 6135.6 6142.9 3279.7 3415.7 3603.4 3652.9 3670.5 1157.7 1160.6 1193.3 1208.4 1220.4 16201.0 16201.2 16202.9 16219.7 16246.7 4739.8 4886.7 5084.6 5136.3 5165.6 13172.2 13177.4 13185.7 13189.7 13236.1 6134.9 6136.3 6142.8 6145.8 6163.4 3952.6 5267.1 5339.1 5488.3 5489.5 4094.2 4199.2 4290.5 4314.0 4337.9 1151.1 1153.7 1157.9 1164.2 1172.8 811.4 817.2 843.8 846.4 856.6 6475.3 6692.2 6732.4 6845.7 6979.9 1041.9 1062.0 1064.9 1081.9 1103.4 4260.4 4290.1 4322.5 4380.9 4394.3 12031.2 13620.6 14011.6 14233.9 14238.0 13082.4 14103.9 14121.3 14126.6 14130.6 10346.4 10605.6 10609.2 10626.6 10637.9 6616.2 6716.2 6767.3 6775.2 7005.6 12160.2 12202.0 12480.8 12487.2 12626.2 12484.1 12487.4 12773.2 12813.8 12846.1 8224.9 8786.4 9285.6 9457.2 9544.8 10538.7 10618.0 10629.7 10722.4 10749.6 8678.2 8721.0 8788.0 9136.5 9163.5 8470.1 8602.1 8616.6 8646.8 8859.2 7813.2 7862.0 7935.2 7936.7 7960.2 5914.3 5923.9 5934.4 5947.8 6062.3 5924.2 6088.9 6112.4 6174.4 6210.0 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4628.25 Median:5094.31 stddev:1293.26 (25.4%) stddev-sans-first:1256.08 17:11:05 INFO - PROCESS | 1649 | Values: 333.8 378.2 389.0 391.7 400.1 4726.5 5207.5 5276.6 5293.6 5388.6 4817.4 5169.2 5198.9 5238.5 5258.9 3728.6 4191.3 4221.8 4329.7 4353.7 3964.4 4349.0 4383.4 4384.5 4398.6 4340.2 4343.4 4368.5 4395.9 4431.5 3955.3 4017.3 4288.5 4366.5 4377.7 3810.0 3873.5 3958.7 3973.3 4004.8 5121.9 5157.0 5178.5 5192.8 5200.0 3779.3 4266.4 4579.5 5106.2 5134.4 4801.6 4913.1 5206.6 5588.5 5651.4 3871.3 4408.5 4460.7 4523.8 4533.6 5277.9 5798.7 5859.7 5936.9 5958.5 4023.9 4587.6 4597.5 4605.9 4618.9 3978.8 4073.5 4425.6 4677.6 4748.9 4301.8 4564.8 4921.7 4983.6 5088.2 4732.7 5188.0 5378.5 5643.5 5672.3 1382.4 1436.4 1441.7 1542.0 1574.5 1011.5 1087.9 1185.8 1192.3 1216.7 4330.0 4498.8 5018.0 5226.5 5358.8 5664.5 5829.4 5842.9 5897.2 5907.3 4753.5 4844.8 5029.8 5184.8 5215.2 2894.5 3076.7 3084.8 3087.8 3102.6 5149.0 5718.8 5794.5 6091.3 6104.9 5285.8 5323.1 5788.1 6036.6 6048.6 5151.7 5239.0 5697.6 5759.9 5779.6 5170.8 5509.5 5840.4 5884.3 5942.6 5042.8 5120.2 5139.0 5409.8 5523.9 5269.8 5473.8 5542.5 5623.9 6027.0 3853.7 3955.3 4118.8 4144.6 4281.8 5472.7 5522.2 5699.9 5721.2 5774.2 5145.8 5245.4 5431.9 5483.3 5726.7 4761.6 5091.9 5403.6 5638.6 5655.7 4973.1 5096.7 5269.2 5363.4 5464.4 5158.6 5233.6 5306.4 5326.9 5732.4 4440.5 4583.9 4961.6 5518.6 5520.4 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:19989.58 Median:16468.96 stddev:28482.14 (172.9%) stddev-sans-first:28525.40 17:11:05 INFO - PROCESS | 1649 | Values: 750.1 759.4 780.4 837.2 857.9 15643.9 16284.3 16422.9 16515.0 16584.5 15455.1 15724.4 15737.5 15885.4 15890.4 960.9 994.4 1000.7 1001.4 1004.6 969.4 985.6 987.0 1003.0 1004.8 1042.7 1044.6 1046.3 1048.0 1050.9 876.6 1037.0 1042.2 1042.3 1043.5 763.9 777.4 778.6 779.5 779.5 14815.5 15219.2 15252.8 17182.0 17646.9 10959.8 14325.1 14340.6 14387.6 14390.5 11661.5 11667.6 11674.5 11749.1 11964.4 3629.6 3729.6 3759.2 3772.9 3885.9 113535.4 115758.4 117877.0 117932.0 118661.0 10826.3 10996.6 11090.5 11147.9 11253.8 22585.3 23077.3 23170.4 23514.5 23547.1 6961.4 7259.2 7531.4 7624.9 7678.2 4288.3 4305.8 4333.8 4404.4 4553.7 3459.8 3509.6 3523.5 3555.6 3560.5 567.6 567.9 568.4 568.7 568.8 564.7 659.2 705.6 713.2 719.4 8020.8 10599.2 10605.7 10697.3 10748.8 688.8 706.2 707.4 714.2 715.0 4727.0 5068.9 5075.9 5082.1 5082.7 98500.5 100771.5 101021.5 101618.0 103058.0 57192.7 58520.4 78233.6 82420.6 84927.0 63874.7 64961.0 67012.7 68323.0 69432.3 10892.6 11243.8 11320.2 11461.6 11492.4 47958.0 50043.7 50190.5 50769.7 51563.0 61222.0 62955.7 63218.7 63829.7 64428.4 12581.9 12677.2 12929.5 12955.2 13034.5 12115.4 14802.5 15032.4 15105.3 15120.3 11789.6 11917.6 12185.1 12225.5 12504.4 13874.8 14124.6 14189.9 14365.2 14661.5 14077.5 14290.6 14311.6 14335.0 14695.9 9916.3 10066.8 10389.5 10397.7 10478.0 9292.6 10076.6 10809.4 11352.3 11810.4 17:11:05 INFO - PROCESS | 1649 | -------- Summary: end -------- 17:11:05 INFO - PROCESS | 1649 | 17:11:05 INFO - TEST-INFO | 1649: exit 0 17:11:06 INFO - TEST-OK | dromaeo_css | took 1115994ms 17:11:06 INFO - TEST-START | kraken 17:11:06 INFO - operating with platform_type : mac_ 17:11:06 INFO - Initialising browser for kraken test... 17:11:06 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmp8lvu_Q/profile http://localhost:49248/getInfo.html) 17:11:08 INFO - PROCESS | 1657 | 2017-05-12 17:11:08.764 plugin-container[1659:11002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 17:11:08 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:11:08 INFO - PROCESS | 1657 | 2017-05-12 17:11:08.765 plugin-container[1659:11002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 17:11:08 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:11:08 INFO - PROCESS | 1657 | 2017-05-12 17:11:08.765 plugin-container[1659:11002] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:11:08 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 17:11:08 INFO - PROCESS | 1657 | colorDepth:24 17:11:08 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 17:11:08 INFO - PROCESS | 1657 | __metrics 17:11:08 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 17:11:09 INFO - TEST-INFO | 1657: exit 0 17:11:09 INFO - Browser initialized. 17:11:09 INFO - Running cycle 1/1 for kraken test... 17:11:09 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmp8lvu_Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:11:11 INFO - PROCESS | 1660 | 2017-05-12 17:11:11.931 plugin-container[1661:11131] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.tsm.portname' 17:11:11 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:11:11 INFO - PROCESS | 1660 | 2017-05-12 17:11:11.932 plugin-container[1661:11131] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891b, name = 'com.apple.CFPasteboardClient' 17:11:11 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:11:11 INFO - PROCESS | 1660 | 2017-05-12 17:11:11.932 plugin-container[1661:11131] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:11:21 INFO - PROCESS | 1660 | RSS: Main: 202878976 17:11:21 INFO - PROCESS | 1660 | 17:11:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 17:11:25 INFO - PROCESS | 1660 | RSS: Main: 216526848 17:11:25 INFO - PROCESS | 1660 | 17:11:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 17:11:28 INFO - PROCESS | 1660 | RSS: Main: 224518144 17:11:28 INFO - PROCESS | 1660 | 17:11:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 17:11:32 INFO - PROCESS | 1660 | RSS: Main: 228139008 17:11:32 INFO - PROCESS | 1660 | 17:11:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 17:11:35 INFO - PROCESS | 1660 | RSS: Main: 229511168 17:11:35 INFO - PROCESS | 1660 | 17:11:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:11:38 INFO - PROCESS | 1660 | RSS: Main: 229560320 17:11:38 INFO - PROCESS | 1660 | 17:11:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 17:11:42 INFO - PROCESS | 1660 | RSS: Main: 233947136 17:11:42 INFO - PROCESS | 1660 | 17:11:45 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 17:11:46 INFO - PROCESS | 1660 | RSS: Main: 233107456 17:11:46 INFO - PROCESS | 1660 | 17:11:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 17:11:50 INFO - PROCESS | 1660 | RSS: Main: 233402368 17:11:50 INFO - PROCESS | 1660 | 17:11:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:11:52 INFO - PROCESS | 1660 | RSS: Main: 232873984 17:11:52 INFO - PROCESS | 1660 | 17:11:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:11:54 INFO - PROCESS | 1660 | RSS: Main: 232755200 17:11:54 INFO - PROCESS | 1660 | 17:11:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:11:57 INFO - PROCESS | 1660 | RSS: Main: 232873984 17:11:57 INFO - PROCESS | 1660 | 17:12:00 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:12:01 INFO - PROCESS | 1660 | RSS: Main: 233504768 17:12:01 INFO - PROCESS | 1660 | 17:12:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:12:04 INFO - PROCESS | 1660 | RSS: Main: 233181184 17:12:04 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 17:12:06 INFO - PROCESS | 1660 | RSS: Main: 235171840 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | __start_tp_report 17:12:06 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 17:12:06 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 17:12:06 INFO - PROCESS | 1660 | |i|pagename|runs| 17:12:06 INFO - PROCESS | 1660 | |0;ai-astar;110;120;118;119;117;118;117;119;117;118 17:12:06 INFO - PROCESS | 1660 | |1;audio-beat-detection;136;111;116;175;125;118;110;113;128;111 17:12:06 INFO - PROCESS | 1660 | |2;audio-dft;200;202;195;192;196;197;198;224;264;200 17:12:06 INFO - PROCESS | 1660 | |3;audio-fft;77;60;85;82;80;83;84;54;90;76 17:12:06 INFO - PROCESS | 1660 | |4;audio-oscillator;110;90;77;73;102;60;96;105;57;106 17:12:06 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;132;89;83;139;103;111;122;80;121;135 17:12:06 INFO - PROCESS | 1660 | |6;imaging-darkroom;97;98;119;159;140;97;151;98;152;156 17:12:06 INFO - PROCESS | 1660 | |7;imaging-desaturate;142;145;122;89;108;135;88;139;90;144 17:12:06 INFO - PROCESS | 1660 | |8;json-parse-financial;66;66;66;70;70;85;101;84;71;69 17:12:06 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;59;57;66;70;65;58;59;58;60;59 17:12:06 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;96;84;84;87;88;87;90;105;88;84 17:12:06 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;141;137;136;138;142;141;142;153;171;154 17:12:06 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;166;165;168;167;167;165;167;165;167;162 17:12:06 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;66;79;74;86;85;85;79;67;65;68 17:12:06 INFO - PROCESS | 1660 | __end_tp_report 17:12:06 INFO - PROCESS | 1660 | __start_cc_report 17:12:06 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1008 17:12:06 INFO - PROCESS | 1660 | __end_cc_report 17:12:06 INFO - PROCESS | 1660 | __startTimestamp1494634326898__endTimestamp 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | ------- Summary: start ------- 17:12:06 INFO - PROCESS | 1660 | Number of tests: 14 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:117.30 Median:118.00 stddev:2.75 (2.3%) stddev-sans-first:1.05 17:12:06 INFO - PROCESS | 1660 | Values: 110.0 120.0 118.0 119.0 117.0 118.0 117.0 119.0 117.0 118.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:121.50 stddev:19.77 (16.3%) stddev-sans-first:20.51 17:12:06 INFO - PROCESS | 1660 | Values: 136.0 111.0 116.0 175.0 125.0 118.0 110.0 113.0 128.0 111.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:206.80 Median:200.00 stddev:21.94 (11.0%) stddev-sans-first:23.13 17:12:06 INFO - PROCESS | 1660 | Values: 200.0 202.0 195.0 192.0 196.0 197.0 198.0 224.0 264.0 200.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:77.10 Median:82.50 stddev:11.41 (13.8%) stddev-sans-first:12.10 17:12:06 INFO - PROCESS | 1660 | Values: 77.0 60.0 85.0 82.0 80.0 83.0 84.0 54.0 90.0 76.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:66.50 stddev:19.58 (29.4%) stddev-sans-first:19.02 17:12:06 INFO - PROCESS | 1660 | Values: 110.0 90.0 77.0 73.0 102.0 60.0 96.0 105.0 57.0 106.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:111.50 Median:137.00 stddev:21.89 (16.0%) stddev-sans-first:21.92 17:12:06 INFO - PROCESS | 1660 | Values: 132.0 89.0 83.0 139.0 103.0 111.0 122.0 80.0 121.0 135.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:126.70 Median:128.00 stddev:27.45 (21.4%) stddev-sans-first:26.93 17:12:06 INFO - PROCESS | 1660 | Values: 97.0 98.0 119.0 159.0 140.0 97.0 151.0 98.0 152.0 156.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:120.20 Median:144.50 stddev:24.28 (16.8%) stddev-sans-first:24.43 17:12:06 INFO - PROCESS | 1660 | Values: 142.0 145.0 122.0 89.0 108.0 135.0 88.0 139.0 90.0 144.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:74.80 Median:70.00 stddev:11.55 (16.5%) stddev-sans-first:11.81 17:12:06 INFO - PROCESS | 1660 | Values: 66.0 66.0 66.0 70.0 70.0 85.0 101.0 84.0 71.0 69.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:59.50 stddev:4.33 (7.3%) stddev-sans-first:4.53 17:12:06 INFO - PROCESS | 1660 | Values: 59.0 57.0 66.0 70.0 65.0 58.0 59.0 58.0 60.0 59.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:87.50 stddev:6.58 (7.5%) stddev-sans-first:6.52 17:12:06 INFO - PROCESS | 1660 | Values: 96.0 84.0 84.0 87.0 88.0 87.0 90.0 105.0 88.0 84.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:142.00 stddev:10.87 (7.7%) stddev-sans-first:11.40 17:12:06 INFO - PROCESS | 1660 | Values: 141.0 137.0 136.0 138.0 142.0 141.0 142.0 153.0 171.0 154.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:167.00 stddev:1.73 (1.0%) stddev-sans-first:1.83 17:12:06 INFO - PROCESS | 1660 | Values: 166.0 165.0 168.0 167.0 167.0 165.0 167.0 165.0 167.0 162.0 17:12:06 INFO - PROCESS | 1660 | 17:12:06 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.40 Median:79.00 stddev:8.47 (10.7%) stddev-sans-first:8.28 17:12:06 INFO - PROCESS | 1660 | Values: 66.0 79.0 74.0 86.0 85.0 85.0 79.0 67.0 65.0 68.0 17:12:06 INFO - PROCESS | 1660 | -------- Summary: end -------- 17:12:06 INFO - PROCESS | 1660 | 17:12:07 INFO - TEST-INFO | 1660: exit 0 17:12:07 INFO - TEST-OK | kraken | took 61561ms 17:12:07 INFO - SUITE-END | took 1177s 17:12:08 INFO - Completed test suite (00:19:38) 17:12:08 INFO - javascript benchmark 17:12:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7809.419689197941, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3281.7866838655486, 3440.2087519870447, 3499.072745722384, 3511.4557243627232, 3519.595246546648, 5263.920896216276, 5897.616654917428, 6102.786402475915, 6154.261488621371, 6164.81505554833, 6161.630302181861, 6201.348858369863, 6217.471514921237, 6224.128621992913, 6228.096925945746, 5516.91724624131, 5527.281453411051, 5605.1872478490595, 5643.633163844337, 5645.379008309083, 5660.716964151797, 5677.744501497422, 5683.857903552403, 5692.0, 5703.6546965860625, 4437.334399840018, 5450.482204190595, 5539.695316757577, 5650.943490565103, 5666.546676265889, 5608.46719561641, 5608.747606357723, 5613.691246981414, 5643.8589035274035, 5663.178839068353, 5213.921791173136, 5474.42518535556, 5563.388027316992, 5586.608937374385, 5597.63615365, 5053.242013697938, 5905.704714764288, 6029.698515074273, 6061.0, 6064.63612183266, 6372.7132279047555, 6413.935860641381, 6429.6142231466265, 6540.33221528512, 6567.802965911031, 6649.567778094409, 6671.633060181688, 6675.499337549703, 6689.030090636879, 6694.698738556776, 5999.130126131687, 6308.148399965946, 6309.684515774193, 6337.429631333202, 6338.809835704937, 6512.804615861532, 6532.869342613216, 6571.507136964747, 6655.401013908771, 6682.599044057372, 6434.613923164625, 6445.065465507475, 6447.2263328400895, 6456.903146452807, 6459.806205813833, 5532.695701736363, 6463.353664633499, 6522.249941256816, 6595.0766892635065, 6608.107905432801, 6265.310815810257, 6318.115463835067, 6361.268454127742, 6507.414332710079, 6523.412892839668, 5229.816956406584, 5384.596155288369, 6355.618662880242, 6542.542022058506, 6543.312952140052, 5187.0922588546855, 5190.558802501754, 5211.140161873249, 5245.947540524546, 5246.63273570854, 4615.8846028848875, 4618.976905115453, 4660.39414876064, 4693.859184224479, 4712.434507859079, 5985.880282394415, 5986.102084687332, 6035.336113027563, 6035.7283922223605, 6129.48104857405, 6538.901916471256, 6648.069270302161, 6665.833354166088, 6674.632895190811, 6699.0, 6213.937860621426, 6217.626942383381, 6219.564630475823, 6242.531810114259, 6255.468285195719, 4574.748388838646, 5602.775888964396, 5779.248697669276, 5796.536277097822, 5830.47525722687, 6702.095217145623, 6743.7639682612835, 6757.3242675732035, 6781.932180678327, 6857.691403886937, 6515.511336649865, 6613.00804879272, 6639.867202656016, 6662.133922590032, 6665.766698165541, 6481.8703625928165, 6812.250652428328, 6838.7264509419065, 6840.48696347766, 6843.3840954314355, 6370.203724534434, 6417.197850268716, 6426.967865160831, 6508.837279068061, 6520.869582608416, 6461.61230326182, 6582.045603387386, 6604.801855944329, 6615.702293396713, 6624.867502650017, 6387.808365749035, 6408.166938297992, 6439.935600643934, 6458.160439142881, 6461.483081353575, 6264.968675156595, 6312.652804095819, 6347.111404403479, 6370.840728981812, 6372.203474565679, 6273.466755325666, 6338.683065846597, 6353.96823015882, 6359.141515895295, 6370.872582548416, 6243.282022567463, 6293.559450838397, 6416.358364163546, 6416.647084410402, 6466.644334561644, 6264.373562643699, 6264.436200741955, 6285.905711414424, 6312.400321969406, 6333.240011198685], "value": 6002.003497550484, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.039829814434, 783.2285199078906, 841.6507149532944, 980.6175591519304, 986.9309148359617, 39151.80424097879, 40122.0, 41789.582104178946, 41831.37252941208, 41902.790486047525, 40387.990300242505, 40504.18991620173, 40539.79730101345, 40640.78077657675, 40695.77912662618, 8227.424080314366, 8362.16378362162, 11098.44507774612, 11415.486303116339, 11583.42082895856, 9443.38617989832, 11310.208285420023, 11371.31772093673, 11371.545138194462, 11397.943010284936, 11375.772484550303, 11389.202755807097, 11402.714932126722, 11411.657650270505, 11411.714707132305, 11294.548218071308, 11337.716557086056, 11359.204855660024, 11375.544978200862, 11377.54489820402, 11069.0, 11091.667249982513, 11168.776624467546, 11171.329720216732, 11179.552817887356, 34762.652373476194, 34922.650773492445, 34959.776407825855, 34962.825185874164, 35061.64938350609, 3881.3013657541633, 3904.297226499229, 3911.4915061041884, 3923.019245188703, 3972.2254160438724, 25560.23319300424, 25636.35909102269, 26358.868205658848, 26686.0, 27290.5906411404, 2951.911442656724, 3095.8142511449387, 3138.482150445174, 3338.2655815720505, 3343.180920674426, 321478.0, 347888.0, 348786.0, 348953.0, 349475.99999999744, 45292.54707452883, 45352.77323613361, 45454.545454545034, 45457.54542454599, 45459.0, 46287.76856115765, 46456.0, 47053.0, 47060.7646961763, 47089.529104709196, 14342.0, 14388.0, 14445.566633000817, 14455.98808083424, 14478.203698796668, 12313.44589493475, 12501.74996500083, 12523.874761252453, 12532.81200781989, 12545.996320294355, 9904.356216845881, 10029.247806414549, 11856.170068095324, 11921.105917056255, 12138.968187704144, 4864.6594738368685, 5672.234248376499, 5785.71071446426, 5794.536437085023, 5796.043652797325, 1136.3863513702508, 1167.5855071449682, 1169.818678104895, 1170.777552265067, 1219.231883913129, 21135.154593816384, 27888.442231155263, 28335.858320708263, 28447.431051378855, 28526.572101418496, 1057.360297020307, 1195.9654898512724, 1270.9514650413446, 1276.8786965238287, 1300.947962081525, 11266.830997535044, 14039.649008774863, 14473.493427730189, 14696.632584185267, 14787.334569944378, 438746.0, 439215.0, 442129.0, 442809.0, 443337.0, 48267.51732482701, 50753.238701419505, 56932.0, 57208.0, 57234.42765572374, 1410.4922227997913, 1442.5167568864338, 1450.1299220467636, 1451.368654635216, 1461.7076584683107, 27419.04033358823, 27455.588166177924, 27869.303267418476, 28442.71557284401, 28540.714592854638, 42501.0, 42535.57464425316, 42669.0, 42696.5730342705, 42770.35844462398, 496538.0, 496871.0, 497108.0, 497323.0, 500372.0, 29565.0, 29643.1838975297, 29684.109476715734, 29723.108306750833, 29811.571669258312, 37722.81138594399, 38239.0, 39091.413628795024, 39198.02004949785, 39475.40786888256, 27685.44629107447, 27717.861410692818, 28109.0, 28202.576961346007, 29027.419451611273, 27565.17304480869, 27947.860260698566, 27999.860000699868, 28889.844406223514, 29101.417971640025, 31562.684373155975, 31654.841725791222, 32314.83842580772, 32736.0, 33609.83195084009, 27635.585466218425, 28690.0, 28982.710172898845, 29529.704702952695, 30190.54714179333, 23020.0, 23661.526769464857, 23694.644580330965, 24193.637095443934, 24198.51602967966], "value": 18579.274265492917, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.4633790720434, 434.85215026890853, 438.3009100484727, 443.7138045960356, 450.95490450954907, 19487.902560487215, 20096.59806803863, 21341.0, 21455.463613409673, 21520.923953802325, 21875.343739687836, 22414.318926080527, 23045.654315185286, 23235.419114522105, 23590.410239744015, 6877.65611719412, 6882.311768823102, 7114.395276401512, 7128.108986376703, 7170.426365890742, 6913.239543650195, 7119.110111236096, 7129.287071292881, 7135.714571417163, 7142.500024998252, 6714.3621355971345, 6906.930930690704, 7028.19175794785, 7069.964650176742, 7084.752033678822, 7004.684789184499, 7032.015517827508, 7063.505554611179, 7074.858502829966, 7108.78673639789, 6524.510661700344, 6771.255161932233, 6868.7252509899545, 6920.0, 6922.58464492132, 26794.598081028802, 27561.586576201375, 27626.033088841796, 27760.86119569389, 27928.022519211932, 2831.847884784018, 3029.9848500757357, 3037.4684430224843, 3043.4065357255345, 3051.0999255219763, 16138.515844524683, 16169.838301617068, 16171.91914040434, 16343.34626614934, 16382.344706211738, 2022.1405902491383, 2076.262926661029, 2156.1698745982753, 2209.171560664751, 2223.3885681437573, 156464.0, 158404.0, 159037.93134337285, 161974.0, 167830.0, 17411.216495257744, 19048.047597620065, 19662.508437288896, 22130.0, 22351.888240558692, 27164.864175678995, 31919.52120718191, 32380.028599142064, 32404.837975810442, 32425.675743242737, 8496.320294376435, 10715.24993250465, 10883.401412922365, 10903.672889813319, 11028.393438360967, 8130.634121464548, 9018.59416326267, 9038.367314288069, 9047.095290471032, 9263.490508022123, 9088.182063614306, 9332.603938730907, 9888.110070093726, 9931.950340248253, 10090.243231757648, 3796.563395209531, 4292.978535107304, 4321.2005778931, 4432.933505997413, 4450.265706158512, 801.4950581133849, 851.3189448441223, 899.4423457456288, 908.5684299957468, 958.520739630185, 18218.180181891847, 21177.199938005135, 21889.890550547465, 22199.66700499494, 22396.0, 885.968991085309, 950.3632566180676, 960.5341409416427, 975.9414435133773, 992.9900700992897, 10560.471976401303, 11381.943090284496, 11387.202895797214, 11402.657920262405, 11473.713157171138, 674991.0, 789750.0, 817795.0, 827909.4418111604, 843050.0, 170001.0, 171569.28430715782, 173139.0, 174164.0, 179050.0, 1225.8651548329676, 1317.2030921292671, 1336.8663133686553, 1338.4445455136204, 1340.4638144742182, 20413.38759837207, 20776.896115519325, 20882.582348352644, 21244.150233990465, 21386.679199811704, 60201.0, 66120.0, 68186.0, 68468.31531684619, 69500.6524967372, 88169.67745483952, 88925.0, 89615.10384896067, 89868.10131898597, 91037.0, 11655.125865560287, 11775.0, 11937.4031298433, 11944.044476442037, 12248.87751122513, 13095.607131786062, 13145.868541314465, 13345.866541334462, 13481.056326057082, 13518.05373623837, 10518.316309439862, 10676.0, 10782.568697252022, 10866.185036122164, 10969.670909872717, 10783.460826958777, 10826.67519974402, 10986.725331866768, 11142.997130258316, 11204.49579768896, 9304.441733496013, 11820.822687659864, 11989.580364687194, 12086.274823510617, 12118.454669539715, 11777.234479758788, 11804.468798904241, 11877.703057423634, 12078.0, 12102.0, 10241.385516869012, 11606.593769218036, 11750.882491174978, 12022.519099236282, 12112.7281635427], "value": 11889.20160352289, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1592.8884978051542, 1593.4980481148436, 1616.6362568422098, 1628.5358672778261, 1628.9104099274534, 9666.903330966688, 9893.703188904345, 11141.387223702703, 11836.289822610639, 11861.940690296547, 10876.728081797959, 11160.330380177194, 11163.051140653033, 11179.441027948611, 11219.270747401413, 2410.7950824179966, 2477.492114116611, 2477.6407420923956, 2479.1446950801947, 2482.491089326684, 2389.498205376873, 2410.8312418130736, 2418.1657328221736, 2430.7083150021936, 2435.659007738918, 2433.5741245282093, 2444.315591634336, 2451.252368027751, 2453.950920981579, 2464.7799339327057, 1973.398113575359, 1981.3263490413256, 2450.424150324672, 2467.592847180213, 2498.4253621667117, 2420.9515809683708, 2429.4290841652196, 2440.2069327468466, 2442.072012635205, 2442.4016116051503, 11501.022413094897, 11685.006774424184, 11694.70763230926, 11695.766084678258, 11724.531018759239, 5935.198748168985, 6016.338202797689, 6124.724387402578, 6135.601185922946, 6142.907856382158, 3279.7212236959886, 3415.7096646785053, 3603.4234522476277, 3652.8904132876055, 3670.522832031819, 1157.7163594919214, 1160.646002969094, 1193.2780667696093, 1208.3958020989505, 1220.4263995921901, 16201.0, 16201.189940503045, 16202.946808457413, 16219.675606487923, 16246.675066498721, 4739.834105806281, 4886.706797592155, 5084.618653600995, 5136.280920671109, 5165.560927321145, 13172.209667419986, 13177.407016684272, 13185.736285274432, 13189.736205276033, 13236.13965092266, 6134.9079763803575, 6136.294326152461, 6142.815715528542, 6145.846353841101, 6163.3836616338, 3952.6086218771084, 5267.104592219332, 5339.092354299778, 5488.313960754906, 5489.533389661923, 4094.151567121529, 4199.223143718422, 4290.485141783006, 4314.0077782109665, 4337.891552711208, 1151.0963893343685, 1153.7231064544449, 1157.8652920138143, 1164.1967042740482, 1172.783035138502, 811.4117264982958, 817.15832692327, 843.8312337532518, 846.4286606540561, 856.5588721808274, 6475.287718350977, 6692.163479565054, 6732.394084532318, 6845.657717114124, 6979.8604027919155, 1041.9010194031555, 1062.0229388962173, 1064.914806815453, 1081.886401927802, 1103.3600511703194, 4260.382244574519, 4290.141971605629, 4322.481302243751, 4380.934285985712, 4394.2969124939855, 12031.157818952765, 13620.59138225835, 14011.579652610439, 14233.928830355782, 14238.0, 13082.411291491906, 14103.858961410255, 14121.293935303194, 14126.64683382903, 14130.646733831736, 10346.430946297874, 10605.628802991858, 10609.15126789855, 10626.628068017737, 10637.9468102659, 6616.239132499824, 6716.1604799400075, 6767.289434609295, 6775.1531058617675, 7005.579665220103, 12160.209586376857, 12202.0, 12480.75038499243, 12487.188332758342, 12626.242425454502, 12484.063695223076, 12487.375631218585, 12773.23360598367, 12813.807792882928, 12846.100772945803, 8224.917750822415, 8786.38495305348, 9285.62857485693, 9457.19613832832, 9544.809103817746, 10538.683839484827, 10618.0, 10629.734256643645, 10722.410267435054, 10749.570017199225, 8678.218960293603, 8721.0, 8787.956060219658, 9136.49749263797, 9163.450192988443, 8470.110638383128, 8602.096779838026, 8616.569171541523, 8646.82706345882, 8859.24696400813, 7813.179616140451, 7861.960690196513, 7935.246151615587, 7936.722214722457, 7960.164182760726, 5914.319853216935, 5923.881522369615, 5934.376890426443, 5947.821565353046, 6062.333143354315, 5924.229850119457, 6088.908666370096, 6112.388761123852, 6174.351693072163, 6209.96895015522], "value": 5262.828233853575, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.80472423632176, 378.20010677417235, 389.0138498905274, 391.6749098248453, 400.0558681511633, 4726.456457507387, 5207.479252074795, 5276.577873770094, 5293.6294459387855, 5388.632104934416, 4817.373741413611, 5169.224616307556, 5198.8700282492955, 5238.476152384759, 5258.947410525903, 3728.6017743346247, 4191.329387298033, 4221.831126754926, 4329.709420840169, 4353.651707863379, 3964.385520244367, 4349.043210493686, 4383.364412160235, 4384.539623339551, 4398.626116780061, 4340.1771256072525, 4343.370211319372, 4368.453943257093, 4395.857077159945, 4431.49037860647, 3955.2880481513316, 4017.337139372, 4288.549702281278, 4366.497852746943, 4377.737335759849, 3809.9713077469205, 3873.5351757788962, 3958.6833053355663, 3973.304671682444, 4004.799760012017, 5121.901249743889, 5156.974215128937, 5178.482151784829, 5192.818251361185, 5200.0, 3779.3340459753995, 4266.400099962514, 4579.519150489184, 5106.161781758601, 5134.409542902539, 4801.591864691505, 4913.06651736169, 5206.594219560698, 5588.497035266845, 5651.378348381716, 3871.3031654302213, 4408.515063343029, 4460.710053846522, 4523.751193684346, 4533.6146427553695, 5277.947220527784, 5798.650477726882, 5859.6561375450165, 5936.910946335808, 5958.523318134538, 4023.879283621496, 4587.568588647955, 4597.517260687647, 4605.907881842411, 4618.884527886829, 3978.840846366113, 4073.5315438724924, 4425.579569940851, 4677.579017888406, 4748.857534273978, 4301.827926882889, 4564.817407303671, 4921.704697718148, 4983.5763960063805, 5088.160453525201, 4732.674851041714, 5187.962407518511, 5378.542823859937, 5643.5203007743985, 5672.262605861211, 1382.3710211853584, 1436.3895344478556, 1441.654003039263, 1541.9591775551683, 1574.527641707483, 1011.5144730529387, 1087.8694556653095, 1185.7628474305172, 1192.3382522699812, 1216.6593353860933, 4329.978350108292, 4498.830304120952, 5017.974910125426, 5226.529612334908, 5358.758855851496, 5664.54683625309, 5829.387914269026, 5842.883142337215, 5897.2038774765715, 5907.261592300963, 4753.47711751707, 4844.8304309349705, 5029.84910452687, 5184.792608295701, 5215.24378965048, 2894.45005448964, 3076.676948920331, 3084.7969291976146, 3087.81473111614, 3102.5966624338694, 5149.0, 5718.827640333721, 5794.478496935211, 6091.299500557493, 6104.908426373608, 5285.814996475182, 5323.083689871643, 5788.0739081746715, 6036.607620504741, 6048.60684055535, 5151.716655583829, 5239.0, 5697.601167918206, 5759.913601295984, 5779.595428319977, 5170.844874653766, 5509.531689806411, 5840.386759390372, 5884.293884733859, 5942.554308426972, 5042.84871453857, 5120.206368012964, 5138.97430512845, 5409.810656626999, 5523.8895222096135, 5269.762860671202, 5473.808416705396, 5542.473465020817, 5623.915641265303, 6027.0, 3853.7475320520384, 3955.307821131348, 4118.8055463915125, 4144.564820693784, 4281.801095693211, 5472.6990015547935, 5522.199281104137, 5699.917015766991, 5721.227634269487, 5774.249347584955, 5145.842185508231, 5245.423003469539, 5431.891362172813, 5483.314585676791, 5726.74229659658, 4761.571458568746, 5091.898162036665, 5403.621746477709, 5638.633488823213, 5655.7454914528125, 4973.126336190481, 5096.725818545364, 5269.235960785742, 5363.436839131991, 5464.426235245401, 5158.587313015025, 5233.581313494835, 5306.442823503477, 5326.937011218487, 5732.4204108152335, 4440.492011311028, 4583.931241031454, 4961.6030717543235, 5518.558515318685, 5520.447955204448], "value": 4248.1163719512315, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.0736590310962, 759.4083356465874, 780.4029917113405, 837.2255663511253, 857.9184977427142, 15643.921780391096, 16284.348626054943, 16422.917885410585, 16515.00909945404, 16584.502464926056, 15455.149966751853, 15724.37102515898, 15737.527874163765, 15885.36458541657, 15890.443834465796, 960.8798900137483, 994.4083270454068, 1000.6597756762699, 1001.3991605036992, 1004.6182450668736, 969.4280374578997, 985.6106837799065, 987.027777639025, 1002.959881604735, 1004.7990401919645, 1042.734102803784, 1044.582167133149, 1046.3251202974077, 1047.979040419195, 1050.8686414198226, 876.5967654878764, 1037.0044757033256, 1042.197507918902, 1042.2547878267037, 1043.4782608695652, 763.904511936008, 777.3703300326754, 778.5834578500532, 779.4582764978343, 779.5050143159078, 14815.518353186992, 15219.239038048165, 15252.847471525365, 17181.969081855128, 17646.91176544109, 10959.780804383867, 14325.068870523382, 14340.64148396288, 14387.640308992255, 14390.496332628412, 11661.533538658443, 11667.6499705009, 11674.533018679243, 11749.118816088827, 11964.40177991097, 3629.5825980012373, 3729.589745128034, 3759.154190307186, 3772.8868133956025, 3885.8834234972996, 113535.43232283786, 115758.42120789427, 117877.0, 117932.0, 118661.0, 10826.296290741077, 10996.615118470974, 11090.500927458284, 11147.888521114848, 11253.831192532118, 22585.270260213892, 23077.307680769605, 23170.420739481648, 23514.52970940606, 23547.058117675442, 6961.408280296231, 7259.201487836333, 7531.3598344141365, 7624.920810896491, 7678.232176782277, 4288.292431748789, 4305.806238719265, 4333.761643109659, 4404.427424434803, 4553.707784681397, 3459.775114617592, 3509.649035096521, 3523.4714792781288, 3555.591107022674, 3560.5193298904833, 567.5941701683316, 567.9233303503975, 568.4116938968148, 568.7128000359792, 568.778176511161, 564.7418464775628, 659.1696444944458, 705.5625512182377, 713.1727196452091, 719.3921136639531, 8020.759377218693, 10599.152067834555, 10605.681829545127, 10697.251192416612, 10748.838767418494, 688.7658196213312, 706.2443185791154, 707.4199156691567, 714.2179313651543, 714.9606771627506, 4727.0, 5068.873278168076, 5075.949240507622, 5082.110630639697, 5082.720450375191, 98500.50749746205, 100771.49614251882, 101021.49489252506, 101618.0, 103058.0, 57192.71403642955, 58520.414795851495, 78233.60883195662, 82420.58789706013, 84927.0, 63874.68062659657, 64961.0, 67012.664936675, 68323.0, 69432.30567694259, 10892.564297428014, 11243.831342530033, 11320.150988675718, 11461.598844040418, 11492.36791976416, 47958.0, 50043.74978125086, 50190.498095018585, 50769.746151269006, 51563.0, 61222.0, 62955.6852215736, 63218.683906580176, 63829.6808515973, 64428.35571644411, 12581.937090314856, 12677.17598356104, 12929.482820687443, 12955.157914735511, 13034.543790967271, 12115.394230288626, 14802.454085813082, 15032.398704051715, 15105.32026058808, 15120.319585618894, 11789.587364442203, 11917.642470725892, 12185.086118540967, 12225.510979560719, 12504.374781261082, 13874.791878122038, 14124.646883827576, 14189.929050354682, 14365.20991345486, 14661.48684796027, 14077.507287244896, 14290.64273393132, 14311.57065288043, 14334.996550241383, 14695.92652036733, 9916.256280778825, 10066.848997265193, 10389.48052597352, 10397.740056498647, 10478.0, 9292.58183381763, 10076.596936122472, 10809.44400924171, 11352.262102963612, 11810.350430726392], "value": 7652.074780343722, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 120.0, 118.0, 119.0, 117.0, 118.0, 117.0, 119.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 111.0, 116.0, 175.0, 125.0, 118.0, 110.0, 113.0, 128.0, 111.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 202.0, 195.0, 192.0, 196.0, 197.0, 198.0, 224.0, 264.0, 200.0], "value": 206.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 60.0, 85.0, 82.0, 80.0, 83.0, 84.0, 54.0, 90.0, 76.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 90.0, 77.0, 73.0, 102.0, 60.0, 96.0, 105.0, 57.0, 106.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 89.0, 83.0, 139.0, 103.0, 111.0, 122.0, 80.0, 121.0, 135.0], "value": 111.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 119.0, 159.0, 140.0, 97.0, 151.0, 98.0, 152.0, 156.0], "value": 126.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 122.0, 89.0, 108.0, 135.0, 88.0, 139.0, 90.0, 144.0], "value": 120.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 70.0, 70.0, 85.0, 101.0, 84.0, 71.0, 69.0], "value": 74.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 66.0, 70.0, 65.0, 58.0, 59.0, 58.0, 60.0, 59.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 84.0, 84.0, 87.0, 88.0, 87.0, 90.0, 105.0, 88.0, 84.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 137.0, 136.0, 138.0, 142.0, 141.0, 142.0, 153.0, 171.0, 154.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 168.0, 167.0, 167.0, 165.0, 167.0, 165.0, 167.0, 162.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 74.0, 86.0, 85.0, 85.0, 79.0, 67.0, 65.0, 68.0], "value": 75.4, "unit": "score"}]}]} 17:12:08 INFO - Return code: 0 17:12:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:12:08 ERROR - # TBPL SUCCESS # 17:12:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:12:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:12:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:12:08 INFO - 2017-05-12 17:12:08.244 screenresolution[1665:11521] starting screenresolution argv=screenresolution get 17:12:08 INFO - 2017-05-12 17:12:08.247 screenresolution[1665:11521] Display 0: 1600x1200x32@60 17:12:08 INFO - 2017-05-12 17:12:08.252 screenresolution[1666:11524] starting screenresolution argv=screenresolution list 17:12:08 INFO - Available Modes on Display 0 17:12:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:12:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:12:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:12:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:12:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:12:08 INFO - Intel Iris: 17:12:08 INFO - Chipset Model: Intel Iris 17:12:08 INFO - Type: GPU 17:12:08 INFO - Bus: Built-In 17:12:08 INFO - VRAM (Dynamic, Max): 1536 MB 17:12:08 INFO - Vendor: Intel (0x8086) 17:12:08 INFO - Device ID: 0x0a2e 17:12:08 INFO - Revision ID: 0x0009 17:12:08 INFO - Displays: 17:12:08 INFO - Display: 17:12:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:12:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:12:08 INFO - Display Serial Number: 1600x1200 60 17:12:08 INFO - Main Display: Yes 17:12:08 INFO - Mirror: Off 17:12:08 INFO - Online: Yes 17:12:08 INFO - Rotation: Supported 17:12:08 INFO - Return code: 0 17:12:08 INFO - Running post-action listener: _resource_record_post_action 17:12:08 INFO - [mozharness: 2017-05-13 00:12:08.487537Z] Finished run-tests step (success) 17:12:08 INFO - Running post-run listener: _resource_record_post_run 17:12:08 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 35495936; Write bytes: 513950720; Read time: 324; Write time: 1757 17:12:08 INFO - TinderboxPrint: CPU usage
54.6% 17:12:08 INFO - TinderboxPrint: I/O read bytes / time
35,495,936 / 324 17:12:08 INFO - TinderboxPrint: I/O write bytes / time
513,950,720 / 1,757 17:12:08 INFO - TinderboxPrint: CPU idle
2,177.0 (45.4%) 17:12:08 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 17:12:08 INFO - TinderboxPrint: CPU user
1,654.6 (34.5%) 17:12:08 INFO - TinderboxPrint: Swap in / out
240,799,744 / 0 17:12:08 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203975168; Write bytes: 202532864; Read time: 15727; Write time: 438 17:12:08 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 33472512; Write bytes: 300620800; Read time: 302; Write time: 1283 17:12:08 INFO - Running post-run listener: _upload_blobber_files 17:12:08 INFO - Blob upload gear active. 17:12:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:12:08 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 17:12:08 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', 'Jamun', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:12:08 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 Jamun -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:12:09 INFO - (blobuploader) - INFO - Open directory for files ... 17:12:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:12:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:12:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:12:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:12:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:12:10 INFO - (blobuploader) - INFO - Done attempting. 17:12:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:12:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:12:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:12:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:12:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:12:11 INFO - (blobuploader) - INFO - Done attempting. 17:12:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:12:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:12:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:12:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:12:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:12:13 INFO - (blobuploader) - INFO - Done attempting. 17:12:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:12:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:12:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:12:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:12:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:12:15 INFO - (blobuploader) - INFO - Done attempting. 17:12:15 INFO - (blobuploader) - INFO - Iteration through files over. 17:12:15 INFO - Return code: 0 17:12:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:12:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:12:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/8ba41278c5103a2727cb79f31ffc5e5887dcc89f8bbc5c926a5cf22a3c60b65fd96d9ec6ee0ad56a8adf9c955425d8f9d72ef12f88c4470716258d7dd7d433fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/4198bbfaf959cf194f1d366d1f2e67db61df149da120bef38ce2f5848ebeb03bca03e88421ae2729b7f2ca4f7c2d97255f1ff95c631183dc2a68ba6ade83d024", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/15ce660c82f4e206a68bb3019ced1a44e056f87a8a53694b341acd45427fc3e78386ff4a152a7d557a07819aeca850ca09bed50d156d83bb3eb962ad13cb4d4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/a34acaf1980fc26194a9bb6033d45f9da4628f8323541c51d21621b33ff7cb3af39512f84dfebd352f723794438fda9670f28c1ee804974fcb7e9f1ea4e41510"} 17:12:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:12:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:12:15 INFO - Contents: 17:12:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/8ba41278c5103a2727cb79f31ffc5e5887dcc89f8bbc5c926a5cf22a3c60b65fd96d9ec6ee0ad56a8adf9c955425d8f9d72ef12f88c4470716258d7dd7d433fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/4198bbfaf959cf194f1d366d1f2e67db61df149da120bef38ce2f5848ebeb03bca03e88421ae2729b7f2ca4f7c2d97255f1ff95c631183dc2a68ba6ade83d024", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/15ce660c82f4e206a68bb3019ced1a44e056f87a8a53694b341acd45427fc3e78386ff4a152a7d557a07819aeca850ca09bed50d156d83bb3eb962ad13cb4d4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/a34acaf1980fc26194a9bb6033d45f9da4628f8323541c51d21621b33ff7cb3af39512f84dfebd352f723794438fda9670f28c1ee804974fcb7e9f1ea4e41510"} 17:12:15 INFO - Running post-run listener: copy_logs_to_upload_dir 17:12:15 INFO - Copying logs to upload dir... 17:12:15 INFO - mkdir: /builds/slave/test/build/upload/logs 17:12:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2485.280932 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 25 secs) (at 2017-05-12 17:12:15.930324) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 17:12:15.934495) ========= 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.8U2MBuGlpr/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.puKQnXkOzG/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/Jamun/sha512/8ba41278c5103a2727cb79f31ffc5e5887dcc89f8bbc5c926a5cf22a3c60b65fd96d9ec6ee0ad56a8adf9c955425d8f9d72ef12f88c4470716258d7dd7d433fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/4198bbfaf959cf194f1d366d1f2e67db61df149da120bef38ce2f5848ebeb03bca03e88421ae2729b7f2ca4f7c2d97255f1ff95c631183dc2a68ba6ade83d024", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/15ce660c82f4e206a68bb3019ced1a44e056f87a8a53694b341acd45427fc3e78386ff4a152a7d557a07819aeca850ca09bed50d156d83bb3eb962ad13cb4d4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/a34acaf1980fc26194a9bb6033d45f9da4628f8323541c51d21621b33ff7cb3af39512f84dfebd352f723794438fda9670f28c1ee804974fcb7e9f1ea4e41510"} build_url:https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010487 build_url: 'https://queue.taskcluster.net/v1/task/Xb-kh6__RrSZBOsyZCHLqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/8ba41278c5103a2727cb79f31ffc5e5887dcc89f8bbc5c926a5cf22a3c60b65fd96d9ec6ee0ad56a8adf9c955425d8f9d72ef12f88c4470716258d7dd7d433fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/4198bbfaf959cf194f1d366d1f2e67db61df149da120bef38ce2f5848ebeb03bca03e88421ae2729b7f2ca4f7c2d97255f1ff95c631183dc2a68ba6ade83d024", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/15ce660c82f4e206a68bb3019ced1a44e056f87a8a53694b341acd45427fc3e78386ff4a152a7d557a07819aeca850ca09bed50d156d83bb3eb962ad13cb4d4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/a34acaf1980fc26194a9bb6033d45f9da4628f8323541c51d21621b33ff7cb3af39512f84dfebd352f723794438fda9670f28c1ee804974fcb7e9f1ea4e41510"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 17:12:15.968913) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 17:12:15.969273) ========= 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.8U2MBuGlpr/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.puKQnXkOzG/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.004969 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 17:12:16.018118) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 17:12:16.018438) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 17:12:16.018789) ========= ========= Total master_lag: 0.20 =========