builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0188 starttime: 1475634040.57 results: success (0) buildid: 20161004172100 builduid: 2e42beacab544212b8734c54b3dc44cd revision: cda7c3e91ad7eb8119e1ae352cf362d9afa19918 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.573549) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.574153) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.574444) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.628252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:40.628516) ========= 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.TYmraXlM47/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.U1oFToyzOl/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-04 19:20:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 78.8M=0s 2016-10-04 19:20:41 (78.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.461574 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:41.111051) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:41.111345) ========= 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.TYmraXlM47/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.U1oFToyzOl/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.021921 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:41.159998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-04 19:20:41.160343) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cda7c3e91ad7eb8119e1ae352cf362d9afa19918 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cda7c3e91ad7eb8119e1ae352cf362d9afa19918 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TYmraXlM47/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.U1oFToyzOl/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-04 19:20:41,226 truncating revision to first 12 chars 2016-10-04 19:20:41,226 Setting DEBUG logging. 2016-10-04 19:20:41,226 attempt 1/10 2016-10-04 19:20:41,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cda7c3e91ad7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 19:20:42,426 unpacking tar archive at: mozilla-central-cda7c3e91ad7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.450595 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-04 19:20:42.632604) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:42.632960) ========= script_repo_revision: cda7c3e91ad7eb8119e1ae352cf362d9afa19918 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:42.633365) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:42.633636) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 19:20:42.652380) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-04 19:20:42.652874) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TYmraXlM47/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.U1oFToyzOl/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:20:42 INFO - MultiFileLogger online at 20161004 19:20:42 in /builds/slave/test 19:20:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:20:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:20:42 INFO - {'append_to_log': False, 19:20:42 INFO - 'base_work_dir': '/builds/slave/test', 19:20:42 INFO - 'blob_upload_branch': 'Firefox', 19:20:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:20:42 INFO - 'branch': 'Firefox', 19:20:42 INFO - 'buildbot_json_path': 'buildprops.json', 19:20:42 INFO - 'config_files': ('talos/mac_config.py',), 19:20:42 INFO - 'default_actions': ('clobber', 19:20:42 INFO - 'read-buildbot-config', 19:20:42 INFO - 'download-and-extract', 19:20:42 INFO - 'populate-webroot', 19:20:42 INFO - 'create-virtualenv', 19:20:42 INFO - 'install', 19:20:42 INFO - 'run-tests'), 19:20:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:20:42 INFO - 'download_minidump_stackwalk': True, 19:20:42 INFO - 'download_symbols': 'ondemand', 19:20:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:20:42 INFO - 'tooltool.py': '/tools/tooltool.py', 19:20:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:20:42 INFO - '/tools/misc-python/virtualenv.py')}, 19:20:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:20:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:20:42 INFO - 'installer_path': 'installer.exe', 19:20:42 INFO - 'log_level': 'info', 19:20:42 INFO - 'log_name': 'talos', 19:20:42 INFO - 'log_to_console': True, 19:20:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:20:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:20:42 INFO - 'opt_config_files': (), 19:20:42 INFO - 'pip_index': False, 19:20:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:20:42 INFO - 'cmd': ('bash', 19:20:42 INFO - '-c', 19:20:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:20:42 INFO - 'enabled': True, 19:20:42 INFO - 'halt_on_failure': False, 19:20:42 INFO - 'name': 'check_screen_resolution'},), 19:20:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:20:42 INFO - 'cmd': ('bash', 19:20:42 INFO - '-c', 19:20:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:20:42 INFO - 'enabled': True, 19:20:42 INFO - 'halt_on_failure': False, 19:20:42 INFO - 'name': 'check_screen_resolution'},), 19:20:42 INFO - 'run_cmd_checks_enabled': True, 19:20:42 INFO - 'sps_profile': False, 19:20:42 INFO - 'sps_profile_interval': 0, 19:20:42 INFO - 'suite': 'dromaeojs-e10s', 19:20:42 INFO - 'system_bits': '32', 19:20:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:20:42 INFO - 'title': 't-yosemite-r7-0188', 19:20:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:20:42 INFO - 'use_talos_json': True, 19:20:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:20:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:20:42 INFO - 'work_dir': 'build'} 19:20:42 INFO - [mozharness: 2016-10-05 02:20:42.844046Z] Running clobber step. 19:20:42 INFO - Running pre-action listener: _resource_record_pre_action 19:20:42 INFO - Running main action method: clobber 19:20:42 INFO - rmtree: /builds/slave/test/build 19:20:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:20:44 INFO - Running post-action listener: _resource_record_post_action 19:20:44 INFO - [mozharness: 2016-10-05 02:20:44.357888Z] Finished clobber step (success) 19:20:44 INFO - [mozharness: 2016-10-05 02:20:44.358028Z] Running read-buildbot-config step. 19:20:44 INFO - Running pre-action listener: _resource_record_pre_action 19:20:44 INFO - Running main action method: read_buildbot_config 19:20:44 INFO - Using buildbot properties: 19:20:44 INFO - { 19:20:44 INFO - "project": "", 19:20:44 INFO - "product": "firefox", 19:20:44 INFO - "script_repo_revision": "production", 19:20:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:20:44 INFO - "repository": "", 19:20:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:20:44 INFO - "buildid": "20161004172100", 19:20:44 INFO - "pgo_build": "False", 19:20:44 INFO - "basedir": "/builds/slave/test", 19:20:44 INFO - "buildnumber": 307, 19:20:44 INFO - "slavename": "t-yosemite-r7-0188", 19:20:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:20:44 INFO - "platform": "yosemite_r7", 19:20:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:20:44 INFO - "branch": "mozilla-central", 19:20:44 INFO - "repo_path": "mozilla-central", 19:20:44 INFO - "revision": "cda7c3e91ad7eb8119e1ae352cf362d9afa19918", 19:20:44 INFO - "stage_platform": "macosx64", 19:20:44 INFO - "builduid": "2e42beacab544212b8734c54b3dc44cd", 19:20:44 INFO - "slavebuilddir": "test" 19:20:44 INFO - } 19:20:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:20:44 INFO - Running post-action listener: _resource_record_post_action 19:20:44 INFO - [mozharness: 2016-10-05 02:20:44.361410Z] Finished read-buildbot-config step (success) 19:20:44 INFO - [mozharness: 2016-10-05 02:20:44.361491Z] Running download-and-extract step. 19:20:44 INFO - Running pre-action listener: _resource_record_pre_action 19:20:44 INFO - Running main action method: download_and_extract 19:20:44 INFO - mkdir: /builds/slave/test/build/tests 19:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:20:44 INFO - trying https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:20:44 INFO - Downloading https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:20:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 19:20:45 INFO - Downloaded 1199 bytes. 19:20:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:20:45 INFO - Using the following test package requirements: 19:20:45 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:20:45 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:20:45 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:20:45 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'jsshell-mac.zip'], 19:20:45 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:20:45 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:20:45 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:20:45 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:20:45 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 19:20:45 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:20:45 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:20:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:20:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:20:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:20:45 INFO - Fetch https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:20:45 INFO - Http code: 200 19:20:45 INFO - via: 1.1 75d3bdff1b8eb2920f04077d77f16ca0.cloudfront.net (CloudFront) 19:20:45 INFO - x-amz-cf-id: LhhHedETc354q_ARYRAKAiuC_FSTVSUN5zLvaleUiJYKVoP1oJ-Wig== 19:20:45 INFO - x-amz-version-id: 7r5MRpl34Z1bw3cePnzocwglbZOj6jJ9 19:20:46 INFO - Expected file size: 16476831 19:20:46 INFO - Obtained file size: 16476831 19:20:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:20:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:20:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:20:52 INFO - Fetch https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:20:53 INFO - Http code: 200 19:20:53 INFO - via: 1.1 2a47832c458ab2a6b20c8363f5aa35ea.cloudfront.net (CloudFront) 19:20:53 INFO - x-amz-cf-id: wfCAhP71lbcf_DPw1J0BPVfgQmUpn9fOcU9etYPASvSUlx2m3_30Wg== 19:20:53 INFO - x-amz-version-id: 7r5MRpl34Z1bw3cePnzocwglbZOj6jJ9 19:20:53 INFO - Expected file size: 16476831 19:20:53 INFO - Obtained file size: 16476831 19:21:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:21:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:21:00 INFO - Fetch https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:21:01 INFO - Http code: 200 19:21:01 INFO - via: 1.1 3344ddc09ccf1d185bb41add18940cc4.cloudfront.net (CloudFront) 19:21:01 INFO - x-amz-cf-id: WT8MaoKFcnbJrA1lLMMnlk-aYS2be1vjUsLNZwKJPL0HKx8mIgSnew== 19:21:01 INFO - x-amz-version-id: XobCOJzIponJYRjdli4AarZapzTrihz6 19:21:02 INFO - Expected file size: 14409006 19:21:02 INFO - Obtained file size: 14409006 19:21:02 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')]} 19:21:02 INFO - trying https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:21:02 INFO - Downloading https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 19:21:09 INFO - Downloaded 111664185 bytes. 19:21:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:21:09 INFO - mkdir: /builds/slave/test/properties 19:21:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:21:09 INFO - Writing to file /builds/slave/test/properties/build_url 19:21:09 INFO - Contents: 19:21:09 INFO - build_url:https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:21:09 INFO - Running post-action listener: _resource_record_post_action 19:21:09 INFO - Running post-action listener: set_extra_try_arguments 19:21:09 INFO - [mozharness: 2016-10-05 02:21:09.968325Z] Finished download-and-extract step (success) 19:21:09 INFO - [mozharness: 2016-10-05 02:21:09.968446Z] Running populate-webroot step. 19:21:09 INFO - Running pre-action listener: _resource_record_pre_action 19:21:09 INFO - Running main action method: populate_webroot 19:21:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:21:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:21:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:21:09 INFO - '--tppagecycles', 19:21:09 INFO - '7'], 19:21:09 INFO - 'tests': ['tsvgm']}}, 19:21:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:21:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:21:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:21:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:21:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:21:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:21:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['damp', 'tps']}, 19:21:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'tests': ['damp', 'tps']}, 19:21:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['dromaeo_dom']}, 19:21:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:21:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['basic_compositor_video']}, 19:21:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:21:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other-e10s': {'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['a11yr', 19:21:09 INFO - 'ts_paint', 19:21:09 INFO - 'tpaint', 19:21:09 INFO - 'sessionrestore', 19:21:09 INFO - 'sessionrestore_no_auto_restore', 19:21:09 INFO - 'tabpaint']}, 19:21:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['tsvgx', 19:21:09 INFO - 'tsvgr_opacity', 19:21:09 INFO - 'tart', 19:21:09 INFO - 'tscrollx', 19:21:09 INFO - 'cart']}, 19:21:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:21:09 INFO - 'tsvgr_opacity', 19:21:09 INFO - 'tart', 19:21:09 INFO - 'tscrollx', 19:21:09 INFO - 'cart']}, 19:21:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--disable-e10s'], 19:21:09 INFO - 'tests': ['tp5o']}, 19:21:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'tests': ['tp5o']}, 19:21:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--disable-e10s', 19:21:09 INFO - '--xperf_path', 19:21:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:21:09 INFO - 'tests': ['tp5n']}, 19:21:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:09 INFO - 'talos_options': ['--xperf_path', 19:21:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:21:09 INFO - 'tests': ['tp5n']}}, 19:21:09 INFO - 'talos.zip': {'path': '', 19:21:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:21:09 INFO - Running post-action listener: _resource_record_post_action 19:21:09 INFO - [mozharness: 2016-10-05 02:21:09.987664Z] Finished populate-webroot step (success) 19:21:09 INFO - [mozharness: 2016-10-05 02:21:09.987742Z] Running create-virtualenv step. 19:21:09 INFO - Running pre-action listener: _resource_record_pre_action 19:21:09 INFO - Running main action method: create_virtualenv 19:21:09 INFO - Creating virtualenv /builds/slave/test/build/venv 19:21:09 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 19:21:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:21:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:21:10 INFO - Using real prefix '/tools/python27' 19:21:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:21:10 INFO - Installing distribute.............................................................................................................................................................................................done. 19:21:13 INFO - Installing pip.................done. 19:21:13 INFO - Return code: 0 19:21:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:21:14 INFO - Reading from file tmpfile_stdout 19:21:14 INFO - Output received: 19:21:14 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) 19:21:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:14 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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:14 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 19:21:14 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 19:21:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:14 INFO - 'HOME': '/Users/cltbld', 19:21:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:14 INFO - 'LOGNAME': 'cltbld', 19:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:14 INFO - 'MOZ_NO_REMOTE': '1', 19:21:14 INFO - 'NO_EM_RESTART': '1', 19:21:14 INFO - 'PAGER': '/bin/cat', 19:21:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:14 INFO - 'PWD': '/builds/slave/test', 19:21:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:14 INFO - 'SHELL': '/bin/bash', 19:21:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:14 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:14 INFO - 'USER': 'cltbld', 19:21:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:14 INFO - 'XPC_FLAGS': '0x0', 19:21:14 INFO - 'XPC_SERVICE_NAME': '0', 19:21:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:14 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 19:21:14 INFO - Cleaning up... 19:21:14 INFO - Return code: 0 19:21:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:14 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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:14 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 19:21:14 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 19:21:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:14 INFO - 'HOME': '/Users/cltbld', 19:21:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:14 INFO - 'LOGNAME': 'cltbld', 19:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:14 INFO - 'MOZ_NO_REMOTE': '1', 19:21:14 INFO - 'NO_EM_RESTART': '1', 19:21:14 INFO - 'PAGER': '/bin/cat', 19:21:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:14 INFO - 'PWD': '/builds/slave/test', 19:21:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:14 INFO - 'SHELL': '/bin/bash', 19:21:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:14 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:14 INFO - 'USER': 'cltbld', 19:21:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:14 INFO - 'XPC_FLAGS': '0x0', 19:21:14 INFO - 'XPC_SERVICE_NAME': '0', 19:21:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:14 INFO - Downloading/unpacking psutil>=3.1.1 19:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:21:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:21:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:21:18 INFO - Installing collected packages: psutil 19:21:18 INFO - Running setup.py install for psutil 19:21:18 INFO - building 'psutil._psutil_osx' extension 19:21:18 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 19:21:18 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 19:21:18 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 19:21:18 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 19:21:18 INFO - building 'psutil._psutil_posix' extension 19:21:18 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 19:21:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:21:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:21:19 INFO - ^ 19:21:19 INFO - 1 warning generated. 19:21:19 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 19:21:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:21:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:21:19 INFO - Successfully installed psutil 19:21:19 INFO - Cleaning up... 19:21:19 INFO - Return code: 0 19:21:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:21:19 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')]} 19:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:19 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')]} 19:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:19 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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:19 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 19:21:19 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 19:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:19 INFO - 'HOME': '/Users/cltbld', 19:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:19 INFO - 'LOGNAME': 'cltbld', 19:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:19 INFO - 'MOZ_NO_REMOTE': '1', 19:21:19 INFO - 'NO_EM_RESTART': '1', 19:21:19 INFO - 'PAGER': '/bin/cat', 19:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:19 INFO - 'PWD': '/builds/slave/test', 19:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:19 INFO - 'SHELL': '/bin/bash', 19:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:19 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:19 INFO - 'USER': 'cltbld', 19:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:19 INFO - 'XPC_FLAGS': '0x0', 19:21:19 INFO - 'XPC_SERVICE_NAME': '0', 19:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:21: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 19:21: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 19:21: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 19:21: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 19:21: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 19:21: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 19:21:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:21:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:21:22 INFO - Installing collected packages: mozsystemmonitor 19:21:22 INFO - Running setup.py install for mozsystemmonitor 19:21:22 INFO - Successfully installed mozsystemmonitor 19:21:22 INFO - Cleaning up... 19:21:22 INFO - Return code: 0 19:21:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:21:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:22 INFO - 'HOME': '/Users/cltbld', 19:21:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:22 INFO - 'LOGNAME': 'cltbld', 19:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:22 INFO - 'MOZ_NO_REMOTE': '1', 19:21:22 INFO - 'NO_EM_RESTART': '1', 19:21:22 INFO - 'PAGER': '/bin/cat', 19:21:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:22 INFO - 'PWD': '/builds/slave/test', 19:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:22 INFO - 'SHELL': '/bin/bash', 19:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:22 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:22 INFO - 'USER': 'cltbld', 19:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:22 INFO - 'XPC_FLAGS': '0x0', 19:21:22 INFO - 'XPC_SERVICE_NAME': '0', 19:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:22 INFO - Downloading/unpacking jsonschema==2.5.1 19:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:21:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:21:25 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 19:21:25 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 19:21:25 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 19:21:25 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 19:21:25 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 19:21:25 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 19:21:25 INFO - Downloading functools32-3.2.3-2.tar.gz 19:21:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:21:25 INFO - warning: no files found matching '*.txt' 19:21:25 INFO - no previously-included directories found matching 'build' 19:21:25 INFO - no previously-included directories found matching 'dist' 19:21:25 INFO - no previously-included directories found matching '.git*' 19:21:25 INFO - Installing collected packages: jsonschema, functools32 19:21:25 INFO - Running setup.py install for functools32 19:21:25 INFO - warning: no files found matching '*.txt' 19:21:25 INFO - no previously-included directories found matching 'build' 19:21:25 INFO - no previously-included directories found matching 'dist' 19:21:25 INFO - no previously-included directories found matching '.git*' 19:21:25 INFO - Successfully installed jsonschema functools32 19:21:25 INFO - Cleaning up... 19:21:25 INFO - Return code: 0 19:21:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:25 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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:25 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 19:21:25 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 19:21:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:25 INFO - 'HOME': '/Users/cltbld', 19:21:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:25 INFO - 'LOGNAME': 'cltbld', 19:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:25 INFO - 'MOZ_NO_REMOTE': '1', 19:21:25 INFO - 'NO_EM_RESTART': '1', 19:21:25 INFO - 'PAGER': '/bin/cat', 19:21:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:25 INFO - 'PWD': '/builds/slave/test', 19:21:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:25 INFO - 'SHELL': '/bin/bash', 19:21:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:25 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:25 INFO - 'USER': 'cltbld', 19:21:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:25 INFO - 'XPC_FLAGS': '0x0', 19:21:25 INFO - 'XPC_SERVICE_NAME': '0', 19:21:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:21:26 INFO - Cleaning up... 19:21:26 INFO - Return code: 0 19:21:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:21: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')]} 19:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21: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')]} 19:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:21:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:26 INFO - 'HOME': '/Users/cltbld', 19:21:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:26 INFO - 'LOGNAME': 'cltbld', 19:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:26 INFO - 'MOZ_NO_REMOTE': '1', 19:21:26 INFO - 'NO_EM_RESTART': '1', 19:21:26 INFO - 'PAGER': '/bin/cat', 19:21:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:26 INFO - 'PWD': '/builds/slave/test', 19:21:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:26 INFO - 'SHELL': '/bin/bash', 19:21:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:26 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:26 INFO - 'USER': 'cltbld', 19:21:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:26 INFO - 'XPC_FLAGS': '0x0', 19:21:26 INFO - 'XPC_SERVICE_NAME': '0', 19:21:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:26 INFO - Downloading/unpacking blobuploader==1.2.4 19:21:26 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 19:21:26 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 19:21:26 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 19:21:26 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 19:21:26 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 19:21:26 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 19:21:28 INFO - Downloading blobuploader-1.2.4.tar.gz 19:21:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:21:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:21:29 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 19:21:29 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 19:21:29 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 19:21:29 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 19:21:29 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 19:21:29 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 19:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:21:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:21:29 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 19:21:29 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 19:21:29 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 19:21:29 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 19:21:29 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 19:21:29 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 19:21:30 INFO - Downloading docopt-0.6.1.tar.gz 19:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:21:30 INFO - Installing collected packages: blobuploader, requests, docopt 19:21:30 INFO - Running setup.py install for blobuploader 19:21:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:21:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:21:30 INFO - Running setup.py install for requests 19:21:30 INFO - Running setup.py install for docopt 19:21:30 INFO - Successfully installed blobuploader requests docopt 19:21:30 INFO - Cleaning up... 19:21:31 INFO - Return code: 0 19:21:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:21: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')]} 19:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21: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')]} 19:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:31 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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:31 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 19:21:31 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 19:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:31 INFO - 'HOME': '/Users/cltbld', 19:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:31 INFO - 'LOGNAME': 'cltbld', 19:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:31 INFO - 'MOZ_NO_REMOTE': '1', 19:21:31 INFO - 'NO_EM_RESTART': '1', 19:21:31 INFO - 'PAGER': '/bin/cat', 19:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:31 INFO - 'PWD': '/builds/slave/test', 19:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:31 INFO - 'SHELL': '/bin/bash', 19:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:31 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:31 INFO - 'USER': 'cltbld', 19:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:31 INFO - 'XPC_FLAGS': '0x0', 19:21:31 INFO - 'XPC_SERVICE_NAME': '0', 19:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:21:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-s5AkuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:21:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-s7dK0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:21:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-__gnyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:21:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-eAOItC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:21:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-hntSOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:21:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-A7nw6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:21:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-184UPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-wH3S3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-MpsI5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-KhAyvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ACsuKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-785omy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-1HuEuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-qDAz2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-UDmoBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-MTgWKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:21:32 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-7vyjvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:21:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:21:33 INFO - Running setup.py install for manifestparser 19:21:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:21:33 INFO - Running setup.py install for mozcrash 19:21:33 INFO - Running setup.py install for mozdebug 19:21:33 INFO - Running setup.py install for mozdevice 19:21:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:21:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:21:33 INFO - Running setup.py install for mozfile 19:21:33 INFO - Running setup.py install for mozhttpd 19:21:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:21:33 INFO - Running setup.py install for mozinfo 19:21:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for mozInstall 19:21:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for mozleak 19:21:34 INFO - Running setup.py install for mozlog 19:21:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for moznetwork 19:21:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:21:34 INFO - Running setup.py install for mozprocess 19:21:34 INFO - Running setup.py install for mozprofile 19:21:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Running setup.py install for mozrunner 19:21:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Running setup.py install for mozscreenshot 19:21:35 INFO - Running setup.py install for moztest 19:21:35 INFO - Running setup.py install for mozversion 19:21:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:21:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:21:35 INFO - Cleaning up... 19:21:35 INFO - Return code: 0 19:21:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:35 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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:35 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 19:21:35 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 19:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:35 INFO - 'HOME': '/Users/cltbld', 19:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:35 INFO - 'LOGNAME': 'cltbld', 19:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:35 INFO - 'MOZ_NO_REMOTE': '1', 19:21:35 INFO - 'NO_EM_RESTART': '1', 19:21:35 INFO - 'PAGER': '/bin/cat', 19:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:35 INFO - 'PWD': '/builds/slave/test', 19:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:35 INFO - 'SHELL': '/bin/bash', 19:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:35 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:35 INFO - 'USER': 'cltbld', 19:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:35 INFO - 'XPC_FLAGS': '0x0', 19:21:35 INFO - 'XPC_SERVICE_NAME': '0', 19:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:21:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-nq_Ch0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:21:36 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)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-874v2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-k6Ha41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:21:36 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)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-hA2R4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-9AAvso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:21:36 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)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-SMqbT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:21:36 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)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-JcwTdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:21:36 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)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-JRzt45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:21:36 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)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-YBeRK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:21:36 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)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-IraPlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:21:36 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)) 19:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:21:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-uBYgT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:21:37 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)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:21:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-QYu5jB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:21:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-7kpcmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:21:37 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)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:21:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-lQ6rc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-OMJqZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:37 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)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:21:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-68Topa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:21:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZafLJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:21:37 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)) 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:21:37 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)) 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:21:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:21: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 19:21: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 19:21: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 19:21: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 19:21: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 19:21: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 19:21:40 INFO - Downloading blessings-1.6.tar.gz 19:21:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:21:40 INFO - Installing collected packages: blessings 19:21:40 INFO - Running setup.py install for blessings 19:21:40 INFO - Successfully installed blessings 19:21:40 INFO - Cleaning up... 19:21:40 INFO - Return code: 0 19:21:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:21:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:21:40 INFO - Reading from file tmpfile_stdout 19:21:40 INFO - Current package versions: 19:21:40 INFO - blessings == 1.6 19:21:40 INFO - blobuploader == 1.2.4 19:21:40 INFO - docopt == 0.6.1 19:21:40 INFO - functools32 == 3.2.3-2 19:21:40 INFO - jsonschema == 2.5.1 19:21:40 INFO - manifestparser == 1.1 19:21:40 INFO - mozInstall == 1.12 19:21:40 INFO - mozcrash == 0.17 19:21:40 INFO - mozdebug == 0.1 19:21:40 INFO - mozdevice == 0.48 19:21:40 INFO - mozfile == 1.2 19:21:40 INFO - mozhttpd == 0.7 19:21:40 INFO - mozinfo == 0.9 19:21:40 INFO - mozleak == 0.1 19:21:40 INFO - mozlog == 3.4 19:21:40 INFO - moznetwork == 0.27 19:21:40 INFO - mozprocess == 0.23 19:21:40 INFO - mozprofile == 0.28 19:21:40 INFO - mozrunner == 6.12 19:21:40 INFO - mozscreenshot == 0.1 19:21:40 INFO - mozsystemmonitor == 0.3 19:21:40 INFO - moztest == 0.7 19:21:40 INFO - mozversion == 1.4 19:21:40 INFO - psutil == 3.1.1 19:21:40 INFO - requests == 1.2.3 19:21:40 INFO - wsgiref == 0.1.2 19:21:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:40 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 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:40 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 19:21:40 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 19:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:40 INFO - 'HOME': '/Users/cltbld', 19:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:40 INFO - 'LOGNAME': 'cltbld', 19:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:40 INFO - 'MOZ_NO_REMOTE': '1', 19:21:40 INFO - 'NO_EM_RESTART': '1', 19:21:40 INFO - 'PAGER': '/bin/cat', 19:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:40 INFO - 'PWD': '/builds/slave/test', 19:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:40 INFO - 'SHELL': '/bin/bash', 19:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:40 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:40 INFO - 'USER': 'cltbld', 19:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:40 INFO - 'XPC_FLAGS': '0x0', 19:21:40 INFO - 'XPC_SERVICE_NAME': '0', 19:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 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)) 19:21:41 INFO - Cleaning up... 19:21:41 INFO - Return code: 0 19:21:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa23dc73a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, '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.TYmraXlM47/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.U1oFToyzOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:21:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:21:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:21:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:41 INFO - 'HOME': '/Users/cltbld', 19:21:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:41 INFO - 'LOGNAME': 'cltbld', 19:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:41 INFO - 'MOZ_NO_REMOTE': '1', 19:21:41 INFO - 'NO_EM_RESTART': '1', 19:21:41 INFO - 'PAGER': '/bin/cat', 19:21:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:41 INFO - 'PWD': '/builds/slave/test', 19:21:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:41 INFO - 'SHELL': '/bin/bash', 19:21:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:21:41 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:21:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:41 INFO - 'USER': 'cltbld', 19:21:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:41 INFO - 'XPC_FLAGS': '0x0', 19:21:41 INFO - 'XPC_SERVICE_NAME': '0', 19:21:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:21:41 INFO - Cleaning up... 19:21:41 INFO - Return code: 0 19:21:41 INFO - Running post-action listener: _resource_record_post_action 19:21:41 INFO - Running post-action listener: _start_resource_monitoring 19:21:41 INFO - Starting resource monitoring. 19:21:41 INFO - [mozharness: 2016-10-05 02:21:41.356338Z] Finished create-virtualenv step (success) 19:21:41 INFO - [mozharness: 2016-10-05 02:21:41.357412Z] Running install step. 19:21:41 INFO - Running pre-action listener: _resource_record_pre_action 19:21:41 INFO - Running main action method: install 19:21:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:21:41 INFO - Reading from file tmpfile_stdout 19:21:41 INFO - Detecting whether we're running mozinstall >=1.0... 19:21:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:21:41 INFO - Reading from file tmpfile_stdout 19:21:41 INFO - Output received: 19:21:41 INFO - Usage: mozinstall [options] installer 19:21:41 INFO - Options: 19:21:41 INFO - -h, --help show this help message and exit 19:21:41 INFO - -d DEST, --destination=DEST 19:21:41 INFO - Directory to install application into. [default: 19:21:41 INFO - "/builds/slave/test"] 19:21:41 INFO - --app=APP Application being installed. [default: firefox] 19:21:41 INFO - mkdir: /builds/slave/test/build/application 19:21:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:22:16 INFO - Reading from file tmpfile_stdout 19:22:16 INFO - Output received: 19:22:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:22:16 INFO - Running post-action listener: _resource_record_post_action 19:22:16 INFO - [mozharness: 2016-10-05 02:22:16.491413Z] Finished install step (success) 19:22:16 INFO - [mozharness: 2016-10-05 02:22:16.491552Z] Running run-tests step. 19:22:16 INFO - Running pre-action listener: _resource_record_pre_action 19:22:16 INFO - Running main action method: run_tests 19:22:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:22:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:22:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:22:16 INFO - 2016-10-04 19:22:16.505 screenresolution[1928:16346] starting screenresolution argv=screenresolution get 19:22:16 INFO - 2016-10-04 19:22:16.508 screenresolution[1928:16346] Display 0: 1600x1200x32@60 19:22:16 INFO - 2016-10-04 19:22:16.515 screenresolution[1929:16349] starting screenresolution argv=screenresolution list 19:22:16 INFO - Available Modes on Display 0 19:22:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:22:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:22:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:22:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:22:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:22:16 INFO - Intel Iris: 19:22:16 INFO - Chipset Model: Intel Iris 19:22:16 INFO - Type: GPU 19:22:16 INFO - Bus: Built-In 19:22:16 INFO - VRAM (Dynamic, Max): 1536 MB 19:22:16 INFO - Vendor: Intel (0x8086) 19:22:16 INFO - Device ID: 0x0a2e 19:22:16 INFO - Revision ID: 0x0009 19:22:16 INFO - Displays: 19:22:16 INFO - Display: 19:22:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:22:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:22:16 INFO - Display Serial Number: 1600x1200 60 19:22:16 INFO - Main Display: Yes 19:22:16 INFO - Mirror: Off 19:22:16 INFO - Online: Yes 19:22:16 INFO - Rotation: Supported 19:22:16 INFO - Return code: 0 19:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:22:16 INFO - Python 2.7.3 19:22:16 INFO - Return code: 0 19:22:16 INFO - grabbing minidump binary from tooltool 19:22:16 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')]} 19:22:16 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 0x10ca09d30>, '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 0x10c82fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:22:16 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 19:22:16 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 19:22:16 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 19:22:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:22:16 INFO - Return code: 0 19:22:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:22:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:22:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:22:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:22:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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 19:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0188 --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 19:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYmraXlM47/Render', 19:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:16 INFO - 'HOME': '/Users/cltbld', 19:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:16 INFO - 'LOGNAME': 'cltbld', 19:22:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:22:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:16 INFO - 'MOZ_NO_REMOTE': '1', 19:22:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:22:16 INFO - 'NO_EM_RESTART': '1', 19:22:16 INFO - 'PAGER': '/bin/cat', 19:22:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:16 INFO - 'PWD': '/builds/slave/test', 19:22:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:16 INFO - 'SHELL': '/bin/bash', 19:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1oFToyzOl/Listeners', 19:22:16 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:16 INFO - 'USER': 'cltbld', 19:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:16 INFO - 'XPC_FLAGS': '0x0', 19:22:16 INFO - 'XPC_SERVICE_NAME': '0', 19:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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 19:22:17 INFO - mozversion application_buildid: 20161004172100 19:22:17 INFO - mozversion application_changeset: cda7c3e91ad7eb8119e1ae352cf362d9afa19918 19:22:17 INFO - mozversion application_display_name: Nightly 19:22:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:22:17 INFO - mozversion application_name: Firefox 19:22:17 INFO - mozversion application_remotingname: firefox 19:22:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:22:17 INFO - mozversion application_vendor: Mozilla 19:22:17 INFO - mozversion application_version: 52.0a1 19:22:17 INFO - mozversion platform_buildid: 20161004172100 19:22:17 INFO - mozversion platform_changeset: cda7c3e91ad7eb8119e1ae352cf362d9afa19918 19:22:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:22:17 INFO - mozversion platform_version: 52.0a1 19:22:17 INFO - using testdate: 1475634137 19:22:17 INFO - actual date: 1475634137 19:22:17 INFO - starting webserver on 'localhost:51762' 19:22:17 INFO - SUITE-START | Running 2 tests 19:22:17 INFO - TEST-START | dromaeo_css 19:22:17 INFO - operating with platform_type : mac_ 19:22:17 INFO - Initialising browser for dromaeo_css test... 19:22:17 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmphwOEIc/profile http://localhost:51762/getInfo.html) 19:22:19 INFO - PROCESS | 1939 | 2016-10-04 19:22:19.822 plugin-container[1941:16509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c4b, name = 'com.apple.tsm.portname' 19:22:19 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:19 INFO - PROCESS | 1939 | 2016-10-04 19:22:19.825 plugin-container[1941:16509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x492b, name = 'com.apple.CFPasteboardClient' 19:22:19 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:19 INFO - PROCESS | 1939 | 2016-10-04 19:22:19.825 plugin-container[1941:16509] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:22:19 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 19:22:19 INFO - PROCESS | 1939 | colorDepth:24 19:22:19 INFO - PROCESS | 1939 | Browser inner width/height: 1024/663 19:22:19 INFO - PROCESS | 1939 | __metrics 19:22:19 ERROR - PROCESS | 1939 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 19:22:20 INFO - TEST-INFO | 1939: exit 0 19:22:20 INFO - Browser initialized. 19:22:20 INFO - Running cycle 1/1 for dromaeo_css test... 19:22:20 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmphwOEIc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:22:23 INFO - PROCESS | 1942 | 2016-10-04 19:22:23.306 plugin-container[1943:16631] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d27, name = 'com.apple.tsm.portname' 19:22:23 INFO - PROCESS | 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:23 INFO - PROCESS | 1942 | 2016-10-04 19:22:23.308 plugin-container[1943:16631] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9203, name = 'com.apple.CFPasteboardClient' 19:22:23 INFO - PROCESS | 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:23 INFO - PROCESS | 1942 | 2016-10-04 19:22:23.308 plugin-container[1943:16631] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:22:32 INFO - PROCESS | 1942 | RSS: Main: 252821504 19:22:32 INFO - PROCESS | 1942 | 19:23:22 INFO - PROCESS | 1942 | 1475634202429 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:25:34 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51762/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51762/tests/dromaeo/cssquery-ext.html) 19:25:35 INFO - PROCESS | 1942 | RSS: Main: 267583488 19:25:35 INFO - PROCESS | 1942 | 19:28:38 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51762/tests/dromaeo/cssquery-ext.html (next: http://localhost:51762/tests/dromaeo/cssquery-jquery.html) 19:28:38 INFO - PROCESS | 1942 | RSS: Main: 278392832 19:28:38 INFO - PROCESS | 1942 | 19:31:40 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51762/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51762/tests/dromaeo/cssquery-mootools.html) 19:31:41 INFO - PROCESS | 1942 | RSS: Main: 281006080 19:31:41 INFO - PROCESS | 1942 | 19:34:43 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51762/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51762/tests/dromaeo/cssquery-prototype.html) 19:34:44 INFO - PROCESS | 1942 | RSS: Main: 280305664 19:34:44 INFO - PROCESS | 1942 | 19:37:46 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51762/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51762/tests/dromaeo/cssquery-yui.html) 19:37:47 INFO - PROCESS | 1942 | RSS: Main: 285564928 19:37:47 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51762/tests/dromaeo/cssquery-yui.html (next: http://localhost:51762/tests/dromaeo/cssquery-dojo.html) 19:40:50 INFO - PROCESS | 1942 | RSS: Main: 288468992 19:40:50 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | __start_tp_report 19:40:50 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 19:40:50 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 19:40:50 INFO - PROCESS | 1942 | |i|pagename|runs| 19:40:50 INFO - PROCESS | 1942 | |0;dojo.html;3323.4682450807877;3383.2049468374926;3385.526026356309;3398.541196938411;3416.419208734515;5684.090545512719;5719.1421286807;5761.078227483604;5798.797042103527;5810.3027636683655;5542.5565954723625;5571.470710282517;5715.114157305614;5758.942410575893;5810.883782324351;5033.7734801934;5041.823536176225;5075.467075957026;5113.386393632769;5200.297959775438;187.6121060832637;5157.7163256021095;5180.663256888309;5222.112240919053;5229.163333866582;4259.893502662428;5086.415062267831;5133.743312834381;5203.583713302945;5227.059129356714;5204.8959020819375;5227.764750586195;5258.3689957205;5282.471752824726;5293.788248470057;5087.542121209072;5170.560502357304;5199.194124910645;5205.947940520584;5261.316028916216;5403.7027963462;5559.749811258463;5622.550195984311;5672.432756724335;5756;6162.630242185483;6194.380561943814;6209.3790620938;6236.095766113934;6246.3441338659695;6208.192934918477;6211.689415529206;6246.750129994794;6271.43557079865;6294.213223347082;5596.524295434893;5671.432856714296;5697.772089116472;5765.933302338997;5766.538676905838;6255.718492667841;6284.277308109626;6285.151504546919;6294.874102518015;6328.367163283635;6120.785772497941;6125.387461253839;6155.322914479404;6187.071939209155;6210.099535567318;6112.113743507167;6142.222533142175;6158.507319414436;6192.473639740672;6205.596636218631;6147.432404736764;6230;6237.656928868956;6240.687965601793;6252.968735156295;4464.397306363664;5090.312807770903;6148.661823599745;6177.96911015442;6190.783322583778;4833.347498087784;4834.879128021757;4886.486918873538;4914.115459217303;4927.7536123193695;3913.767163343595;4380.759058251763;4405.405270288534;4449.7997590108525;4516.593506584423;5281.808049659348;5800.767969281266;5824.271966004249;5866.413358664185;5913.438223368773;6109.47263184204;6138.143176733699;6154.076888466676;6215;6215.254169499689;5902.940970590239;5908;5916.970415147896;5925.703714814242;5935.643861368331;5403.081476149064;5419.214213938957;5427.0773968425465;5434.3207099112615;5436.673799572039;6180.381961803873;6252.5935814171935;6279.96860015697;6281.3404592518955;6373.458256048195;6202.56582039271;6222.875542489215;6240.344763799736;6254.374562543891;6263.279722831932;6347.936520634919;6381.521385895983;6387.68061596909;6411.743530258924;6415.743370265137;6026.90959635597;6107.083937409424;6108.694565271808;6188;6197.690115494297;5112.233165025276;6071.362506936708;6164.630122192503;6229.501639868709;6247.219097612799;6122.418370254819;6132.325444201224;6154.507639388748;6158.353372895782;6163.414475624809;5932.2584676915385;5975.701214939322;6060.393960603904;6140.109684095692;6143.170671959407;4951.579115775199;5236.162214045735;5762.337331206797;5764.394738552392;5840.357560668238;4884.682495637914;4943.876403089952;6084.817455476343;6131.662758548323;6171.660558669317;5115.846524604268;5941.197938278277;5969.313528944227;5970.373110823301;5982.312034115958 19:40:50 INFO - PROCESS | 1942 | |1;ext.html;458.16385097197605;479.6810121269356;491.95080491950785;528.9100852855015;532.9840104796855;40071.79964100179;42081.78959105204;42186.578134218726;42267.36598951018;42271.57728422715;43301;43786.78106609471;43954.90112747183;43971;44017.899552511204;9854.704358869245;10115.696529104138;10120.190384769201;10123;10138;9951.303408761389;10018.99810018996;10036.447995360253;10062.33971394719;10064.345817521875;9998.800023999553;10043.64847230347;10049.397036177816;10071.496425178748;10076.748081297952;10006.89993100067;10040.949795250977;10067.043630855058;10072.244581656405;10088.596456141746;9760.853587196198;9816.558254878548;9921.107100361001;9923.25575581827;9935.950320248352;41414;41590;41761.164776704296;41811.581884181076;41825;3287.7205437537837;3308.520264561625;3360.7647464677484;3822.311983842907;4027.234825383197;22404.215852445086;25982.480350392885;31096;31459.84270078635;31608.841955790074;3188.4898416253286;3207.182168547029;3230.709236168733;3240.303334783009;3300.092474569489;477240;480557;481449;486549.567252165;487839;53144.671383214954;63664.68167659132;64066.67966660137;64185.67907160434;64192.67903660452;54998.45001550013;65784.67107664431;66975.66512167409;67107;67342.65314693679;16328.510144695678;16440.095794731165;16636.83363166377;16676.83323166777;16688.6662266754;12742.299173545543;12768.80846787299;12777.105602607915;12796.552120675919;12820.35898205086;18932.864028158354;19380.4185874424;19442.4167274982;19462.38323216353;19483.123259453358;6057.212562366864;7300.342969132804;7562.697492100365;7712.5758083305955;7741.690332386754;832.7252006837729;1016.2987538598344;1026.6201505443034;1053.0364716284666;1085.468120620906;28646.997355092888;29265.85367073151;29614.111576652736;29863.253418664706;29937;980.3578655980344;1010.7119470950744;1040.4017689828227;1044.5612842606124;1072.3137192197;14413.567592972227;15326.551525906581;15357.462488812838;15365.802737928432;15707.842921570638;533961;670770;672772;674567;698212;51588;56988.71505642445;57448.138277925864;57810.710946445;57947.71026144927;1163.947622356996;1218.7257866979858;1238.9876101239051;1333.7799263121476;1334.8598397168157;23040.539189216455;28358.574621381107;29435.558466622588;29875.701242987292;29960.850195749754;40167;49951.500484994685;50167.498325016284;50341.24488132754;50446.99106017932;548122;551156;551452.340242565;552498;553744;26427.60358594661;26534.938602455684;26926.32684182911;26981.0556630517;27070.323241919108;46486.302705458766;46940.765296173304;47787.522124779694;47956.80107997328;48748.5125148744;38878.4168237471;38900.221995560496;38977.2204555913;40259.59740402558;40429.595704042586;38162;38307.61692383041;38382.84851454461;39491.40762888616;39728;32385.51421728723;32722.50916236306;35828.64171358253;38442.615573845025;38472.80763596276;28735.563221839242;29066.7093329064;32812.58592332238;33372.165695857795;34013.21917228254;24298.906549204974;25373.238802835946;25631.487370252864;26125.08562200314;26380.8680956594 19:40:50 INFO - PROCESS | 1942 | |2;jquery.html;362.50880057522056;363.28795560701025;370.1745108408249;381.4069122514489;395.4305799648508;19976.400707978766;20888.268910588133;21081.894590527045;21581.56836863262;21780.782192178074;20744;20823.895880520613;20912.163513459443;21162.682559761615;21269.68095478565;5858.5606079543995;5940.554458415614;6155.938440615604;6157.322694503601;6204.6587437690905;4200.264953633102;4434.844780432685;6051.394860513958;6184.350643182469;6192.9380706192815;6467.256265529477;6800.149981252343;6869.416099631538;6916.170059592856;6931.618760968145;6307.873842523147;6864.691088900987;6913.20498142715;6957.304269573053;6957.9652101739675;5972.163897054415;5991.3109992351;6056.636601803906;6060.575759696823;6069.666168360738;26530.6020409694;26632.201033969013;26675.46649067027;26894;27029.053983110498;2210.436338733629;2374.4063984004;2425.357280320716;2623.527765002299;2791.567307067408;15550.61123471911;15695.450659226872;15768.290426930816;15913;16361.918190409091;1854.7496088028079;1905.0855589317066;1910.0831600831539;1928.5853541488648;1957.1388589020785;113099.30351044741;114022;114811.42594287143;115102;115109;10773.35359878394;10850.023497885228;10919.290246133976;11024.338539687644;11090.722731931766;13690.52083177084;14035.859641403455;14039.157650541;14056.875449963978;14063.43746250159;6217.751289948442;6666.799996000128;8303.294219991247;8625.223729864343;8770.780730481662;8535.317174626016;8621.741347759576;8633.395662303705;8673.653053877773;8779.165979231962;6592.5055620828625;6703.497237707192;6739.528233023641;6754.425873800684;6789.796306110825;1573.5908663747507;1654.1729135432283;1840.8067152948747;1877.7934427212995;1918.8103375907026;801.294860522748;816.457056057715;864.9091845356273;914.3553794574809;917.7980844214262;20568.794312056987;22114.668279975813;22162.66755998629;22334.888325558593;22377.992990315473;787.6061969015492;907.6026650876493;938.380668758616;970.7912798748303;990.8711867457184;9381.484018379055;11081.72295692598;11416.543338266543;11428.771424571629;11453.656390308304;508298;508998;514823;606769;609543;123098.38311318301;134313;138046.3097684525;148871;151311;977.8679878216492;1000.1698590170141;1005.1004351105865;1022.1260821997234;1024.1039090795555;18462.723059153854;18697.439076827715;19820.70268945938;19849.00754962275;19922.003899804662;49116.75441622769;50579;54916.72541637426;56777.432225677214;58105;85812.28375432332;86821;86974.56512717396;89151.55424222838;89539;5895.823125306248;7897.960510197412;8093.3929955252415;8199.672013119409;8232.753017409488;10762.19283553721;11863;12114.636560903187;12782.61652150437;12948.870511295143;11018.724531886766;11240.55037798479;11245.156613254203;11345.829812552984;11546.249493782878;10833.02502774754;11033.558657653604;11134.777304453704;11175.72060698489;11389.886101138882;9178.449293042439;10915.345079295268;11368.204225704121;11565.537378505102;11701.356425396685;7875.133735289228;8134.796630084295;8152.877706834521;8216.2605365515;8342.749717508485;5451.155070964007;9825.803483930424;10780.083692886192;11006.889931100906;11074.390908499787 19:40:50 INFO - PROCESS | 1942 | |3;mootools.html;1340.2427628389962;1576.1410031532814;1750.282384222469;1859.553707110294;1888.7544619199502;8148.307393871513;8396.958015209915;8520.701775437854;9111.362204645677;9131.863022054666;8541.572921353923;8880.24517915978;8889.688860889853;8995.59519821608;8998.19016288534;2191.463091542574;2230.69885565449;2236.966445503319;2237.1722462688745;2249.2465024216826;2317.7682231776857;2345.9296221113395;2363.621820508719;2365.9408514787183;2372.7508611595795;2348.0725113580124;2363.7045369328835;2368.484170131109;2375.5486457573093;2377.1442280778992;2323.523677646087;2323.9307287969837;2342.2387723989773;2346.8500434786833;2347.192661650528;2319.478117423583;2320.037184568419;2323.93028209154;2326.860388376686;2340.742518322983;6980.162380514371;8221.383396245246;8435.789105272355;8891.555422228863;8935.955320223422;4295.926018495376;5347.144456886879;5359.22291267764;5370.03339398912;5384.219288203228;3287.342531493711;3412;3458.360203362386;3651.379265524867;3655.963440365589;880.3221519430067;921.7234829551107;1042.796654652343;1103.453790373762;1105.8175401058816;9218;10526.000029997214;11832.290062596272;11839.704007399798;11976.940115299367;4569.177548041318;4600.838970636011;4801.18379875415;4816.54242846929;4826.975865120652;8766.956165219133;9993.450360230112;10131.13885319756;10145.543450544743;10154.796904062025;5268.683878967274;5270.156774915995;5275.182346736262;5277.551408130275;5300.01949639318;4385.3641222022625;4473.642108631302;4761.190597598416;4813.422389313235;4819.614430845524;3390.7287417006582;3868.806559672061;3975.634497473998;3997.080671445584;4008.719389642697;1005.9798804023878;1159.5999380213814;1271.8664540223328;1291.1542939374724;1305.3929922586044;784.3098073695112;900.617237673986;925.4493576322078;936.5249731672643;939.3096074385321;5694.117411801178;5808.593398462067;5927.644341339447;6043.7884674036195;6148.1699970503405;426.42219792181146;440.2471773267684;440.7707991844223;810.8175660476346;1021.4382089850552;3831.7892515911894;3944.5069366329208;4048.2510735514024;4050.858219962346;4105.34314509683;10791.136709063256;10959.890401096047;11043.503042362952;11047;11051.668449946514;10965.725856853483;10990.340579565252;11001.559937602406;11006.119510439146;11011.064059554867;5970.611910225822;7806.843863122705;8241.299489543457;8395.53824539644;8402.453840500348;5395.784168633368;5943.554233432596;6062.393760623902;6213.5339849510565;6268.310485846644;9818.558164882455;10039.49802509857;10136.949315253376;10236.641717539851;10348.741281468023;7698.46110772263;8508.404411691123;10029.498525073863;10443.268971171945;10468.895311046792;7566.886496702664;7600.643800421666;7622.961885190539;7635.23647635232;7641.541507509567;7245.420366370783;8288.50268983863;8326.25063744266;8423.231121464562;8433.789155271168;6346.809595712136;7545.849083018418;7567.659455324413;7590.1650818408825;7652.693892244248;6251.53113516479;7352.044234249727;7389.482736208413;7546.9245307546225;7593.848123037398;6865.141857267841;7029.648517573998;7032.859342813217;7046.788596342118;7055.223925368308;4549.954500454953;4988.5260900214425;5074.797008119782;5093.694378337312;5165.767540460613;4227.471566054243;4452.732836029849;4945.703257804544;5097.898042039213;5187.014467251211 19:40:50 INFO - PROCESS | 1942 | |4;prototype.html;257.55443083465605;298.11311348737496;303.91490382692854;311.34772651295543;313.41548012955815;4672.46266679332;5304.814331498398;5561.249231353766;5594.916076258859;5688.943110568893;4101.384792281159;4968.900621987558;5283.471652834724;5509.685931908598;5532.640378375403;3472.565929258849;3635.672789448949;4091.9386209206737;4219.831206751726;4246.450045733301;3695.542464261263;4094.6110119538744;4227.87316380509;4243.029879084637;4262.893427664302;3622.7464077514583;4176.2900306947895;4185.790710464465;4190.895227619319;4204.474440694913;3475.6350583188655;3590.6768390844686;4176.248275310443;4182.874513764592;4201;3264.9183770405693;3293.8023718576724;3691.8892433227047;3715.3126671565847;3727.9627203727887;3979.1047014421815;5400.810971616014;5427.728613569345;5431.565474762009;5433.755481003325;4048.4534587830562;4050.2102090092444;4345.54476720655;4618.399608050931;4904.337914381548;4876.341693871317;5642.266505354278;5792.710364481759;5794.92793833138;5959.970200148971;3813.4089216171537;3919;4009.3184158693093;4055;4058.7473951956654;6077.232116088054;6142;6148.9692551536955;6204.534659900481;6255.499560035232;4134.883581432996;4136.482939632546;4144.8134833932545;4152.294110001307;4155.58444155582;4010.6791456683395;4153.979230103831;4159.812808423628;4210.326347784401;4223.640990515779;4133;4758;4843.951560484421;4844.32179494871;4881.780319885614;4810.632557209897;4847.418309802846;5284.365876094893;5693.946619875336;5876.529877609781;1009.9646512372032;1329.0696512441136;1414.766563517008;1420.3395421129223;1421.46694989379;789.72754399732;842.6713581703141;861.3324673378057;870.3602851903846;883.2139395937621;3890.6109389060866;4148.481439820022;4521.123733650564;4623.42207224097;4631.467381251132;4401.779911004501;4675.976620116878;4820.903581928412;4977.626677999092;4993.650444468853;4116.835326586903;4572.765353354642;4578.793954272066;4590.472095709036;4590.862274131715;2188.1466228171025;2287.4673968441184;2378.7859092681742;2624.6981597116196;2649.933751656224;4206.833480757412;4502.501111882871;5113.233015047698;5156.871078223075;5167.586593072545;4487.394201782783;4693.577578017995;4784.569388755029;5047.854661768733;5050.595952323805;4608.488373836267;4618;4780.402449693788;5007.924881126786;5163.4578369270685;5008.974955125201;5034.489653103981;5039.773210205476;5049.444561098349;5061.797528098982;4741.459025816693;4937.728424936663;4947.82682606107;4960.801567937242;4979.601631869369;5294.338207724035;5364.061289274439;5414.3232095988005;6067.362926892789;6207.037909124093;3374.4094783413298;3394.4385582632267;3784.7232627131166;3980.5223373195404;4008.779517126586;5635.577331700057;5751.7411716472025;5968.432998865101;6147.262328520606;6149.169862068564;5040.521150490697;5658.745356458887;5890.7054647266605;5929.822105336847;5934.554908381802;5575.4424557543925;5933.792317268875;5999.460048595647;6006.309274433496;6021.729022193924;5542.251796007648;5749.942500574941;5786.479216870502;5790.7973220937065;5837.066069429041;5385;5612.747426365741;5832.795852145155;5947.345791962967;6009.31739113049;5071.442141364373;5448.793238738317;5824.3593204748295;5843.707814609337;5846.970765146317 19:40:50 INFO - PROCESS | 1942 | |5;yui.html;628.4469666693309;700.2787129256867;761.7943155348056;797.16696052625;811.107781440416;14926.402943882245;15915.84084159158;15942.84057159428;15978.600534986617;15978.84021159788;14037.087589306688;14367;14532.854671453282;14789.112653240813;14853;828.6892415344261;837.1753822484848;851.2806678356778;855.4354126276659;857.1043259793499;836.5273620404452;838.0111468467206;839.4291881520594;841.1924552429675;844.3245403677065;701.0290747314955;724.971001159953;875.8554838451648;885.9734207973771;891.7057371067532;884.0805562215288;892.4288455388556;895.5790778334173;897.7306807957586;898.1781669772183;652.1001018594357;660.201156600514;667.2526770017563;668.7258224128133;668.9397954184146;14805.481808136663;14873.47942822007;14873.70252594953;14895.255237238094;14911.403543858232;13945.233012184326;14050.789238161437;14080.084794488326;14260.14439133645;14352.354144063542;9797.951010244975;11365.886341136566;13022.348882555834;13027;13036.152650077714;3860.5367355917188;3890.6692931100893;4380.987490721386;4520.52534483881;4790.952090479085;93557.5322123385;94415;99008;108599;111956;10475.528601212964;10704.518296676513;11089;11164.665060048212;11360.318380897173;17393.304267829397;20919.163233470492;21187;21214.939253037286;21225.78774212269;6580.736770529221;7732.6906923723545;7771.533707977539;7801.687932482637;7968.840623187504;4353.252310343755;4356.346548017823;4375.78121093944;4462.866114016585;4576.107659006462;3407.386670399302;3489.1626009757474;3529.8588056477965;3530.982345088258;3533.699635531009;590.4567797626108;610.7190692281512;614.0911451052467;636.5862189576812;648.1347401219331;580.5819809736983;592.0320276348116;623.0436280309793;641.6759536434164;644.5488158289216;9294.581743821544;9309.72070837876;9576.138147566753;9577.616895324109;9826.508674566243;500.2671086857771;570.1277046119444;578.1097110449944;617.2068891474433;667.7062092679206;4341.975419395308;4515.322701594721;4760.309755085494;4781.187198176319;4857.028594281158;129895;130767;136414.95377569343;139956.80129596128;161843.81561843958;98673;127347;128993.35503322423;129369;131434;72415;74119;108046;111356;117684.41157794156;9571.138597526256;9830.311878168459;9903.257255705706;9934;9965;58982.70508647601;61701;62965;63151.6842415785;63525.68237158785;90310;96983.54524682276;97099;97785.51107244418;100519.49220761548;13602.836667214964;13623.18260904349;13846.861531384557;13980.23108728989;13994.370253338828;11853.34806585646;13134.14628049212;13165.53920612774;15139.394424223348;15889.583739496064;13769.793453098411;13923.303834808421;14006.228539487392;14178;14672.633184170481;13930.233837139054;14134.858651413355;14148.504802331869;14187.645308866948;14217;12794.87205127974;13397.53108641193;13474.93262533681;13625.25061121608;14024;11210.55157793679;11238.831417528907;11519.942400287944;11600.471490790096;11908.880911190778;8749.91250087491;8848.513331766815;11224.326540407601;11738.354390508604;11837.881621184022 19:40:50 INFO - PROCESS | 1942 | __end_tp_report 19:40:50 INFO - PROCESS | 1942 | __start_cc_report 19:40:50 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,370 19:40:50 INFO - PROCESS | 1942 | __end_cc_report 19:40:50 INFO - PROCESS | 1942 | __startTimestamp1475635250613__endTimestamp 19:40:50 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | ------- Summary: start ------- 19:40:50 INFO - PROCESS | 1942 | Number of tests: 6 19:40:50 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5646.24 Median:5913.44 stddev:794.74 (13.4%) stddev-sans-first:776.00 19:40:50 INFO - PROCESS | 1942 | Values: 3323.5 3383.2 3385.5 3398.5 3416.4 5684.1 5719.1 5761.1 5798.8 5810.3 5542.6 5571.5 5715.1 5758.9 5810.9 5033.8 5041.8 5075.5 5113.4 5200.3 187.6 5157.7 5180.7 5222.1 5229.2 4259.9 5086.4 5133.7 5203.6 5227.1 5204.9 5227.8 5258.4 5282.5 5293.8 5087.5 5170.6 5199.2 5205.9 5261.3 5403.7 5559.7 5622.6 5672.4 5756.0 6162.6 6194.4 6209.4 6236.1 6246.3 6208.2 6211.7 6246.8 6271.4 6294.2 5596.5 5671.4 5697.8 5765.9 5766.5 6255.7 6284.3 6285.2 6294.9 6328.4 6120.8 6125.4 6155.3 6187.1 6210.1 6112.1 6142.2 6158.5 6192.5 6205.6 6147.4 6230.0 6237.7 6240.7 6253.0 4464.4 5090.3 6148.7 6178.0 6190.8 4833.3 4834.9 4886.5 4914.1 4927.8 3913.8 4380.8 4405.4 4449.8 4516.6 5281.8 5800.8 5824.3 5866.4 5913.4 6109.5 6138.1 6154.1 6215.0 6215.3 5902.9 5908.0 5917.0 5925.7 5935.6 5403.1 5419.2 5427.1 5434.3 5436.7 6180.4 6252.6 6280.0 6281.3 6373.5 6202.6 6222.9 6240.3 6254.4 6263.3 6347.9 6381.5 6387.7 6411.7 6415.7 6026.9 6107.1 6108.7 6188.0 6197.7 5112.2 6071.4 6164.6 6229.5 6247.2 6122.4 6132.3 6154.5 6158.4 6163.4 5932.3 5975.7 6060.4 6140.1 6143.2 4951.6 5236.2 5762.3 5764.4 5840.4 4884.7 4943.9 6084.8 6131.7 6171.7 5115.8 5941.2 5969.3 5970.4 5982.3 19:40:50 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:69601.72 Median:36995.32 stddev:151414.81 (409.3%) stddev-sans-first:151750.57 19:40:50 INFO - PROCESS | 1942 | Values: 458.2 479.7 492.0 528.9 533.0 40071.8 42081.8 42186.6 42267.4 42271.6 43301.0 43786.8 43954.9 43971.0 44017.9 9854.7 10115.7 10120.2 10123.0 10138.0 9951.3 10019.0 10036.4 10062.3 10064.3 9998.8 10043.6 10049.4 10071.5 10076.7 10006.9 10040.9 10067.0 10072.2 10088.6 9760.9 9816.6 9921.1 9923.3 9936.0 41414.0 41590.0 41761.2 41811.6 41825.0 3287.7 3308.5 3360.8 3822.3 4027.2 22404.2 25982.5 31096.0 31459.8 31608.8 3188.5 3207.2 3230.7 3240.3 3300.1 477240.0 480557.0 481449.0 486549.6 487839.0 53144.7 63664.7 64066.7 64185.7 64192.7 54998.5 65784.7 66975.7 67107.0 67342.7 16328.5 16440.1 16636.8 16676.8 16688.7 12742.3 12768.8 12777.1 12796.6 12820.4 18932.9 19380.4 19442.4 19462.4 19483.1 6057.2 7300.3 7562.7 7712.6 7741.7 832.7 1016.3 1026.6 1053.0 1085.5 28647.0 29265.9 29614.1 29863.3 29937.0 980.4 1010.7 1040.4 1044.6 1072.3 14413.6 15326.6 15357.5 15365.8 15707.8 533961.0 670770.0 672772.0 674567.0 698212.0 51588.0 56988.7 57448.1 57810.7 57947.7 1163.9 1218.7 1239.0 1333.8 1334.9 23040.5 28358.6 29435.6 29875.7 29960.9 40167.0 49951.5 50167.5 50341.2 50447.0 548122.0 551156.0 551452.3 552498.0 553744.0 26427.6 26534.9 26926.3 26981.1 27070.3 46486.3 46940.8 47787.5 47956.8 48748.5 38878.4 38900.2 38977.2 40259.6 40429.6 38162.0 38307.6 38382.8 39491.4 39728.0 32385.5 32722.5 35828.6 38442.6 38472.8 28735.6 29066.7 32812.6 33372.2 34013.2 24298.9 25373.2 25631.5 26125.1 26380.9 19:40:50 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:34471.23 Median:26653.83 stddev:92919.46 (348.6%) stddev-sans-first:93144.83 19:40:50 INFO - PROCESS | 1942 | Values: 362.5 363.3 370.2 381.4 395.4 19976.4 20888.3 21081.9 21581.6 21780.8 20744.0 20823.9 20912.2 21162.7 21269.7 5858.6 5940.6 6155.9 6157.3 6204.7 4200.3 4434.8 6051.4 6184.4 6192.9 6467.3 6800.1 6869.4 6916.2 6931.6 6307.9 6864.7 6913.2 6957.3 6958.0 5972.2 5991.3 6056.6 6060.6 6069.7 26530.6 26632.2 26675.5 26894.0 27029.1 2210.4 2374.4 2425.4 2623.5 2791.6 15550.6 15695.5 15768.3 15913.0 16361.9 1854.7 1905.1 1910.1 1928.6 1957.1 113099.3 114022.0 114811.4 115102.0 115109.0 10773.4 10850.0 10919.3 11024.3 11090.7 13690.5 14035.9 14039.2 14056.9 14063.4 6217.8 6666.8 8303.3 8625.2 8770.8 8535.3 8621.7 8633.4 8673.7 8779.2 6592.5 6703.5 6739.5 6754.4 6789.8 1573.6 1654.2 1840.8 1877.8 1918.8 801.3 816.5 864.9 914.4 917.8 20568.8 22114.7 22162.7 22334.9 22378.0 787.6 907.6 938.4 970.8 990.9 9381.5 11081.7 11416.5 11428.8 11453.7 508298.0 508998.0 514823.0 606769.0 609543.0 123098.4 134313.0 138046.3 148871.0 151311.0 977.9 1000.2 1005.1 1022.1 1024.1 18462.7 18697.4 19820.7 19849.0 19922.0 49116.8 50579.0 54916.7 56777.4 58105.0 85812.3 86821.0 86974.6 89151.6 89539.0 5895.8 7898.0 8093.4 8199.7 8232.8 10762.2 11863.0 12114.6 12782.6 12948.9 11018.7 11240.6 11245.2 11345.8 11546.2 10833.0 11033.6 11134.8 11175.7 11389.9 9178.4 10915.3 11368.2 11565.5 11701.4 7875.1 8134.8 8152.9 8216.3 8342.7 5451.2 9825.8 10780.1 11006.9 11074.4 19:40:50 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5643.94 Median:4807.30 stddev:3199.56 (66.6%) stddev-sans-first:3192.19 19:40:50 INFO - PROCESS | 1942 | Values: 1340.2 1576.1 1750.3 1859.6 1888.8 8148.3 8397.0 8520.7 9111.4 9131.9 8541.6 8880.2 8889.7 8995.6 8998.2 2191.5 2230.7 2237.0 2237.2 2249.2 2317.8 2345.9 2363.6 2365.9 2372.8 2348.1 2363.7 2368.5 2375.5 2377.1 2323.5 2323.9 2342.2 2346.9 2347.2 2319.5 2320.0 2323.9 2326.9 2340.7 6980.2 8221.4 8435.8 8891.6 8936.0 4295.9 5347.1 5359.2 5370.0 5384.2 3287.3 3412.0 3458.4 3651.4 3656.0 880.3 921.7 1042.8 1103.5 1105.8 9218.0 10526.0 11832.3 11839.7 11976.9 4569.2 4600.8 4801.2 4816.5 4827.0 8767.0 9993.5 10131.1 10145.5 10154.8 5268.7 5270.2 5275.2 5277.6 5300.0 4385.4 4473.6 4761.2 4813.4 4819.6 3390.7 3868.8 3975.6 3997.1 4008.7 1006.0 1159.6 1271.9 1291.2 1305.4 784.3 900.6 925.4 936.5 939.3 5694.1 5808.6 5927.6 6043.8 6148.2 426.4 440.2 440.8 810.8 1021.4 3831.8 3944.5 4048.3 4050.9 4105.3 10791.1 10959.9 11043.5 11047.0 11051.7 10965.7 10990.3 11001.6 11006.1 11011.1 5970.6 7806.8 8241.3 8395.5 8402.5 5395.8 5943.6 6062.4 6213.5 6268.3 9818.6 10039.5 10136.9 10236.6 10348.7 7698.5 8508.4 10029.5 10443.3 10468.9 7566.9 7600.6 7623.0 7635.2 7641.5 7245.4 8288.5 8326.3 8423.2 8433.8 6346.8 7545.8 7567.7 7590.2 7652.7 6251.5 7352.0 7389.5 7546.9 7593.8 6865.1 7029.6 7032.9 7046.8 7055.2 4550.0 4988.5 5074.8 5093.7 5165.8 4227.5 4452.7 4945.7 5097.9 5187.0 19:40:50 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:4459.78 Median:4815.77 stddev:1391.77 (28.9%) stddev-sans-first:1359.47 19:40:50 INFO - PROCESS | 1942 | Values: 257.6 298.1 303.9 311.3 313.4 4672.5 5304.8 5561.2 5594.9 5688.9 4101.4 4968.9 5283.5 5509.7 5532.6 3472.6 3635.7 4091.9 4219.8 4246.5 3695.5 4094.6 4227.9 4243.0 4262.9 3622.7 4176.3 4185.8 4190.9 4204.5 3475.6 3590.7 4176.2 4182.9 4201.0 3264.9 3293.8 3691.9 3715.3 3728.0 3979.1 5400.8 5427.7 5431.6 5433.8 4048.5 4050.2 4345.5 4618.4 4904.3 4876.3 5642.3 5792.7 5794.9 5960.0 3813.4 3919.0 4009.3 4055.0 4058.7 6077.2 6142.0 6149.0 6204.5 6255.5 4134.9 4136.5 4144.8 4152.3 4155.6 4010.7 4154.0 4159.8 4210.3 4223.6 4133.0 4758.0 4844.0 4844.3 4881.8 4810.6 4847.4 5284.4 5693.9 5876.5 1010.0 1329.1 1414.8 1420.3 1421.5 789.7 842.7 861.3 870.4 883.2 3890.6 4148.5 4521.1 4623.4 4631.5 4401.8 4676.0 4820.9 4977.6 4993.7 4116.8 4572.8 4578.8 4590.5 4590.9 2188.1 2287.5 2378.8 2624.7 2649.9 4206.8 4502.5 5113.2 5156.9 5167.6 4487.4 4693.6 4784.6 5047.9 5050.6 4608.5 4618.0 4780.4 5007.9 5163.5 5009.0 5034.5 5039.8 5049.4 5061.8 4741.5 4937.7 4947.8 4960.8 4979.6 5294.3 5364.1 5414.3 6067.4 6207.0 3374.4 3394.4 3784.7 3980.5 4008.8 5635.6 5751.7 5968.4 6147.3 6149.2 5040.5 5658.7 5890.7 5929.8 5934.6 5575.4 5933.8 5999.5 6006.3 6021.7 5542.3 5749.9 5786.5 5790.8 5837.1 5385.0 5612.7 5832.8 5947.3 6009.3 5071.4 5448.8 5824.4 5843.7 5847.0 19:40:50 INFO - PROCESS | 1942 | 19:40:50 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:24004.92 Median:4354.80 stddev:37603.61 (863.5%) stddev-sans-first:37668.13 19:40:50 INFO - PROCESS | 1942 | Values: 628.4 700.3 761.8 797.2 811.1 14926.4 15915.8 15942.8 15978.6 15978.8 14037.1 14367.0 14532.9 14789.1 14853.0 828.7 837.2 851.3 855.4 857.1 836.5 838.0 839.4 841.2 844.3 701.0 725.0 875.9 886.0 891.7 884.1 892.4 895.6 897.7 898.2 652.1 660.2 667.3 668.7 668.9 14805.5 14873.5 14873.7 14895.3 14911.4 13945.2 14050.8 14080.1 14260.1 14352.4 9798.0 11365.9 13022.3 13027.0 13036.2 3860.5 3890.7 4381.0 4520.5 4791.0 93557.5 94415.0 99008.0 108599.0 111956.0 10475.5 10704.5 11089.0 11164.7 11360.3 17393.3 20919.2 21187.0 21214.9 21225.8 6580.7 7732.7 7771.5 7801.7 7968.8 4353.3 4356.3 4375.8 4462.9 4576.1 3407.4 3489.2 3529.9 3531.0 3533.7 590.5 610.7 614.1 636.6 648.1 580.6 592.0 623.0 641.7 644.5 9294.6 9309.7 9576.1 9577.6 9826.5 500.3 570.1 578.1 617.2 667.7 4342.0 4515.3 4760.3 4781.2 4857.0 129895.0 130767.0 136415.0 139956.8 161843.8 98673.0 127347.0 128993.4 129369.0 131434.0 72415.0 74119.0 108046.0 111356.0 117684.4 9571.1 9830.3 9903.3 9934.0 9965.0 58982.7 61701.0 62965.0 63151.7 63525.7 90310.0 96983.5 97099.0 97785.5 100519.5 13602.8 13623.2 13846.9 13980.2 13994.4 11853.3 13134.1 13165.5 15139.4 15889.6 13769.8 13923.3 14006.2 14178.0 14672.6 13930.2 14134.9 14148.5 14187.6 14217.0 12794.9 13397.5 13474.9 13625.3 14024.0 11210.6 11238.8 11519.9 11600.5 11908.9 8749.9 8848.5 11224.3 11738.4 11837.9 19:40:50 INFO - PROCESS | 1942 | -------- Summary: end -------- 19:40:50 INFO - PROCESS | 1942 | 19:40:51 INFO - TEST-INFO | 1942: exit 0 19:40:51 INFO - TEST-OK | dromaeo_css | took 1114202ms 19:40:51 INFO - TEST-START | kraken 19:40:51 INFO - operating with platform_type : mac_ 19:40:51 INFO - Initialising browser for kraken test... 19:40:51 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpNWdYy6/profile http://localhost:51762/getInfo.html) 19:40:53 INFO - PROCESS | 1948 | 2016-10-04 19:40:53.877 plugin-container[1949:18510] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b27, name = 'com.apple.tsm.portname' 19:40:53 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:40:53 INFO - PROCESS | 1948 | 2016-10-04 19:40:53.880 plugin-container[1949:18510] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 19:40:53 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:40:53 INFO - PROCESS | 1948 | 2016-10-04 19:40:53.880 plugin-container[1949:18510] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:40:53 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 19:40:53 INFO - PROCESS | 1948 | colorDepth:24 19:40:53 INFO - PROCESS | 1948 | Browser inner width/height: 1024/663 19:40:53 INFO - PROCESS | 1948 | __metrics 19:40:54 ERROR - PROCESS | 1948 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 19:40:54 INFO - TEST-INFO | 1948: exit 0 19:40:54 INFO - Browser initialized. 19:40:54 INFO - Running cycle 1/1 for kraken test... 19:40:54 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpNWdYy6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:40:57 INFO - PROCESS | 1950 | 2016-10-04 19:40:57.126 plugin-container[1951:18620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8927, name = 'com.apple.tsm.portname' 19:40:57 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:40:57 INFO - PROCESS | 1950 | 2016-10-04 19:40:57.131 plugin-container[1951:18620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 19:40:57 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:40:57 INFO - PROCESS | 1950 | 2016-10-04 19:40:57.131 plugin-container[1951:18620] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:41:06 INFO - PROCESS | 1950 | RSS: Main: 253648896 19:41:06 INFO - PROCESS | 1950 | 19:41:09 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51762/tests/kraken/driver.html?testName=audio-beat-detection) 19:41:10 INFO - PROCESS | 1950 | RSS: Main: 264982528 19:41:10 INFO - PROCESS | 1950 | 19:41:13 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51762/tests/kraken/driver.html?testName=audio-dft) 19:41:14 INFO - PROCESS | 1950 | RSS: Main: 272486400 19:41:14 INFO - PROCESS | 1950 | 19:41:17 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51762/tests/kraken/driver.html?testName=audio-fft) 19:41:18 INFO - PROCESS | 1950 | RSS: Main: 270417920 19:41:18 INFO - PROCESS | 1950 | 19:41:20 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51762/tests/kraken/driver.html?testName=audio-oscillator) 19:41:20 INFO - PROCESS | 1950 | RSS: Main: 271097856 19:41:20 INFO - PROCESS | 1950 | 19:41:22 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51762/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:41:23 INFO - PROCESS | 1950 | RSS: Main: 271458304 19:41:23 INFO - PROCESS | 1950 | 19:41:26 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51762/tests/kraken/driver.html?testName=imaging-darkroom) 19:41:27 INFO - PROCESS | 1950 | RSS: Main: 272134144 19:41:27 INFO - PROCESS | 1950 | 19:41:30 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51762/tests/kraken/driver.html?testName=imaging-desaturate) 19:41:31 INFO - PROCESS | 1950 | RSS: Main: 270475264 19:41:31 INFO - PROCESS | 1950 | 19:41:34 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51762/tests/kraken/driver.html?testName=json-parse-financial) 19:41:34 INFO - PROCESS | 1950 | RSS: Main: 271392768 19:41:34 INFO - PROCESS | 1950 | 19:41:36 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51762/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:41:37 INFO - PROCESS | 1950 | RSS: Main: 271060992 19:41:37 INFO - PROCESS | 1950 | 19:41:39 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51762/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:41:39 INFO - PROCESS | 1950 | RSS: Main: 271077376 19:41:39 INFO - PROCESS | 1950 | 19:41:42 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51762/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:41:42 INFO - PROCESS | 1950 | RSS: Main: 271114240 19:41:42 INFO - PROCESS | 1950 | 19:41:45 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51762/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:41:46 INFO - PROCESS | 1950 | RSS: Main: 271405056 19:41:46 INFO - PROCESS | 1950 | 19:41:49 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51762/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:41:50 INFO - PROCESS | 1950 | RSS: Main: 271446016 19:41:50 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51762/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51762/tests/kraken/driver.html?testName=ai-astar) 19:41:52 INFO - PROCESS | 1950 | RSS: Main: 274251776 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | __start_tp_report 19:41:52 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 19:41:52 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 19:41:52 INFO - PROCESS | 1950 | |i|pagename|runs| 19:41:52 INFO - PROCESS | 1950 | |0;ai-astar;105;118;117;118;118;117;117;119;117;118 19:41:52 INFO - PROCESS | 1950 | |1;audio-beat-detection;150;126;112;157;115;117;118;134;145;126 19:41:52 INFO - PROCESS | 1950 | |2;audio-dft;199;205;241;274;199;199;200;202;200;204 19:41:52 INFO - PROCESS | 1950 | |3;audio-fft;86;64;58;89;69;87;85;73;80;75 19:41:52 INFO - PROCESS | 1950 | |4;audio-oscillator;87;70;88;73;69;71;87;72;72;94 19:41:52 INFO - PROCESS | 1950 | |5;imaging-gaussian-blur;139;118;91;89;90;92;123;88;103;113 19:41:52 INFO - PROCESS | 1950 | |6;imaging-darkroom;115;172;164;122;102;113;131;174;126;168 19:41:52 INFO - PROCESS | 1950 | |7;imaging-desaturate;148;149;90;94;90;92;123;149;90;99 19:41:52 INFO - PROCESS | 1950 | |8;json-parse-financial;57;57;58;51;54;84;87;90;86;88 19:41:52 INFO - PROCESS | 1950 | |9;json-stringify-tinderbox;71;75;72;67;105;61;79;101;104;109 19:41:52 INFO - PROCESS | 1950 | |10;stanford-crypto-aes;91;92;93;90;91;85;80;97;110;112 19:41:52 INFO - PROCESS | 1950 | |11;stanford-crypto-ccm;147;146;146;147;154;153;149;149;188;153 19:41:52 INFO - PROCESS | 1950 | |12;stanford-crypto-pbkdf2;177;173;201;238;196;175;176;178;175;178 19:41:52 INFO - PROCESS | 1950 | |13;stanford-crypto-sha256-iterative;70;71;68;69;74;94;71;86;68;69 19:41:52 INFO - PROCESS | 1950 | __end_tp_report 19:41:52 INFO - PROCESS | 1950 | __start_cc_report 19:41:52 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,957 19:41:52 INFO - PROCESS | 1950 | __end_cc_report 19:41:52 INFO - PROCESS | 1950 | __startTimestamp1475635312256__endTimestamp 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | ------- Summary: start ------- 19:41:52 INFO - PROCESS | 1950 | Number of tests: 14 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#0] ai-astar Cycles:10 Average:116.40 Median:118.00 stddev:4.06 (3.4%) stddev-sans-first:0.71 19:41:52 INFO - PROCESS | 1950 | Values: 105.0 118.0 117.0 118.0 118.0 117.0 117.0 119.0 117.0 118.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:130.00 stddev:15.86 (12.2%) stddev-sans-first:15.08 19:41:52 INFO - PROCESS | 1950 | Values: 150.0 126.0 112.0 157.0 115.0 117.0 118.0 134.0 145.0 126.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#2] audio-dft Cycles:10 Average:212.30 Median:203.00 stddev:25.15 (12.4%) stddev-sans-first:26.21 19:41:52 INFO - PROCESS | 1950 | Values: 199.0 205.0 241.0 274.0 199.0 199.0 200.0 202.0 200.0 204.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#3] audio-fft Cycles:10 Average:76.60 Median:82.50 stddev:10.60 (12.8%) stddev-sans-first:10.68 19:41:52 INFO - PROCESS | 1950 | Values: 86.0 64.0 58.0 89.0 69.0 87.0 85.0 73.0 80.0 75.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:80.00 stddev:9.48 (11.8%) stddev-sans-first:9.51 19:41:52 INFO - PROCESS | 1950 | Values: 87.0 70.0 88.0 73.0 69.0 71.0 87.0 72.0 72.0 94.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#5] imaging-gaussian-blur Cycles:10 Average:104.60 Median:88.50 stddev:17.80 (20.1%) stddev-sans-first:13.85 19:41:52 INFO - PROCESS | 1950 | Values: 139.0 118.0 91.0 89.0 90.0 92.0 123.0 88.0 103.0 113.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#6] imaging-darkroom Cycles:10 Average:138.70 Median:147.50 stddev:27.73 (18.8%) stddev-sans-first:28.06 19:41:52 INFO - PROCESS | 1950 | Values: 115.0 172.0 164.0 122.0 102.0 113.0 131.0 174.0 126.0 168.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#7] imaging-desaturate Cycles:10 Average:112.40 Median:90.00 stddev:26.87 (29.9%) stddev-sans-first:25.22 19:41:52 INFO - PROCESS | 1950 | Values: 148.0 149.0 90.0 94.0 90.0 92.0 123.0 149.0 90.0 99.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#8] json-parse-financial Cycles:10 Average:71.20 Median:85.00 stddev:16.83 (19.8%) stddev-sans-first:17.05 19:41:52 INFO - PROCESS | 1950 | Values: 57.0 57.0 58.0 51.0 54.0 84.0 87.0 90.0 86.0 88.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#9] json-stringify-tinderbox Cycles:10 Average:84.40 Median:69.00 stddev:18.23 (26.4%) stddev-sans-first:18.68 19:41:52 INFO - PROCESS | 1950 | Values: 71.0 75.0 72.0 67.0 105.0 61.0 79.0 101.0 104.0 109.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#10] stanford-crypto-aes Cycles:10 Average:94.10 Median:91.00 stddev:10.03 (11.0%) stddev-sans-first:10.57 19:41:52 INFO - PROCESS | 1950 | Values: 91.0 92.0 93.0 90.0 91.0 85.0 80.0 97.0 110.0 112.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#11] stanford-crypto-ccm Cycles:10 Average:153.20 Median:151.00 stddev:12.59 (8.3%) stddev-sans-first:13.16 19:41:52 INFO - PROCESS | 1950 | Values: 147.0 146.0 146.0 147.0 154.0 153.0 149.0 149.0 188.0 153.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.70 Median:178.00 stddev:20.40 (11.5%) stddev-sans-first:21.33 19:41:52 INFO - PROCESS | 1950 | Values: 177.0 173.0 201.0 238.0 196.0 175.0 176.0 178.0 175.0 178.0 19:41:52 INFO - PROCESS | 1950 | 19:41:52 INFO - PROCESS | 1950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:71.00 stddev:8.82 (12.4%) stddev-sans-first:9.23 19:41:52 INFO - PROCESS | 1950 | Values: 70.0 71.0 68.0 69.0 74.0 94.0 71.0 86.0 68.0 69.0 19:41:52 INFO - PROCESS | 1950 | -------- Summary: end -------- 19:41:52 INFO - PROCESS | 1950 | 19:41:53 INFO - TEST-INFO | 1950: exit 0 19:41:53 INFO - TEST-OK | kraken | took 61845ms 19:41:53 INFO - SUITE-END | took 1176s 19:41:53 INFO - Completed test suite (00:19:36) 19:41:53 INFO - javascript benchmark 19:41:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7319.1674234975135, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.4682450807877, 3383.2049468374926, 3385.526026356309, 3398.541196938411, 3416.419208734515, 5684.090545512719, 5719.1421286807, 5761.078227483604, 5798.797042103527, 5810.3027636683655, 5542.5565954723625, 5571.470710282517, 5715.114157305614, 5758.942410575893, 5810.883782324351, 5033.7734801934, 5041.823536176225, 5075.467075957026, 5113.386393632769, 5200.297959775438, 187.6121060832637, 5157.7163256021095, 5180.663256888309, 5222.112240919053, 5229.163333866582, 4259.893502662428, 5086.415062267831, 5133.743312834381, 5203.583713302945, 5227.059129356714, 5204.8959020819375, 5227.764750586195, 5258.3689957205, 5282.471752824726, 5293.788248470057, 5087.542121209072, 5170.560502357304, 5199.194124910645, 5205.947940520584, 5261.316028916216, 5403.7027963462, 5559.749811258463, 5622.550195984311, 5672.432756724335, 5756.0, 6162.630242185483, 6194.380561943814, 6209.3790620938, 6236.095766113934, 6246.3441338659695, 6208.192934918477, 6211.689415529206, 6246.750129994794, 6271.43557079865, 6294.213223347082, 5596.524295434893, 5671.432856714296, 5697.772089116472, 5765.933302338997, 5766.538676905838, 6255.718492667841, 6284.277308109626, 6285.151504546919, 6294.874102518015, 6328.367163283635, 6120.785772497941, 6125.387461253839, 6155.322914479404, 6187.071939209155, 6210.099535567318, 6112.113743507167, 6142.222533142175, 6158.507319414436, 6192.473639740672, 6205.596636218631, 6147.432404736764, 6230.0, 6237.656928868956, 6240.687965601793, 6252.968735156295, 4464.397306363664, 5090.312807770903, 6148.661823599745, 6177.96911015442, 6190.783322583778, 4833.347498087784, 4834.879128021757, 4886.486918873538, 4914.115459217303, 4927.7536123193695, 3913.767163343595, 4380.759058251763, 4405.405270288534, 4449.7997590108525, 4516.593506584423, 5281.808049659348, 5800.767969281266, 5824.271966004249, 5866.413358664185, 5913.438223368773, 6109.47263184204, 6138.143176733699, 6154.076888466676, 6215.0, 6215.254169499689, 5902.940970590239, 5908.0, 5916.970415147896, 5925.703714814242, 5935.643861368331, 5403.081476149064, 5419.214213938957, 5427.0773968425465, 5434.3207099112615, 5436.673799572039, 6180.381961803873, 6252.5935814171935, 6279.96860015697, 6281.3404592518955, 6373.458256048195, 6202.56582039271, 6222.875542489215, 6240.344763799736, 6254.374562543891, 6263.279722831932, 6347.936520634919, 6381.521385895983, 6387.68061596909, 6411.743530258924, 6415.743370265137, 6026.90959635597, 6107.083937409424, 6108.694565271808, 6188.0, 6197.690115494297, 5112.233165025276, 6071.362506936708, 6164.630122192503, 6229.501639868709, 6247.219097612799, 6122.418370254819, 6132.325444201224, 6154.507639388748, 6158.353372895782, 6163.414475624809, 5932.2584676915385, 5975.701214939322, 6060.393960603904, 6140.109684095692, 6143.170671959407, 4951.579115775199, 5236.162214045735, 5762.337331206797, 5764.394738552392, 5840.357560668238, 4884.682495637914, 4943.876403089952, 6084.817455476343, 6131.662758548323, 6171.660558669317, 5115.846524604268, 5941.197938278277, 5969.313528944227, 5970.373110823301, 5982.312034115958], "value": 5598.8533472013205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.16385097197605, 479.6810121269356, 491.95080491950785, 528.9100852855015, 532.9840104796855, 40071.79964100179, 42081.78959105204, 42186.578134218726, 42267.36598951018, 42271.57728422715, 43301.0, 43786.78106609471, 43954.90112747183, 43971.0, 44017.899552511204, 9854.704358869245, 10115.696529104138, 10120.190384769201, 10123.0, 10138.0, 9951.303408761389, 10018.99810018996, 10036.447995360253, 10062.33971394719, 10064.345817521875, 9998.800023999553, 10043.64847230347, 10049.397036177816, 10071.496425178748, 10076.748081297952, 10006.89993100067, 10040.949795250977, 10067.043630855058, 10072.244581656405, 10088.596456141746, 9760.853587196198, 9816.558254878548, 9921.107100361001, 9923.25575581827, 9935.950320248352, 41414.0, 41590.0, 41761.164776704296, 41811.581884181076, 41825.0, 3287.7205437537837, 3308.520264561625, 3360.7647464677484, 3822.311983842907, 4027.234825383197, 22404.215852445086, 25982.480350392885, 31096.0, 31459.84270078635, 31608.841955790074, 3188.4898416253286, 3207.182168547029, 3230.709236168733, 3240.303334783009, 3300.092474569489, 477240.0, 480557.0, 481449.0, 486549.567252165, 487839.0, 53144.671383214954, 63664.68167659132, 64066.67966660137, 64185.67907160434, 64192.67903660452, 54998.45001550013, 65784.67107664431, 66975.66512167409, 67107.0, 67342.65314693679, 16328.510144695678, 16440.095794731165, 16636.83363166377, 16676.83323166777, 16688.6662266754, 12742.299173545543, 12768.80846787299, 12777.105602607915, 12796.552120675919, 12820.35898205086, 18932.864028158354, 19380.4185874424, 19442.4167274982, 19462.38323216353, 19483.123259453358, 6057.212562366864, 7300.342969132804, 7562.697492100365, 7712.5758083305955, 7741.690332386754, 832.7252006837729, 1016.2987538598344, 1026.6201505443034, 1053.0364716284666, 1085.468120620906, 28646.997355092888, 29265.85367073151, 29614.111576652736, 29863.253418664706, 29937.0, 980.3578655980344, 1010.7119470950744, 1040.4017689828227, 1044.5612842606124, 1072.3137192197, 14413.567592972227, 15326.551525906581, 15357.462488812838, 15365.802737928432, 15707.842921570638, 533961.0, 670770.0, 672772.0, 674567.0, 698212.0, 51588.0, 56988.71505642445, 57448.138277925864, 57810.710946445, 57947.71026144927, 1163.947622356996, 1218.7257866979858, 1238.9876101239051, 1333.7799263121476, 1334.8598397168157, 23040.539189216455, 28358.574621381107, 29435.558466622588, 29875.701242987292, 29960.850195749754, 40167.0, 49951.500484994685, 50167.498325016284, 50341.24488132754, 50446.99106017932, 548122.0, 551156.0, 551452.340242565, 552498.0, 553744.0, 26427.60358594661, 26534.938602455684, 26926.32684182911, 26981.0556630517, 27070.323241919108, 46486.302705458766, 46940.765296173304, 47787.522124779694, 47956.80107997328, 48748.5125148744, 38878.4168237471, 38900.221995560496, 38977.2204555913, 40259.59740402558, 40429.595704042586, 38162.0, 38307.61692383041, 38382.84851454461, 39491.40762888616, 39728.0, 32385.51421728723, 32722.50916236306, 35828.64171358253, 38442.615573845025, 38472.80763596276, 28735.563221839242, 29066.7093329064, 32812.58592332238, 33372.165695857795, 34013.21917228254, 24298.906549204974, 25373.238802835946, 25631.487370252864, 26125.08562200314, 26380.8680956594], "value": 19968.0961658601, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.50880057522056, 363.28795560701025, 370.1745108408249, 381.4069122514489, 395.4305799648508, 19976.400707978766, 20888.268910588133, 21081.894590527045, 21581.56836863262, 21780.782192178074, 20744.0, 20823.895880520613, 20912.163513459443, 21162.682559761615, 21269.68095478565, 5858.5606079543995, 5940.554458415614, 6155.938440615604, 6157.322694503601, 6204.6587437690905, 4200.264953633102, 4434.844780432685, 6051.394860513958, 6184.350643182469, 6192.9380706192815, 6467.256265529477, 6800.149981252343, 6869.416099631538, 6916.170059592856, 6931.618760968145, 6307.873842523147, 6864.691088900987, 6913.20498142715, 6957.304269573053, 6957.9652101739675, 5972.163897054415, 5991.3109992351, 6056.636601803906, 6060.575759696823, 6069.666168360738, 26530.6020409694, 26632.201033969013, 26675.46649067027, 26894.0, 27029.053983110498, 2210.436338733629, 2374.4063984004, 2425.357280320716, 2623.527765002299, 2791.567307067408, 15550.61123471911, 15695.450659226872, 15768.290426930816, 15913.0, 16361.918190409091, 1854.7496088028079, 1905.0855589317066, 1910.0831600831539, 1928.5853541488648, 1957.1388589020785, 113099.30351044741, 114022.0, 114811.42594287143, 115102.0, 115109.0, 10773.35359878394, 10850.023497885228, 10919.290246133976, 11024.338539687644, 11090.722731931766, 13690.52083177084, 14035.859641403455, 14039.157650541, 14056.875449963978, 14063.43746250159, 6217.751289948442, 6666.799996000128, 8303.294219991247, 8625.223729864343, 8770.780730481662, 8535.317174626016, 8621.741347759576, 8633.395662303705, 8673.653053877773, 8779.165979231962, 6592.5055620828625, 6703.497237707192, 6739.528233023641, 6754.425873800684, 6789.796306110825, 1573.5908663747507, 1654.1729135432283, 1840.8067152948747, 1877.7934427212995, 1918.8103375907026, 801.294860522748, 816.457056057715, 864.9091845356273, 914.3553794574809, 917.7980844214262, 20568.794312056987, 22114.668279975813, 22162.66755998629, 22334.888325558593, 22377.992990315473, 787.6061969015492, 907.6026650876493, 938.380668758616, 970.7912798748303, 990.8711867457184, 9381.484018379055, 11081.72295692598, 11416.543338266543, 11428.771424571629, 11453.656390308304, 508298.0, 508998.0, 514823.0, 606769.0, 609543.0, 123098.38311318301, 134313.0, 138046.3097684525, 148871.0, 151311.0, 977.8679878216492, 1000.1698590170141, 1005.1004351105865, 1022.1260821997234, 1024.1039090795555, 18462.723059153854, 18697.439076827715, 19820.70268945938, 19849.00754962275, 19922.003899804662, 49116.75441622769, 50579.0, 54916.72541637426, 56777.432225677214, 58105.0, 85812.28375432332, 86821.0, 86974.56512717396, 89151.55424222838, 89539.0, 5895.823125306248, 7897.960510197412, 8093.3929955252415, 8199.672013119409, 8232.753017409488, 10762.19283553721, 11863.0, 12114.636560903187, 12782.61652150437, 12948.870511295143, 11018.724531886766, 11240.55037798479, 11245.156613254203, 11345.829812552984, 11546.249493782878, 10833.02502774754, 11033.558657653604, 11134.777304453704, 11175.72060698489, 11389.886101138882, 9178.449293042439, 10915.345079295268, 11368.204225704121, 11565.537378505102, 11701.356425396685, 7875.133735289228, 8134.796630084295, 8152.877706834521, 8216.2605365515, 8342.749717508485, 5451.155070964007, 9825.803483930424, 10780.083692886192, 11006.889931100906, 11074.390908499787], "value": 9844.772003301561, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.2427628389962, 1576.1410031532814, 1750.282384222469, 1859.553707110294, 1888.7544619199502, 8148.307393871513, 8396.958015209915, 8520.701775437854, 9111.362204645677, 9131.863022054666, 8541.572921353923, 8880.24517915978, 8889.688860889853, 8995.59519821608, 8998.19016288534, 2191.463091542574, 2230.69885565449, 2236.966445503319, 2237.1722462688745, 2249.2465024216826, 2317.7682231776857, 2345.9296221113395, 2363.621820508719, 2365.9408514787183, 2372.7508611595795, 2348.0725113580124, 2363.7045369328835, 2368.484170131109, 2375.5486457573093, 2377.1442280778992, 2323.523677646087, 2323.9307287969837, 2342.2387723989773, 2346.8500434786833, 2347.192661650528, 2319.478117423583, 2320.037184568419, 2323.93028209154, 2326.860388376686, 2340.742518322983, 6980.162380514371, 8221.383396245246, 8435.789105272355, 8891.555422228863, 8935.955320223422, 4295.926018495376, 5347.144456886879, 5359.22291267764, 5370.03339398912, 5384.219288203228, 3287.342531493711, 3412.0, 3458.360203362386, 3651.379265524867, 3655.963440365589, 880.3221519430067, 921.7234829551107, 1042.796654652343, 1103.453790373762, 1105.8175401058816, 9218.0, 10526.000029997214, 11832.290062596272, 11839.704007399798, 11976.940115299367, 4569.177548041318, 4600.838970636011, 4801.18379875415, 4816.54242846929, 4826.975865120652, 8766.956165219133, 9993.450360230112, 10131.13885319756, 10145.543450544743, 10154.796904062025, 5268.683878967274, 5270.156774915995, 5275.182346736262, 5277.551408130275, 5300.01949639318, 4385.3641222022625, 4473.642108631302, 4761.190597598416, 4813.422389313235, 4819.614430845524, 3390.7287417006582, 3868.806559672061, 3975.634497473998, 3997.080671445584, 4008.719389642697, 1005.9798804023878, 1159.5999380213814, 1271.8664540223328, 1291.1542939374724, 1305.3929922586044, 784.3098073695112, 900.617237673986, 925.4493576322078, 936.5249731672643, 939.3096074385321, 5694.117411801178, 5808.593398462067, 5927.644341339447, 6043.7884674036195, 6148.1699970503405, 426.42219792181146, 440.2471773267684, 440.7707991844223, 810.8175660476346, 1021.4382089850552, 3831.7892515911894, 3944.5069366329208, 4048.2510735514024, 4050.858219962346, 4105.34314509683, 10791.136709063256, 10959.890401096047, 11043.503042362952, 11047.0, 11051.668449946514, 10965.725856853483, 10990.340579565252, 11001.559937602406, 11006.119510439146, 11011.064059554867, 5970.611910225822, 7806.843863122705, 8241.299489543457, 8395.53824539644, 8402.453840500348, 5395.784168633368, 5943.554233432596, 6062.393760623902, 6213.5339849510565, 6268.310485846644, 9818.558164882455, 10039.49802509857, 10136.949315253376, 10236.641717539851, 10348.741281468023, 7698.46110772263, 8508.404411691123, 10029.498525073863, 10443.268971171945, 10468.895311046792, 7566.886496702664, 7600.643800421666, 7622.961885190539, 7635.23647635232, 7641.541507509567, 7245.420366370783, 8288.50268983863, 8326.25063744266, 8423.231121464562, 8433.789155271168, 6346.809595712136, 7545.849083018418, 7567.659455324413, 7590.1650818408825, 7652.693892244248, 6251.53113516479, 7352.044234249727, 7389.482736208413, 7546.9245307546225, 7593.848123037398, 6865.141857267841, 7029.648517573998, 7032.859342813217, 7046.788596342118, 7055.223925368308, 4549.954500454953, 4988.5260900214425, 5074.797008119782, 5093.694378337312, 5165.767540460613, 4227.471566054243, 4452.732836029849, 4945.703257804544, 5097.898042039213, 5187.014467251211], "value": 4489.928064766835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.55443083465605, 298.11311348737496, 303.91490382692854, 311.34772651295543, 313.41548012955815, 4672.46266679332, 5304.814331498398, 5561.249231353766, 5594.916076258859, 5688.943110568893, 4101.384792281159, 4968.900621987558, 5283.471652834724, 5509.685931908598, 5532.640378375403, 3472.565929258849, 3635.672789448949, 4091.9386209206737, 4219.831206751726, 4246.450045733301, 3695.542464261263, 4094.6110119538744, 4227.87316380509, 4243.029879084637, 4262.893427664302, 3622.7464077514583, 4176.2900306947895, 4185.790710464465, 4190.895227619319, 4204.474440694913, 3475.6350583188655, 3590.6768390844686, 4176.248275310443, 4182.874513764592, 4201.0, 3264.9183770405693, 3293.8023718576724, 3691.8892433227047, 3715.3126671565847, 3727.9627203727887, 3979.1047014421815, 5400.810971616014, 5427.728613569345, 5431.565474762009, 5433.755481003325, 4048.4534587830562, 4050.2102090092444, 4345.54476720655, 4618.399608050931, 4904.337914381548, 4876.341693871317, 5642.266505354278, 5792.710364481759, 5794.92793833138, 5959.970200148971, 3813.4089216171537, 3919.0, 4009.3184158693093, 4055.0, 4058.7473951956654, 6077.232116088054, 6142.0, 6148.9692551536955, 6204.534659900481, 6255.499560035232, 4134.883581432996, 4136.482939632546, 4144.8134833932545, 4152.294110001307, 4155.58444155582, 4010.6791456683395, 4153.979230103831, 4159.812808423628, 4210.326347784401, 4223.640990515779, 4133.0, 4758.0, 4843.951560484421, 4844.32179494871, 4881.780319885614, 4810.632557209897, 4847.418309802846, 5284.365876094893, 5693.946619875336, 5876.529877609781, 1009.9646512372032, 1329.0696512441136, 1414.766563517008, 1420.3395421129223, 1421.46694989379, 789.72754399732, 842.6713581703141, 861.3324673378057, 870.3602851903846, 883.2139395937621, 3890.6109389060866, 4148.481439820022, 4521.123733650564, 4623.42207224097, 4631.467381251132, 4401.779911004501, 4675.976620116878, 4820.903581928412, 4977.626677999092, 4993.650444468853, 4116.835326586903, 4572.765353354642, 4578.793954272066, 4590.472095709036, 4590.862274131715, 2188.1466228171025, 2287.4673968441184, 2378.7859092681742, 2624.6981597116196, 2649.933751656224, 4206.833480757412, 4502.501111882871, 5113.233015047698, 5156.871078223075, 5167.586593072545, 4487.394201782783, 4693.577578017995, 4784.569388755029, 5047.854661768733, 5050.595952323805, 4608.488373836267, 4618.0, 4780.402449693788, 5007.924881126786, 5163.4578369270685, 5008.974955125201, 5034.489653103981, 5039.773210205476, 5049.444561098349, 5061.797528098982, 4741.459025816693, 4937.728424936663, 4947.82682606107, 4960.801567937242, 4979.601631869369, 5294.338207724035, 5364.061289274439, 5414.3232095988005, 6067.362926892789, 6207.037909124093, 3374.4094783413298, 3394.4385582632267, 3784.7232627131166, 3980.5223373195404, 4008.779517126586, 5635.577331700057, 5751.7411716472025, 5968.432998865101, 6147.262328520606, 6149.169862068564, 5040.521150490697, 5658.745356458887, 5890.7054647266605, 5929.822105336847, 5934.554908381802, 5575.4424557543925, 5933.792317268875, 5999.460048595647, 6006.309274433496, 6021.729022193924, 5542.251796007648, 5749.942500574941, 5786.479216870502, 5790.7973220937065, 5837.066069429041, 5385.0, 5612.747426365741, 5832.795852145155, 5947.345791962967, 6009.31739113049, 5071.442141364373, 5448.793238738317, 5824.3593204748295, 5843.707814609337, 5846.970765146317], "value": 4015.1994253602443, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.4469666693309, 700.2787129256867, 761.7943155348056, 797.16696052625, 811.107781440416, 14926.402943882245, 15915.84084159158, 15942.84057159428, 15978.600534986617, 15978.84021159788, 14037.087589306688, 14367.0, 14532.854671453282, 14789.112653240813, 14853.0, 828.6892415344261, 837.1753822484848, 851.2806678356778, 855.4354126276659, 857.1043259793499, 836.5273620404452, 838.0111468467206, 839.4291881520594, 841.1924552429675, 844.3245403677065, 701.0290747314955, 724.971001159953, 875.8554838451648, 885.9734207973771, 891.7057371067532, 884.0805562215288, 892.4288455388556, 895.5790778334173, 897.7306807957586, 898.1781669772183, 652.1001018594357, 660.201156600514, 667.2526770017563, 668.7258224128133, 668.9397954184146, 14805.481808136663, 14873.47942822007, 14873.70252594953, 14895.255237238094, 14911.403543858232, 13945.233012184326, 14050.789238161437, 14080.084794488326, 14260.14439133645, 14352.354144063542, 9797.951010244975, 11365.886341136566, 13022.348882555834, 13027.0, 13036.152650077714, 3860.5367355917188, 3890.6692931100893, 4380.987490721386, 4520.52534483881, 4790.952090479085, 93557.5322123385, 94415.0, 99008.0, 108599.0, 111956.0, 10475.528601212964, 10704.518296676513, 11089.0, 11164.665060048212, 11360.318380897173, 17393.304267829397, 20919.163233470492, 21187.0, 21214.939253037286, 21225.78774212269, 6580.736770529221, 7732.6906923723545, 7771.533707977539, 7801.687932482637, 7968.840623187504, 4353.252310343755, 4356.346548017823, 4375.78121093944, 4462.866114016585, 4576.107659006462, 3407.386670399302, 3489.1626009757474, 3529.8588056477965, 3530.982345088258, 3533.699635531009, 590.4567797626108, 610.7190692281512, 614.0911451052467, 636.5862189576812, 648.1347401219331, 580.5819809736983, 592.0320276348116, 623.0436280309793, 641.6759536434164, 644.5488158289216, 9294.581743821544, 9309.72070837876, 9576.138147566753, 9577.616895324109, 9826.508674566243, 500.2671086857771, 570.1277046119444, 578.1097110449944, 617.2068891474433, 667.7062092679206, 4341.975419395308, 4515.322701594721, 4760.309755085494, 4781.187198176319, 4857.028594281158, 129895.0, 130767.0, 136414.95377569343, 139956.80129596128, 161843.81561843958, 98673.0, 127347.0, 128993.35503322423, 129369.0, 131434.0, 72415.0, 74119.0, 108046.0, 111356.0, 117684.41157794156, 9571.138597526256, 9830.311878168459, 9903.257255705706, 9934.0, 9965.0, 58982.70508647601, 61701.0, 62965.0, 63151.6842415785, 63525.68237158785, 90310.0, 96983.54524682276, 97099.0, 97785.51107244418, 100519.49220761548, 13602.836667214964, 13623.18260904349, 13846.861531384557, 13980.23108728989, 13994.370253338828, 11853.34806585646, 13134.14628049212, 13165.53920612774, 15139.394424223348, 15889.583739496064, 13769.793453098411, 13923.303834808421, 14006.228539487392, 14178.0, 14672.633184170481, 13930.233837139054, 14134.858651413355, 14148.504802331869, 14187.645308866948, 14217.0, 12794.87205127974, 13397.53108641193, 13474.93262533681, 13625.25061121608, 14024.0, 11210.55157793679, 11238.831417528907, 11519.942400287944, 11600.471490790096, 11908.880911190778, 8749.91250087491, 8848.513331766815, 11224.326540407601, 11738.354390508604, 11837.881621184022], "value": 7747.004779765124, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1632.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 119.0, 117.0, 118.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 126.0, 112.0, 157.0, 115.0, 117.0, 118.0, 134.0, 145.0, 126.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 205.0, 241.0, 274.0, 199.0, 199.0, 200.0, 202.0, 200.0, 204.0], "value": 212.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 64.0, 58.0, 89.0, 69.0, 87.0, 85.0, 73.0, 80.0, 75.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 88.0, 73.0, 69.0, 71.0, 87.0, 72.0, 72.0, 94.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 118.0, 91.0, 89.0, 90.0, 92.0, 123.0, 88.0, 103.0, 113.0], "value": 104.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 172.0, 164.0, 122.0, 102.0, 113.0, 131.0, 174.0, 126.0, 168.0], "value": 138.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 90.0, 94.0, 90.0, 92.0, 123.0, 149.0, 90.0, 99.0], "value": 112.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 51.0, 54.0, 84.0, 87.0, 90.0, 86.0, 88.0], "value": 71.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 72.0, 67.0, 105.0, 61.0, 79.0, 101.0, 104.0, 109.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 93.0, 90.0, 91.0, 85.0, 80.0, 97.0, 110.0, 112.0], "value": 94.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 154.0, 153.0, 149.0, 149.0, 188.0, 153.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 173.0, 201.0, 238.0, 196.0, 175.0, 176.0, 178.0, 175.0, 178.0], "value": 186.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 68.0, 69.0, 74.0, 94.0, 71.0, 86.0, 68.0, 69.0], "value": 74.0, "unit": "score"}]}]} 19:41:53 INFO - Return code: 0 19:41:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:41:53 ERROR - # TBPL SUCCESS # 19:41:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:41:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:41:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:41:53 INFO - 2016-10-04 19:41:53.591 screenresolution[1955:19010] starting screenresolution argv=screenresolution get 19:41:53 INFO - 2016-10-04 19:41:53.594 screenresolution[1955:19010] Display 0: 1600x1200x32@60 19:41:53 INFO - 2016-10-04 19:41:53.600 screenresolution[1956:19013] starting screenresolution argv=screenresolution list 19:41:53 INFO - Available Modes on Display 0 19:41:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:41:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:41:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:41:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:41:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:41:53 INFO - Intel Iris: 19:41:53 INFO - Chipset Model: Intel Iris 19:41:53 INFO - Type: GPU 19:41:53 INFO - Bus: Built-In 19:41:53 INFO - VRAM (Dynamic, Max): 1536 MB 19:41:53 INFO - Vendor: Intel (0x8086) 19:41:53 INFO - Device ID: 0x0a2e 19:41:53 INFO - Revision ID: 0x0009 19:41:53 INFO - Displays: 19:41:53 INFO - Display: 19:41:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:41:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:41:53 INFO - Display Serial Number: 1600x1200 60 19:41:53 INFO - Main Display: Yes 19:41:53 INFO - Mirror: Off 19:41:53 INFO - Online: Yes 19:41:53 INFO - Rotation: Supported 19:41:53 INFO - Return code: 0 19:41:53 INFO - Running post-action listener: _resource_record_post_action 19:41:53 INFO - [mozharness: 2016-10-05 02:41:53.834165Z] Finished run-tests step (success) 19:41:53 INFO - Running post-run listener: _resource_record_post_run 19:41:53 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1312256; Write bytes: 710224384; Read time: 13; Write time: 2026 19:41:53 INFO - TinderboxPrint: CPU usage
54.4% 19:41:53 INFO - TinderboxPrint: I/O read bytes / time
1,312,256 / 13 19:41:53 INFO - TinderboxPrint: I/O write bytes / time
710,224,384 / 2,026 19:41:53 INFO - TinderboxPrint: CPU idle
2,209.1 (45.6%) 19:41:53 INFO - TinderboxPrint: CPU system
981.2 (20.2%) 19:41:53 INFO - TinderboxPrint: CPU user
1,659.4 (34.2%) 19:41:53 INFO - TinderboxPrint: Swap in / out
408,293,376 / 0 19:41:53 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 408021504; Write bytes: 410171392; Read time: 30870; Write time: 802 19:41:53 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1254912; Write bytes: 298734080; Read time: 12; Write time: 1217 19:41:54 INFO - Running post-run listener: _upload_blobber_files 19:41:54 INFO - Blob upload gear active. 19:41:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:41:54 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 19:41:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:41:54 INFO - (blobuploader) - INFO - Open directory for files ... 19:41:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:41:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:41:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:41:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:41:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:41:55 INFO - (blobuploader) - INFO - Done attempting. 19:41:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:41:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:41:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:41:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:41:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:41:56 INFO - (blobuploader) - INFO - Done attempting. 19:41:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:41:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:41:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:41:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:41:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:41:59 INFO - (blobuploader) - INFO - Done attempting. 19:41:59 INFO - (blobuploader) - INFO - Iteration through files over. 19:41:59 INFO - Return code: 0 19:41:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:41:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:41:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42344dc6c29936ec02b4c5b16a697aae8ccdb7ccbfcbc3e4699d8b468e9f2f1bffe0510ad5ff631192c89aa1bfdd3187676f53ef853a4eff24bd67c9f5405761", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8f32258d16cab3eef59d01dc2b829433ac28aeffd900d85159b5db0ba3bd50832b73ddc9fe113323d902d986f37149ea78c01d9182d2636b98dafbe862d9b0f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c4159265827896c76e5312f92acc4ab4d589ff3f4beadceeb8d1c6bfa2ed6b91e1ea4b5541042119d202e5a662f000c2cca8957365eea6c5926830a58524bc2"} 19:41:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:41:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:41:59 INFO - Contents: 19:41:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42344dc6c29936ec02b4c5b16a697aae8ccdb7ccbfcbc3e4699d8b468e9f2f1bffe0510ad5ff631192c89aa1bfdd3187676f53ef853a4eff24bd67c9f5405761", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8f32258d16cab3eef59d01dc2b829433ac28aeffd900d85159b5db0ba3bd50832b73ddc9fe113323d902d986f37149ea78c01d9182d2636b98dafbe862d9b0f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c4159265827896c76e5312f92acc4ab4d589ff3f4beadceeb8d1c6bfa2ed6b91e1ea4b5541042119d202e5a662f000c2cca8957365eea6c5926830a58524bc2"} 19:41:59 INFO - Running post-run listener: copy_logs_to_upload_dir 19:41:59 INFO - Copying logs to upload dir... 19:41:59 INFO - mkdir: /builds/slave/test/build/upload/logs 19:41:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.477941 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-04 19:41:59.167745) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 19:41:59.171170) ========= 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.TYmraXlM47/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.U1oFToyzOl/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42344dc6c29936ec02b4c5b16a697aae8ccdb7ccbfcbc3e4699d8b468e9f2f1bffe0510ad5ff631192c89aa1bfdd3187676f53ef853a4eff24bd67c9f5405761", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8f32258d16cab3eef59d01dc2b829433ac28aeffd900d85159b5db0ba3bd50832b73ddc9fe113323d902d986f37149ea78c01d9182d2636b98dafbe862d9b0f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c4159265827896c76e5312f92acc4ab4d589ff3f4beadceeb8d1c6bfa2ed6b91e1ea4b5541042119d202e5a662f000c2cca8957365eea6c5926830a58524bc2"} build_url:https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/MQHNxb7kTnyHcRXmxJixqQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42344dc6c29936ec02b4c5b16a697aae8ccdb7ccbfcbc3e4699d8b468e9f2f1bffe0510ad5ff631192c89aa1bfdd3187676f53ef853a4eff24bd67c9f5405761", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8f32258d16cab3eef59d01dc2b829433ac28aeffd900d85159b5db0ba3bd50832b73ddc9fe113323d902d986f37149ea78c01d9182d2636b98dafbe862d9b0f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c4159265827896c76e5312f92acc4ab4d589ff3f4beadceeb8d1c6bfa2ed6b91e1ea4b5541042119d202e5a662f000c2cca8957365eea6c5926830a58524bc2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 19:41:59.204073) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:41:59.204412) ========= 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.TYmraXlM47/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.U1oFToyzOl/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.005123 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:41:59.253300) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 19:41:59.253631) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 19:41:59.253991) ========= ========= Total master_lag: 0.17 =========