builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0317 starttime: 1478259116.33 results: success (0) buildid: 20161104022021 builduid: d8279fd5a4974685877346792f91cd6f revision: ad086fddd66a309a119289f62fb782aa51bec15d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:56.326838) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:56.327422) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:56.327703) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:56.787275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:56.787614) ========= 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.l1ajpXg8my/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.TglJMnEmCU/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-04 04:31:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.80M=0.001s 2016-11-04 04:31:56 (7.80 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113320 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:56.956063) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:56.956357) ========= 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.l1ajpXg8my/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.TglJMnEmCU/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.023153 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:57.006527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-04 04:31:57.006881) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev ad086fddd66a309a119289f62fb782aa51bec15d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev ad086fddd66a309a119289f62fb782aa51bec15d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l1ajpXg8my/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.TglJMnEmCU/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-04 04:31:57,132 truncating revision to first 12 chars 2016-11-04 04:31:57,133 Setting DEBUG logging. 2016-11-04 04:31:57,133 attempt 1/10 2016-11-04 04:31:57,133 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/ad086fddd66a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-04 04:31:57,904 unpacking tar archive at: cedar-ad086fddd66a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.046677 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-04 04:31:58.079336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:58.080024) ========= script_repo_revision: ad086fddd66a309a119289f62fb782aa51bec15d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:58.080968) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:58.081620) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 04:31:58.101545) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-04 04:31:58.102263) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l1ajpXg8my/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.TglJMnEmCU/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:31:58 INFO - MultiFileLogger online at 20161104 04:31:58 in /builds/slave/test 04:31:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 04:31:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:31:58 INFO - {'append_to_log': False, 04:31:58 INFO - 'base_work_dir': '/builds/slave/test', 04:31:58 INFO - 'blob_upload_branch': 'Cedar', 04:31:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:31:58 INFO - 'branch': 'Cedar', 04:31:58 INFO - 'buildbot_json_path': 'buildprops.json', 04:31:58 INFO - 'config_files': ('talos/mac_config.py',), 04:31:58 INFO - 'default_actions': ('clobber', 04:31:58 INFO - 'read-buildbot-config', 04:31:58 INFO - 'download-and-extract', 04:31:58 INFO - 'populate-webroot', 04:31:58 INFO - 'create-virtualenv', 04:31:58 INFO - 'install', 04:31:58 INFO - 'run-tests'), 04:31:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:31:58 INFO - 'download_minidump_stackwalk': True, 04:31:58 INFO - 'download_symbols': 'ondemand', 04:31:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:31:58 INFO - 'tooltool.py': '/tools/tooltool.py', 04:31:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:31:58 INFO - '/tools/misc-python/virtualenv.py')}, 04:31:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:31:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:31:58 INFO - 'installer_path': 'installer.exe', 04:31:58 INFO - 'log_level': 'info', 04:31:58 INFO - 'log_name': 'talos', 04:31:58 INFO - 'log_to_console': True, 04:31:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:31:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:31:58 INFO - 'opt_config_files': (), 04:31:58 INFO - 'pip_index': False, 04:31:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:31:58 INFO - 'cmd': ('bash', 04:31:58 INFO - '-c', 04:31:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:31:58 INFO - 'enabled': True, 04:31:58 INFO - 'halt_on_failure': False, 04:31:58 INFO - 'name': 'check_screen_resolution'},), 04:31:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:31:58 INFO - 'cmd': ('bash', 04:31:58 INFO - '-c', 04:31:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:31:58 INFO - 'enabled': True, 04:31:58 INFO - 'halt_on_failure': False, 04:31:58 INFO - 'name': 'check_screen_resolution'},), 04:31:58 INFO - 'run_cmd_checks_enabled': True, 04:31:58 INFO - 'sps_profile': False, 04:31:58 INFO - 'sps_profile_interval': 0, 04:31:58 INFO - 'suite': 'dromaeojs-e10s', 04:31:58 INFO - 'system_bits': '32', 04:31:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:31:58 INFO - 'title': 't-yosemite-r7-0317', 04:31:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:31:58 INFO - 'use_talos_json': True, 04:31:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:31:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:31:58 INFO - 'work_dir': 'build'} 04:31:58 INFO - [mozharness: 2016-11-04 11:31:58.346575Z] Running clobber step. 04:31:58 INFO - Running pre-action listener: _resource_record_pre_action 04:31:58 INFO - Running main action method: clobber 04:31:58 INFO - rmtree: /builds/slave/test/build 04:31:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:32:00 INFO - Running post-action listener: _resource_record_post_action 04:32:00 INFO - [mozharness: 2016-11-04 11:32:00.245605Z] Finished clobber step (success) 04:32:00 INFO - [mozharness: 2016-11-04 11:32:00.245733Z] Running read-buildbot-config step. 04:32:00 INFO - Running pre-action listener: _resource_record_pre_action 04:32:00 INFO - Running main action method: read_buildbot_config 04:32:00 INFO - Using buildbot properties: 04:32:00 INFO - { 04:32:00 INFO - "project": "", 04:32:00 INFO - "product": "firefox", 04:32:00 INFO - "script_repo_revision": "default", 04:32:00 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 04:32:00 INFO - "repository": "", 04:32:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 04:32:00 INFO - "buildid": "20161104022021", 04:32:00 INFO - "pgo_build": "False", 04:32:00 INFO - "basedir": "/builds/slave/test", 04:32:00 INFO - "buildnumber": 2, 04:32:00 INFO - "slavename": "t-yosemite-r7-0317", 04:32:00 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:32:00 INFO - "platform": "yosemite_r7", 04:32:00 INFO - "scheduler": "tests-cedar-macosx64-talos", 04:32:00 INFO - "branch": "cedar", 04:32:00 INFO - "repo_path": "projects/cedar", 04:32:00 INFO - "revision": "ad086fddd66a309a119289f62fb782aa51bec15d", 04:32:00 INFO - "stage_platform": "macosx64", 04:32:00 INFO - "builduid": "d8279fd5a4974685877346792f91cd6f", 04:32:00 INFO - "slavebuilddir": "test" 04:32:00 INFO - } 04:32:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:32:00 INFO - Running post-action listener: _resource_record_post_action 04:32:00 INFO - [mozharness: 2016-11-04 11:32:00.249961Z] Finished read-buildbot-config step (success) 04:32:00 INFO - [mozharness: 2016-11-04 11:32:00.250108Z] Running download-and-extract step. 04:32:00 INFO - Running pre-action listener: _resource_record_pre_action 04:32:00 INFO - Running main action method: download_and_extract 04:32:00 INFO - mkdir: /builds/slave/test/build/tests 04:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:00 INFO - trying https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:32:00 INFO - Downloading https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/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 04:32:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/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 04:32:01 INFO - Downloaded 1199 bytes. 04:32:01 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:32:01 INFO - Using the following test package requirements: 04:32:01 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:32:01 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:32:01 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:32:01 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:32:01 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:32:01 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:32:01 INFO - u'jsshell-mac.zip'], 04:32:01 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:32:01 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:32:01 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:32:01 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:32:01 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:32:01 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:32:01 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:32:01 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:32:01 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:32:01 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:32:01 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:32:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:32:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:32:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:32:01 INFO - Fetch https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:32:02 INFO - Http code: 200 04:32:02 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 04:32:02 INFO - x-amz-cf-id: CkLkj1uqfZnmT17Kvl1AYBOQGpuqQiAhjFhLLurL6ht3Fcyh_axP5w== 04:32:02 INFO - x-amz-version-id: MObXNA2PvoGebayBFfTyO7Q1vplEG1vn 04:32:02 INFO - Expected file size: 16397962 04:32:02 INFO - Obtained file size: 16397962 04:32:09 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 04:32:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:32:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:32:09 INFO - Fetch https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:32:09 INFO - Http code: 200 04:32:09 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 04:32:09 INFO - x-amz-cf-id: VODvQSbhsr5TqL-6UmK96Dfp2nu94bcXw1i6Uu8PAMCQiQrkNI9nSw== 04:32:09 INFO - x-amz-version-id: MObXNA2PvoGebayBFfTyO7Q1vplEG1vn 04:32:10 INFO - Expected file size: 16397962 04:32:10 INFO - Obtained file size: 16397962 04:32:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:32:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:32:16 INFO - Fetch https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:32:17 INFO - Http code: 200 04:32:17 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 04:32:17 INFO - x-amz-cf-id: I2zxbibNYG5TLkLS6Cn4fHikyl-tc_C9AZUqdSAHlNYRMjYSSMZFFA== 04:32:17 INFO - x-amz-version-id: QjHnMl3jtz9MYRHE2xs81_.eAaYkYtA8 04:32:17 INFO - Expected file size: 14409038 04:32:17 INFO - Obtained file size: 14409038 04:32:18 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')]} 04:32:18 INFO - trying https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:32:18 INFO - Downloading https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:32:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/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 04:32:20 INFO - Downloaded 111955889 bytes. 04:32:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:32:20 INFO - mkdir: /builds/slave/test/properties 04:32:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:32:20 INFO - Writing to file /builds/slave/test/properties/build_url 04:32:20 INFO - Contents: 04:32:20 INFO - build_url:https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:32:21 INFO - Running post-action listener: _resource_record_post_action 04:32:21 INFO - Running post-action listener: set_extra_try_arguments 04:32:21 INFO - [mozharness: 2016-11-04 11:32:21.636088Z] Finished download-and-extract step (success) 04:32:21 INFO - [mozharness: 2016-11-04 11:32:21.636189Z] Running populate-webroot step. 04:32:21 INFO - Running pre-action listener: _resource_record_pre_action 04:32:21 INFO - Running main action method: populate_webroot 04:32:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:32:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:32:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:32:21 INFO - '--tppagecycles', 04:32:21 INFO - '7'], 04:32:21 INFO - 'tests': ['tsvgm']}}, 04:32:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:32:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:32:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:32:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:32:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:21 INFO - 'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:32:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:32:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:21 INFO - 'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['damp', 'tps']}, 04:32:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:21 INFO - 'tests': ['damp', 'tps']}, 04:32:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['dromaeo_dom']}, 04:32:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:32:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['basic_compositor_video']}, 04:32:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:32:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['a11yr', 04:32:21 INFO - 'ts_paint', 04:32:21 INFO - 'tpaint', 04:32:21 INFO - 'sessionrestore', 04:32:21 INFO - 'sessionrestore_no_auto_restore', 04:32:21 INFO - 'tabpaint']}, 04:32:21 INFO - 'other-e10s': {'tests': ['a11yr', 04:32:21 INFO - 'ts_paint', 04:32:21 INFO - 'tpaint', 04:32:21 INFO - 'sessionrestore', 04:32:21 INFO - 'sessionrestore_no_auto_restore', 04:32:21 INFO - 'tabpaint']}, 04:32:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:32:21 INFO - 'ts_paint', 04:32:21 INFO - 'tpaint', 04:32:21 INFO - 'sessionrestore', 04:32:21 INFO - 'sessionrestore_no_auto_restore', 04:32:21 INFO - 'tabpaint']}, 04:32:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:32:21 INFO - 'ts_paint', 04:32:21 INFO - 'tpaint', 04:32:21 INFO - 'sessionrestore', 04:32:21 INFO - 'sessionrestore_no_auto_restore', 04:32:21 INFO - 'tabpaint']}, 04:32:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['a11yr', 04:32:21 INFO - 'ts_paint', 04:32:21 INFO - 'tpaint', 04:32:21 INFO - 'sessionrestore', 04:32:21 INFO - 'sessionrestore_no_auto_restore', 04:32:21 INFO - 'tabpaint']}, 04:32:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['a11yr', 04:32:21 INFO - 'ts_paint', 04:32:21 INFO - 'tpaint', 04:32:21 INFO - 'sessionrestore', 04:32:21 INFO - 'sessionrestore_no_auto_restore', 04:32:21 INFO - 'tabpaint']}, 04:32:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['tsvgx', 04:32:21 INFO - 'tsvgr_opacity', 04:32:21 INFO - 'tart', 04:32:21 INFO - 'tscrollx', 04:32:21 INFO - 'cart']}, 04:32:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:32:21 INFO - 'tsvgr_opacity', 04:32:21 INFO - 'tart', 04:32:21 INFO - 'tscrollx', 04:32:21 INFO - 'cart']}, 04:32:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:21 INFO - 'talos_options': ['--disable-e10s'], 04:32:21 INFO - 'tests': ['tp5o']}, 04:32:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:21 INFO - 'tests': ['tp5o']}, 04:32:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:21 INFO - 'talos_options': ['--disable-e10s', 04:32:21 INFO - '--xperf_path', 04:32:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:32:21 INFO - 'tests': ['tp5n']}, 04:32:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:21 INFO - 'talos_options': ['--xperf_path', 04:32:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:32:21 INFO - 'tests': ['tp5n']}}, 04:32:21 INFO - 'talos.zip': {'path': '', 04:32:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:32:21 INFO - Running post-action listener: _resource_record_post_action 04:32:21 INFO - [mozharness: 2016-11-04 11:32:21.655011Z] Finished populate-webroot step (success) 04:32:21 INFO - [mozharness: 2016-11-04 11:32:21.655148Z] Running create-virtualenv step. 04:32:21 INFO - Running pre-action listener: _resource_record_pre_action 04:32:21 INFO - Running main action method: create_virtualenv 04:32:21 INFO - Creating virtualenv /builds/slave/test/build/venv 04:32:21 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 04:32:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:32:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:32:21 INFO - Using real prefix '/tools/python27' 04:32:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:32:22 INFO - Installing distribute.............................................................................................................................................................................................done. 04:32:25 INFO - Installing pip.................done. 04:32:25 INFO - Return code: 0 04:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:32:25 INFO - Reading from file tmpfile_stdout 04:32:25 INFO - Output received: 04:32:25 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) 04:32:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32: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', 'pip>=1.5'] in /builds/slave/test/build 04:32: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 pip>=1.5 04:32:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:25 INFO - 'HOME': '/Users/cltbld', 04:32:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:25 INFO - 'LOGNAME': 'cltbld', 04:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:25 INFO - 'MOZ_NO_REMOTE': '1', 04:32:25 INFO - 'NO_EM_RESTART': '1', 04:32:25 INFO - 'PAGER': '/bin/cat', 04:32:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:25 INFO - 'PWD': '/builds/slave/test', 04:32:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:25 INFO - 'SHELL': '/bin/bash', 04:32:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:25 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:25 INFO - 'USER': 'cltbld', 04:32:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:25 INFO - 'XPC_FLAGS': '0x0', 04:32:25 INFO - 'XPC_SERVICE_NAME': '0', 04:32:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:25 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 04:32:25 INFO - Cleaning up... 04:32:25 INFO - Return code: 0 04:32:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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', '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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:32: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 psutil>=3.1.1 04:32:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:25 INFO - 'HOME': '/Users/cltbld', 04:32:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:25 INFO - 'LOGNAME': 'cltbld', 04:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:25 INFO - 'MOZ_NO_REMOTE': '1', 04:32:25 INFO - 'NO_EM_RESTART': '1', 04:32:25 INFO - 'PAGER': '/bin/cat', 04:32:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:25 INFO - 'PWD': '/builds/slave/test', 04:32:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:25 INFO - 'SHELL': '/bin/bash', 04:32:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:25 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:25 INFO - 'USER': 'cltbld', 04:32:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:25 INFO - 'XPC_FLAGS': '0x0', 04:32:25 INFO - 'XPC_SERVICE_NAME': '0', 04:32:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:26 INFO - Downloading/unpacking psutil>=3.1.1 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:32:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:30 INFO - Installing collected packages: psutil 04:32:30 INFO - Running setup.py install for psutil 04:32:30 INFO - building 'psutil._psutil_osx' extension 04:32:30 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 04:32:30 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 04:32:30 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 04:32:30 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 04:32:30 INFO - building 'psutil._psutil_posix' extension 04:32:30 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 04:32:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:32:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:32:30 INFO - ^ 04:32:30 INFO - 1 warning generated. 04:32:30 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 04:32:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:30 INFO - Successfully installed psutil 04:32:30 INFO - Cleaning up... 04:32:31 INFO - Return code: 0 04:32:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:31 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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32:31 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 04:32:31 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 04:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:31 INFO - 'HOME': '/Users/cltbld', 04:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:31 INFO - 'LOGNAME': 'cltbld', 04:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:31 INFO - 'MOZ_NO_REMOTE': '1', 04:32:31 INFO - 'NO_EM_RESTART': '1', 04:32:31 INFO - 'PAGER': '/bin/cat', 04:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:31 INFO - 'PWD': '/builds/slave/test', 04:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:31 INFO - 'SHELL': '/bin/bash', 04:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:31 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:31 INFO - 'USER': 'cltbld', 04:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:31 INFO - 'XPC_FLAGS': '0x0', 04:32:31 INFO - 'XPC_SERVICE_NAME': '0', 04:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:32:31 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 04:32:31 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 04:32:31 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 04:32:31 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 04:32:31 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 04:32:31 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 04:32:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:32:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:32:33 INFO - Installing collected packages: mozsystemmonitor 04:32:33 INFO - Running setup.py install for mozsystemmonitor 04:32:34 INFO - Successfully installed mozsystemmonitor 04:32:34 INFO - Cleaning up... 04:32:34 INFO - Return code: 0 04:32:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:32:34 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')]} 04:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:34 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')]} 04:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:34 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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32:34 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 04:32:34 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 04:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:34 INFO - 'HOME': '/Users/cltbld', 04:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:34 INFO - 'LOGNAME': 'cltbld', 04:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:34 INFO - 'MOZ_NO_REMOTE': '1', 04:32:34 INFO - 'NO_EM_RESTART': '1', 04:32:34 INFO - 'PAGER': '/bin/cat', 04:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:34 INFO - 'PWD': '/builds/slave/test', 04:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:34 INFO - 'SHELL': '/bin/bash', 04:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:34 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:34 INFO - 'USER': 'cltbld', 04:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:34 INFO - 'XPC_FLAGS': '0x0', 04:32:34 INFO - 'XPC_SERVICE_NAME': '0', 04:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:34 INFO - Downloading/unpacking jsonschema==2.5.1 04:32:34 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 04:32:34 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 04:32:34 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 04:32:34 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 04:32:34 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 04:32:34 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 04:32:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:32:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:32:36 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 04:32:36 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 04:32:36 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 04:32:36 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 04:32:36 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 04:32:36 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 04:32:37 INFO - Downloading functools32-3.2.3-2.tar.gz 04:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:32:37 INFO - warning: no files found matching '*.txt' 04:32:37 INFO - no previously-included directories found matching 'build' 04:32:37 INFO - no previously-included directories found matching 'dist' 04:32:37 INFO - no previously-included directories found matching '.git*' 04:32:37 INFO - Installing collected packages: jsonschema, functools32 04:32:37 INFO - Running setup.py install for functools32 04:32:37 INFO - warning: no files found matching '*.txt' 04:32:37 INFO - no previously-included directories found matching 'build' 04:32:37 INFO - no previously-included directories found matching 'dist' 04:32:37 INFO - no previously-included directories found matching '.git*' 04:32:37 INFO - Successfully installed jsonschema functools32 04:32:37 INFO - Cleaning up... 04:32:37 INFO - Return code: 0 04:32:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:37 INFO - 'HOME': '/Users/cltbld', 04:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:37 INFO - 'LOGNAME': 'cltbld', 04:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:37 INFO - 'MOZ_NO_REMOTE': '1', 04:32:37 INFO - 'NO_EM_RESTART': '1', 04:32:37 INFO - 'PAGER': '/bin/cat', 04:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:37 INFO - 'PWD': '/builds/slave/test', 04:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:37 INFO - 'SHELL': '/bin/bash', 04:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:37 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:37 INFO - 'USER': 'cltbld', 04:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:37 INFO - 'XPC_FLAGS': '0x0', 04:32:37 INFO - 'XPC_SERVICE_NAME': '0', 04:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:32:37 INFO - Cleaning up... 04:32:37 INFO - Return code: 0 04:32:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:37 INFO - 'HOME': '/Users/cltbld', 04:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:37 INFO - 'LOGNAME': 'cltbld', 04:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:37 INFO - 'MOZ_NO_REMOTE': '1', 04:32:38 INFO - 'NO_EM_RESTART': '1', 04:32:38 INFO - 'PAGER': '/bin/cat', 04:32:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:38 INFO - 'PWD': '/builds/slave/test', 04:32:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:38 INFO - 'SHELL': '/bin/bash', 04:32:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:38 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:38 INFO - 'USER': 'cltbld', 04:32:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:38 INFO - 'XPC_FLAGS': '0x0', 04:32:38 INFO - 'XPC_SERVICE_NAME': '0', 04:32:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:38 INFO - Downloading/unpacking blobuploader==1.2.4 04:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:40 INFO - Downloading blobuploader-1.2.4.tar.gz 04:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:32:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:32:40 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 04:32:40 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 04:32:40 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 04:32:40 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 04:32:40 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 04:32:40 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 04:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:32:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:41 INFO - Downloading docopt-0.6.1.tar.gz 04:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:32:42 INFO - Installing collected packages: blobuploader, requests, docopt 04:32:42 INFO - Running setup.py install for blobuploader 04:32:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:32:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:32:42 INFO - Running setup.py install for requests 04:32:42 INFO - Running setup.py install for docopt 04:32:42 INFO - Successfully installed blobuploader requests docopt 04:32:42 INFO - Cleaning up... 04:32:42 INFO - Return code: 0 04:32:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:42 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')]} 04:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:42 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')]} 04:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:42 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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32:42 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 04:32:42 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 04:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:42 INFO - 'HOME': '/Users/cltbld', 04:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:42 INFO - 'LOGNAME': 'cltbld', 04:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:42 INFO - 'MOZ_NO_REMOTE': '1', 04:32:42 INFO - 'NO_EM_RESTART': '1', 04:32:42 INFO - 'PAGER': '/bin/cat', 04:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:42 INFO - 'PWD': '/builds/slave/test', 04:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:42 INFO - 'SHELL': '/bin/bash', 04:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:42 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:42 INFO - 'USER': 'cltbld', 04:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:42 INFO - 'XPC_FLAGS': '0x0', 04:32:42 INFO - 'XPC_SERVICE_NAME': '0', 04:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:32:43 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-LXjIt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:43 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-7MeC8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:43 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-qIauYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:43 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-zRj5BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:43 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-JKawKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:43 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-79jIp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:43 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-dBQvgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:43 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-OtjIDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-_t9Q7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ry2bw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ixXFst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-6YYBTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-SxDKRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-J8DC4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-F1zm4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-EWqj7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:44 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-8gGJp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:32:44 INFO - Running setup.py install for manifestparser 04:32:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:32:45 INFO - Running setup.py install for mozcrash 04:32:45 INFO - Running setup.py install for mozdebug 04:32:45 INFO - Running setup.py install for mozdevice 04:32:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:32:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:32:45 INFO - Running setup.py install for mozfile 04:32:45 INFO - Running setup.py install for mozhttpd 04:32:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:32:45 INFO - Running setup.py install for mozinfo 04:32:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Running setup.py install for mozInstall 04:32:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Running setup.py install for mozleak 04:32:46 INFO - Running setup.py install for mozlog 04:32:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Running setup.py install for moznetwork 04:32:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Running setup.py install for mozprocess 04:32:46 INFO - Running setup.py install for mozprofile 04:32:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:32:46 INFO - Running setup.py install for mozrunner 04:32:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:32:47 INFO - Running setup.py install for mozscreenshot 04:32:47 INFO - Running setup.py install for moztest 04:32:47 INFO - Running setup.py install for mozversion 04:32:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:32:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:32:47 INFO - Cleaning up... 04:32:47 INFO - Return code: 0 04:32:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:47 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')]} 04:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:47 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')]} 04:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:47 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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32:47 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 04:32:47 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 04:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:47 INFO - 'HOME': '/Users/cltbld', 04:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:47 INFO - 'LOGNAME': 'cltbld', 04:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:47 INFO - 'MOZ_NO_REMOTE': '1', 04:32:47 INFO - 'NO_EM_RESTART': '1', 04:32:47 INFO - 'PAGER': '/bin/cat', 04:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:47 INFO - 'PWD': '/builds/slave/test', 04:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:47 INFO - 'SHELL': '/bin/bash', 04:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:47 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:47 INFO - 'USER': 'cltbld', 04:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:47 INFO - 'XPC_FLAGS': '0x0', 04:32:47 INFO - 'XPC_SERVICE_NAME': '0', 04:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:32:47 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-YryopI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-r_qGxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-y_cAD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Mw7SNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-h9c0mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-yvjWBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-e_xsmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-aJbSQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-VB9qt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-A4RD9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:48 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)) 04:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:48 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-i7RX0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:49 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)) 04:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:49 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-DaZ66i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:49 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)) 04:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:49 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-uYegLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:49 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)) 04:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:49 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-m33Vvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:49 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)) 04:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:49 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-hjDS8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:49 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)) 04:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:49 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-FpkYpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:49 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)) 04:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:49 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-e11jmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:49 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)) 04:32:49 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)) 04:32:49 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)) 04:32:49 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)) 04:32:49 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)) 04:32:49 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)) 04:32:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:52 INFO - Downloading blessings-1.6.tar.gz 04:32:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:32:52 INFO - Installing collected packages: blessings 04:32:52 INFO - Running setup.py install for blessings 04:32:52 INFO - Successfully installed blessings 04:32:52 INFO - Cleaning up... 04:32:52 INFO - Return code: 0 04:32:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:32:52 INFO - Reading from file tmpfile_stdout 04:32:52 INFO - Current package versions: 04:32:52 INFO - blessings == 1.6 04:32:52 INFO - blobuploader == 1.2.4 04:32:52 INFO - docopt == 0.6.1 04:32:52 INFO - functools32 == 3.2.3-2 04:32:52 INFO - jsonschema == 2.5.1 04:32:52 INFO - manifestparser == 1.1 04:32:52 INFO - mozInstall == 1.12 04:32:52 INFO - mozcrash == 0.17 04:32:52 INFO - mozdebug == 0.1 04:32:52 INFO - mozdevice == 0.48 04:32:52 INFO - mozfile == 1.2 04:32:52 INFO - mozhttpd == 0.7 04:32:52 INFO - mozinfo == 0.9 04:32:52 INFO - mozleak == 0.1 04:32:52 INFO - mozlog == 3.4 04:32:52 INFO - moznetwork == 0.27 04:32:52 INFO - mozprocess == 0.23 04:32:52 INFO - mozprofile == 0.28 04:32:52 INFO - mozrunner == 6.12 04:32:52 INFO - mozscreenshot == 0.1 04:32:52 INFO - mozsystemmonitor == 0.3 04:32:52 INFO - moztest == 0.7 04:32:52 INFO - mozversion == 1.4 04:32:52 INFO - psutil == 3.1.1 04:32:52 INFO - requests == 1.2.3 04:32:52 INFO - wsgiref == 0.1.2 04:32:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:52 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')]} 04:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:52 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')]} 04:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:52 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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32:52 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 04:32:52 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 04:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:52 INFO - 'HOME': '/Users/cltbld', 04:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:52 INFO - 'LOGNAME': 'cltbld', 04:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:52 INFO - 'MOZ_NO_REMOTE': '1', 04:32:52 INFO - 'NO_EM_RESTART': '1', 04:32:52 INFO - 'PAGER': '/bin/cat', 04:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:52 INFO - 'PWD': '/builds/slave/test', 04:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:52 INFO - 'SHELL': '/bin/bash', 04:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:52 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:52 INFO - 'USER': 'cltbld', 04:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:52 INFO - 'XPC_FLAGS': '0x0', 04:32:52 INFO - 'XPC_SERVICE_NAME': '0', 04:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 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)) 04:32:53 INFO - Cleaning up... 04:32:53 INFO - Return code: 0 04:32:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:53 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 0x10a421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec735af6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, '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.l1ajpXg8my/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.TglJMnEmCU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 04:32:53 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 04:32:53 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 04:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:53 INFO - 'HOME': '/Users/cltbld', 04:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:53 INFO - 'LOGNAME': 'cltbld', 04:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:53 INFO - 'MOZ_NO_REMOTE': '1', 04:32:53 INFO - 'NO_EM_RESTART': '1', 04:32:53 INFO - 'PAGER': '/bin/cat', 04:32:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:53 INFO - 'PWD': '/builds/slave/test', 04:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:53 INFO - 'SHELL': '/bin/bash', 04:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:32:53 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:53 INFO - 'USER': 'cltbld', 04:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:53 INFO - 'XPC_FLAGS': '0x0', 04:32:53 INFO - 'XPC_SERVICE_NAME': '0', 04:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:32:53 INFO - Cleaning up... 04:32:53 INFO - Return code: 0 04:32:53 INFO - Running post-action listener: _resource_record_post_action 04:32:53 INFO - Running post-action listener: _start_resource_monitoring 04:32:53 INFO - Starting resource monitoring. 04:32:53 INFO - [mozharness: 2016-11-04 11:32:53.459102Z] Finished create-virtualenv step (success) 04:32:53 INFO - [mozharness: 2016-11-04 11:32:53.460187Z] Running install step. 04:32:53 INFO - Running pre-action listener: _resource_record_pre_action 04:32:53 INFO - Running main action method: install 04:32:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:32:53 INFO - Reading from file tmpfile_stdout 04:32:53 INFO - Detecting whether we're running mozinstall >=1.0... 04:32:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:32:53 INFO - Reading from file tmpfile_stdout 04:32:53 INFO - Output received: 04:32:53 INFO - Usage: mozinstall [options] installer 04:32:53 INFO - Options: 04:32:53 INFO - -h, --help show this help message and exit 04:32:53 INFO - -d DEST, --destination=DEST 04:32:53 INFO - Directory to install application into. [default: 04:32:53 INFO - "/builds/slave/test"] 04:32:53 INFO - --app=APP Application being installed. [default: firefox] 04:32:53 INFO - mkdir: /builds/slave/test/build/application 04:32:53 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'] 04:32:53 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 04:33:28 INFO - Reading from file tmpfile_stdout 04:33:28 INFO - Output received: 04:33:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:33:28 INFO - Running post-action listener: _resource_record_post_action 04:33:28 INFO - [mozharness: 2016-11-04 11:33:28.012329Z] Finished install step (success) 04:33:28 INFO - [mozharness: 2016-11-04 11:33:28.012458Z] Running run-tests step. 04:33:28 INFO - Running pre-action listener: _resource_record_pre_action 04:33:28 INFO - Running main action method: run_tests 04:33:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:33:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:33:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:33:28 INFO - 2016-11-04 04:33:28.027 screenresolution[3950:109704] starting screenresolution argv=screenresolution get 04:33:28 INFO - 2016-11-04 04:33:28.030 screenresolution[3950:109704] Display 0: 1600x1200x32@60 04:33:28 INFO - 2016-11-04 04:33:28.035 screenresolution[3951:109706] starting screenresolution argv=screenresolution list 04:33:28 INFO - Available Modes on Display 0 04:33:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:33:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:33:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:33:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:33:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:33:28 INFO - Intel Iris: 04:33:28 INFO - Chipset Model: Intel Iris 04:33:28 INFO - Type: GPU 04:33:28 INFO - Bus: Built-In 04:33:28 INFO - VRAM (Dynamic, Max): 1536 MB 04:33:28 INFO - Vendor: Intel (0x8086) 04:33:28 INFO - Device ID: 0x0a2e 04:33:28 INFO - Revision ID: 0x0009 04:33:28 INFO - Displays: 04:33:28 INFO - Display: 04:33:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:33:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:33:28 INFO - Display Serial Number: 1600x1200 60 04:33:28 INFO - Main Display: Yes 04:33:28 INFO - Mirror: Off 04:33:28 INFO - Online: Yes 04:33:28 INFO - Rotation: Supported 04:33:28 INFO - Return code: 0 04:33:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:33:28 INFO - Python 2.7.3 04:33:28 INFO - Return code: 0 04:33:28 INFO - grabbing minidump binary from tooltool 04:33:28 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')]} 04:33:28 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 0x10a235d30>, '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 0x10a05bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a22db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:33:28 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 04:33:28 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 04:33:28 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 04:33:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:33:28 INFO - Return code: 0 04:33:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:33:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:33:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:33:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:33:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:33:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--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 04:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0317 --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 04:33:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l1ajpXg8my/Render', 04:33:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:28 INFO - 'HOME': '/Users/cltbld', 04:33:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:28 INFO - 'LOGNAME': 'cltbld', 04:33:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:33:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:33:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:28 INFO - 'MOZ_NO_REMOTE': '1', 04:33:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:33:28 INFO - 'NO_EM_RESTART': '1', 04:33:28 INFO - 'PAGER': '/bin/cat', 04:33:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:28 INFO - 'PWD': '/builds/slave/test', 04:33:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:33:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:28 INFO - 'SHELL': '/bin/bash', 04:33:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TglJMnEmCU/Listeners', 04:33:28 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 04:33:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:28 INFO - 'USER': 'cltbld', 04:33:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:28 INFO - 'XPC_FLAGS': '0x0', 04:33:28 INFO - 'XPC_SERVICE_NAME': '0', 04:33:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--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 04:33:28 INFO - mozversion application_buildid: 20161104022021 04:33:28 INFO - mozversion application_changeset: ad086fddd66a309a119289f62fb782aa51bec15d 04:33:28 INFO - mozversion application_display_name: Nightly 04:33:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:33:28 INFO - mozversion application_name: Firefox 04:33:28 INFO - mozversion application_remotingname: firefox 04:33:28 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 04:33:28 INFO - mozversion application_vendor: Mozilla 04:33:28 INFO - mozversion application_version: 52.0a1 04:33:28 INFO - mozversion platform_buildid: 20161104022021 04:33:28 INFO - mozversion platform_changeset: ad086fddd66a309a119289f62fb782aa51bec15d 04:33:28 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 04:33:28 INFO - mozversion platform_version: 52.0a1 04:33:28 INFO - using testdate: 1478259208 04:33:28 INFO - actual date: 1478259208 04:33:28 INFO - starting webserver on 'localhost:59444' 04:33:28 INFO - SUITE-START | Running 2 tests 04:33:28 INFO - TEST-START | dromaeo_css 04:33:28 INFO - operating with platform_type : mac_ 04:33:28 INFO - Initialising browser for dromaeo_css test... 04:33:28 INFO - TEST-INFO | started process 3961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpV_8yfm/profile http://localhost:59444/getInfo.html) 04:33:31 INFO - PROCESS | 3961 | 2016-11-04 04:33:31.172 plugin-container[3963:109860] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4f, name = 'com.apple.tsm.portname' 04:33:31 INFO - PROCESS | 3961 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:33:31 INFO - PROCESS | 3961 | 2016-11-04 04:33:31.175 plugin-container[3963:109860] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9513, name = 'com.apple.CFPasteboardClient' 04:33:31 INFO - PROCESS | 3961 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:33:31 INFO - PROCESS | 3961 | 2016-11-04 04:33:31.175 plugin-container[3963:109860] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:33:31 INFO - PROCESS | 3961 | __metrics Screen width/height:1600/1200 04:33:31 INFO - PROCESS | 3961 | colorDepth:24 04:33:31 INFO - PROCESS | 3961 | Browser inner width/height: 1024/689 04:33:31 INFO - PROCESS | 3961 | __metrics 04:33:31 ERROR - PROCESS | 3961 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 04:33:31 INFO - TEST-INFO | 3961: exit 0 04:33:31 INFO - Browser initialized. 04:33:31 INFO - Running cycle 1/1 for dromaeo_css test... 04:33:31 INFO - TEST-INFO | started process 3964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpV_8yfm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:33:34 INFO - PROCESS | 3964 | 2016-11-04 04:33:34.576 plugin-container[3965:109986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 04:33:34 INFO - PROCESS | 3964 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:33:34 INFO - PROCESS | 3964 | 2016-11-04 04:33:34.580 plugin-container[3965:109986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 04:33:34 INFO - PROCESS | 3964 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:33:34 INFO - PROCESS | 3964 | 2016-11-04 04:33:34.580 plugin-container[3965:109986] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:33:44 INFO - PROCESS | 3964 | RSS: Main: 238997504 04:33:44 INFO - PROCESS | 3964 | 04:34:33 INFO - PROCESS | 3964 | 1478259273739 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] 04:36:46 INFO - PROCESS | 3964 | Cycle 1(1): loaded http://localhost:59444/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59444/tests/dromaeo/cssquery-ext.html) 04:36:48 INFO - PROCESS | 3964 | RSS: Main: 253059072 04:36:48 INFO - PROCESS | 3964 | 04:39:50 INFO - PROCESS | 3964 | Cycle 1(1): loaded http://localhost:59444/tests/dromaeo/cssquery-ext.html (next: http://localhost:59444/tests/dromaeo/cssquery-jquery.html) 04:39:51 INFO - PROCESS | 3964 | RSS: Main: 262000640 04:39:51 INFO - PROCESS | 3964 | 04:42:52 INFO - PROCESS | 3964 | Cycle 1(1): loaded http://localhost:59444/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59444/tests/dromaeo/cssquery-mootools.html) 04:42:53 INFO - PROCESS | 3964 | RSS: Main: 267071488 04:42:53 INFO - PROCESS | 3964 | 04:45:55 INFO - PROCESS | 3964 | Cycle 1(1): loaded http://localhost:59444/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59444/tests/dromaeo/cssquery-prototype.html) 04:45:56 INFO - PROCESS | 3964 | RSS: Main: 265674752 04:45:56 INFO - PROCESS | 3964 | 04:48:58 INFO - PROCESS | 3964 | Cycle 1(1): loaded http://localhost:59444/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59444/tests/dromaeo/cssquery-yui.html) 04:48:59 INFO - PROCESS | 3964 | RSS: Main: 273285120 04:48:59 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | Cycle 1(1): loaded http://localhost:59444/tests/dromaeo/cssquery-yui.html (next: http://localhost:59444/tests/dromaeo/cssquery-dojo.html) 04:52:02 INFO - PROCESS | 3964 | RSS: Main: 275427328 04:52:02 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | __start_tp_report 04:52:02 INFO - PROCESS | 3964 | _x_x_mozilla_page_load 04:52:02 INFO - PROCESS | 3964 | _x_x_mozilla_page_load_details 04:52:02 INFO - PROCESS | 3964 | |i|pagename|runs| 04:52:02 INFO - PROCESS | 3964 | |0;dojo.html;2910.72348126928;3335.749818763592;3360.69753722165;3367.9494807577885;3395.830208489578;5624.690642014688;5712.714364281785;5712.771489140439;5732.770689172438;5841.3282472515675;5668.404817494155;5737.741801618927;5781.62419442737;5851.297844258685;5858.20914176586;5180.792768289264;5213.843584692465;5262.4474430184855;5327.854511280074;5334.626576139672;5306.92039619406;5340.759665815028;5356.11624082026;5360.758765855527;5456.727163641822;5210.869728256786;5323.920141197885;5337.11937530307;5340.706261155635;5356.35723713153;5153.871153221162;5224.8693782655355;5260.92108618371;5272.604554658377;5309.362876454811;5197.766100525486;5209.973950130263;5222.8694282642855;5224.346956630421;5279.366476022902;5653;5688.061469857468;5798.217240672498;5822.563307751935;5841.737121829486;6141.056449558104;6161.260648722137;6257;6293.339199384044;6320.051992201161;6260.530460215502;6292.465140463066;6294.937050629481;6301.338359472281;6328.050792381134;4716.481187069419;5942.762289508458;5957.748872736731;5968.492678122321;5981;5194;5663;5930.911036334458;6194.977828658311;6201.379862013853;6177.567570270128;6209.813705588839;6228.283747369111;6230.937690623035;6257.8748425031245;6183.350748171467;6269.780557680459;6280.214973128359;6289.213848268966;6293.339199383999;6054.455099041107;6148.692565371803;6185.072239164071;6188.659623720649;6226.657533835682;6219.782307619212;6271.27880293763;6272.780452684134;6282.748690052438;6285.9057114143325;4956.702597844069;4989.875253118628;4991.900161996812;4993.201087825931;5008.29883816266;4377;4410.933835992462;4412.6690498212765;4448.488423831236;4493.820247190076;5921.3190483094995;5983.311919129269;5999.640021598718;6053.909191362133;6055.970485017558;6270.467010304084;6277.466415354655;6287.1198032275515;6297.905531417033;6325.849211268191;5812.953668339739;5889.764409423575;5954.702264886739;6036.396360363929;6097.390260973956;5436.782528698887;5506.421825708243;5519.586031047687;5534;5552.7223638817895;6376.872462550723;6386.90419643715;6407.455366293824;6408.967955160194;6415.166028416276;6181.072839074175;6249.656268905072;6280.9685951569945;6284.937150628435;6287.559870808999;5654.491095801398;6280.497560195082;6303.621782693053;6347.047942808431;6387.712552935222;4843.515648435128;5043.646944713835;5084.313617661568;5145.639805213599;6288.937110629018;6124.991279909774;6270.184875966095;6280.654563999024;6296.118543403835;6301.7164227608955;6120.843333897588;6127.678242056309;6137.376985335611;6142.560166306883;6143.354947730602;6144.863200307957;6165.815025549241;6179.227596550431;6216.689165541615;6230.937690623035;4735.905281894274;5977.970110149421;6060.363661815622;6110.511159107172;6161.260648722182;6109.526129009804;6146.6004709693725;6159.384061593805;6174.691265436621;6189.876202476015;6041.18993135008;6101.368489499298;6104.7926496150985;6106.551676171802;6129.14485165794 04:52:02 INFO - PROCESS | 3964 | |1;ext.html;466.7106394035698;470.1631096647966;481.33816002995894;491.8671958571186;509.0658641393044;32088;34590;39394.409083863764;39395.21209575811;39546.406803897895;40018;40311.79844100783;40489.59510404895;40520.392194117114;40523;9394.624215031408;9579.13787759105;9581.95209023954;9597.328187026911;9628.903710962872;9316.627334906596;9461.432314061143;9527.475988820646;9528.523573821281;9534.189593884528;9319.068093190694;9382.155605995458;9393.76515587109;9496.857547136799;9545.422735861313;9426.387284826464;9458.527073646359;9460.290478214127;9518.143367096925;9579.664711735057;9284.025177356416;9325.860112098324;9330.906690933072;9364.95317523408;9467.52662366879;39137;39274;39361.22874470627;39371;39488;3262.1029216965285;3752.962470375316;4038.838446462138;4041.4746083009313;4042.797860106983;30124.69875301241;30870.536941945888;31123.37753244945;31135.844320778477;31654;3152.290734584723;3171.334019855833;3298.802071875695;3303.7357011439267;3344.0803778960735;464322;467288;471534;471651;473868;56203;56570.43429565734;56919;56990.71504642533;56991.430085698616;58206.560048792686;58902;59089;59450.70274648599;59527.51181220504;15297.617559560877;15413.306401211972;15502.689946201013;15504.457343992906;15566.610834729223;10041.598336066476;11986.280823150639;12049.33728644933;12146.635600931986;12178;13832.654183645489;15225.771613425808;15236.847631523764;15296.541103766906;15410.383584656713;6077.4226448487325;6169.660668663267;6186.484311343749;6244.344343843831;6273.960962687145;787.4094429178116;845.9873101903477;938.9764168660018;982.111189373618;1067.1836045425257;29256.122316330544;29425.85287073551;29713;29803.701962980525;31540.89606863749;884.3102380143498;900.9864852027225;1011.8970322348675;1056;1120.6469961962118;12113.027114789757;15011;15423;15506.5348039559;15786.36854525829;650765;653750;659681;661363;661526;56520;56569.43430565642;56594.717026414604;56758.71620641871;56928.71535642295;1297.8896793772635;1343.3887581150748;1344.4823742859069;1356.220173400279;1365.2491129878526;28749;29650;29875.253118672208;30293;30342.393152136392;48345.03309933711;48758.268625969926;48779;48832.51167488279;48839.5116048835;575465;580260;580524;580595;581078;39239.60760392359;39389.80305098456;39821.40267896042;40170.79914600408;40339.798301008304;45037.19851205915;45939.392121275596;46608.30087548622;46742.06515869596;46998.53001469941;37990.24019519649;38239.80880095582;38670.6132938667;38709;39619.20761584694;37497.25005499929;37809.62190378061;38332.23335533218;38876.6112338873;38964.805175975074;37104.44343334798;37105.701300454355;37546.249075018895;38058.429123562615;38226.42660360041;28823.135305940854;32183.678163219003;33428.1642958928;34090.488642670876;34329.828350858086;21283.68074478915;21534.784652153903;23206.76793232046;25128.617926014245;25830.095946641777 04:52:02 INFO - PROCESS | 3964 | |2;jquery.html;347.22915128414917;370.4739270236266;385.90018447424836;387.59689922480584;407.86206483909893;18610.812567172397;19962.800371996313;20305.796942030574;20643.587128257426;20821.167153313887;19332.613347733073;19387;19941;20028.799712002874;20283.78297302163;4861.173600487926;5768.480836724683;5826.271716035496;5996.130561068642;6049.848753781147;5930.466258036776;5961.284645842505;6067.757289708428;6069.453749162574;6074.544409169308;4780.880477988043;5609.719514024303;5925.5555833312455;5950.642961422307;6007.849803754897;5898.085796701518;5908.822735317947;5959.135925290809;5960.701964901759;5978;6897.137857767779;7279.927200727978;7345.816354591125;7347.08161479815;7375.63121843911;26833.46333073347;27309;27434.5884811728;28579;28653;2325.9651105233434;2382.4401265702545;2383.976160238393;2472.4684192898612;2522.9116980905765;12180.939095304555;14018.43926242949;15244.542663720105;15413.53759387209;16614.916925415415;1668.2242757117876;1735.5400818782907;1747.2836137183695;1984.880907145576;2100.359390385932;124049.37975310156;136327;140169;141802;153295.9999999989;20680.482987925123;21456.892715536324;21701.240456583942;21709.96060551524;21921.01355439009;34388.312233755685;34482.655173448446;34526.82736586301;34689;35122.12194695153;8120.187981201832;10132.493375331203;10207.642732504326;10392.324498907547;10439.739006524898;8402.117777633383;8488.278496327757;8538.573071346533;8669.306455483546;8704.738857834274;9213.447193168431;9251.306152038624;9389.248860091177;9453.291003174789;9458.668946586837;3768.736188466781;3850.8074596270076;3875.1087249932675;3879.728419010642;3882.456456096149;699.4893727578896;751.5678484871221;844.923956843887;897.4480694372913;952.4666186935339;17710.734338984927;18494.260229590665;19701.916394598436;20474.79525204759;22413.215537456435;867.6702852915852;889.675268526996;932.38462614674;941.2987324443334;941.8540126280437;10361.481925903825;10581.84127238092;10876.72808179786;10912.836307455394;11044.558217671203;544166;566086;576970;578039;594980;77523.2247677516;96545.51727241318;105241.47379263055;134183.31633367474;138893.3055334717;1007.1439276614818;1012.9341592796592;1041.2607048995299;1043.3009883378154;1045.0594464981425;17508.737368939732;18137.093145342416;18362;19146.904265478584;20023.29918452847;50377;53609.762682038985;54809;54841.72579137238;55603.443965559825;51860;56433.71783141058;67223.66388168027;78830.6058469727;80054.599727001;6537.346265373425;7573.545587264782;7577.621118943921;7824.295813376596;7957.482763620346;10639.787204256027;12447.502099915902;12491.500339986662;12988.805167922677;13199.406026728628;8786;10668.62659806903;10895.509702063267;11251.718707032389;11275.04162146194;9216.400933939272;10590.364578125336;10676.57293708243;11158.051565617014;11281.548738050386;11094.334339939629;11430;11490.597829076276;11534.3079415232;11565.884341156818;8050.838983220186;8066;8186.754397368089;8265.586720664063;8320.459170153921;8806.383553151218;10517.579296828042;10686.145108391465;10868;11324.716882078013 04:52:02 INFO - PROCESS | 3964 | |3;mootools.html;1213.6541085790552;1325.2446105719737;1575.1415478564181;1749.5975925537134;1810.565464288571;7553.244675532444;7845.725399611015;8106.986626671666;8527.786805329863;8556.229939305462;7991.680332786696;8281.58592070397;8293.087760346358;8401.285890699282;8402.369822263325;2308.203669733943;2325.011869955271;2333.311673056444;2337.357226762637;2349.518348738513;1872.7846297675806;1944.931927382542;2342.3441436397834;2374.5488357212075;2375.3824005758543;1929.601039246542;2311.9884400578057;2316.791488766011;2339.578875802355;2343.8593684378907;2281.4638559938403;2291.4385975436044;2304.320225533472;2320.2227253869974;2325.476767727256;2264.116994372196;2294.0365046680436;2323.3610757041947;2349.729781075181;2353.341064501942;8379.62291696875;8439.071702112762;8444.915550844475;8453.535055571941;8505.149485051506;5096.286519887219;5253.4221235664045;5274.683518988873;5275.208718692227;5293.920591191097;3304.6364899860996;3372.5278461015478;3652.4703917932015;3665.21197942441;3669.5963444021136;1046.7383154211448;1055.4353420919847;1082.1775450657558;1095.6055819904827;1102.3330884814652;10814.188935829845;10865.022148006718;10984;10990;11089.944550277278;4446.199684056901;4596.632269418438;4679.64902632304;4681.7190968542;4767.189577771787;9351.672691455766;9484.193843523377;9501.572429240701;9509.66716164931;9513.476758778195;5145.4597267287145;5205.453427390145;5212.921806172911;5214.713190774544;5231.659942103751;3694.7598406103516;4529.547045295444;4745.501722319107;4791.736454495036;4819.373481447389;3827.559830619459;3834.8466061357235;3904.2972264992572;3928.5678575356687;3944.3491823849317;1024.4672770159475;1146.277844957688;1211.6308571314535;1265.171312790124;1285.7107150890974;765.8774596064603;783.9804004899923;896.3994123936978;909.5543183840003;925.4123631493915;5656.321241451053;5729.169270455761;5775.5668324875805;5859.091840764689;5889.322727886348;415.6093272324006;428.1009879253543;440.1592957451253;443.0430270615455;447.53456405338085;3542.6457354264367;3806;3848.2111167210655;3951.269015232191;3982.5420076691553;9875.40747555149;10131.088202061703;10215.131713804261;10244.59021639141;10252.846207306897;9456.14894659484;10133.64532241368;10135.695929122137;10156.085952264331;10158.542865571068;7773.727919522789;7949.64226609804;7964.283214510722;7973.920260797434;8006.2394072564175;5129.615278854176;5534.169874518693;5752.884942301047;5851;5958.285005799332;8471.110533394152;9315.208207302456;9318.207952324128;9372.625094996396;9471.715848524555;9290.35224960883;9361.625534978797;9382.155605995493;9440.48077355752;9491.952540237255;6517.120188774455;6600.867982640416;6715.731370745116;6906.309369063053;6997.510174287751;7831.2560306770765;8001.279884810395;8002.83994320122;8007.959960200162;8013.398995075276;7042.154941407064;7211.3870321023305;7211.567305961659;7266.055412796302;7266.091738532684;7062;7099.290070992859;7148.249433809583;7234.529755565872;7254.5647261164495;6599.373059559334;6614.371634694697;6644.933550664431;6698.497612678971;6702.463802895854;4854.708717476963;4946.332245146859;4988.800447982186;4988.850334489971;5028.270900719448;4811.206150985048;4871.756412179448;4944.851654450372;5063.518965698253;5096.184610462308 04:52:02 INFO - PROCESS | 3964 | |4;prototype.html;289.54975013853465;301.11021930196273;307.89993252193045;309.9085769697939;312.97496200303976;4434.911301773963;5340.706261155635;5487.835364939053;5545.805896793613;5593.916091258634;4783.9760801196035;5190.273361729361;5433.293671822667;5435.755391007404;5486.753096110675;3290.8848190313342;3496.1958749487635;3999.3401088820383;4257.914231870868;4261.552536983619;3653.9586217927895;3704.3332200203954;3796.810159492028;4216.5452918742985;4269.423627810237;4142.813573389205;4168.541460439349;4177.310743727281;4178.409386180572;4182.22628813669;3027.8940237091815;3410.9488357674654;3917.8834032300942;4175.645070169039;4188.811503482335;3305.8512366943546;3353.4131526982924;3446.7414943879057;3487.1805125795418;3513.8770143045126;4243.936340954888;4812.374391329105;5193.195054766479;5265.842024739264;5336.546393556514;3974.721769476138;4059.700895713373;4342.739435633841;4389.692721509496;4554.089182163548;4438.979034822009;5590.832275031756;5605.990921634104;5682.488576028178;5747.56893233005;3827.8468861245515;3853.749506282111;3858.787766672832;3863.8840834775;3870.3033453978273;5320.893582128336;5943.821685349447;5981.371955944606;6037.516998640098;6098.756049758004;3133.6396314423678;3759.9248015039548;3908.296506628777;3909.4135879618284;3910.804459777;3145.685431456836;3585.0640654047043;3595.5977168904146;3931.980340098281;3986.621270979252;4221.860097773645;4337.804798783999;4652.720836749806;4691.601213896789;4723.449337357857;4529.977350113229;5520.779168833282;5711.457411545896;5736.71316434185;5814.244148260699;1113.4210210690396;1172.0799172649429;1328.8671132886827;1355.833982774813;1374.8625137486172;706.9540479868792;779.4527862193542;813.8290958898698;825.9628316725762;836.0093289452019;3667.2665466906724;3823.9808800955816;4600.204831014426;4617.746023968714;4648.302754586839;4676.883077923011;4963.6277279204205;4979.004199160183;4988.127077761377;5023.522765337287;4116.629503344713;4156.91686166281;4177.227212965611;4249.447571815706;4256.723312984708;2457.7465492598903;2831;3122.8906988255303;3134.98432507836;3142.4343618148496;5258.031160553317;5310.2831117798605;5451.073317536028;6021.217241758543;6212;4941.465558574419;5164.8192313268855;5931.466168044811;6080.361562036011;6177.722002509898;5062.848114556569;5922.674252916131;6018.849528761729;6079.6960151992225;6092.9535232383805;5786.855328616818;5799.652020878761;5890.676012819336;5907.645541267538;5976.462118409364;5161.638685292144;5279.551238144801;5528.308961379827;5587.552995760248;5755.971220143872;5160.458151893997;5616.438356164351;5782.017057100219;6044.667543285162;6098.011974132732;3652.5345391859855;3706.6108058653454;3709.2396058808417;3834.7315687902696;3864.71014673895;5162.277281180713;5490.372368322342;5777.91333129995;5820.272465941757;5895.056790913433;4668.5098064702715;4923.926141107958;5159.742012899415;5611.607187496836;5744.597878148489;5339.652922560022;5566.415526369673;5593.685483911359;5638.407967163389;5714.828555143353;5437.770514089205;5571.4428557143965;5575.188063879336;5585.385607583244;5648.265725455829;5128.358955130609;5184.948150518447;5439.728013599225;5626.915596265977;5658.85852853682;4357;4617.699849509771;5109.92691534767;5307.946920530745;5474.890502190014 04:52:02 INFO - PROCESS | 3964 | |5;yui.html;598.4823127994281;652.8890088684923;733.4755649710456;737.365865355794;761.0182864105304;14454.988150829433;14937.402503899859;15156.1664108474;15312.846871531281;15357.616059598515;13733.176009439441;13760.417551981525;13923.1646101234;13961.30193490324;13962.581122566315;818.9836203275916;824.756696774453;826.499967519651;828.2131974624101;829.8257365953157;666.3569655282652;687.4603436302503;809.2797410304834;831.0359982420393;835.5947365527715;847.3814115695515;850.8851305073829;884.1379654836547;884.5002573545926;887.5961437545933;834.5576844272546;872.9869051964225;878.9824203515926;881.6429346114802;881.8809460722784;612.0268772651506;636.4590098416355;641.3426238105932;652.360686527206;656.4223483334682;13887.513937012258;13959.302034898214;13971.580852574338;14034.017618766691;14115.576532704035;10244.309688901323;13032.478700852049;13499.527516536875;13531.985101117358;13570.117942333716;9449.477768425057;11383.203175777699;11388.71528211793;11410.087193024538;11641.243319184226;3637.363186566056;3668.6881615062753;3694.4827724118645;3708.3510385682666;3726.9068273293115;84486.57756711238;85244;85723.57138214269;88737;89579;10499.370037797604;10545.9472702636;10546.367217966947;10749.83875241872;10759.601251837368;20351.4912127198;20520.692189616868;20628.587428251652;20719.378418647466;20822.479438014172;6446.302430458562;7537.397008239328;7624.656890439944;7635.809104772425;7650.846983060419;4249.68127390447;4250.703535421726;4324.394584758136;4376.321670141133;4414.962483816301;3397.3545026444895;3502.492138639883;3515.191505953645;3517.577890653138;3524.1718196223865;623.4856243599049;629.820501157168;646.3859333633068;655.1875674164003;658.4107224034462;620.6431302001367;643.5398689936718;645.2837350540896;646.8932626116732;668.0647094068344;8523.872141917876;8955.910440895637;8964.506952117566;9016.819663606691;9033.548322583845;630.3664816859119;660.8380946668001;661.7851331507784;663.754410867982;685.5680921019725;3483.6516348365467;4162.8344063662225;4346.304591265382;4379.4087798147475;4392.224225143926;91742;101436;102019;102799.48600257101;117213.09837768796;65245.021324680165;70774;74268;88525.22949541103;109566.90433095567;82157;85647;86201.5689921559;86373.56813215894;88476.5576172115;8414.83170336602;8450.957745211235;8471.66113355459;8598.61306241233;8681.088485709162;48357.75821120872;56197.43802561922;57775.55561110838;58401;59579.10631340447;77822;80491.98770030796;84369;85706;89195;10917.945410272898;11118.689295013155;11301.943490282496;11443.25618834773;11662;11913;11986.70033249141;12019.399030048287;12280.815787763013;12284.385780711107;11516.654500365003;11788.646340609796;11818.231814932002;11956.820647690118;12032.518699252281;11731.23746956445;11781.234219775688;11849.992750616295;11874.287542747463;12322.938385308375;11445.942770286096;11451.25566838153;11583.884161158281;11587.246828956091;11677.240979336317;9303.581338839904;9309.813803724022;9339.813203735752;9834.21326293909;10115.115685565044;7689.269519395649;8361.331093512626;8832.145249497677;9144.817103657757;9383 04:52:02 INFO - PROCESS | 3964 | __end_tp_report 04:52:02 INFO - PROCESS | 3964 | __start_cc_report 04:52:02 INFO - PROCESS | 3964 | _x_x_mozilla_cycle_collect,363 04:52:02 INFO - PROCESS | 3964 | __end_cc_report 04:52:02 INFO - PROCESS | 3964 | __startTimestamp1478260322504__endTimestamp 04:52:02 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | ------- Summary: start ------- 04:52:02 INFO - PROCESS | 3964 | Number of tests: 6 04:52:02 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | [#0] dojo.html Cycles:165 Average:5754.34 Median:6041.19 stddev:666.73 (11.0%) stddev-sans-first:630.36 04:52:02 INFO - PROCESS | 3964 | Values: 2910.7 3335.7 3360.7 3367.9 3395.8 5624.7 5712.7 5712.8 5732.8 5841.3 5668.4 5737.7 5781.6 5851.3 5858.2 5180.8 5213.8 5262.4 5327.9 5334.6 5306.9 5340.8 5356.1 5360.8 5456.7 5210.9 5323.9 5337.1 5340.7 5356.4 5153.9 5224.9 5260.9 5272.6 5309.4 5197.8 5210.0 5222.9 5224.3 5279.4 5653.0 5688.1 5798.2 5822.6 5841.7 6141.1 6161.3 6257.0 6293.3 6320.1 6260.5 6292.5 6294.9 6301.3 6328.1 4716.5 5942.8 5957.7 5968.5 5981.0 5194.0 5663.0 5930.9 6195.0 6201.4 6177.6 6209.8 6228.3 6230.9 6257.9 6183.4 6269.8 6280.2 6289.2 6293.3 6054.5 6148.7 6185.1 6188.7 6226.7 6219.8 6271.3 6272.8 6282.7 6285.9 4956.7 4989.9 4991.9 4993.2 5008.3 4377.0 4410.9 4412.7 4448.5 4493.8 5921.3 5983.3 5999.6 6053.9 6056.0 6270.5 6277.5 6287.1 6297.9 6325.8 5813.0 5889.8 5954.7 6036.4 6097.4 5436.8 5506.4 5519.6 5534.0 5552.7 6376.9 6386.9 6407.5 6409.0 6415.2 6181.1 6249.7 6281.0 6284.9 6287.6 5654.5 6280.5 6303.6 6347.0 6387.7 4843.5 5043.6 5084.3 5145.6 6288.9 6125.0 6270.2 6280.7 6296.1 6301.7 6120.8 6127.7 6137.4 6142.6 6143.4 6144.9 6165.8 6179.2 6216.7 6230.9 4735.9 5978.0 6060.4 6110.5 6161.3 6109.5 6146.6 6159.4 6174.7 6189.9 6041.2 6101.4 6104.8 6106.6 6129.1 04:52:02 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | [#1] ext.html Cycles:180 Average:69808.35 Median:40241.30 stddev:153543.36 (381.6%) stddev-sans-first:153885.82 04:52:02 INFO - PROCESS | 3964 | Values: 466.7 470.2 481.3 491.9 509.1 32088.0 34590.0 39394.4 39395.2 39546.4 40018.0 40311.8 40489.6 40520.4 40523.0 9394.6 9579.1 9582.0 9597.3 9628.9 9316.6 9461.4 9527.5 9528.5 9534.2 9319.1 9382.2 9393.8 9496.9 9545.4 9426.4 9458.5 9460.3 9518.1 9579.7 9284.0 9325.9 9330.9 9365.0 9467.5 39137.0 39274.0 39361.2 39371.0 39488.0 3262.1 3753.0 4038.8 4041.5 4042.8 30124.7 30870.5 31123.4 31135.8 31654.0 3152.3 3171.3 3298.8 3303.7 3344.1 464322.0 467288.0 471534.0 471651.0 473868.0 56203.0 56570.4 56919.0 56990.7 56991.4 58206.6 58902.0 59089.0 59450.7 59527.5 15297.6 15413.3 15502.7 15504.5 15566.6 10041.6 11986.3 12049.3 12146.6 12178.0 13832.7 15225.8 15236.8 15296.5 15410.4 6077.4 6169.7 6186.5 6244.3 6274.0 787.4 846.0 939.0 982.1 1067.2 29256.1 29425.9 29713.0 29803.7 31540.9 884.3 901.0 1011.9 1056.0 1120.6 12113.0 15011.0 15423.0 15506.5 15786.4 650765.0 653750.0 659681.0 661363.0 661526.0 56520.0 56569.4 56594.7 56758.7 56928.7 1297.9 1343.4 1344.5 1356.2 1365.2 28749.0 29650.0 29875.3 30293.0 30342.4 48345.0 48758.3 48779.0 48832.5 48839.5 575465.0 580260.0 580524.0 580595.0 581078.0 39239.6 39389.8 39821.4 40170.8 40339.8 45037.2 45939.4 46608.3 46742.1 46998.5 37990.2 38239.8 38670.6 38709.0 39619.2 37497.3 37809.6 38332.2 38876.6 38964.8 37104.4 37105.7 37546.2 38058.4 38226.4 28823.1 32183.7 33428.2 34090.5 34329.8 21283.7 21534.8 23206.8 25128.6 25830.1 04:52:02 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | [#2] jquery.html Cycles:180 Average:35295.71 Median:34504.74 stddev:95701.46 (277.4%) stddev-sans-first:95933.95 04:52:02 INFO - PROCESS | 3964 | Values: 347.2 370.5 385.9 387.6 407.9 18610.8 19962.8 20305.8 20643.6 20821.2 19332.6 19387.0 19941.0 20028.8 20283.8 4861.2 5768.5 5826.3 5996.1 6049.8 5930.5 5961.3 6067.8 6069.5 6074.5 4780.9 5609.7 5925.6 5950.6 6007.8 5898.1 5908.8 5959.1 5960.7 5978.0 6897.1 7279.9 7345.8 7347.1 7375.6 26833.5 27309.0 27434.6 28579.0 28653.0 2326.0 2382.4 2384.0 2472.5 2522.9 12180.9 14018.4 15244.5 15413.5 16614.9 1668.2 1735.5 1747.3 1984.9 2100.4 124049.4 136327.0 140169.0 141802.0 153296.0 20680.5 21456.9 21701.2 21710.0 21921.0 34388.3 34482.7 34526.8 34689.0 35122.1 8120.2 10132.5 10207.6 10392.3 10439.7 8402.1 8488.3 8538.6 8669.3 8704.7 9213.4 9251.3 9389.2 9453.3 9458.7 3768.7 3850.8 3875.1 3879.7 3882.5 699.5 751.6 844.9 897.4 952.5 17710.7 18494.3 19701.9 20474.8 22413.2 867.7 889.7 932.4 941.3 941.9 10361.5 10581.8 10876.7 10912.8 11044.6 544166.0 566086.0 576970.0 578039.0 594980.0 77523.2 96545.5 105241.5 134183.3 138893.3 1007.1 1012.9 1041.3 1043.3 1045.1 17508.7 18137.1 18362.0 19146.9 20023.3 50377.0 53609.8 54809.0 54841.7 55603.4 51860.0 56433.7 67223.7 78830.6 80054.6 6537.3 7573.5 7577.6 7824.3 7957.5 10639.8 12447.5 12491.5 12988.8 13199.4 8786.0 10668.6 10895.5 11251.7 11275.0 9216.4 10590.4 10676.6 11158.1 11281.5 11094.3 11430.0 11490.6 11534.3 11565.9 8050.8 8066.0 8186.8 8265.6 8320.5 8806.4 10517.6 10686.1 10868.0 11324.7 04:52:02 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | [#3] mootools.html Cycles:180 Average:5453.09 Median:5045.89 stddev:3016.33 (59.8%) stddev-sans-first:3007.96 04:52:02 INFO - PROCESS | 3964 | Values: 1213.7 1325.2 1575.1 1749.6 1810.6 7553.2 7845.7 8107.0 8527.8 8556.2 7991.7 8281.6 8293.1 8401.3 8402.4 2308.2 2325.0 2333.3 2337.4 2349.5 1872.8 1944.9 2342.3 2374.5 2375.4 1929.6 2312.0 2316.8 2339.6 2343.9 2281.5 2291.4 2304.3 2320.2 2325.5 2264.1 2294.0 2323.4 2349.7 2353.3 8379.6 8439.1 8444.9 8453.5 8505.1 5096.3 5253.4 5274.7 5275.2 5293.9 3304.6 3372.5 3652.5 3665.2 3669.6 1046.7 1055.4 1082.2 1095.6 1102.3 10814.2 10865.0 10984.0 10990.0 11089.9 4446.2 4596.6 4679.6 4681.7 4767.2 9351.7 9484.2 9501.6 9509.7 9513.5 5145.5 5205.5 5212.9 5214.7 5231.7 3694.8 4529.5 4745.5 4791.7 4819.4 3827.6 3834.8 3904.3 3928.6 3944.3 1024.5 1146.3 1211.6 1265.2 1285.7 765.9 784.0 896.4 909.6 925.4 5656.3 5729.2 5775.6 5859.1 5889.3 415.6 428.1 440.2 443.0 447.5 3542.6 3806.0 3848.2 3951.3 3982.5 9875.4 10131.1 10215.1 10244.6 10252.8 9456.1 10133.6 10135.7 10156.1 10158.5 7773.7 7949.6 7964.3 7973.9 8006.2 5129.6 5534.2 5752.9 5851.0 5958.3 8471.1 9315.2 9318.2 9372.6 9471.7 9290.4 9361.6 9382.2 9440.5 9492.0 6517.1 6600.9 6715.7 6906.3 6997.5 7831.3 8001.3 8002.8 8008.0 8013.4 7042.2 7211.4 7211.6 7266.1 7266.1 7062.0 7099.3 7148.2 7234.5 7254.6 6599.4 6614.4 6644.9 6698.5 6702.5 4854.7 4946.3 4988.8 4988.9 5028.3 4811.2 4871.8 4944.9 5063.5 5096.2 04:52:02 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | [#4] prototype.html Cycles:180 Average:4456.81 Median:4932.70 stddev:1414.27 (28.7%) stddev-sans-first:1383.22 04:52:02 INFO - PROCESS | 3964 | Values: 289.5 301.1 307.9 309.9 313.0 4434.9 5340.7 5487.8 5545.8 5593.9 4784.0 5190.3 5433.3 5435.8 5486.8 3290.9 3496.2 3999.3 4257.9 4261.6 3654.0 3704.3 3796.8 4216.5 4269.4 4142.8 4168.5 4177.3 4178.4 4182.2 3027.9 3410.9 3917.9 4175.6 4188.8 3305.9 3353.4 3446.7 3487.2 3513.9 4243.9 4812.4 5193.2 5265.8 5336.5 3974.7 4059.7 4342.7 4389.7 4554.1 4439.0 5590.8 5606.0 5682.5 5747.6 3827.8 3853.7 3858.8 3863.9 3870.3 5320.9 5943.8 5981.4 6037.5 6098.8 3133.6 3759.9 3908.3 3909.4 3910.8 3145.7 3585.1 3595.6 3932.0 3986.6 4221.9 4337.8 4652.7 4691.6 4723.4 4530.0 5520.8 5711.5 5736.7 5814.2 1113.4 1172.1 1328.9 1355.8 1374.9 707.0 779.5 813.8 826.0 836.0 3667.3 3824.0 4600.2 4617.7 4648.3 4676.9 4963.6 4979.0 4988.1 5023.5 4116.6 4156.9 4177.2 4249.4 4256.7 2457.7 2831.0 3122.9 3135.0 3142.4 5258.0 5310.3 5451.1 6021.2 6212.0 4941.5 5164.8 5931.5 6080.4 6177.7 5062.8 5922.7 6018.8 6079.7 6093.0 5786.9 5799.7 5890.7 5907.6 5976.5 5161.6 5279.6 5528.3 5587.6 5756.0 5160.5 5616.4 5782.0 6044.7 6098.0 3652.5 3706.6 3709.2 3834.7 3864.7 5162.3 5490.4 5777.9 5820.3 5895.1 4668.5 4923.9 5159.7 5611.6 5744.6 5339.7 5566.4 5593.7 5638.4 5714.8 5437.8 5571.4 5575.2 5585.4 5648.3 5128.4 5184.9 5439.7 5626.9 5658.9 4357.0 4617.7 5109.9 5307.9 5474.9 04:52:02 INFO - PROCESS | 3964 | 04:52:02 INFO - PROCESS | 3964 | [#5] yui.html Cycles:180 Average:19966.42 Median:605.25 stddev:29363.83 (4851.5%) stddev-sans-first:29410.20 04:52:02 INFO - PROCESS | 3964 | Values: 598.5 652.9 733.5 737.4 761.0 14455.0 14937.4 15156.2 15312.8 15357.6 13733.2 13760.4 13923.2 13961.3 13962.6 819.0 824.8 826.5 828.2 829.8 666.4 687.5 809.3 831.0 835.6 847.4 850.9 884.1 884.5 887.6 834.6 873.0 879.0 881.6 881.9 612.0 636.5 641.3 652.4 656.4 13887.5 13959.3 13971.6 14034.0 14115.6 10244.3 13032.5 13499.5 13532.0 13570.1 9449.5 11383.2 11388.7 11410.1 11641.2 3637.4 3668.7 3694.5 3708.4 3726.9 84486.6 85244.0 85723.6 88737.0 89579.0 10499.4 10545.9 10546.4 10749.8 10759.6 20351.5 20520.7 20628.6 20719.4 20822.5 6446.3 7537.4 7624.7 7635.8 7650.8 4249.7 4250.7 4324.4 4376.3 4415.0 3397.4 3502.5 3515.2 3517.6 3524.2 623.5 629.8 646.4 655.2 658.4 620.6 643.5 645.3 646.9 668.1 8523.9 8955.9 8964.5 9016.8 9033.5 630.4 660.8 661.8 663.8 685.6 3483.7 4162.8 4346.3 4379.4 4392.2 91742.0 101436.0 102019.0 102799.5 117213.1 65245.0 70774.0 74268.0 88525.2 109566.9 82157.0 85647.0 86201.6 86373.6 88476.6 8414.8 8451.0 8471.7 8598.6 8681.1 48357.8 56197.4 57775.6 58401.0 59579.1 77822.0 80492.0 84369.0 85706.0 89195.0 10917.9 11118.7 11301.9 11443.3 11662.0 11913.0 11986.7 12019.4 12280.8 12284.4 11516.7 11788.6 11818.2 11956.8 12032.5 11731.2 11781.2 11850.0 11874.3 12322.9 11445.9 11451.3 11583.9 11587.2 11677.2 9303.6 9309.8 9339.8 9834.2 10115.1 7689.3 8361.3 8832.1 9144.8 9383.0 04:52:02 INFO - PROCESS | 3964 | -------- Summary: end -------- 04:52:02 INFO - PROCESS | 3964 | 04:52:03 INFO - TEST-INFO | 3964: exit 0 04:52:03 INFO - TEST-OK | dromaeo_css | took 1114518ms 04:52:03 INFO - TEST-START | kraken 04:52:03 INFO - operating with platform_type : mac_ 04:52:03 INFO - Initialising browser for kraken test... 04:52:03 INFO - TEST-INFO | started process 3968 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmp5r2Jfb/profile http://localhost:59444/getInfo.html) 04:52:05 INFO - PROCESS | 3968 | 2016-11-04 04:52:05.686 plugin-container[3969:112049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 04:52:05 INFO - PROCESS | 3968 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:52:05 INFO - PROCESS | 3968 | 2016-11-04 04:52:05.689 plugin-container[3969:112049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 04:52:05 INFO - PROCESS | 3968 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:52:05 INFO - PROCESS | 3968 | 2016-11-04 04:52:05.689 plugin-container[3969:112049] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:52:05 INFO - PROCESS | 3968 | __metrics Screen width/height:1600/1200 04:52:05 INFO - PROCESS | 3968 | colorDepth:24 04:52:05 INFO - PROCESS | 3968 | Browser inner width/height: 1024/689 04:52:05 INFO - PROCESS | 3968 | __metrics 04:52:05 ERROR - PROCESS | 3968 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 04:52:06 INFO - TEST-INFO | 3968: exit 0 04:52:06 INFO - Browser initialized. 04:52:06 INFO - Running cycle 1/1 for kraken test... 04:52:06 INFO - TEST-INFO | started process 3970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmp5r2Jfb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:52:09 INFO - PROCESS | 3970 | 2016-11-04 04:52:09.123 plugin-container[3971:112169] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 04:52:09 INFO - PROCESS | 3970 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:52:09 INFO - PROCESS | 3970 | 2016-11-04 04:52:09.123 plugin-container[3971:112169] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 04:52:09 INFO - PROCESS | 3970 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:52:09 INFO - PROCESS | 3970 | 2016-11-04 04:52:09.123 plugin-container[3971:112169] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:52:18 INFO - PROCESS | 3970 | RSS: Main: 239099904 04:52:18 INFO - PROCESS | 3970 | 04:52:21 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59444/tests/kraken/driver.html?testName=audio-beat-detection) 04:52:22 INFO - PROCESS | 3970 | RSS: Main: 251600896 04:52:22 INFO - PROCESS | 3970 | 04:52:25 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59444/tests/kraken/driver.html?testName=audio-dft) 04:52:26 INFO - PROCESS | 3970 | RSS: Main: 259997696 04:52:26 INFO - PROCESS | 3970 | 04:52:29 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59444/tests/kraken/driver.html?testName=audio-fft) 04:52:30 INFO - PROCESS | 3970 | RSS: Main: 262356992 04:52:30 INFO - PROCESS | 3970 | 04:52:32 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59444/tests/kraken/driver.html?testName=audio-oscillator) 04:52:32 INFO - PROCESS | 3970 | RSS: Main: 263712768 04:52:32 INFO - PROCESS | 3970 | 04:52:34 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59444/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:52:35 INFO - PROCESS | 3970 | RSS: Main: 264720384 04:52:35 INFO - PROCESS | 3970 | 04:52:38 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59444/tests/kraken/driver.html?testName=imaging-darkroom) 04:52:39 INFO - PROCESS | 3970 | RSS: Main: 264835072 04:52:39 INFO - PROCESS | 3970 | 04:52:42 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59444/tests/kraken/driver.html?testName=imaging-desaturate) 04:52:42 INFO - PROCESS | 3970 | RSS: Main: 265072640 04:52:42 INFO - PROCESS | 3970 | 04:52:45 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59444/tests/kraken/driver.html?testName=json-parse-financial) 04:52:46 INFO - PROCESS | 3970 | RSS: Main: 265322496 04:52:46 INFO - PROCESS | 3970 | 04:52:47 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59444/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:52:48 INFO - PROCESS | 3970 | RSS: Main: 265154560 04:52:48 INFO - PROCESS | 3970 | 04:52:50 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59444/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:52:51 INFO - PROCESS | 3970 | RSS: Main: 265388032 04:52:51 INFO - PROCESS | 3970 | 04:52:53 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59444/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:52:53 INFO - PROCESS | 3970 | RSS: Main: 265211904 04:52:53 INFO - PROCESS | 3970 | 04:52:56 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:52:57 INFO - PROCESS | 3970 | RSS: Main: 265338880 04:52:57 INFO - PROCESS | 3970 | 04:53:01 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:53:01 INFO - PROCESS | 3970 | RSS: Main: 263258112 04:53:01 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | Cycle 1(1): loaded http://localhost:59444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59444/tests/kraken/driver.html?testName=ai-astar) 04:53:03 INFO - PROCESS | 3970 | RSS: Main: 265015296 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | __start_tp_report 04:53:03 INFO - PROCESS | 3970 | _x_x_mozilla_page_load 04:53:03 INFO - PROCESS | 3970 | _x_x_mozilla_page_load_details 04:53:03 INFO - PROCESS | 3970 | |i|pagename|runs| 04:53:03 INFO - PROCESS | 3970 | |0;ai-astar;101;111;116;117;117;117;119;119;117;120 04:53:03 INFO - PROCESS | 3970 | |1;audio-beat-detection;180;105;136;187;151;118;126;115;143;113 04:53:03 INFO - PROCESS | 3970 | |2;audio-dft;213;210;245;198;196;199;197;197;196;196 04:53:03 INFO - PROCESS | 3970 | |3;audio-fft;67;52;63;103;86;52;52;54;100;84 04:53:03 INFO - PROCESS | 3970 | |4;audio-oscillator;103;72;74;94;104;88;72;97;104;126 04:53:03 INFO - PROCESS | 3970 | |5;imaging-gaussian-blur;93;87;90;88;94;107;139;121;125;133 04:53:03 INFO - PROCESS | 3970 | |6;imaging-darkroom;95;118;157;115;102;95;95;96;96;96 04:53:03 INFO - PROCESS | 3970 | |7;imaging-desaturate;89;89;87;88;91;84;110;145;138;98 04:53:03 INFO - PROCESS | 3970 | |8;json-parse-financial;57;55;57;56;57;58;57;59;56;56 04:53:03 INFO - PROCESS | 3970 | |9;json-stringify-tinderbox;78;73;74;79;71;72;64;75;74;75 04:53:03 INFO - PROCESS | 3970 | |10;stanford-crypto-aes;86;90;89;91;91;89;86;90;86;89 04:53:03 INFO - PROCESS | 3970 | |11;stanford-crypto-ccm;154;152;149;180;163;167;152;166;239;148 04:53:03 INFO - PROCESS | 3970 | |12;stanford-crypto-pbkdf2;174;204;203;172;172;247;244;189;174;171 04:53:03 INFO - PROCESS | 3970 | |13;stanford-crypto-sha256-iterative;67;69;70;67;68;68;59;91;75;70 04:53:03 INFO - PROCESS | 3970 | __end_tp_report 04:53:03 INFO - PROCESS | 3970 | __start_cc_report 04:53:03 INFO - PROCESS | 3970 | _x_x_mozilla_cycle_collect,952 04:53:03 INFO - PROCESS | 3970 | __end_cc_report 04:53:03 INFO - PROCESS | 3970 | __startTimestamp1478260383672__endTimestamp 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | ------- Summary: start ------- 04:53:03 INFO - PROCESS | 3970 | Number of tests: 14 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#0] ai-astar Cycles:10 Average:115.40 Median:117.00 stddev:5.62 (4.8%) stddev-sans-first:2.60 04:53:03 INFO - PROCESS | 3970 | Values: 101.0 111.0 116.0 117.0 117.0 117.0 119.0 119.0 117.0 120.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:139.50 stddev:28.18 (20.2%) stddev-sans-first:25.32 04:53:03 INFO - PROCESS | 3970 | Values: 180.0 105.0 136.0 187.0 151.0 118.0 126.0 115.0 143.0 113.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#2] audio-dft Cycles:10 Average:204.70 Median:198.50 stddev:15.43 (7.8%) stddev-sans-first:16.08 04:53:03 INFO - PROCESS | 3970 | Values: 213.0 210.0 245.0 198.0 196.0 199.0 197.0 197.0 196.0 196.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#3] audio-fft Cycles:10 Average:71.30 Median:58.50 stddev:20.30 (34.7%) stddev-sans-first:21.48 04:53:03 INFO - PROCESS | 3970 | Values: 67.0 52.0 63.0 103.0 86.0 52.0 52.0 54.0 100.0 84.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:73.00 stddev:17.37 (23.8%) stddev-sans-first:18.07 04:53:03 INFO - PROCESS | 3970 | Values: 103.0 72.0 74.0 94.0 104.0 88.0 72.0 97.0 104.0 126.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#5] imaging-gaussian-blur Cycles:10 Average:107.70 Median:87.50 stddev:20.08 (23.0%) stddev-sans-first:20.59 04:53:03 INFO - PROCESS | 3970 | Values: 93.0 87.0 90.0 88.0 94.0 107.0 139.0 121.0 125.0 133.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#6] imaging-darkroom Cycles:10 Average:106.50 Median:95.00 stddev:19.73 (20.8%) stddev-sans-first:20.48 04:53:03 INFO - PROCESS | 3970 | Values: 95.0 118.0 157.0 115.0 102.0 95.0 95.0 96.0 96.0 96.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:88.50 stddev:22.18 (25.1%) stddev-sans-first:23.03 04:53:03 INFO - PROCESS | 3970 | Values: 89.0 89.0 87.0 88.0 91.0 84.0 110.0 145.0 138.0 98.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.00 stddev:1.14 (2.0%) stddev-sans-first:1.20 04:53:03 INFO - PROCESS | 3970 | Values: 57.0 55.0 57.0 56.0 57.0 58.0 57.0 59.0 56.0 56.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#9] json-stringify-tinderbox Cycles:10 Average:73.50 Median:74.50 stddev:4.14 (5.6%) stddev-sans-first:4.06 04:53:03 INFO - PROCESS | 3970 | Values: 78.0 73.0 74.0 79.0 71.0 72.0 64.0 75.0 74.0 75.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:89.50 stddev:2.00 (2.2%) stddev-sans-first:1.87 04:53:03 INFO - PROCESS | 3970 | Values: 86.0 90.0 89.0 91.0 91.0 89.0 86.0 90.0 86.0 89.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#11] stanford-crypto-ccm Cycles:10 Average:167.00 Median:164.50 stddev:27.23 (16.6%) stddev-sans-first:28.47 04:53:03 INFO - PROCESS | 3970 | Values: 154.0 152.0 149.0 180.0 163.0 167.0 152.0 166.0 239.0 148.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.00 Median:196.00 stddev:29.44 (15.0%) stddev-sans-first:30.23 04:53:03 INFO - PROCESS | 3970 | Values: 174.0 204.0 203.0 172.0 172.0 247.0 244.0 189.0 174.0 171.0 04:53:03 INFO - PROCESS | 3970 | 04:53:03 INFO - PROCESS | 3970 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:69.50 stddev:8.25 (11.9%) stddev-sans-first:8.66 04:53:03 INFO - PROCESS | 3970 | Values: 67.0 69.0 70.0 67.0 68.0 68.0 59.0 91.0 75.0 70.0 04:53:03 INFO - PROCESS | 3970 | -------- Summary: end -------- 04:53:03 INFO - PROCESS | 3970 | 04:53:04 INFO - TEST-INFO | 3970: exit 0 04:53:04 INFO - TEST-OK | kraken | took 61433ms 04:53:04 INFO - SUITE-END | took 1175s 04:53:04 INFO - Completed test suite (00:19:36) 04:53:04 INFO - javascript benchmark 04:53:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7232.252745722631, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2910.72348126928, 3335.749818763592, 3360.69753722165, 3367.9494807577885, 3395.830208489578, 5624.690642014688, 5712.714364281785, 5712.771489140439, 5732.770689172438, 5841.3282472515675, 5668.404817494155, 5737.741801618927, 5781.62419442737, 5851.297844258685, 5858.20914176586, 5180.792768289264, 5213.843584692465, 5262.4474430184855, 5327.854511280074, 5334.626576139672, 5306.92039619406, 5340.759665815028, 5356.11624082026, 5360.758765855527, 5456.727163641822, 5210.869728256786, 5323.920141197885, 5337.11937530307, 5340.706261155635, 5356.35723713153, 5153.871153221162, 5224.8693782655355, 5260.92108618371, 5272.604554658377, 5309.362876454811, 5197.766100525486, 5209.973950130263, 5222.8694282642855, 5224.346956630421, 5279.366476022902, 5653.0, 5688.061469857468, 5798.217240672498, 5822.563307751935, 5841.737121829486, 6141.056449558104, 6161.260648722137, 6257.0, 6293.339199384044, 6320.051992201161, 6260.530460215502, 6292.465140463066, 6294.937050629481, 6301.338359472281, 6328.050792381134, 4716.481187069419, 5942.762289508458, 5957.748872736731, 5968.492678122321, 5981.0, 5194.0, 5663.0, 5930.911036334458, 6194.977828658311, 6201.379862013853, 6177.567570270128, 6209.813705588839, 6228.283747369111, 6230.937690623035, 6257.8748425031245, 6183.350748171467, 6269.780557680459, 6280.214973128359, 6289.213848268966, 6293.339199383999, 6054.455099041107, 6148.692565371803, 6185.072239164071, 6188.659623720649, 6226.657533835682, 6219.782307619212, 6271.27880293763, 6272.780452684134, 6282.748690052438, 6285.9057114143325, 4956.702597844069, 4989.875253118628, 4991.900161996812, 4993.201087825931, 5008.29883816266, 4377.0, 4410.933835992462, 4412.6690498212765, 4448.488423831236, 4493.820247190076, 5921.3190483094995, 5983.311919129269, 5999.640021598718, 6053.909191362133, 6055.970485017558, 6270.467010304084, 6277.466415354655, 6287.1198032275515, 6297.905531417033, 6325.849211268191, 5812.953668339739, 5889.764409423575, 5954.702264886739, 6036.396360363929, 6097.390260973956, 5436.782528698887, 5506.421825708243, 5519.586031047687, 5534.0, 5552.7223638817895, 6376.872462550723, 6386.90419643715, 6407.455366293824, 6408.967955160194, 6415.166028416276, 6181.072839074175, 6249.656268905072, 6280.9685951569945, 6284.937150628435, 6287.559870808999, 5654.491095801398, 6280.497560195082, 6303.621782693053, 6347.047942808431, 6387.712552935222, 4843.515648435128, 5043.646944713835, 5084.313617661568, 5145.639805213599, 6288.937110629018, 6124.991279909774, 6270.184875966095, 6280.654563999024, 6296.118543403835, 6301.7164227608955, 6120.843333897588, 6127.678242056309, 6137.376985335611, 6142.560166306883, 6143.354947730602, 6144.863200307957, 6165.815025549241, 6179.227596550431, 6216.689165541615, 6230.937690623035, 4735.905281894274, 5977.970110149421, 6060.363661815622, 6110.511159107172, 6161.260648722182, 6109.526129009804, 6146.6004709693725, 6159.384061593805, 6174.691265436621, 6189.876202476015, 6041.18993135008, 6101.368489499298, 6104.7926496150985, 6106.551676171802, 6129.14485165794], "value": 5711.363784571227, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.7106394035698, 470.1631096647966, 481.33816002995894, 491.8671958571186, 509.0658641393044, 32088.0, 34590.0, 39394.409083863764, 39395.21209575811, 39546.406803897895, 40018.0, 40311.79844100783, 40489.59510404895, 40520.392194117114, 40523.0, 9394.624215031408, 9579.13787759105, 9581.95209023954, 9597.328187026911, 9628.903710962872, 9316.627334906596, 9461.432314061143, 9527.475988820646, 9528.523573821281, 9534.189593884528, 9319.068093190694, 9382.155605995458, 9393.76515587109, 9496.857547136799, 9545.422735861313, 9426.387284826464, 9458.527073646359, 9460.290478214127, 9518.143367096925, 9579.664711735057, 9284.025177356416, 9325.860112098324, 9330.906690933072, 9364.95317523408, 9467.52662366879, 39137.0, 39274.0, 39361.22874470627, 39371.0, 39488.0, 3262.1029216965285, 3752.962470375316, 4038.838446462138, 4041.4746083009313, 4042.797860106983, 30124.69875301241, 30870.536941945888, 31123.37753244945, 31135.844320778477, 31654.0, 3152.290734584723, 3171.334019855833, 3298.802071875695, 3303.7357011439267, 3344.0803778960735, 464322.0, 467288.0, 471534.0, 471651.0, 473868.0, 56203.0, 56570.43429565734, 56919.0, 56990.71504642533, 56991.430085698616, 58206.560048792686, 58902.0, 59089.0, 59450.70274648599, 59527.51181220504, 15297.617559560877, 15413.306401211972, 15502.689946201013, 15504.457343992906, 15566.610834729223, 10041.598336066476, 11986.280823150639, 12049.33728644933, 12146.635600931986, 12178.0, 13832.654183645489, 15225.771613425808, 15236.847631523764, 15296.541103766906, 15410.383584656713, 6077.4226448487325, 6169.660668663267, 6186.484311343749, 6244.344343843831, 6273.960962687145, 787.4094429178116, 845.9873101903477, 938.9764168660018, 982.111189373618, 1067.1836045425257, 29256.122316330544, 29425.85287073551, 29713.0, 29803.701962980525, 31540.89606863749, 884.3102380143498, 900.9864852027225, 1011.8970322348675, 1056.0, 1120.6469961962118, 12113.027114789757, 15011.0, 15423.0, 15506.5348039559, 15786.36854525829, 650765.0, 653750.0, 659681.0, 661363.0, 661526.0, 56520.0, 56569.43430565642, 56594.717026414604, 56758.71620641871, 56928.71535642295, 1297.8896793772635, 1343.3887581150748, 1344.4823742859069, 1356.220173400279, 1365.2491129878526, 28749.0, 29650.0, 29875.253118672208, 30293.0, 30342.393152136392, 48345.03309933711, 48758.268625969926, 48779.0, 48832.51167488279, 48839.5116048835, 575465.0, 580260.0, 580524.0, 580595.0, 581078.0, 39239.60760392359, 39389.80305098456, 39821.40267896042, 40170.79914600408, 40339.798301008304, 45037.19851205915, 45939.392121275596, 46608.30087548622, 46742.06515869596, 46998.53001469941, 37990.24019519649, 38239.80880095582, 38670.6132938667, 38709.0, 39619.20761584694, 37497.25005499929, 37809.62190378061, 38332.23335533218, 38876.6112338873, 38964.805175975074, 37104.44343334798, 37105.701300454355, 37546.249075018895, 38058.429123562615, 38226.42660360041, 28823.135305940854, 32183.678163219003, 33428.1642958928, 34090.488642670876, 34329.828350858086, 21283.68074478915, 21534.784652153903, 23206.76793232046, 25128.617926014245, 25830.095946641777], "value": 19533.785355400472, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.22915128414917, 370.4739270236266, 385.90018447424836, 387.59689922480584, 407.86206483909893, 18610.812567172397, 19962.800371996313, 20305.796942030574, 20643.587128257426, 20821.167153313887, 19332.613347733073, 19387.0, 19941.0, 20028.799712002874, 20283.78297302163, 4861.173600487926, 5768.480836724683, 5826.271716035496, 5996.130561068642, 6049.848753781147, 5930.466258036776, 5961.284645842505, 6067.757289708428, 6069.453749162574, 6074.544409169308, 4780.880477988043, 5609.719514024303, 5925.5555833312455, 5950.642961422307, 6007.849803754897, 5898.085796701518, 5908.822735317947, 5959.135925290809, 5960.701964901759, 5978.0, 6897.137857767779, 7279.927200727978, 7345.816354591125, 7347.08161479815, 7375.63121843911, 26833.46333073347, 27309.0, 27434.5884811728, 28579.0, 28653.0, 2325.9651105233434, 2382.4401265702545, 2383.976160238393, 2472.4684192898612, 2522.9116980905765, 12180.939095304555, 14018.43926242949, 15244.542663720105, 15413.53759387209, 16614.916925415415, 1668.2242757117876, 1735.5400818782907, 1747.2836137183695, 1984.880907145576, 2100.359390385932, 124049.37975310156, 136327.0, 140169.0, 141802.0, 153295.9999999989, 20680.482987925123, 21456.892715536324, 21701.240456583942, 21709.96060551524, 21921.01355439009, 34388.312233755685, 34482.655173448446, 34526.82736586301, 34689.0, 35122.12194695153, 8120.187981201832, 10132.493375331203, 10207.642732504326, 10392.324498907547, 10439.739006524898, 8402.117777633383, 8488.278496327757, 8538.573071346533, 8669.306455483546, 8704.738857834274, 9213.447193168431, 9251.306152038624, 9389.248860091177, 9453.291003174789, 9458.668946586837, 3768.736188466781, 3850.8074596270076, 3875.1087249932675, 3879.728419010642, 3882.456456096149, 699.4893727578896, 751.5678484871221, 844.923956843887, 897.4480694372913, 952.4666186935339, 17710.734338984927, 18494.260229590665, 19701.916394598436, 20474.79525204759, 22413.215537456435, 867.6702852915852, 889.675268526996, 932.38462614674, 941.2987324443334, 941.8540126280437, 10361.481925903825, 10581.84127238092, 10876.72808179786, 10912.836307455394, 11044.558217671203, 544166.0, 566086.0, 576970.0, 578039.0, 594980.0, 77523.2247677516, 96545.51727241318, 105241.47379263055, 134183.31633367474, 138893.3055334717, 1007.1439276614818, 1012.9341592796592, 1041.2607048995299, 1043.3009883378154, 1045.0594464981425, 17508.737368939732, 18137.093145342416, 18362.0, 19146.904265478584, 20023.29918452847, 50377.0, 53609.762682038985, 54809.0, 54841.72579137238, 55603.443965559825, 51860.0, 56433.71783141058, 67223.66388168027, 78830.6058469727, 80054.599727001, 6537.346265373425, 7573.545587264782, 7577.621118943921, 7824.295813376596, 7957.482763620346, 10639.787204256027, 12447.502099915902, 12491.500339986662, 12988.805167922677, 13199.406026728628, 8786.0, 10668.62659806903, 10895.509702063267, 11251.718707032389, 11275.04162146194, 9216.400933939272, 10590.364578125336, 10676.57293708243, 11158.051565617014, 11281.548738050386, 11094.334339939629, 11430.0, 11490.597829076276, 11534.3079415232, 11565.884341156818, 8050.838983220186, 8066.0, 8186.754397368089, 8265.586720664063, 8320.459170153921, 8806.383553151218, 10517.579296828042, 10686.145108391465, 10868.0, 11324.716882078013], "value": 10467.491422279085, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1213.6541085790552, 1325.2446105719737, 1575.1415478564181, 1749.5975925537134, 1810.565464288571, 7553.244675532444, 7845.725399611015, 8106.986626671666, 8527.786805329863, 8556.229939305462, 7991.680332786696, 8281.58592070397, 8293.087760346358, 8401.285890699282, 8402.369822263325, 2308.203669733943, 2325.011869955271, 2333.311673056444, 2337.357226762637, 2349.518348738513, 1872.7846297675806, 1944.931927382542, 2342.3441436397834, 2374.5488357212075, 2375.3824005758543, 1929.601039246542, 2311.9884400578057, 2316.791488766011, 2339.578875802355, 2343.8593684378907, 2281.4638559938403, 2291.4385975436044, 2304.320225533472, 2320.2227253869974, 2325.476767727256, 2264.116994372196, 2294.0365046680436, 2323.3610757041947, 2349.729781075181, 2353.341064501942, 8379.62291696875, 8439.071702112762, 8444.915550844475, 8453.535055571941, 8505.149485051506, 5096.286519887219, 5253.4221235664045, 5274.683518988873, 5275.208718692227, 5293.920591191097, 3304.6364899860996, 3372.5278461015478, 3652.4703917932015, 3665.21197942441, 3669.5963444021136, 1046.7383154211448, 1055.4353420919847, 1082.1775450657558, 1095.6055819904827, 1102.3330884814652, 10814.188935829845, 10865.022148006718, 10984.0, 10990.0, 11089.944550277278, 4446.199684056901, 4596.632269418438, 4679.64902632304, 4681.7190968542, 4767.189577771787, 9351.672691455766, 9484.193843523377, 9501.572429240701, 9509.66716164931, 9513.476758778195, 5145.4597267287145, 5205.453427390145, 5212.921806172911, 5214.713190774544, 5231.659942103751, 3694.7598406103516, 4529.547045295444, 4745.501722319107, 4791.736454495036, 4819.373481447389, 3827.559830619459, 3834.8466061357235, 3904.2972264992572, 3928.5678575356687, 3944.3491823849317, 1024.4672770159475, 1146.277844957688, 1211.6308571314535, 1265.171312790124, 1285.7107150890974, 765.8774596064603, 783.9804004899923, 896.3994123936978, 909.5543183840003, 925.4123631493915, 5656.321241451053, 5729.169270455761, 5775.5668324875805, 5859.091840764689, 5889.322727886348, 415.6093272324006, 428.1009879253543, 440.1592957451253, 443.0430270615455, 447.53456405338085, 3542.6457354264367, 3806.0, 3848.2111167210655, 3951.269015232191, 3982.5420076691553, 9875.40747555149, 10131.088202061703, 10215.131713804261, 10244.59021639141, 10252.846207306897, 9456.14894659484, 10133.64532241368, 10135.695929122137, 10156.085952264331, 10158.542865571068, 7773.727919522789, 7949.64226609804, 7964.283214510722, 7973.920260797434, 8006.2394072564175, 5129.615278854176, 5534.169874518693, 5752.884942301047, 5851.0, 5958.285005799332, 8471.110533394152, 9315.208207302456, 9318.207952324128, 9372.625094996396, 9471.715848524555, 9290.35224960883, 9361.625534978797, 9382.155605995493, 9440.48077355752, 9491.952540237255, 6517.120188774455, 6600.867982640416, 6715.731370745116, 6906.309369063053, 6997.510174287751, 7831.2560306770765, 8001.279884810395, 8002.83994320122, 8007.959960200162, 8013.398995075276, 7042.154941407064, 7211.3870321023305, 7211.567305961659, 7266.055412796302, 7266.091738532684, 7062.0, 7099.290070992859, 7148.249433809583, 7234.529755565872, 7254.5647261164495, 6599.373059559334, 6614.371634694697, 6644.933550664431, 6698.497612678971, 6702.463802895854, 4854.708717476963, 4946.332245146859, 4988.800447982186, 4988.850334489971, 5028.270900719448, 4811.206150985048, 4871.756412179448, 4944.851654450372, 5063.518965698253, 5096.184610462308], "value": 4328.918344538486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.54975013853465, 301.11021930196273, 307.89993252193045, 309.9085769697939, 312.97496200303976, 4434.911301773963, 5340.706261155635, 5487.835364939053, 5545.805896793613, 5593.916091258634, 4783.9760801196035, 5190.273361729361, 5433.293671822667, 5435.755391007404, 5486.753096110675, 3290.8848190313342, 3496.1958749487635, 3999.3401088820383, 4257.914231870868, 4261.552536983619, 3653.9586217927895, 3704.3332200203954, 3796.810159492028, 4216.5452918742985, 4269.423627810237, 4142.813573389205, 4168.541460439349, 4177.310743727281, 4178.409386180572, 4182.22628813669, 3027.8940237091815, 3410.9488357674654, 3917.8834032300942, 4175.645070169039, 4188.811503482335, 3305.8512366943546, 3353.4131526982924, 3446.7414943879057, 3487.1805125795418, 3513.8770143045126, 4243.936340954888, 4812.374391329105, 5193.195054766479, 5265.842024739264, 5336.546393556514, 3974.721769476138, 4059.700895713373, 4342.739435633841, 4389.692721509496, 4554.089182163548, 4438.979034822009, 5590.832275031756, 5605.990921634104, 5682.488576028178, 5747.56893233005, 3827.8468861245515, 3853.749506282111, 3858.787766672832, 3863.8840834775, 3870.3033453978273, 5320.893582128336, 5943.821685349447, 5981.371955944606, 6037.516998640098, 6098.756049758004, 3133.6396314423678, 3759.9248015039548, 3908.296506628777, 3909.4135879618284, 3910.804459777, 3145.685431456836, 3585.0640654047043, 3595.5977168904146, 3931.980340098281, 3986.621270979252, 4221.860097773645, 4337.804798783999, 4652.720836749806, 4691.601213896789, 4723.449337357857, 4529.977350113229, 5520.779168833282, 5711.457411545896, 5736.71316434185, 5814.244148260699, 1113.4210210690396, 1172.0799172649429, 1328.8671132886827, 1355.833982774813, 1374.8625137486172, 706.9540479868792, 779.4527862193542, 813.8290958898698, 825.9628316725762, 836.0093289452019, 3667.2665466906724, 3823.9808800955816, 4600.204831014426, 4617.746023968714, 4648.302754586839, 4676.883077923011, 4963.6277279204205, 4979.004199160183, 4988.127077761377, 5023.522765337287, 4116.629503344713, 4156.91686166281, 4177.227212965611, 4249.447571815706, 4256.723312984708, 2457.7465492598903, 2831.0, 3122.8906988255303, 3134.98432507836, 3142.4343618148496, 5258.031160553317, 5310.2831117798605, 5451.073317536028, 6021.217241758543, 6212.0, 4941.465558574419, 5164.8192313268855, 5931.466168044811, 6080.361562036011, 6177.722002509898, 5062.848114556569, 5922.674252916131, 6018.849528761729, 6079.6960151992225, 6092.9535232383805, 5786.855328616818, 5799.652020878761, 5890.676012819336, 5907.645541267538, 5976.462118409364, 5161.638685292144, 5279.551238144801, 5528.308961379827, 5587.552995760248, 5755.971220143872, 5160.458151893997, 5616.438356164351, 5782.017057100219, 6044.667543285162, 6098.011974132732, 3652.5345391859855, 3706.6108058653454, 3709.2396058808417, 3834.7315687902696, 3864.71014673895, 5162.277281180713, 5490.372368322342, 5777.91333129995, 5820.272465941757, 5895.056790913433, 4668.5098064702715, 4923.926141107958, 5159.742012899415, 5611.607187496836, 5744.597878148489, 5339.652922560022, 5566.415526369673, 5593.685483911359, 5638.407967163389, 5714.828555143353, 5437.770514089205, 5571.4428557143965, 5575.188063879336, 5585.385607583244, 5648.265725455829, 5128.358955130609, 5184.948150518447, 5439.728013599225, 5626.915596265977, 5658.85852853682, 4357.0, 4617.699849509771, 5109.92691534767, 5307.946920530745, 5474.890502190014], "value": 4005.1502990604313, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.4823127994281, 652.8890088684923, 733.4755649710456, 737.365865355794, 761.0182864105304, 14454.988150829433, 14937.402503899859, 15156.1664108474, 15312.846871531281, 15357.616059598515, 13733.176009439441, 13760.417551981525, 13923.1646101234, 13961.30193490324, 13962.581122566315, 818.9836203275916, 824.756696774453, 826.499967519651, 828.2131974624101, 829.8257365953157, 666.3569655282652, 687.4603436302503, 809.2797410304834, 831.0359982420393, 835.5947365527715, 847.3814115695515, 850.8851305073829, 884.1379654836547, 884.5002573545926, 887.5961437545933, 834.5576844272546, 872.9869051964225, 878.9824203515926, 881.6429346114802, 881.8809460722784, 612.0268772651506, 636.4590098416355, 641.3426238105932, 652.360686527206, 656.4223483334682, 13887.513937012258, 13959.302034898214, 13971.580852574338, 14034.017618766691, 14115.576532704035, 10244.309688901323, 13032.478700852049, 13499.527516536875, 13531.985101117358, 13570.117942333716, 9449.477768425057, 11383.203175777699, 11388.71528211793, 11410.087193024538, 11641.243319184226, 3637.363186566056, 3668.6881615062753, 3694.4827724118645, 3708.3510385682666, 3726.9068273293115, 84486.57756711238, 85244.0, 85723.57138214269, 88737.0, 89579.0, 10499.370037797604, 10545.9472702636, 10546.367217966947, 10749.83875241872, 10759.601251837368, 20351.4912127198, 20520.692189616868, 20628.587428251652, 20719.378418647466, 20822.479438014172, 6446.302430458562, 7537.397008239328, 7624.656890439944, 7635.809104772425, 7650.846983060419, 4249.68127390447, 4250.703535421726, 4324.394584758136, 4376.321670141133, 4414.962483816301, 3397.3545026444895, 3502.492138639883, 3515.191505953645, 3517.577890653138, 3524.1718196223865, 623.4856243599049, 629.820501157168, 646.3859333633068, 655.1875674164003, 658.4107224034462, 620.6431302001367, 643.5398689936718, 645.2837350540896, 646.8932626116732, 668.0647094068344, 8523.872141917876, 8955.910440895637, 8964.506952117566, 9016.819663606691, 9033.548322583845, 630.3664816859119, 660.8380946668001, 661.7851331507784, 663.754410867982, 685.5680921019725, 3483.6516348365467, 4162.8344063662225, 4346.304591265382, 4379.4087798147475, 4392.224225143926, 91742.0, 101436.0, 102019.0, 102799.48600257101, 117213.09837768796, 65245.021324680165, 70774.0, 74268.0, 88525.22949541103, 109566.90433095567, 82157.0, 85647.0, 86201.5689921559, 86373.56813215894, 88476.5576172115, 8414.83170336602, 8450.957745211235, 8471.66113355459, 8598.61306241233, 8681.088485709162, 48357.75821120872, 56197.43802561922, 57775.55561110838, 58401.0, 59579.10631340447, 77822.0, 80491.98770030796, 84369.0, 85706.0, 89195.0, 10917.945410272898, 11118.689295013155, 11301.943490282496, 11443.25618834773, 11662.0, 11913.0, 11986.70033249141, 12019.399030048287, 12280.815787763013, 12284.385780711107, 11516.654500365003, 11788.646340609796, 11818.231814932002, 11956.820647690118, 12032.518699252281, 11731.23746956445, 11781.234219775688, 11849.992750616295, 11874.287542747463, 12322.938385308375, 11445.942770286096, 11451.25566838153, 11583.884161158281, 11587.246828956091, 11677.240979336317, 9303.581338839904, 9309.813803724022, 9339.813203735752, 9834.21326293909, 10115.115685565044, 7689.269519395649, 8361.331093512626, 8832.145249497677, 9144.817103657757, 9383.0], "value": 7066.83327129835, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 116.0, 117.0, 117.0, 117.0, 119.0, 119.0, 117.0, 120.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 105.0, 136.0, 187.0, 151.0, 118.0, 126.0, 115.0, 143.0, 113.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 210.0, 245.0, 198.0, 196.0, 199.0, 197.0, 197.0, 196.0, 196.0], "value": 204.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 52.0, 63.0, 103.0, 86.0, 52.0, 52.0, 54.0, 100.0, 84.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 72.0, 74.0, 94.0, 104.0, 88.0, 72.0, 97.0, 104.0, 126.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 90.0, 88.0, 94.0, 107.0, 139.0, 121.0, 125.0, 133.0], "value": 107.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 118.0, 157.0, 115.0, 102.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 106.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 87.0, 88.0, 91.0, 84.0, 110.0, 145.0, 138.0, 98.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 57.0, 56.0, 57.0, 58.0, 57.0, 59.0, 56.0, 56.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 74.0, 79.0, 71.0, 72.0, 64.0, 75.0, 74.0, 75.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 89.0, 91.0, 91.0, 89.0, 86.0, 90.0, 86.0, 89.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 152.0, 149.0, 180.0, 163.0, 167.0, 152.0, 166.0, 239.0, 148.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 204.0, 203.0, 172.0, 172.0, 247.0, 244.0, 189.0, 174.0, 171.0], "value": 195.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 70.0, 67.0, 68.0, 68.0, 59.0, 91.0, 75.0, 70.0], "value": 70.4, "unit": "score"}]}]} 04:53:04 INFO - Return code: 0 04:53:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:53:05 ERROR - # TBPL SUCCESS # 04:53:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:53:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:53:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:53:05 INFO - 2016-11-04 04:53:05.017 screenresolution[3975:112548] starting screenresolution argv=screenresolution get 04:53:05 INFO - 2016-11-04 04:53:05.020 screenresolution[3975:112548] Display 0: 1600x1200x32@60 04:53:05 INFO - 2016-11-04 04:53:05.026 screenresolution[3976:112551] starting screenresolution argv=screenresolution list 04:53:05 INFO - Available Modes on Display 0 04:53:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:53:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:53:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:53:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:53:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:53:05 INFO - Intel Iris: 04:53:05 INFO - Chipset Model: Intel Iris 04:53:05 INFO - Type: GPU 04:53:05 INFO - Bus: Built-In 04:53:05 INFO - VRAM (Dynamic, Max): 1536 MB 04:53:05 INFO - Vendor: Intel (0x8086) 04:53:05 INFO - Device ID: 0x0a2e 04:53:05 INFO - Revision ID: 0x0009 04:53:05 INFO - Displays: 04:53:05 INFO - Display: 04:53:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:53:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:53:05 INFO - Display Serial Number: 1600x1200 60 04:53:05 INFO - Main Display: Yes 04:53:05 INFO - Mirror: Off 04:53:05 INFO - Online: Yes 04:53:05 INFO - Rotation: Supported 04:53:05 INFO - Return code: 0 04:53:05 INFO - Running post-action listener: _resource_record_post_action 04:53:05 INFO - [mozharness: 2016-11-04 11:53:05.262625Z] Finished run-tests step (success) 04:53:05 INFO - Running post-run listener: _resource_record_post_run 04:53:05 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 0; Write bytes: 711565824; Read time: 0; Write time: 2023 04:53:05 INFO - TinderboxPrint: CPU usage
54.5% 04:53:05 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:53:05 INFO - TinderboxPrint: I/O write bytes / time
711,565,824 / 2,023 04:53:05 INFO - TinderboxPrint: CPU idle
2,203.3 (45.5%) 04:53:05 INFO - TinderboxPrint: CPU system
984.9 (20.3%) 04:53:05 INFO - TinderboxPrint: CPU user
1,657.5 (34.2%) 04:53:05 INFO - TinderboxPrint: Swap in / out
405,524,480 / 0 04:53:05 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396896768; Write bytes: 392395776; Read time: 29519; Write time: 811 04:53:05 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 0; Write bytes: 300738048; Read time: 0; Write time: 1159 04:53:05 INFO - Running post-run listener: _upload_blobber_files 04:53:05 INFO - Blob upload gear active. 04:53:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:53:05 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 04:53:05 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:53:05 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:53:06 INFO - (blobuploader) - INFO - Open directory for files ... 04:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:07 INFO - (blobuploader) - INFO - Done attempting. 04:53:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:53:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:53:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:08 INFO - (blobuploader) - INFO - Done attempting. 04:53:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:53:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:53:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:10 INFO - (blobuploader) - INFO - Done attempting. 04:53:10 INFO - (blobuploader) - INFO - Iteration through files over. 04:53:10 INFO - Return code: 0 04:53:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:53:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:53:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/05cfdec3d208a61a5190867c35299ca05c989bb962c7d1de5b8fcc70d3d46578e71e0cb4fa390818bc8329cdf8372cab672e548b27d0163126af7343f9a078cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/790647b1551d75eb872c4fb4935d6725577e4fd89cd91c9d1f4d627de347086b2b71b3dcd5e9687b38dae6bbaee8d59bd13fed11381b6a6ed4eb4047ee8ff2dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/20c6ce584809330405cc5ac884aaf17769c3e4af2be461da7ecaa5ff91fffa99e5c9ef10723d626a27ec3791acf1d8443ab602b1f8d14d4b88a448c3a9284c51"} 04:53:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:53:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:53:10 INFO - Contents: 04:53:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/05cfdec3d208a61a5190867c35299ca05c989bb962c7d1de5b8fcc70d3d46578e71e0cb4fa390818bc8329cdf8372cab672e548b27d0163126af7343f9a078cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/790647b1551d75eb872c4fb4935d6725577e4fd89cd91c9d1f4d627de347086b2b71b3dcd5e9687b38dae6bbaee8d59bd13fed11381b6a6ed4eb4047ee8ff2dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/20c6ce584809330405cc5ac884aaf17769c3e4af2be461da7ecaa5ff91fffa99e5c9ef10723d626a27ec3791acf1d8443ab602b1f8d14d4b88a448c3a9284c51"} 04:53:10 INFO - Running post-run listener: copy_logs_to_upload_dir 04:53:10 INFO - Copying logs to upload dir... 04:53:10 INFO - mkdir: /builds/slave/test/build/upload/logs 04:53:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.440393 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-04 04:53:10.582234) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 04:53:10.585707) ========= 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.l1ajpXg8my/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.TglJMnEmCU/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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/Cedar/sha512/05cfdec3d208a61a5190867c35299ca05c989bb962c7d1de5b8fcc70d3d46578e71e0cb4fa390818bc8329cdf8372cab672e548b27d0163126af7343f9a078cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/790647b1551d75eb872c4fb4935d6725577e4fd89cd91c9d1f4d627de347086b2b71b3dcd5e9687b38dae6bbaee8d59bd13fed11381b6a6ed4eb4047ee8ff2dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/20c6ce584809330405cc5ac884aaf17769c3e4af2be461da7ecaa5ff91fffa99e5c9ef10723d626a27ec3791acf1d8443ab602b1f8d14d4b88a448c3a9284c51"} build_url:https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011746 build_url: 'https://queue.taskcluster.net/v1/task/AXXXvPClQYOAfYpoLbApFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/05cfdec3d208a61a5190867c35299ca05c989bb962c7d1de5b8fcc70d3d46578e71e0cb4fa390818bc8329cdf8372cab672e548b27d0163126af7343f9a078cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/790647b1551d75eb872c4fb4935d6725577e4fd89cd91c9d1f4d627de347086b2b71b3dcd5e9687b38dae6bbaee8d59bd13fed11381b6a6ed4eb4047ee8ff2dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/20c6ce584809330405cc5ac884aaf17769c3e4af2be461da7ecaa5ff91fffa99e5c9ef10723d626a27ec3791acf1d8443ab602b1f8d14d4b88a448c3a9284c51"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 04:53:10.619210) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 04:53:10.619563) ========= 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.l1ajpXg8my/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.TglJMnEmCU/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.005574 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 04:53:10.668255) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-04 04:53:10.668610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-04 04:53:10.668954) ========= ========= Total master_lag: 0.21 =========