builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0067 starttime: 1476703838.29 results: success (0) buildid: 20161017021835 builduid: c329bc3ed79942b19c012c015ea55489 revision: 0191c2f3a6e8c0a241bc440cb61a8f15d061bc38 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:38.289766) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:38.290264) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:38.290600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:38.315688) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:38.316031) ========= 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.W49x4S6E1r/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.S1xKbAYRLf/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-17 04:30:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.4M=0s 2016-10-17 04:30:38 (39.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127888 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:38.466435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:38.466809) ========= 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.W49x4S6E1r/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.S1xKbAYRLf/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.025814 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:38.515699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-17 04:30:38.516095) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0191c2f3a6e8c0a241bc440cb61a8f15d061bc38 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0191c2f3a6e8c0a241bc440cb61a8f15d061bc38 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.W49x4S6E1r/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.S1xKbAYRLf/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-17 04:30:38,591 truncating revision to first 12 chars 2016-10-17 04:30:38,591 Setting DEBUG logging. 2016-10-17 04:30:38,591 attempt 1/10 2016-10-17 04:30:38,591 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0191c2f3a6e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 04:30:40,845 unpacking tar archive at: mozilla-central-0191c2f3a6e8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.488504 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-17 04:30:41.027363) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:41.027725) ========= script_repo_revision: 0191c2f3a6e8c0a241bc440cb61a8f15d061bc38 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:41.028176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:41.028483) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 04:30:41.047399) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-17 04:30:41.047834) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.W49x4S6E1r/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.S1xKbAYRLf/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:30:41 INFO - MultiFileLogger online at 20161017 04:30:41 in /builds/slave/test 04:30:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:30:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:30:41 INFO - {'append_to_log': False, 04:30:41 INFO - 'base_work_dir': '/builds/slave/test', 04:30:41 INFO - 'blob_upload_branch': 'Firefox', 04:30:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:30:41 INFO - 'branch': 'Firefox', 04:30:41 INFO - 'buildbot_json_path': 'buildprops.json', 04:30:41 INFO - 'config_files': ('talos/mac_config.py',), 04:30:41 INFO - 'default_actions': ('clobber', 04:30:41 INFO - 'read-buildbot-config', 04:30:41 INFO - 'download-and-extract', 04:30:41 INFO - 'populate-webroot', 04:30:41 INFO - 'create-virtualenv', 04:30:41 INFO - 'install', 04:30:41 INFO - 'run-tests'), 04:30:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:30:41 INFO - 'download_minidump_stackwalk': True, 04:30:41 INFO - 'download_symbols': 'ondemand', 04:30:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:30:41 INFO - 'tooltool.py': '/tools/tooltool.py', 04:30:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:30:41 INFO - '/tools/misc-python/virtualenv.py')}, 04:30:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:30:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:30:41 INFO - 'installer_path': 'installer.exe', 04:30:41 INFO - 'log_level': 'info', 04:30:41 INFO - 'log_name': 'talos', 04:30:41 INFO - 'log_to_console': True, 04:30:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:30:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:30:41 INFO - 'opt_config_files': (), 04:30:41 INFO - 'pip_index': False, 04:30:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:30:41 INFO - 'cmd': ('bash', 04:30:41 INFO - '-c', 04:30:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:30:41 INFO - 'enabled': True, 04:30:41 INFO - 'halt_on_failure': False, 04:30:41 INFO - 'name': 'check_screen_resolution'},), 04:30:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:30:41 INFO - 'cmd': ('bash', 04:30:41 INFO - '-c', 04:30:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:30:41 INFO - 'enabled': True, 04:30:41 INFO - 'halt_on_failure': False, 04:30:41 INFO - 'name': 'check_screen_resolution'},), 04:30:41 INFO - 'run_cmd_checks_enabled': True, 04:30:41 INFO - 'sps_profile': False, 04:30:41 INFO - 'sps_profile_interval': 0, 04:30:41 INFO - 'suite': 'dromaeojs-e10s', 04:30:41 INFO - 'system_bits': '32', 04:30:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:30:41 INFO - 'title': 't-yosemite-r7-0067', 04:30:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:30:41 INFO - 'use_talos_json': True, 04:30:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:30:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:30:41 INFO - 'work_dir': 'build'} 04:30:41 INFO - [mozharness: 2016-10-17 11:30:41.258413Z] Running clobber step. 04:30:41 INFO - Running pre-action listener: _resource_record_pre_action 04:30:41 INFO - Running main action method: clobber 04:30:41 INFO - rmtree: /builds/slave/test/build 04:30:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:30:43 INFO - Running post-action listener: _resource_record_post_action 04:30:43 INFO - [mozharness: 2016-10-17 11:30:43.334579Z] Finished clobber step (success) 04:30:43 INFO - [mozharness: 2016-10-17 11:30:43.334682Z] Running read-buildbot-config step. 04:30:43 INFO - Running pre-action listener: _resource_record_pre_action 04:30:43 INFO - Running main action method: read_buildbot_config 04:30:43 INFO - Using buildbot properties: 04:30:43 INFO - { 04:30:43 INFO - "project": "", 04:30:43 INFO - "product": "firefox", 04:30:43 INFO - "script_repo_revision": "production", 04:30:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:30:43 INFO - "repository": "", 04:30:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:30:43 INFO - "buildid": "20161017021835", 04:30:43 INFO - "pgo_build": "False", 04:30:43 INFO - "basedir": "/builds/slave/test", 04:30:43 INFO - "buildnumber": 8, 04:30:43 INFO - "slavename": "t-yosemite-r7-0067", 04:30:43 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:30:43 INFO - "platform": "yosemite_r7", 04:30:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:30:43 INFO - "branch": "mozilla-central", 04:30:43 INFO - "repo_path": "mozilla-central", 04:30:43 INFO - "revision": "0191c2f3a6e8c0a241bc440cb61a8f15d061bc38", 04:30:43 INFO - "stage_platform": "macosx64", 04:30:43 INFO - "builduid": "c329bc3ed79942b19c012c015ea55489", 04:30:43 INFO - "slavebuilddir": "test" 04:30:43 INFO - } 04:30:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:30:43 INFO - Running post-action listener: _resource_record_post_action 04:30:43 INFO - [mozharness: 2016-10-17 11:30:43.337996Z] Finished read-buildbot-config step (success) 04:30:43 INFO - [mozharness: 2016-10-17 11:30:43.338130Z] Running download-and-extract step. 04:30:43 INFO - Running pre-action listener: _resource_record_pre_action 04:30:43 INFO - Running main action method: download_and_extract 04:30:43 INFO - mkdir: /builds/slave/test/build/tests 04:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:43 INFO - trying https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:30:43 INFO - Downloading https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/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:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/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:30:44 INFO - Downloaded 1199 bytes. 04:30:44 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:30:44 INFO - Using the following test package requirements: 04:30:44 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:30:44 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:30:44 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:30:44 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:30:44 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:30:44 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:30:44 INFO - u'jsshell-mac.zip'], 04:30:44 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:30:44 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:30:44 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:30:44 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:30:44 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:30:44 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:30:44 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:30:44 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:30:44 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:30:44 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:30:44 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:30:44 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:30:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:30:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:30:44 INFO - Fetch https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:30:45 INFO - Http code: 200 04:30:45 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 04:30:45 INFO - x-amz-cf-id: 9Sd6VlrKoSo8x0015-1m3VxmB606fna9GZsdIGsa3hbQA_TXKvjKQQ== 04:30:45 INFO - x-amz-version-id: _uzunl2n9knjP5IV5xuJTvAIq.8EGCKO 04:30:45 INFO - Expected file size: 16509929 04:30:45 INFO - Obtained file size: 16509929 04:30:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:30:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:30:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:30:52 INFO - Fetch https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:30:53 INFO - Http code: 200 04:30:53 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 04:30:53 INFO - x-amz-cf-id: kFic_En-u9z2V9pRY5h4vXjmcoz6SMbL_AsOTV09ZjJXcFO8ABLmNw== 04:30:53 INFO - x-amz-version-id: _uzunl2n9knjP5IV5xuJTvAIq.8EGCKO 04:30:53 INFO - Expected file size: 16509929 04:30:53 INFO - Obtained file size: 16509929 04:31:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:31:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:31:00 INFO - Fetch https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:31:01 INFO - Http code: 200 04:31:01 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 04:31:01 INFO - x-amz-cf-id: ZmmviaZwMunkn-GU_0DKWPz-J0L53QWB42iMRiMsfWIbvZH8MIP3lw== 04:31:01 INFO - x-amz-version-id: cm6GxxK3tCXmjlKQ1pGNYrm11PJaHLBo 04:31:01 INFO - Expected file size: 14409028 04:31:01 INFO - Obtained file size: 14409028 04:31:01 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:31:01 INFO - trying https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:31:01 INFO - Downloading https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:31:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/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:31:04 INFO - Downloaded 111640283 bytes. 04:31:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:31:04 INFO - mkdir: /builds/slave/test/properties 04:31:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:31:04 INFO - Writing to file /builds/slave/test/properties/build_url 04:31:04 INFO - Contents: 04:31:04 INFO - build_url:https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:31:05 INFO - Running post-action listener: _resource_record_post_action 04:31:05 INFO - Running post-action listener: set_extra_try_arguments 04:31:05 INFO - [mozharness: 2016-10-17 11:31:05.482117Z] Finished download-and-extract step (success) 04:31:05 INFO - [mozharness: 2016-10-17 11:31:05.482254Z] Running populate-webroot step. 04:31:05 INFO - Running pre-action listener: _resource_record_pre_action 04:31:05 INFO - Running main action method: populate_webroot 04:31:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:31:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:31:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:31:05 INFO - '--tppagecycles', 04:31:05 INFO - '7'], 04:31:05 INFO - 'tests': ['tsvgm']}}, 04:31:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:31:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:31:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:31:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:31:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:05 INFO - 'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:31:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:31:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:05 INFO - 'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['damp', 'tps']}, 04:31:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:05 INFO - 'tests': ['damp', 'tps']}, 04:31:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['dromaeo_dom']}, 04:31:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:31:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['basic_compositor_video']}, 04:31:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:31:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['a11yr', 04:31:05 INFO - 'ts_paint', 04:31:05 INFO - 'tpaint', 04:31:05 INFO - 'sessionrestore', 04:31:05 INFO - 'sessionrestore_no_auto_restore', 04:31:05 INFO - 'tabpaint']}, 04:31:05 INFO - 'other-e10s': {'tests': ['a11yr', 04:31:05 INFO - 'ts_paint', 04:31:05 INFO - 'tpaint', 04:31:05 INFO - 'sessionrestore', 04:31:05 INFO - 'sessionrestore_no_auto_restore', 04:31:05 INFO - 'tabpaint']}, 04:31:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:31:05 INFO - 'ts_paint', 04:31:05 INFO - 'tpaint', 04:31:05 INFO - 'sessionrestore', 04:31:05 INFO - 'sessionrestore_no_auto_restore', 04:31:05 INFO - 'tabpaint']}, 04:31:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:31:05 INFO - 'ts_paint', 04:31:05 INFO - 'tpaint', 04:31:05 INFO - 'sessionrestore', 04:31:05 INFO - 'sessionrestore_no_auto_restore', 04:31:05 INFO - 'tabpaint']}, 04:31:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['a11yr', 04:31:05 INFO - 'ts_paint', 04:31:05 INFO - 'tpaint', 04:31:05 INFO - 'sessionrestore', 04:31:05 INFO - 'sessionrestore_no_auto_restore', 04:31:05 INFO - 'tabpaint']}, 04:31:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['a11yr', 04:31:05 INFO - 'ts_paint', 04:31:05 INFO - 'tpaint', 04:31:05 INFO - 'sessionrestore', 04:31:05 INFO - 'sessionrestore_no_auto_restore', 04:31:05 INFO - 'tabpaint']}, 04:31:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['tsvgx', 04:31:05 INFO - 'tsvgr_opacity', 04:31:05 INFO - 'tart', 04:31:05 INFO - 'tscrollx', 04:31:05 INFO - 'cart']}, 04:31:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:31:05 INFO - 'tsvgr_opacity', 04:31:05 INFO - 'tart', 04:31:05 INFO - 'tscrollx', 04:31:05 INFO - 'cart']}, 04:31:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:05 INFO - 'talos_options': ['--disable-e10s'], 04:31:05 INFO - 'tests': ['tp5o']}, 04:31:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:05 INFO - 'tests': ['tp5o']}, 04:31:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:05 INFO - 'talos_options': ['--disable-e10s', 04:31:05 INFO - '--xperf_path', 04:31:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:31:05 INFO - 'tests': ['tp5n']}, 04:31:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:05 INFO - 'talos_options': ['--xperf_path', 04:31:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:31:05 INFO - 'tests': ['tp5n']}}, 04:31:05 INFO - 'talos.zip': {'path': '', 04:31:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:31:05 INFO - Running post-action listener: _resource_record_post_action 04:31:05 INFO - [mozharness: 2016-10-17 11:31:05.501398Z] Finished populate-webroot step (success) 04:31:05 INFO - [mozharness: 2016-10-17 11:31:05.501521Z] Running create-virtualenv step. 04:31:05 INFO - Running pre-action listener: _resource_record_pre_action 04:31:05 INFO - Running main action method: create_virtualenv 04:31:05 INFO - Creating virtualenv /builds/slave/test/build/venv 04:31:05 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:31:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:31:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:31:05 INFO - Using real prefix '/tools/python27' 04:31:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:31:06 INFO - Installing distribute.............................................................................................................................................................................................done. 04:31:09 INFO - Installing pip.................done. 04:31:09 INFO - Return code: 0 04:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:31:09 INFO - Reading from file tmpfile_stdout 04:31:09 INFO - Output received: 04:31:09 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:31:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:31:09 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:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:09 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:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:09 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 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:09 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:31:09 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:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:09 INFO - 'HOME': '/Users/cltbld', 04:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:09 INFO - 'LOGNAME': 'cltbld', 04:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:09 INFO - 'MOZ_NO_REMOTE': '1', 04:31:09 INFO - 'NO_EM_RESTART': '1', 04:31:09 INFO - 'PAGER': '/bin/cat', 04:31:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:09 INFO - 'PWD': '/builds/slave/test', 04:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:09 INFO - 'SHELL': '/bin/bash', 04:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:09 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:09 INFO - 'USER': 'cltbld', 04:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:09 INFO - 'XPC_FLAGS': '0x0', 04:31:09 INFO - 'XPC_SERVICE_NAME': '0', 04:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:09 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:31:09 INFO - Cleaning up... 04:31:09 INFO - Return code: 0 04:31:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:31:09 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:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:09 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:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:09 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 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:09 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:31:09 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:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:09 INFO - 'HOME': '/Users/cltbld', 04:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:09 INFO - 'LOGNAME': 'cltbld', 04:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:09 INFO - 'MOZ_NO_REMOTE': '1', 04:31:09 INFO - 'NO_EM_RESTART': '1', 04:31:09 INFO - 'PAGER': '/bin/cat', 04:31:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:09 INFO - 'PWD': '/builds/slave/test', 04:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:09 INFO - 'SHELL': '/bin/bash', 04:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:09 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:09 INFO - 'USER': 'cltbld', 04:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:09 INFO - 'XPC_FLAGS': '0x0', 04:31:09 INFO - 'XPC_SERVICE_NAME': '0', 04:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:10 INFO - Downloading/unpacking psutil>=3.1.1 04:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:31:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:31:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:31:13 INFO - Installing collected packages: psutil 04:31:13 INFO - Running setup.py install for psutil 04:31:13 INFO - building 'psutil._psutil_osx' extension 04:31:13 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:31:14 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:31:14 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:31:14 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:31:14 INFO - building 'psutil._psutil_posix' extension 04:31:14 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:31:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:31:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:31:14 INFO - ^ 04:31:14 INFO - 1 warning generated. 04:31:14 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:31:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:31:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:31:14 INFO - Successfully installed psutil 04:31:14 INFO - Cleaning up... 04:31:14 INFO - Return code: 0 04:31:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:14 INFO - 'HOME': '/Users/cltbld', 04:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:14 INFO - 'LOGNAME': 'cltbld', 04:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:14 INFO - 'MOZ_NO_REMOTE': '1', 04:31:14 INFO - 'NO_EM_RESTART': '1', 04:31:14 INFO - 'PAGER': '/bin/cat', 04:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:14 INFO - 'PWD': '/builds/slave/test', 04:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:14 INFO - 'SHELL': '/bin/bash', 04:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:14 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:14 INFO - 'USER': 'cltbld', 04:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:14 INFO - 'XPC_FLAGS': '0x0', 04:31:14 INFO - 'XPC_SERVICE_NAME': '0', 04:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:31:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:31:17 INFO - Installing collected packages: mozsystemmonitor 04:31:17 INFO - Running setup.py install for mozsystemmonitor 04:31:17 INFO - Successfully installed mozsystemmonitor 04:31:17 INFO - Cleaning up... 04:31:17 INFO - Return code: 0 04:31:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:31:17 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:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:17 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:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:17 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 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:17 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:31:17 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:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:17 INFO - 'HOME': '/Users/cltbld', 04:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:17 INFO - 'LOGNAME': 'cltbld', 04:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:17 INFO - 'MOZ_NO_REMOTE': '1', 04:31:17 INFO - 'NO_EM_RESTART': '1', 04:31:17 INFO - 'PAGER': '/bin/cat', 04:31:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:17 INFO - 'PWD': '/builds/slave/test', 04:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:17 INFO - 'SHELL': '/bin/bash', 04:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:17 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:17 INFO - 'USER': 'cltbld', 04:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:17 INFO - 'XPC_FLAGS': '0x0', 04:31:17 INFO - 'XPC_SERVICE_NAME': '0', 04:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:18 INFO - Downloading/unpacking jsonschema==2.5.1 04:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:31:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:31:20 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:31:20 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:31:20 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:31:20 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:31:20 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:31:20 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:31:21 INFO - Downloading functools32-3.2.3-2.tar.gz 04:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:31:21 INFO - warning: no files found matching '*.txt' 04:31:21 INFO - no previously-included directories found matching 'build' 04:31:21 INFO - no previously-included directories found matching 'dist' 04:31:21 INFO - no previously-included directories found matching '.git*' 04:31:21 INFO - Installing collected packages: jsonschema, functools32 04:31:21 INFO - Running setup.py install for functools32 04:31:21 INFO - warning: no files found matching '*.txt' 04:31:21 INFO - no previously-included directories found matching 'build' 04:31:21 INFO - no previously-included directories found matching 'dist' 04:31:21 INFO - no previously-included directories found matching '.git*' 04:31:21 INFO - Successfully installed jsonschema functools32 04:31:21 INFO - Cleaning up... 04:31:21 INFO - Return code: 0 04:31:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:31:21 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:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:21 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:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:21 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 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:21 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:31:21 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:31:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:21 INFO - 'HOME': '/Users/cltbld', 04:31:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:21 INFO - 'LOGNAME': 'cltbld', 04:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:21 INFO - 'MOZ_NO_REMOTE': '1', 04:31:21 INFO - 'NO_EM_RESTART': '1', 04:31:21 INFO - 'PAGER': '/bin/cat', 04:31:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:21 INFO - 'PWD': '/builds/slave/test', 04:31:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:21 INFO - 'SHELL': '/bin/bash', 04:31:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:21 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:21 INFO - 'USER': 'cltbld', 04:31:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:21 INFO - 'XPC_FLAGS': '0x0', 04:31:21 INFO - 'XPC_SERVICE_NAME': '0', 04:31:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:31:21 INFO - Cleaning up... 04:31:21 INFO - Return code: 0 04:31:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:31:21 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:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:21 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:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:21 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 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:21 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:31:21 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:31:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:21 INFO - 'HOME': '/Users/cltbld', 04:31:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:21 INFO - 'LOGNAME': 'cltbld', 04:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:21 INFO - 'MOZ_NO_REMOTE': '1', 04:31:21 INFO - 'NO_EM_RESTART': '1', 04:31:21 INFO - 'PAGER': '/bin/cat', 04:31:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:21 INFO - 'PWD': '/builds/slave/test', 04:31:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:21 INFO - 'SHELL': '/bin/bash', 04:31:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:21 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:21 INFO - 'USER': 'cltbld', 04:31:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:21 INFO - 'XPC_FLAGS': '0x0', 04:31:21 INFO - 'XPC_SERVICE_NAME': '0', 04:31:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:21 INFO - Downloading/unpacking blobuploader==1.2.4 04:31:21 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:31:21 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:31:21 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:31:21 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:31:21 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:31:21 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:31:24 INFO - Downloading blobuploader-1.2.4.tar.gz 04:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:31:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:31:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:25 INFO - Downloading docopt-0.6.1.tar.gz 04:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:31:25 INFO - Installing collected packages: blobuploader, requests, docopt 04:31:25 INFO - Running setup.py install for blobuploader 04:31:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:31:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:31:25 INFO - Running setup.py install for requests 04:31:26 INFO - Running setup.py install for docopt 04:31:26 INFO - Successfully installed blobuploader requests docopt 04:31:26 INFO - Cleaning up... 04:31:26 INFO - Return code: 0 04:31:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:26 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 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:26 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:31:26 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:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:26 INFO - 'HOME': '/Users/cltbld', 04:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:26 INFO - 'LOGNAME': 'cltbld', 04:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:26 INFO - 'MOZ_NO_REMOTE': '1', 04:31:26 INFO - 'NO_EM_RESTART': '1', 04:31:26 INFO - 'PAGER': '/bin/cat', 04:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:26 INFO - 'PWD': '/builds/slave/test', 04:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:26 INFO - 'SHELL': '/bin/bash', 04:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:26 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:26 INFO - 'USER': 'cltbld', 04:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:26 INFO - 'XPC_FLAGS': '0x0', 04:31:26 INFO - 'XPC_SERVICE_NAME': '0', 04:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:26 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-eC2oLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:26 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-gs39Bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-uSR42F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-LksTf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-xK025T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-VpN6ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ypqYWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-OIvJck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-kZoWJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-CCogEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-JE75Vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:27 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-AzbCsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:28 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-AsdHd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:28 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-SiYVJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:28 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-F77upJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:28 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DvvXtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:28 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-4aKSpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:31:28 INFO - Running setup.py install for manifestparser 04:31:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:31:28 INFO - Running setup.py install for mozcrash 04:31:28 INFO - Running setup.py install for mozdebug 04:31:29 INFO - Running setup.py install for mozdevice 04:31:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:31:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:31:29 INFO - Running setup.py install for mozfile 04:31:29 INFO - Running setup.py install for mozhttpd 04:31:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:31:29 INFO - Running setup.py install for mozinfo 04:31:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:31:29 INFO - Running setup.py install for mozInstall 04:31:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:31:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:31:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:31:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:31:29 INFO - Running setup.py install for mozleak 04:31:29 INFO - Running setup.py install for mozlog 04:31:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:31:30 INFO - Running setup.py install for moznetwork 04:31:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:31:30 INFO - Running setup.py install for mozprocess 04:31:30 INFO - Running setup.py install for mozprofile 04:31:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:31:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:31:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:31:30 INFO - Running setup.py install for mozrunner 04:31:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:31:30 INFO - Running setup.py install for mozscreenshot 04:31:30 INFO - Running setup.py install for moztest 04:31:30 INFO - Running setup.py install for mozversion 04:31:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:31:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:31:31 INFO - Cleaning up... 04:31:31 INFO - Return code: 0 04:31:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31: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:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31: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:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:31:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:31 INFO - 'HOME': '/Users/cltbld', 04:31:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:31 INFO - 'LOGNAME': 'cltbld', 04:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:31 INFO - 'MOZ_NO_REMOTE': '1', 04:31:31 INFO - 'NO_EM_RESTART': '1', 04:31:31 INFO - 'PAGER': '/bin/cat', 04:31:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:31 INFO - 'PWD': '/builds/slave/test', 04:31:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:31 INFO - 'SHELL': '/bin/bash', 04:31:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:31 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:31 INFO - 'USER': 'cltbld', 04:31:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:31 INFO - 'XPC_FLAGS': '0x0', 04:31:31 INFO - 'XPC_SERVICE_NAME': '0', 04:31:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:31 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ptXIym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:31 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-3uCjlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:31 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ptQJnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:31 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Gm_dN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:31 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-afueAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:31 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ic4sxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Eq4ULG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-XydaKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-qZlDmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-M3oF1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-0Lf1Nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-vq4vSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-52AGAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-QoimvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-XBAUXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ADaAWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:33 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:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ZCTTVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:35 INFO - Downloading blessings-1.6.tar.gz 04:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:31:35 INFO - Installing collected packages: blessings 04:31:35 INFO - Running setup.py install for blessings 04:31:35 INFO - Successfully installed blessings 04:31:35 INFO - Cleaning up... 04:31:36 INFO - Return code: 0 04:31:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:31:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:36 INFO - Reading from file tmpfile_stdout 04:31:36 INFO - Current package versions: 04:31:36 INFO - blessings == 1.6 04:31:36 INFO - blobuploader == 1.2.4 04:31:36 INFO - docopt == 0.6.1 04:31:36 INFO - functools32 == 3.2.3-2 04:31:36 INFO - jsonschema == 2.5.1 04:31:36 INFO - manifestparser == 1.1 04:31:36 INFO - mozInstall == 1.12 04:31:36 INFO - mozcrash == 0.17 04:31:36 INFO - mozdebug == 0.1 04:31:36 INFO - mozdevice == 0.48 04:31:36 INFO - mozfile == 1.2 04:31:36 INFO - mozhttpd == 0.7 04:31:36 INFO - mozinfo == 0.9 04:31:36 INFO - mozleak == 0.1 04:31:36 INFO - mozlog == 3.4 04:31:36 INFO - moznetwork == 0.27 04:31:36 INFO - mozprocess == 0.23 04:31:36 INFO - mozprofile == 0.28 04:31:36 INFO - mozrunner == 6.12 04:31:36 INFO - mozscreenshot == 0.1 04:31:36 INFO - mozsystemmonitor == 0.3 04:31:36 INFO - moztest == 0.7 04:31:36 INFO - mozversion == 1.4 04:31:36 INFO - psutil == 3.1.1 04:31:36 INFO - requests == 1.2.3 04:31:36 INFO - wsgiref == 0.1.2 04:31:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:36 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:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:36 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:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:37 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 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:37 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:31:37 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:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:37 INFO - 'HOME': '/Users/cltbld', 04:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:37 INFO - 'LOGNAME': 'cltbld', 04:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:37 INFO - 'MOZ_NO_REMOTE': '1', 04:31:37 INFO - 'NO_EM_RESTART': '1', 04:31:37 INFO - 'PAGER': '/bin/cat', 04:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:37 INFO - 'PWD': '/builds/slave/test', 04:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:37 INFO - 'SHELL': '/bin/bash', 04:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:37 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:37 INFO - 'USER': 'cltbld', 04:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:37 INFO - 'XPC_FLAGS': '0x0', 04:31:37 INFO - 'XPC_SERVICE_NAME': '0', 04:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:37 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:31:37 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:31:37 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:31:37 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:31:37 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:31:37 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:31:37 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:31:37 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:31:37 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:31:37 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:31:37 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:31:37 INFO - Cleaning up... 04:31:37 INFO - Return code: 0 04:31:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100be5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b5b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa319ce3360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, '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.W49x4S6E1r/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.S1xKbAYRLf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:31:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:37 INFO - 'HOME': '/Users/cltbld', 04:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:37 INFO - 'LOGNAME': 'cltbld', 04:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:37 INFO - 'MOZ_NO_REMOTE': '1', 04:31:37 INFO - 'NO_EM_RESTART': '1', 04:31:37 INFO - 'PAGER': '/bin/cat', 04:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:37 INFO - 'PWD': '/builds/slave/test', 04:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:37 INFO - 'SHELL': '/bin/bash', 04:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:31:37 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:37 INFO - 'USER': 'cltbld', 04:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:37 INFO - 'XPC_FLAGS': '0x0', 04:31:37 INFO - 'XPC_SERVICE_NAME': '0', 04:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:31:37 INFO - Cleaning up... 04:31:37 INFO - Return code: 0 04:31:37 INFO - Running post-action listener: _resource_record_post_action 04:31:37 INFO - Running post-action listener: _start_resource_monitoring 04:31:37 INFO - Starting resource monitoring. 04:31:38 INFO - [mozharness: 2016-10-17 11:31:38.002424Z] Finished create-virtualenv step (success) 04:31:38 INFO - [mozharness: 2016-10-17 11:31:38.003402Z] Running install step. 04:31:38 INFO - Running pre-action listener: _resource_record_pre_action 04:31:38 INFO - Running main action method: install 04:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:38 INFO - Reading from file tmpfile_stdout 04:31:38 INFO - Detecting whether we're running mozinstall >=1.0... 04:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:31:38 INFO - Reading from file tmpfile_stdout 04:31:38 INFO - Output received: 04:31:38 INFO - Usage: mozinstall [options] installer 04:31:38 INFO - Options: 04:31:38 INFO - -h, --help show this help message and exit 04:31:38 INFO - -d DEST, --destination=DEST 04:31:38 INFO - Directory to install application into. [default: 04:31:38 INFO - "/builds/slave/test"] 04:31:38 INFO - --app=APP Application being installed. [default: firefox] 04:31:38 INFO - mkdir: /builds/slave/test/build/application 04:31:38 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:31:38 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:32:12 INFO - Reading from file tmpfile_stdout 04:32:12 INFO - Output received: 04:32:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:32:12 INFO - Running post-action listener: _resource_record_post_action 04:32:12 INFO - [mozharness: 2016-10-17 11:32:12.819885Z] Finished install step (success) 04:32:12 INFO - [mozharness: 2016-10-17 11:32:12.820064Z] Running run-tests step. 04:32:12 INFO - Running pre-action listener: _resource_record_pre_action 04:32:12 INFO - Running main action method: run_tests 04:32:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:32:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:32:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:32:12 INFO - 2016-10-17 04:32:12.834 screenresolution[1639:7446] starting screenresolution argv=screenresolution get 04:32:12 INFO - 2016-10-17 04:32:12.837 screenresolution[1639:7446] Display 0: 1600x1200x32@60 04:32:12 INFO - 2016-10-17 04:32:12.842 screenresolution[1640:7448] starting screenresolution argv=screenresolution list 04:32:12 INFO - Available Modes on Display 0 04:32:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:32:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:32:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:32:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:32:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:32:13 INFO - Intel Iris: 04:32:13 INFO - Chipset Model: Intel Iris 04:32:13 INFO - Type: GPU 04:32:13 INFO - Bus: Built-In 04:32:13 INFO - VRAM (Dynamic, Max): 1536 MB 04:32:13 INFO - Vendor: Intel (0x8086) 04:32:13 INFO - Device ID: 0x0a2e 04:32:13 INFO - Revision ID: 0x0009 04:32:13 INFO - Displays: 04:32:13 INFO - Display: 04:32:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:32:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:32:13 INFO - Display Serial Number: 1600x1200 60 04:32:13 INFO - Main Display: Yes 04:32:13 INFO - Mirror: Off 04:32:13 INFO - Online: Yes 04:32:13 INFO - Rotation: Supported 04:32:13 INFO - Return code: 0 04:32:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:32:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:32:13 INFO - Python 2.7.3 04:32:13 INFO - Return code: 0 04:32:13 INFO - grabbing minidump binary from tooltool 04:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:13 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 0x1009f9d30>, '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 0x10081fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009f1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:32:13 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:32:13 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:32:13 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:32:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:32:13 INFO - Return code: 0 04:32:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:32:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:32:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:32:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:32:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:32:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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:32:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0067 --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:32:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W49x4S6E1r/Render', 04:32:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:13 INFO - 'HOME': '/Users/cltbld', 04:32:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:13 INFO - 'LOGNAME': 'cltbld', 04:32:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:32:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:32:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:13 INFO - 'MOZ_NO_REMOTE': '1', 04:32:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:32:13 INFO - 'NO_EM_RESTART': '1', 04:32:13 INFO - 'PAGER': '/bin/cat', 04:32:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:13 INFO - 'PWD': '/builds/slave/test', 04:32:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:32:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:13 INFO - 'SHELL': '/bin/bash', 04:32:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S1xKbAYRLf/Listeners', 04:32:13 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 04:32:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:13 INFO - 'USER': 'cltbld', 04:32:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:13 INFO - 'XPC_FLAGS': '0x0', 04:32:13 INFO - 'XPC_SERVICE_NAME': '0', 04:32:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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:32:13 INFO - mozversion application_buildid: 20161017021835 04:32:13 INFO - mozversion application_changeset: 0191c2f3a6e8c0a241bc440cb61a8f15d061bc38 04:32:13 INFO - mozversion application_display_name: Nightly 04:32:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:32:13 INFO - mozversion application_name: Firefox 04:32:13 INFO - mozversion application_remotingname: firefox 04:32:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:32:13 INFO - mozversion application_vendor: Mozilla 04:32:13 INFO - mozversion application_version: 52.0a1 04:32:13 INFO - mozversion platform_buildid: 20161017021835 04:32:13 INFO - mozversion platform_changeset: 0191c2f3a6e8c0a241bc440cb61a8f15d061bc38 04:32:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:32:13 INFO - mozversion platform_version: 52.0a1 04:32:13 INFO - using testdate: 1476703933 04:32:13 INFO - actual date: 1476703933 04:32:13 INFO - starting webserver on 'localhost:49268' 04:32:13 INFO - SUITE-START | Running 2 tests 04:32:13 INFO - TEST-START | dromaeo_css 04:32:13 INFO - operating with platform_type : mac_ 04:32:13 INFO - Initialising browser for dromaeo_css test... 04:32:13 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmp4csnVu/profile http://localhost:49268/getInfo.html) 04:32:16 INFO - PROCESS | 1650 | 2016-10-17 04:32:16.335 plugin-container[1652:7611] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 04:32:16 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:32:16 INFO - PROCESS | 1650 | 2016-10-17 04:32:16.338 plugin-container[1652:7611] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 04:32:16 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:32:16 INFO - PROCESS | 1650 | 2016-10-17 04:32:16.338 plugin-container[1652:7611] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:32:16 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 04:32:16 INFO - PROCESS | 1650 | colorDepth:24 04:32:16 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 04:32:16 INFO - PROCESS | 1650 | __metrics 04:32:16 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 04:32:17 INFO - TEST-INFO | 1650: exit 0 04:32:17 INFO - Browser initialized. 04:32:17 INFO - Running cycle 1/1 for dromaeo_css test... 04:32:17 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmp4csnVu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:19 INFO - PROCESS | 1653 | 2016-10-17 04:32:19.575 plugin-container[1654:7733] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x862b, name = 'com.apple.tsm.portname' 04:32:19 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:32:19 INFO - PROCESS | 1653 | 2016-10-17 04:32:19.577 plugin-container[1654:7733] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 04:32:19 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:32:19 INFO - PROCESS | 1653 | 2016-10-17 04:32:19.577 plugin-container[1654:7733] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:32:29 INFO - PROCESS | 1653 | RSS: Main: 237383680 04:32:29 INFO - PROCESS | 1653 | 04:33:18 INFO - PROCESS | 1653 | 1476703998784 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:35:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 04:35:34 INFO - PROCESS | 1653 | RSS: Main: 250277888 04:35:34 INFO - PROCESS | 1653 | 04:38:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 04:38:37 INFO - PROCESS | 1653 | RSS: Main: 263852032 04:38:37 INFO - PROCESS | 1653 | 04:41:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 04:41:40 INFO - PROCESS | 1653 | RSS: Main: 268414976 04:41:40 INFO - PROCESS | 1653 | 04:44:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 04:44:42 INFO - PROCESS | 1653 | RSS: Main: 268005376 04:44:42 INFO - PROCESS | 1653 | 04:47:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 04:47:46 INFO - PROCESS | 1653 | RSS: Main: 273367040 04:47:46 INFO - PROCESS | 1653 | 04:50:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 04:50:49 INFO - PROCESS | 1653 | RSS: Main: 276135936 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - PROCESS | 1653 | __start_tp_report 04:50:49 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 04:50:49 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 04:50:49 INFO - PROCESS | 1653 | |i|pagename|runs| 04:50:49 INFO - PROCESS | 1653 | |0;dojo.html;2766.8339899606026;3024.425359181755;3075.876964921403;3317.2536179359645;3339.7495187860886;5582.8325150245455;5603.775848966042;5618.719064046792;5641.153826925964;5729.512991395736;5066.645334826554;5107.616928730351;5609.439056094388;5642.181883626871;5706.229658996034;5139.460356662553;5143.4599367066485;5269.341332333458;5275.155975043975;5279.4984476474865;5197.324347834795;5283.996040752264;5288.788448462057;5290.0477913975465;5343.706096164709;5070.543651071384;5241.42344342122;5246.895062098775;5272.446393128724;5273.103572392683;5091.134507133796;5140.3060586820675;5164.070467315882;5167.354080739908;5218.8695282617855;5047.49525047496;5114.872128196825;5128.871778205575;5144.948550514484;5159.458256883011;5482.80810171642;5536.529395001429;5555.2500412444615;5658.632188907749;5668.461496157858;6076.908846367308;6096.859313929741;6130.601510901822;6143.078538219258;6159.969200154015;6162.815115546541;6206.534509911775;6247.843803904893;6271.9651257542455;6291.402316779899;5692.516136128393;5751.712414379264;5757.13642953552;5787.8553036174435;5824.4466775656665;6209.5032397408095;6268.498520118471;6287.717052732638;6297.2128483939505;6297.4962003039645;6110.236220472441;6131.570790044743;6142.201513803177;6150.354212807681;6165.075238714228;6127.295361033538;6132.50939924805;6190.81427557174;6207.131001659771;6246.500279977591;5320.414754377015;6132.50939924805;6180.289266734293;6182.319944806068;6192.845178870564;6102.938970610237;6125.234345706786;6141.986572215624;6172.722227499773;6208.658523781235;4812.422509298906;4833.468318484964;4879.097366987119;4929.7781599828095;4942.481039490874;4323.589259020388;4398.164348773723;4402;4471.619912307425;4494.07871386365;5018.5985121190215;5803.448672376117;5825.475707186373;5826.82519524415;5841.883162336814;4883.780229889663;5614.971925140429;6214.347493513206;6226.346233645492;6250.781222657185;4052.3111071117983;4354.368616550582;4816.277558366273;5748.597598168171;5885.499732522783;4492.640588752892;5394.136938089965;5401.513863752281;5412.161115027177;5423.511883930465;6286.748530058747;6347.777827776005;6353.174087368612;6354.3010268870385;6391.488680905423;6085.786997455068;6097.615421629385;6124.6325220486915;6128.3503509891225;6144.600600960924;5789.305283366023;5948.672822994604;6310.558260921692;6327.715252813727;6372.171617689671;5942.376050514634;5985.371535988658;6070.180525628984;6123.112148738497;6126.081087836682;4650.069986002745;6126.571140020156;6207.099970504342;6214.4407003369915;6224.968875155595;5933.3769954154195;5978.880422391615;6023.6385816851125;6060.636361818305;6143.754249829957;6056.45491905713;6060.181875446759;6064.120702498201;6105.236845394325;6127.84680382994;5858.677772722541;5869.501092407022;5982.970085149546;5996.4603185713495;6001.729922153426;4518.412606361284;5913.556483263858;5945.286565612154;6016.067509536029;6048.6975651218145;5886.676232807236;5886.911696324644;5892;5898.705064746659;5917.940820591739 04:50:49 INFO - PROCESS | 1653 | |1;ext.html;451.24641848113646;462.6322073951209;467.9321498382734;509.98725031874187;525.5454032262095;33022.66977330226;43770;43812;43848.3422748659;43996;43084.78457607707;43102;43403.131937361315;43418.78290608543;43513.91215219621;9404.106609872051;9551.904480955205;9565.66520171794;9585.712428627117;9608.279379046564;9455.716328510156;9518.857217141749;9556.474393908333;9565.617375305013;9567.23462123032;9446.055394460533;9505.19205867502;9560.952195239013;9603.279754018442;9603.759906002337;9555.378900371454;9562.330636855422;9582.041795820362;9600.90399096007;9612.95193524035;8110.742834860606;9398.859017114748;9434.05659434058;9509.286803489696;9538.95230523843;40844;41135;41497.377539336936;41604.58395416068;41682.37476437856;3372.679595438454;3433.210361616817;3609.8917032489067;3613.981930090359;4104.958950410488;30938;31479.52780708291;31725.524117138262;31776.205594860312;32205;2703.7972152088673;3006.8195908245575;3031.4994077931347;3299.4555898276753;3325.334933013407;405771.59598828095;495960;501097;501774;502680;56165.87668246694;56541.151882721795;56707.43292567104;56786.86426271452;56847.863042738914;63422.04866927;63950.36049639444;64385.71228575402;64435.35564644387;64503.67748161229;16082.678346433006;16382.426615068416;16462.588435289214;16674.749878751827;16689.165541722865;9815.36200146988;11098;12482.937585312015;13001.479940802452;13026.023048271418;13024.674383140497;16386.508404747878;16397.754033689504;16619.335226591043;16634.833651663328;6376.362363763679;6822.522423430411;7187.425005999507;7263;7297.525660832029;677.6002158726375;761.584936209767;780.1691198873182;985.3989066669326;1025.6974192613047;21167.78832211689;21539.2461263855;26968.865155674095;30520;30586.235344116576;1026.414943482223;1072.2494254022215;1098.555085190499;1101.124605938281;1105.1932089574655;15729.842701572838;15800.288986995613;15873.841261587237;16013.519594412186;16147.838521614869;617936;624701;626453;626919;632394;59263.70368148218;59486.70256648689;59587.70206148941;59603.70198149068;59712.40287597155;1372.7460419822364;1421.9075760075561;1435.4473527691914;1438.5468577398085;1439.9136051836713;28802;29304.853475733336;29528;30154.84922575373;30175.245618859703;50253.246201306276;50311.49688503068;50412.73968150679;50413.23553675604;50566.74716626394;463835;468871;471103;471877.6406117947;471883.6405817949;25705.614415783402;25823.870880645478;25927.740722593288;26204.475910482066;26239.868800655873;46295.76852115853;46934.530654693015;47418.09551825052;49096.50903490919;49169.75415122901;34724.47913281252;39479.407808883465;40238.39642405425;40786;41588.16823663449;39345.40981885331;39608.801955990035;39829.60170398259;40530;40939.7953010233;33184.668153318155;33904.49143262902;37693.62306376901;38643.80678096592;38957.22085558216;34421.483677744356;35304;35634.64365356313;36011.63988360083;36053;22560.887195564574;25734.485310293316;26277;26436.20632411471;26440.73559264383 04:50:49 INFO - PROCESS | 1653 | |2;jquery.html;276.53404014236;315.1758152431392;348.33120408815074;349.4060097833682;374.28511542952964;19748.90125549372;19988.846398980244;20493.38519844405;20954.580908381824;21191.68212476814;15850.731941444685;20556.07497662605;20571.691424628643;20576;20980.265690700828;5503;5810.041343178371;5823.039198532237;5836.15375770513;5836.562257830658;5619.7471113799975;5670.319561652607;5759.913601295984;5803.8258852234285;5825.592208545403;4831.202851529493;5641.435856414367;5757.280339957505;5758.107493338539;5765.538756899459;5465.617406781527;5753.884922301551;5777;5784.768609255646;5787.623804452697;4695.929561056587;4872;4873.634477414208;4996.825111121093;5010.574101201402;20191;22507.77492225073;24995.750042499705;25218.621720674204;25416.491670166677;2155.504234026183;2357.2456813819585;2449.448874003353;2455.3493324269084;2749.6150538924567;10297.845532316946;15328.15695136767;15335.693286134216;15496.535103946899;16035.599110022225;1479.9112053276838;1484.2059498168428;1489.7169537787895;1498.6103613060575;1523.9771403428958;113506;114876;115694;116072.41963790212;116279;7801.375889928792;9671.371360861522;9993.950030249802;10449.373037617766;10653.72155341364;12540.372981351078;12578.119531632881;12588.181768185223;12589.685257868627;12593.244405335709;8020.238077382639;8313.168683131638;8381.036180839159;8453.704120355758;8472.983242010874;7489.812754681177;8738.912610873937;8743.213110820057;8749.343799214957;8898.59956301968;6251.218597675291;6397.609267088451;6404.071409645669;6420.357964203542;6497.805065848032;1443.585286419315;1468.7870258812413;1724.6119623084705;1750.7569625565736;1767.719641662267;886.8448021596195;936.864154697565;952.5237381309346;965.5510187762724;997.5760301871676;20303.4924126898;21252.149914003578;21469.78530214709;21743.67384489234;21793.455163620718;957.4733896356976;971.5725080964353;1030.6959446963165;1057.8095942730229;1060.2154405739852;10673.786524269472;11572.6528204154;11640.60312762474;11649.1263155262;11651.53393864236;598514;600706;602766;620433;623727.5254494884;92087.61868571976;104860.47569762255;113156.30265546024;117180.9999999983;117574;1000.6347683095618;1004.864343313658;1005.1456262177237;1031.7781676939494;1033.9121174700085;14498.985071044926;15022.549323520312;15954.441594544136;16258.187090645184;16871.90332628375;41680;48647.75676121597;51716.48283517117;52831.735841320544;53075.46924530705;54955;57190.42809571851;68115.63768724697;74233.6288318555;76856.61571692106;6275.435210831047;7182.928170718226;7227.638618069181;7403.703851845866;7743.806404839699;12358.876411236131;12635.167900654169;12829.87170128324;12946.741065178832;13253.271070090852;10906.236563440483;11045.183478213004;11072.778544429228;11212.831807523056;11306.94346528295;11060.834087488533;11210.719732006764;11361.943190283995;11603.303801771921;11738.706532336419;11058.11535077208;11186.664400068012;11519;11595.478203481032;11602.535898563963;7484.663190156347;7618.123915749759;7751.418643601865;7863.41024423182;7863.882041769263;10861.619843305447;11278.210525263481;11381.943090284827;11486.770264594494;11493.482793274155 04:50:49 INFO - PROCESS | 1653 | |3;mootools.html;1407.5425486716817;1519.2403798100952;1700.719381302086;1712.4348964841604;1874.3158747057328;7496.512726672762;7744.109427415849;7866.134725180233;8384.119667434923;8414.747557573268;7928.484648497843;8233.053198882131;8248.13394593568;8356.707515236953;8363.707270245543;2259.80791632711;2285.9657105143438;2313.849599776009;2321.245595181564;2326.1858349577683;2280.8631482111045;2296.4258935266184;2302.0446514696464;2324.3027091872455;2334.4747431827873;2268.4669102760836;2311.3874823171873;2314.6412306092584;2316.4787922717423;2326.4183954011496;1902.6480101181326;2279.1225378229337;2279.430142464384;2281.543691261746;2285.440067183534;2276.1578216060166;2278.6809846621486;2279.09975559653;2282.8173746100274;2285.417218609244;8286.171382861665;8292.83414331716;8336.958315208445;8352.997640283143;8391.202835730595;4915.311856340115;5127.564157046656;5128.461511541272;5149.356330458692;5165.560927321182;3322.3687499375283;3387.44107222308;3504.912377190565;3511.841967111486;3517.1383011162175;903;1031.3193292426963;1039.2465462539674;1040.0483557544837;1060.33199084577;9261.861072083857;9305.022972587849;10244.436555989418;10498;10542.894571054268;4182.142660754538;4213.705040647186;4362.411074504965;4379.715318504287;4384.232759267115;8451.197136272045;8472.322214222848;8494.915050849413;8527.488350698977;8536;4969.801207951641;5003.324551185616;5004.899902001939;5017.9247311290355;5022.799088036438;4199.538050814408;4358.389825424443;4546.1135078659345;4649.604783593365;4708.976455117702;3564.928701425957;3580.3913334733156;3665.4135338345736;3684.3184010958057;3686.4286035664513;1126.1441304608393;1131.5813149134872;1137.0221609415896;1148.8161894097072;1149.0692539043403;851.9616617252238;863;908.8000639859221;913.88891660265;956.9473678947586;4838.709677419366;5316.803719163157;5606.6916319602;5672.546196304285;5694.544436445074;353.2475826489561;394.28830960116693;416.4440471969954;416.50435981182295;421.8228344095487;3669.4679271505483;3735.5143831301757;3747.681447077029;3780.716446266541;3814.8474061037805;9507.667231646998;9652.324337296322;9804.36271642341;9855.704328870146;9931.354461960094;9831.360961537477;9848.655297064568;9867;9888.703338899844;9893.703188904345;7270.236625154388;7584.54492730438;7639.809004774814;7678.500897441649;7720.189380115007;4453.888652783577;4716.5755082041405;5685.800996965087;5798.768049277982;5941.079132734433;7850.293473587404;8910.420822646767;9209.263258939403;9241.86137207929;9402.57688404037;9194.08059194102;9299.442033478013;9380;9387.249020078241;9448.716538503855;6886.414654754402;7018.964905175441;7083.716651334095;7169.068021157216;7271.963640181977;7905.841883162189;7912.9208707912185;7930.682772689028;7949.523028618301;7957.8408431832195;5955.464008239279;6960.930390696029;7112.110986126734;7142.392896603847;7165.498415110892;6926.965365173141;6927.653617319013;6940.9652951734915;6970.616616085961;6972.616506092214;6547.214334279917;6554.901676474952;6579;6646.401823835685;6670.899936500859;3936.7834769087976;4809.807607695793;4813.253945638432;4828.975855120702;4918.0901533216465;4884.169691152441;4899.412070551557;4908.582770464551;4937.629677774253;4956.454789973172 04:50:49 INFO - PROCESS | 1653 | |4;prototype.html;269.4489768423574;280.8469384185619;287.8342712016334;293.15278844140437;293.90448104366084;4878.658493905428;5406.621536492447;5568.721563921803;5579.665220086793;5621.7751289948455;4006;4515.983903621684;4555.200695725187;5374.731263436823;5515.586331025169;3302.5541551890487;3950.604939506055;3971.98014009931;4125.855595054174;4192.8197337384545;3351.966480335202;3409.8636054557787;3616.439451884962;3636.654517820803;4170.916581668364;3278.8360581970805;3928.3910993796007;4083.7141400101855;4140.482439695038;4196.727212731163;3035.9089227323216;3402.3535528249554;3980.7014473914574;4125.422440858282;4150.418941348184;3081.984590077035;3500.422430298998;3546.8226588670723;3556.705924678594;3556.822158892045;4869.8782530436665;5444.346678398578;5512.724363781795;5537.224788529609;5583.274174357308;4084;4134.00783811886;4687.601553867926;4774.546418090275;4776.331313616097;5658.7170641467765;5945.702714864283;5954.612950158269;5962.422546472126;6005.129256257862;3636.709063274932;3744.9625503744887;3848.3073046851555;3872.535295764498;3893.415987601854;5004.649674522785;5261.289725887033;6139.631622102644;6212.65830379329;6216.44052035319;3422.9559984205057;3878.84484620612;3887.96112038876;3898.7465814721954;3927.19492504036;3599.6580324869096;3735;3930.7051971102283;3963.841446342172;3993.121513267077;4042.191561687674;4623.9075218495445;4635.258358662598;4639.234526303189;4651.2557990721325;5093.414257360451;5413.944280865194;5605.598600349913;5878.853028674317;5944.494717948936;1260.9156125731863;1267.594369801673;1279.4219647410475;1303.7262174943369;1340.6514306280437;754.909410870699;880.0055936791513;883.4169448164181;897.8249241397865;899.2760827533898;4637.559431853969;5587.3015873015875;5593.944060559424;5604.915926261109;5630;5768.7692492300675;5891.403166991376;6095.573309868355;6098.26820781509;6134.754609815557;3960.960390396059;4525.479569849444;5046.940142570028;5537.473939975695;5564.163825937431;2260.8530445520987;2555.083687234574;2694.6227528146073;2717.4238941413932;2727.799768102029;4851.520286312638;5246.449122842123;5273.945210957824;5291.682499050069;5295.4439783822145;4829.082474329866;4860.736208585793;5212.582993360522;5232.790688372498;5265.736713164327;4590.42619672541;4643.003508491382;5041.0422019816115;5170;5197.792088316501;4111.944027986007;4156.8337266509;4327.978360108179;4713.835015774432;4729.716217026989;4682.41469816273;5030;5037.596992240538;5048.8990220194655;5082.780132768183;4480.798364073559;5129.102232174119;5191.870203244949;5253.94921015806;5292.576593872558;2959.79281450305;3387.796732196076;3450.3271861986673;4088.8773336799945;4092.2633925893624;5070.923936141035;6044.365341639241;6120.755169793337;6190.81427557174;6192.287886893065;5754.446299499028;5793.478586927028;6027.698615069141;6028;6044.395560444096;5065.594752419871;5308.681479111266;5865.589408741347;5929.584929055098;5937.109433584824;4680.7893644786745;5329.148905335627;5429.375621803385;5435.646682965595;5746.425357464387;6027.29870700854;6047.609049918456;6053.334133245251;6055.788047418319;6085.1480792689945;5054.671446355975;5651.90879374634;5691.089425691869;5726.599138060295;5865.706714664335 04:50:49 INFO - PROCESS | 1653 | |5;yui.html;660.4683229999852;704.5702121705756;719.4388377065887;742.9375992330964;844.8986121665399;15528.844711552882;16435.58911027222;16483.505494835143;16508.092054936988;16513.256903439345;14413.711725765479;14819.259037048132;15042.84957150428;15081.321340539675;15141.31864066117;851.1445996773234;851.2253848997414;873.0440168016012;873.4540911930044;874.0167311774254;700.2332445971653;725.2022774947542;890.3455959869491;895.6148855991953;895.6193617712446;889.8398288308102;905.3798148268424;917.0874979395511;925.6019911438079;925.9907400926005;905.0090151284365;911.3438324406417;912.7991841794794;913.8811954445946;914.0767824497273;658.8352911772057;680.6868840333452;682.3483573187643;683.5898460923456;685.1127789512593;12721.809172862415;13586.38861251246;14987.700245995127;15332.616684582974;15510.45713400037;14251.50119745804;14309.499167529191;14337.21145337006;14435.133891966516;14458.27708614565;11750.94124529372;12118.757624847454;12859;12924.289164095972;12968.546100886424;3722.4788529605876;4456.2647163218035;4529.456465224194;4566.977165114186;4621.098885717286;101598.9840101597;102076;102636.48681756618;103253;106744.4662776689;9558.474283914313;11217.775644487065;11375.715607109889;11414.315141091562;11479.514378014597;22696.65955010676;22940.426489337566;22945.54108917812;22960.425989350068;23040.193593224158;7931.524108553505;8016.879746803803;8052.186422600981;8080.4343695940715;8240.340772738287;4376.934345984812;4429.11417716458;4441.400410944546;4475.082608065339;4547.136044151601;3358.6473420290517;3429.879954201591;3447.500112483676;3452.395830729612;3452.6202117767025;602.1750202222983;613.2351627851657;627.8524546731514;637.8437282865727;638.2628064585411;526.2579762534884;582.1733138942711;605.5821483176594;619.9429971897903;621.1924498152474;9035.322350823715;9062.773430664287;9163.954180229057;9215.216706580015;9217.354785165107;588.2294194605081;616.8797084568466;620.9099680546295;632.6678493790797;635.891898377277;4271.722338048017;4297.5702429756775;4566.97716511422;4581.289900065495;4632.351470794091;105248.42127368096;151970.24014879856;155520.55835533357;158404.53561447177;164015.3598464024;118642.35612904454;145916;147507;148692;152390.47609523984;102781;108931;120774;123712;125496;8542.40203185771;8641.783955401166;8649.52427616462;8727.607257673293;8727.65089396417;67110;67396.32603674097;67661.66169169123;68764;69400;87395.56302218448;102050;102534;104620;105475;11497.82753258685;11776.11679124052;11797.587084452003;11906.642800715992;12093.637190884288;12148.27110373345;12263.38683065861;12307.753844922872;12460.563880264148;12724.300163491096;11891.28652280866;11944.880551194725;11989.520419183484;12356.949659278702;12369.072319575887;11620.593279235525;12033;12145.635630931087;12421.006319494238;12434.191777534432;10446.112080473245;10964.616238431616;11077.501512431798;11094.445277736242;11310.264832785842;9597.808043838944;9732.70801875945;9757;10002.749931251778;10115.645952391631;8356.791080223044;8664.870026949475;9893.65372211969;9941.961897810786;9951.203903687832 04:50:49 INFO - PROCESS | 1653 | __end_tp_report 04:50:49 INFO - PROCESS | 1653 | __start_cc_report 04:50:49 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,359 04:50:49 INFO - PROCESS | 1653 | __end_cc_report 04:50:49 INFO - PROCESS | 1653 | __startTimestamp1476705049119__endTimestamp 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - PROCESS | 1653 | ------- Summary: start ------- 04:50:49 INFO - PROCESS | 1653 | Number of tests: 6 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5629.98 Median:5885.50 stddev:698.04 (11.9%) stddev-sans-first:663.07 04:50:49 INFO - PROCESS | 1653 | Values: 2766.8 3024.4 3075.9 3317.3 3339.7 5582.8 5603.8 5618.7 5641.2 5729.5 5066.6 5107.6 5609.4 5642.2 5706.2 5139.5 5143.5 5269.3 5275.2 5279.5 5197.3 5284.0 5288.8 5290.0 5343.7 5070.5 5241.4 5246.9 5272.4 5273.1 5091.1 5140.3 5164.1 5167.4 5218.9 5047.5 5114.9 5128.9 5144.9 5159.5 5482.8 5536.5 5555.3 5658.6 5668.5 6076.9 6096.9 6130.6 6143.1 6160.0 6162.8 6206.5 6247.8 6272.0 6291.4 5692.5 5751.7 5757.1 5787.9 5824.4 6209.5 6268.5 6287.7 6297.2 6297.5 6110.2 6131.6 6142.2 6150.4 6165.1 6127.3 6132.5 6190.8 6207.1 6246.5 5320.4 6132.5 6180.3 6182.3 6192.8 6102.9 6125.2 6142.0 6172.7 6208.7 4812.4 4833.5 4879.1 4929.8 4942.5 4323.6 4398.2 4402.0 4471.6 4494.1 5018.6 5803.4 5825.5 5826.8 5841.9 4883.8 5615.0 6214.3 6226.3 6250.8 4052.3 4354.4 4816.3 5748.6 5885.5 4492.6 5394.1 5401.5 5412.2 5423.5 6286.7 6347.8 6353.2 6354.3 6391.5 6085.8 6097.6 6124.6 6128.4 6144.6 5789.3 5948.7 6310.6 6327.7 6372.2 5942.4 5985.4 6070.2 6123.1 6126.1 4650.1 6126.6 6207.1 6214.4 6225.0 5933.4 5978.9 6023.6 6060.6 6143.8 6056.5 6060.2 6064.1 6105.2 6127.8 5858.7 5869.5 5983.0 5996.5 6001.7 4518.4 5913.6 5945.3 6016.1 6048.7 5886.7 5886.9 5892.0 5898.7 5917.9 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:66574.00 Median:41643.48 stddev:141595.73 (340.0%) stddev-sans-first:141905.91 04:50:49 INFO - PROCESS | 1653 | Values: 451.2 462.6 467.9 510.0 525.5 33022.7 43770.0 43812.0 43848.3 43996.0 43084.8 43102.0 43403.1 43418.8 43513.9 9404.1 9551.9 9565.7 9585.7 9608.3 9455.7 9518.9 9556.5 9565.6 9567.2 9446.1 9505.2 9561.0 9603.3 9603.8 9555.4 9562.3 9582.0 9600.9 9613.0 8110.7 9398.9 9434.1 9509.3 9539.0 40844.0 41135.0 41497.4 41604.6 41682.4 3372.7 3433.2 3609.9 3614.0 4105.0 30938.0 31479.5 31725.5 31776.2 32205.0 2703.8 3006.8 3031.5 3299.5 3325.3 405771.6 495960.0 501097.0 501774.0 502680.0 56165.9 56541.2 56707.4 56786.9 56847.9 63422.0 63950.4 64385.7 64435.4 64503.7 16082.7 16382.4 16462.6 16674.7 16689.2 9815.4 11098.0 12482.9 13001.5 13026.0 13024.7 16386.5 16397.8 16619.3 16634.8 6376.4 6822.5 7187.4 7263.0 7297.5 677.6 761.6 780.2 985.4 1025.7 21167.8 21539.2 26968.9 30520.0 30586.2 1026.4 1072.2 1098.6 1101.1 1105.2 15729.8 15800.3 15873.8 16013.5 16147.8 617936.0 624701.0 626453.0 626919.0 632394.0 59263.7 59486.7 59587.7 59603.7 59712.4 1372.7 1421.9 1435.4 1438.5 1439.9 28802.0 29304.9 29528.0 30154.8 30175.2 50253.2 50311.5 50412.7 50413.2 50566.7 463835.0 468871.0 471103.0 471877.6 471883.6 25705.6 25823.9 25927.7 26204.5 26239.9 46295.8 46934.5 47418.1 49096.5 49169.8 34724.5 39479.4 40238.4 40786.0 41588.2 39345.4 39608.8 39829.6 40530.0 40939.8 33184.7 33904.5 37693.6 38643.8 38957.2 34421.5 35304.0 35634.6 36011.6 36053.0 22560.9 25734.5 26277.0 26436.2 26440.7 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:34197.54 Median:22150.62 stddev:100999.55 (456.0%) stddev-sans-first:101250.77 04:50:49 INFO - PROCESS | 1653 | Values: 276.5 315.2 348.3 349.4 374.3 19748.9 19988.8 20493.4 20954.6 21191.7 15850.7 20556.1 20571.7 20576.0 20980.3 5503.0 5810.0 5823.0 5836.2 5836.6 5619.7 5670.3 5759.9 5803.8 5825.6 4831.2 5641.4 5757.3 5758.1 5765.5 5465.6 5753.9 5777.0 5784.8 5787.6 4695.9 4872.0 4873.6 4996.8 5010.6 20191.0 22507.8 24995.8 25218.6 25416.5 2155.5 2357.2 2449.4 2455.3 2749.6 10297.8 15328.2 15335.7 15496.5 16035.6 1479.9 1484.2 1489.7 1498.6 1524.0 113506.0 114876.0 115694.0 116072.4 116279.0 7801.4 9671.4 9994.0 10449.4 10653.7 12540.4 12578.1 12588.2 12589.7 12593.2 8020.2 8313.2 8381.0 8453.7 8473.0 7489.8 8738.9 8743.2 8749.3 8898.6 6251.2 6397.6 6404.1 6420.4 6497.8 1443.6 1468.8 1724.6 1750.8 1767.7 886.8 936.9 952.5 965.6 997.6 20303.5 21252.1 21469.8 21743.7 21793.5 957.5 971.6 1030.7 1057.8 1060.2 10673.8 11572.7 11640.6 11649.1 11651.5 598514.0 600706.0 602766.0 620433.0 623727.5 92087.6 104860.5 113156.3 117181.0 117574.0 1000.6 1004.9 1005.1 1031.8 1033.9 14499.0 15022.5 15954.4 16258.2 16871.9 41680.0 48647.8 51716.5 52831.7 53075.5 54955.0 57190.4 68115.6 74233.6 76856.6 6275.4 7182.9 7227.6 7403.7 7743.8 12358.9 12635.2 12829.9 12946.7 13253.3 10906.2 11045.2 11072.8 11212.8 11306.9 11060.8 11210.7 11361.9 11603.3 11738.7 11058.1 11186.7 11519.0 11595.5 11602.5 7484.7 7618.1 7751.4 7863.4 7863.9 10861.6 11278.2 11381.9 11486.8 11493.5 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5290.34 Median:5075.18 stddev:2894.97 (57.0%) stddev-sans-first:2888.38 04:50:49 INFO - PROCESS | 1653 | Values: 1407.5 1519.2 1700.7 1712.4 1874.3 7496.5 7744.1 7866.1 8384.1 8414.7 7928.5 8233.1 8248.1 8356.7 8363.7 2259.8 2286.0 2313.8 2321.2 2326.2 2280.9 2296.4 2302.0 2324.3 2334.5 2268.5 2311.4 2314.6 2316.5 2326.4 1902.6 2279.1 2279.4 2281.5 2285.4 2276.2 2278.7 2279.1 2282.8 2285.4 8286.2 8292.8 8337.0 8353.0 8391.2 4915.3 5127.6 5128.5 5149.4 5165.6 3322.4 3387.4 3504.9 3511.8 3517.1 903.0 1031.3 1039.2 1040.0 1060.3 9261.9 9305.0 10244.4 10498.0 10542.9 4182.1 4213.7 4362.4 4379.7 4384.2 8451.2 8472.3 8494.9 8527.5 8536.0 4969.8 5003.3 5004.9 5017.9 5022.8 4199.5 4358.4 4546.1 4649.6 4709.0 3564.9 3580.4 3665.4 3684.3 3686.4 1126.1 1131.6 1137.0 1148.8 1149.1 852.0 863.0 908.8 913.9 956.9 4838.7 5316.8 5606.7 5672.5 5694.5 353.2 394.3 416.4 416.5 421.8 3669.5 3735.5 3747.7 3780.7 3814.8 9507.7 9652.3 9804.4 9855.7 9931.4 9831.4 9848.7 9867.0 9888.7 9893.7 7270.2 7584.5 7639.8 7678.5 7720.2 4453.9 4716.6 5685.8 5798.8 5941.1 7850.3 8910.4 9209.3 9241.9 9402.6 9194.1 9299.4 9380.0 9387.2 9448.7 6886.4 7019.0 7083.7 7169.1 7272.0 7905.8 7912.9 7930.7 7949.5 7957.8 5955.5 6960.9 7112.1 7142.4 7165.5 6927.0 6927.7 6941.0 6970.6 6972.6 6547.2 6554.9 6579.0 6646.4 6670.9 3936.8 4809.8 4813.3 4829.0 4918.1 4884.2 4899.4 4908.6 4937.6 4956.5 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4473.21 Median:4941.65 stddev:1441.52 (29.2%) stddev-sans-first:1410.61 04:50:49 INFO - PROCESS | 1653 | Values: 269.4 280.8 287.8 293.2 293.9 4878.7 5406.6 5568.7 5579.7 5621.8 4006.0 4516.0 4555.2 5374.7 5515.6 3302.6 3950.6 3972.0 4125.9 4192.8 3352.0 3409.9 3616.4 3636.7 4170.9 3278.8 3928.4 4083.7 4140.5 4196.7 3035.9 3402.4 3980.7 4125.4 4150.4 3082.0 3500.4 3546.8 3556.7 3556.8 4869.9 5444.3 5512.7 5537.2 5583.3 4084.0 4134.0 4687.6 4774.5 4776.3 5658.7 5945.7 5954.6 5962.4 6005.1 3636.7 3745.0 3848.3 3872.5 3893.4 5004.6 5261.3 6139.6 6212.7 6216.4 3423.0 3878.8 3888.0 3898.7 3927.2 3599.7 3735.0 3930.7 3963.8 3993.1 4042.2 4623.9 4635.3 4639.2 4651.3 5093.4 5413.9 5605.6 5878.9 5944.5 1260.9 1267.6 1279.4 1303.7 1340.7 754.9 880.0 883.4 897.8 899.3 4637.6 5587.3 5593.9 5604.9 5630.0 5768.8 5891.4 6095.6 6098.3 6134.8 3961.0 4525.5 5046.9 5537.5 5564.2 2260.9 2555.1 2694.6 2717.4 2727.8 4851.5 5246.4 5273.9 5291.7 5295.4 4829.1 4860.7 5212.6 5232.8 5265.7 4590.4 4643.0 5041.0 5170.0 5197.8 4111.9 4156.8 4328.0 4713.8 4729.7 4682.4 5030.0 5037.6 5048.9 5082.8 4480.8 5129.1 5191.9 5253.9 5292.6 2959.8 3387.8 3450.3 4088.9 4092.3 5070.9 6044.4 6120.8 6190.8 6192.3 5754.4 5793.5 6027.7 6028.0 6044.4 5065.6 5308.7 5865.6 5929.6 5937.1 4680.8 5329.1 5429.4 5435.6 5746.4 6027.3 6047.6 6053.3 6055.8 6085.1 5054.7 5651.9 5691.1 5726.6 5865.7 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25337.22 Median:3997.10 stddev:41410.74 (1036.0%) stddev-sans-first:41485.45 04:50:49 INFO - PROCESS | 1653 | Values: 660.5 704.6 719.4 742.9 844.9 15528.8 16435.6 16483.5 16508.1 16513.3 14413.7 14819.3 15042.8 15081.3 15141.3 851.1 851.2 873.0 873.5 874.0 700.2 725.2 890.3 895.6 895.6 889.8 905.4 917.1 925.6 926.0 905.0 911.3 912.8 913.9 914.1 658.8 680.7 682.3 683.6 685.1 12721.8 13586.4 14987.7 15332.6 15510.5 14251.5 14309.5 14337.2 14435.1 14458.3 11750.9 12118.8 12859.0 12924.3 12968.5 3722.5 4456.3 4529.5 4567.0 4621.1 101599.0 102076.0 102636.5 103253.0 106744.5 9558.5 11217.8 11375.7 11414.3 11479.5 22696.7 22940.4 22945.5 22960.4 23040.2 7931.5 8016.9 8052.2 8080.4 8240.3 4376.9 4429.1 4441.4 4475.1 4547.1 3358.6 3429.9 3447.5 3452.4 3452.6 602.2 613.2 627.9 637.8 638.3 526.3 582.2 605.6 619.9 621.2 9035.3 9062.8 9164.0 9215.2 9217.4 588.2 616.9 620.9 632.7 635.9 4271.7 4297.6 4567.0 4581.3 4632.4 105248.4 151970.2 155520.6 158404.5 164015.4 118642.4 145916.0 147507.0 148692.0 152390.5 102781.0 108931.0 120774.0 123712.0 125496.0 8542.4 8641.8 8649.5 8727.6 8727.7 67110.0 67396.3 67661.7 68764.0 69400.0 87395.6 102050.0 102534.0 104620.0 105475.0 11497.8 11776.1 11797.6 11906.6 12093.6 12148.3 12263.4 12307.8 12460.6 12724.3 11891.3 11944.9 11989.5 12356.9 12369.1 11620.6 12033.0 12145.6 12421.0 12434.2 10446.1 10964.6 11077.5 11094.4 11310.3 9597.8 9732.7 9757.0 10002.7 10115.6 8356.8 8664.9 9893.7 9942.0 9951.2 04:50:49 INFO - PROCESS | 1653 | -------- Summary: end -------- 04:50:49 INFO - PROCESS | 1653 | 04:50:49 INFO - TEST-INFO | 1653: exit 0 04:50:49 INFO - TEST-OK | dromaeo_css | took 1116314ms 04:50:49 INFO - TEST-START | kraken 04:50:49 INFO - operating with platform_type : mac_ 04:50:49 INFO - Initialising browser for kraken test... 04:50:49 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpC74AvL/profile http://localhost:49268/getInfo.html) 04:50:52 INFO - PROCESS | 1664 | 2016-10-17 04:50:52.385 plugin-container[1665:9894] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 04:50:52 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:52 INFO - PROCESS | 1664 | 2016-10-17 04:50:52.388 plugin-container[1665:9894] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 04:50:52 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:52 INFO - PROCESS | 1664 | 2016-10-17 04:50:52.388 plugin-container[1665:9894] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:50:52 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 04:50:52 INFO - PROCESS | 1664 | colorDepth:24 04:50:52 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 04:50:52 INFO - PROCESS | 1664 | __metrics 04:50:52 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 04:50:53 INFO - TEST-INFO | 1664: exit 0 04:50:53 INFO - Browser initialized. 04:50:53 INFO - Running cycle 1/1 for kraken test... 04:50:53 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpC74AvL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:55 INFO - PROCESS | 1666 | 2016-10-17 04:50:55.858 plugin-container[1667:10006] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 04:50:55 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:55 INFO - PROCESS | 1666 | 2016-10-17 04:50:55.859 plugin-container[1667:10006] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 04:50:55 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:55 INFO - PROCESS | 1666 | 2016-10-17 04:50:55.859 plugin-container[1667:10006] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:51:05 INFO - PROCESS | 1666 | RSS: Main: 237101056 04:51:05 INFO - PROCESS | 1666 | 04:51:08 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 04:51:09 INFO - PROCESS | 1666 | RSS: Main: 249479168 04:51:09 INFO - PROCESS | 1666 | 04:51:11 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 04:51:12 INFO - PROCESS | 1666 | RSS: Main: 257183744 04:51:12 INFO - PROCESS | 1666 | 04:51:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 04:51:16 INFO - PROCESS | 1666 | RSS: Main: 260009984 04:51:16 INFO - PROCESS | 1666 | 04:51:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 04:51:18 INFO - PROCESS | 1666 | RSS: Main: 262455296 04:51:18 INFO - PROCESS | 1666 | 04:51:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:51:21 INFO - PROCESS | 1666 | RSS: Main: 262742016 04:51:21 INFO - PROCESS | 1666 | 04:51:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 04:51:25 INFO - PROCESS | 1666 | RSS: Main: 262897664 04:51:25 INFO - PROCESS | 1666 | 04:51:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 04:51:29 INFO - PROCESS | 1666 | RSS: Main: 262991872 04:51:29 INFO - PROCESS | 1666 | 04:51:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 04:51:32 INFO - PROCESS | 1666 | RSS: Main: 263438336 04:51:32 INFO - PROCESS | 1666 | 04:51:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:51:34 INFO - PROCESS | 1666 | RSS: Main: 262934528 04:51:34 INFO - PROCESS | 1666 | 04:51:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:51:37 INFO - PROCESS | 1666 | RSS: Main: 263045120 04:51:37 INFO - PROCESS | 1666 | 04:51:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:51:40 INFO - PROCESS | 1666 | RSS: Main: 263057408 04:51:40 INFO - PROCESS | 1666 | 04:51:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:51:44 INFO - PROCESS | 1666 | RSS: Main: 263585792 04:51:44 INFO - PROCESS | 1666 | 04:51:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:51:48 INFO - PROCESS | 1666 | RSS: Main: 263626752 04:51:48 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 04:51:49 INFO - PROCESS | 1666 | RSS: Main: 265072640 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | __start_tp_report 04:51:49 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 04:51:49 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 04:51:49 INFO - PROCESS | 1666 | |i|pagename|runs| 04:51:49 INFO - PROCESS | 1666 | |0;ai-astar;113;117;116;115;116;116;115;115;116;114 04:51:49 INFO - PROCESS | 1666 | |1;audio-beat-detection;148;120;110;199;149;110;151;150;178;125 04:51:49 INFO - PROCESS | 1666 | |2;audio-dft;194;194;204;230;209;197;192;193;208;200 04:51:49 INFO - PROCESS | 1666 | |3;audio-fft;78;58;60;84;61;62;61;59;80;61 04:51:49 INFO - PROCESS | 1666 | |4;audio-oscillator;90;75;87;71;69;71;96;87;64;119 04:51:49 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;111;89;99;118;137;79;139;76;128;106 04:51:49 INFO - PROCESS | 1666 | |6;imaging-darkroom;101;151;128;97;125;111;151;106;106;150 04:51:49 INFO - PROCESS | 1666 | |7;imaging-desaturate;87;121;140;92;88;88;90;91;90;87 04:51:49 INFO - PROCESS | 1666 | |8;json-parse-financial;57;57;57;59;57;59;58;58;58;56 04:51:49 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;69;73;80;76;76;69;67;70;71;73 04:51:49 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;94;103;107;111;81;116;119;112;97;88 04:51:49 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;140;151;158;162;166;149;153;150;224;154 04:51:49 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;225;248;181;174;173;172;177;174;207;246 04:51:49 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;71;72;70;71;73;69;69;70;67;69 04:51:49 INFO - PROCESS | 1666 | __end_tp_report 04:51:49 INFO - PROCESS | 1666 | __start_cc_report 04:51:49 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,946 04:51:49 INFO - PROCESS | 1666 | __end_cc_report 04:51:49 INFO - PROCESS | 1666 | __startTimestamp1476705109969__endTimestamp 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | ------- Summary: start ------- 04:51:49 INFO - PROCESS | 1666 | Number of tests: 14 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:115.30 Median:116.00 stddev:1.16 (1.0%) stddev-sans-first:0.88 04:51:49 INFO - PROCESS | 1666 | Values: 113.0 117.0 116.0 115.0 116.0 116.0 115.0 115.0 116.0 114.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:149.50 stddev:28.98 (19.4%) stddev-sans-first:30.70 04:51:49 INFO - PROCESS | 1666 | Values: 148.0 120.0 110.0 199.0 149.0 110.0 151.0 150.0 178.0 125.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:202.10 Median:202.00 stddev:11.60 (5.7%) stddev-sans-first:11.93 04:51:49 INFO - PROCESS | 1666 | Values: 194.0 194.0 204.0 230.0 209.0 197.0 192.0 193.0 208.0 200.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:66.40 Median:61.50 stddev:10.01 (16.3%) stddev-sans-first:9.70 04:51:49 INFO - PROCESS | 1666 | Values: 78.0 58.0 60.0 84.0 61.0 62.0 61.0 59.0 80.0 61.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:82.90 Median:81.00 stddev:16.52 (20.4%) stddev-sans-first:17.32 04:51:49 INFO - PROCESS | 1666 | Values: 90.0 75.0 87.0 71.0 69.0 71.0 96.0 87.0 64.0 119.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:108.20 Median:107.50 stddev:22.66 (21.1%) stddev-sans-first:24.01 04:51:49 INFO - PROCESS | 1666 | Values: 111.0 89.0 99.0 118.0 137.0 79.0 139.0 76.0 128.0 106.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:122.60 Median:139.00 stddev:21.62 (15.6%) stddev-sans-first:21.47 04:51:49 INFO - PROCESS | 1666 | Values: 101.0 151.0 128.0 97.0 125.0 111.0 151.0 106.0 106.0 150.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:89.00 stddev:18.09 (20.3%) stddev-sans-first:18.79 04:51:49 INFO - PROCESS | 1666 | Values: 87.0 121.0 140.0 92.0 88.0 88.0 90.0 91.0 90.0 87.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:0.97 (1.7%) stddev-sans-first:1.00 04:51:49 INFO - PROCESS | 1666 | Values: 57.0 57.0 57.0 59.0 57.0 59.0 58.0 58.0 58.0 56.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.00 stddev:4.01 (5.5%) stddev-sans-first:4.06 04:51:49 INFO - PROCESS | 1666 | Values: 69.0 73.0 80.0 76.0 76.0 69.0 67.0 70.0 71.0 73.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:102.80 Median:100.00 stddev:12.52 (12.5%) stddev-sans-first:12.87 04:51:49 INFO - PROCESS | 1666 | Values: 94.0 103.0 107.0 111.0 81.0 116.0 119.0 112.0 97.0 88.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:160.70 Median:156.00 stddev:23.39 (15.0%) stddev-sans-first:23.57 04:51:49 INFO - PROCESS | 1666 | Values: 140.0 151.0 158.0 162.0 166.0 149.0 153.0 150.0 224.0 154.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.70 Median:194.00 stddev:31.26 (16.1%) stddev-sans-first:31.56 04:51:49 INFO - PROCESS | 1666 | Values: 225.0 248.0 181.0 174.0 173.0 172.0 177.0 174.0 207.0 246.0 04:51:49 INFO - PROCESS | 1666 | 04:51:49 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.50 stddev:1.73 (2.5%) stddev-sans-first:1.80 04:51:49 INFO - PROCESS | 1666 | Values: 71.0 72.0 70.0 71.0 73.0 69.0 69.0 70.0 67.0 69.0 04:51:49 INFO - PROCESS | 1666 | -------- Summary: end -------- 04:51:49 INFO - PROCESS | 1666 | 04:51:50 INFO - TEST-INFO | 1666: exit 0 04:51:50 INFO - TEST-OK | kraken | took 61115ms 04:51:50 INFO - SUITE-END | took 1177s 04:51:51 INFO - Completed test suite (00:19:37) 04:51:51 INFO - javascript benchmark 04:51:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7145.324219062628, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2766.8339899606026, 3024.425359181755, 3075.876964921403, 3317.2536179359645, 3339.7495187860886, 5582.8325150245455, 5603.775848966042, 5618.719064046792, 5641.153826925964, 5729.512991395736, 5066.645334826554, 5107.616928730351, 5609.439056094388, 5642.181883626871, 5706.229658996034, 5139.460356662553, 5143.4599367066485, 5269.341332333458, 5275.155975043975, 5279.4984476474865, 5197.324347834795, 5283.996040752264, 5288.788448462057, 5290.0477913975465, 5343.706096164709, 5070.543651071384, 5241.42344342122, 5246.895062098775, 5272.446393128724, 5273.103572392683, 5091.134507133796, 5140.3060586820675, 5164.070467315882, 5167.354080739908, 5218.8695282617855, 5047.49525047496, 5114.872128196825, 5128.871778205575, 5144.948550514484, 5159.458256883011, 5482.80810171642, 5536.529395001429, 5555.2500412444615, 5658.632188907749, 5668.461496157858, 6076.908846367308, 6096.859313929741, 6130.601510901822, 6143.078538219258, 6159.969200154015, 6162.815115546541, 6206.534509911775, 6247.843803904893, 6271.9651257542455, 6291.402316779899, 5692.516136128393, 5751.712414379264, 5757.13642953552, 5787.8553036174435, 5824.4466775656665, 6209.5032397408095, 6268.498520118471, 6287.717052732638, 6297.2128483939505, 6297.4962003039645, 6110.236220472441, 6131.570790044743, 6142.201513803177, 6150.354212807681, 6165.075238714228, 6127.295361033538, 6132.50939924805, 6190.81427557174, 6207.131001659771, 6246.500279977591, 5320.414754377015, 6132.50939924805, 6180.289266734293, 6182.319944806068, 6192.845178870564, 6102.938970610237, 6125.234345706786, 6141.986572215624, 6172.722227499773, 6208.658523781235, 4812.422509298906, 4833.468318484964, 4879.097366987119, 4929.7781599828095, 4942.481039490874, 4323.589259020388, 4398.164348773723, 4402.0, 4471.619912307425, 4494.07871386365, 5018.5985121190215, 5803.448672376117, 5825.475707186373, 5826.82519524415, 5841.883162336814, 4883.780229889663, 5614.971925140429, 6214.347493513206, 6226.346233645492, 6250.781222657185, 4052.3111071117983, 4354.368616550582, 4816.277558366273, 5748.597598168171, 5885.499732522783, 4492.640588752892, 5394.136938089965, 5401.513863752281, 5412.161115027177, 5423.511883930465, 6286.748530058747, 6347.777827776005, 6353.174087368612, 6354.3010268870385, 6391.488680905423, 6085.786997455068, 6097.615421629385, 6124.6325220486915, 6128.3503509891225, 6144.600600960924, 5789.305283366023, 5948.672822994604, 6310.558260921692, 6327.715252813727, 6372.171617689671, 5942.376050514634, 5985.371535988658, 6070.180525628984, 6123.112148738497, 6126.081087836682, 4650.069986002745, 6126.571140020156, 6207.099970504342, 6214.4407003369915, 6224.968875155595, 5933.3769954154195, 5978.880422391615, 6023.6385816851125, 6060.636361818305, 6143.754249829957, 6056.45491905713, 6060.181875446759, 6064.120702498201, 6105.236845394325, 6127.84680382994, 5858.677772722541, 5869.501092407022, 5982.970085149546, 5996.4603185713495, 6001.729922153426, 4518.412606361284, 5913.556483263858, 5945.286565612154, 6016.067509536029, 6048.6975651218145, 5886.676232807236, 5886.911696324644, 5892.0, 5898.705064746659, 5917.940820591739], "value": 5584.572059976746, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.24641848113646, 462.6322073951209, 467.9321498382734, 509.98725031874187, 525.5454032262095, 33022.66977330226, 43770.0, 43812.0, 43848.3422748659, 43996.0, 43084.78457607707, 43102.0, 43403.131937361315, 43418.78290608543, 43513.91215219621, 9404.106609872051, 9551.904480955205, 9565.66520171794, 9585.712428627117, 9608.279379046564, 9455.716328510156, 9518.857217141749, 9556.474393908333, 9565.617375305013, 9567.23462123032, 9446.055394460533, 9505.19205867502, 9560.952195239013, 9603.279754018442, 9603.759906002337, 9555.378900371454, 9562.330636855422, 9582.041795820362, 9600.90399096007, 9612.95193524035, 8110.742834860606, 9398.859017114748, 9434.05659434058, 9509.286803489696, 9538.95230523843, 40844.0, 41135.0, 41497.377539336936, 41604.58395416068, 41682.37476437856, 3372.679595438454, 3433.210361616817, 3609.8917032489067, 3613.981930090359, 4104.958950410488, 30938.0, 31479.52780708291, 31725.524117138262, 31776.205594860312, 32205.0, 2703.7972152088673, 3006.8195908245575, 3031.4994077931347, 3299.4555898276753, 3325.334933013407, 405771.59598828095, 495960.0, 501097.0, 501774.0, 502680.0, 56165.87668246694, 56541.151882721795, 56707.43292567104, 56786.86426271452, 56847.863042738914, 63422.04866927, 63950.36049639444, 64385.71228575402, 64435.35564644387, 64503.67748161229, 16082.678346433006, 16382.426615068416, 16462.588435289214, 16674.749878751827, 16689.165541722865, 9815.36200146988, 11098.0, 12482.937585312015, 13001.479940802452, 13026.023048271418, 13024.674383140497, 16386.508404747878, 16397.754033689504, 16619.335226591043, 16634.833651663328, 6376.362363763679, 6822.522423430411, 7187.425005999507, 7263.0, 7297.525660832029, 677.6002158726375, 761.584936209767, 780.1691198873182, 985.3989066669326, 1025.6974192613047, 21167.78832211689, 21539.2461263855, 26968.865155674095, 30520.0, 30586.235344116576, 1026.414943482223, 1072.2494254022215, 1098.555085190499, 1101.124605938281, 1105.1932089574655, 15729.842701572838, 15800.288986995613, 15873.841261587237, 16013.519594412186, 16147.838521614869, 617936.0, 624701.0, 626453.0, 626919.0, 632394.0, 59263.70368148218, 59486.70256648689, 59587.70206148941, 59603.70198149068, 59712.40287597155, 1372.7460419822364, 1421.9075760075561, 1435.4473527691914, 1438.5468577398085, 1439.9136051836713, 28802.0, 29304.853475733336, 29528.0, 30154.84922575373, 30175.245618859703, 50253.246201306276, 50311.49688503068, 50412.73968150679, 50413.23553675604, 50566.74716626394, 463835.0, 468871.0, 471103.0, 471877.6406117947, 471883.6405817949, 25705.614415783402, 25823.870880645478, 25927.740722593288, 26204.475910482066, 26239.868800655873, 46295.76852115853, 46934.530654693015, 47418.09551825052, 49096.50903490919, 49169.75415122901, 34724.47913281252, 39479.407808883465, 40238.39642405425, 40786.0, 41588.16823663449, 39345.40981885331, 39608.801955990035, 39829.60170398259, 40530.0, 40939.7953010233, 33184.668153318155, 33904.49143262902, 37693.62306376901, 38643.80678096592, 38957.22085558216, 34421.483677744356, 35304.0, 35634.64365356313, 36011.63988360083, 36053.0, 22560.887195564574, 25734.485310293316, 26277.0, 26436.20632411471, 26440.73559264383], "value": 19608.051331317416, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.53404014236, 315.1758152431392, 348.33120408815074, 349.4060097833682, 374.28511542952964, 19748.90125549372, 19988.846398980244, 20493.38519844405, 20954.580908381824, 21191.68212476814, 15850.731941444685, 20556.07497662605, 20571.691424628643, 20576.0, 20980.265690700828, 5503.0, 5810.041343178371, 5823.039198532237, 5836.15375770513, 5836.562257830658, 5619.7471113799975, 5670.319561652607, 5759.913601295984, 5803.8258852234285, 5825.592208545403, 4831.202851529493, 5641.435856414367, 5757.280339957505, 5758.107493338539, 5765.538756899459, 5465.617406781527, 5753.884922301551, 5777.0, 5784.768609255646, 5787.623804452697, 4695.929561056587, 4872.0, 4873.634477414208, 4996.825111121093, 5010.574101201402, 20191.0, 22507.77492225073, 24995.750042499705, 25218.621720674204, 25416.491670166677, 2155.504234026183, 2357.2456813819585, 2449.448874003353, 2455.3493324269084, 2749.6150538924567, 10297.845532316946, 15328.15695136767, 15335.693286134216, 15496.535103946899, 16035.599110022225, 1479.9112053276838, 1484.2059498168428, 1489.7169537787895, 1498.6103613060575, 1523.9771403428958, 113506.0, 114876.0, 115694.0, 116072.41963790212, 116279.0, 7801.375889928792, 9671.371360861522, 9993.950030249802, 10449.373037617766, 10653.72155341364, 12540.372981351078, 12578.119531632881, 12588.181768185223, 12589.685257868627, 12593.244405335709, 8020.238077382639, 8313.168683131638, 8381.036180839159, 8453.704120355758, 8472.983242010874, 7489.812754681177, 8738.912610873937, 8743.213110820057, 8749.343799214957, 8898.59956301968, 6251.218597675291, 6397.609267088451, 6404.071409645669, 6420.357964203542, 6497.805065848032, 1443.585286419315, 1468.7870258812413, 1724.6119623084705, 1750.7569625565736, 1767.719641662267, 886.8448021596195, 936.864154697565, 952.5237381309346, 965.5510187762724, 997.5760301871676, 20303.4924126898, 21252.149914003578, 21469.78530214709, 21743.67384489234, 21793.455163620718, 957.4733896356976, 971.5725080964353, 1030.6959446963165, 1057.8095942730229, 1060.2154405739852, 10673.786524269472, 11572.6528204154, 11640.60312762474, 11649.1263155262, 11651.53393864236, 598514.0, 600706.0, 602766.0, 620433.0, 623727.5254494884, 92087.61868571976, 104860.47569762255, 113156.30265546024, 117180.9999999983, 117574.0, 1000.6347683095618, 1004.864343313658, 1005.1456262177237, 1031.7781676939494, 1033.9121174700085, 14498.985071044926, 15022.549323520312, 15954.441594544136, 16258.187090645184, 16871.90332628375, 41680.0, 48647.75676121597, 51716.48283517117, 52831.735841320544, 53075.46924530705, 54955.0, 57190.42809571851, 68115.63768724697, 74233.6288318555, 76856.61571692106, 6275.435210831047, 7182.928170718226, 7227.638618069181, 7403.703851845866, 7743.806404839699, 12358.876411236131, 12635.167900654169, 12829.87170128324, 12946.741065178832, 13253.271070090852, 10906.236563440483, 11045.183478213004, 11072.778544429228, 11212.831807523056, 11306.94346528295, 11060.834087488533, 11210.719732006764, 11361.943190283995, 11603.303801771921, 11738.706532336419, 11058.11535077208, 11186.664400068012, 11519.0, 11595.478203481032, 11602.535898563963, 7484.663190156347, 7618.123915749759, 7751.418643601865, 7863.41024423182, 7863.882041769263, 10861.619843305447, 11278.210525263481, 11381.943090284827, 11486.770264594494, 11493.482793274155], "value": 9338.298925962383, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.5425486716817, 1519.2403798100952, 1700.719381302086, 1712.4348964841604, 1874.3158747057328, 7496.512726672762, 7744.109427415849, 7866.134725180233, 8384.119667434923, 8414.747557573268, 7928.484648497843, 8233.053198882131, 8248.13394593568, 8356.707515236953, 8363.707270245543, 2259.80791632711, 2285.9657105143438, 2313.849599776009, 2321.245595181564, 2326.1858349577683, 2280.8631482111045, 2296.4258935266184, 2302.0446514696464, 2324.3027091872455, 2334.4747431827873, 2268.4669102760836, 2311.3874823171873, 2314.6412306092584, 2316.4787922717423, 2326.4183954011496, 1902.6480101181326, 2279.1225378229337, 2279.430142464384, 2281.543691261746, 2285.440067183534, 2276.1578216060166, 2278.6809846621486, 2279.09975559653, 2282.8173746100274, 2285.417218609244, 8286.171382861665, 8292.83414331716, 8336.958315208445, 8352.997640283143, 8391.202835730595, 4915.311856340115, 5127.564157046656, 5128.461511541272, 5149.356330458692, 5165.560927321182, 3322.3687499375283, 3387.44107222308, 3504.912377190565, 3511.841967111486, 3517.1383011162175, 903.0, 1031.3193292426963, 1039.2465462539674, 1040.0483557544837, 1060.33199084577, 9261.861072083857, 9305.022972587849, 10244.436555989418, 10498.0, 10542.894571054268, 4182.142660754538, 4213.705040647186, 4362.411074504965, 4379.715318504287, 4384.232759267115, 8451.197136272045, 8472.322214222848, 8494.915050849413, 8527.488350698977, 8536.0, 4969.801207951641, 5003.324551185616, 5004.899902001939, 5017.9247311290355, 5022.799088036438, 4199.538050814408, 4358.389825424443, 4546.1135078659345, 4649.604783593365, 4708.976455117702, 3564.928701425957, 3580.3913334733156, 3665.4135338345736, 3684.3184010958057, 3686.4286035664513, 1126.1441304608393, 1131.5813149134872, 1137.0221609415896, 1148.8161894097072, 1149.0692539043403, 851.9616617252238, 863.0, 908.8000639859221, 913.88891660265, 956.9473678947586, 4838.709677419366, 5316.803719163157, 5606.6916319602, 5672.546196304285, 5694.544436445074, 353.2475826489561, 394.28830960116693, 416.4440471969954, 416.50435981182295, 421.8228344095487, 3669.4679271505483, 3735.5143831301757, 3747.681447077029, 3780.716446266541, 3814.8474061037805, 9507.667231646998, 9652.324337296322, 9804.36271642341, 9855.704328870146, 9931.354461960094, 9831.360961537477, 9848.655297064568, 9867.0, 9888.703338899844, 9893.703188904345, 7270.236625154388, 7584.54492730438, 7639.809004774814, 7678.500897441649, 7720.189380115007, 4453.888652783577, 4716.5755082041405, 5685.800996965087, 5798.768049277982, 5941.079132734433, 7850.293473587404, 8910.420822646767, 9209.263258939403, 9241.86137207929, 9402.57688404037, 9194.08059194102, 9299.442033478013, 9380.0, 9387.249020078241, 9448.716538503855, 6886.414654754402, 7018.964905175441, 7083.716651334095, 7169.068021157216, 7271.963640181977, 7905.841883162189, 7912.9208707912185, 7930.682772689028, 7949.523028618301, 7957.8408431832195, 5955.464008239279, 6960.930390696029, 7112.110986126734, 7142.392896603847, 7165.498415110892, 6926.965365173141, 6927.653617319013, 6940.9652951734915, 6970.616616085961, 6972.616506092214, 6547.214334279917, 6554.901676474952, 6579.0, 6646.401823835685, 6670.899936500859, 3936.7834769087976, 4809.807607695793, 4813.253945638432, 4828.975855120702, 4918.0901533216465, 4884.169691152441, 4899.412070551557, 4908.582770464551, 4937.629677774253, 4956.454789973172], "value": 4219.569950959272, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.4489768423574, 280.8469384185619, 287.8342712016334, 293.15278844140437, 293.90448104366084, 4878.658493905428, 5406.621536492447, 5568.721563921803, 5579.665220086793, 5621.7751289948455, 4006.0, 4515.983903621684, 4555.200695725187, 5374.731263436823, 5515.586331025169, 3302.5541551890487, 3950.604939506055, 3971.98014009931, 4125.855595054174, 4192.8197337384545, 3351.966480335202, 3409.8636054557787, 3616.439451884962, 3636.654517820803, 4170.916581668364, 3278.8360581970805, 3928.3910993796007, 4083.7141400101855, 4140.482439695038, 4196.727212731163, 3035.9089227323216, 3402.3535528249554, 3980.7014473914574, 4125.422440858282, 4150.418941348184, 3081.984590077035, 3500.422430298998, 3546.8226588670723, 3556.705924678594, 3556.822158892045, 4869.8782530436665, 5444.346678398578, 5512.724363781795, 5537.224788529609, 5583.274174357308, 4084.0, 4134.00783811886, 4687.601553867926, 4774.546418090275, 4776.331313616097, 5658.7170641467765, 5945.702714864283, 5954.612950158269, 5962.422546472126, 6005.129256257862, 3636.709063274932, 3744.9625503744887, 3848.3073046851555, 3872.535295764498, 3893.415987601854, 5004.649674522785, 5261.289725887033, 6139.631622102644, 6212.65830379329, 6216.44052035319, 3422.9559984205057, 3878.84484620612, 3887.96112038876, 3898.7465814721954, 3927.19492504036, 3599.6580324869096, 3735.0, 3930.7051971102283, 3963.841446342172, 3993.121513267077, 4042.191561687674, 4623.9075218495445, 4635.258358662598, 4639.234526303189, 4651.2557990721325, 5093.414257360451, 5413.944280865194, 5605.598600349913, 5878.853028674317, 5944.494717948936, 1260.9156125731863, 1267.594369801673, 1279.4219647410475, 1303.7262174943369, 1340.6514306280437, 754.909410870699, 880.0055936791513, 883.4169448164181, 897.8249241397865, 899.2760827533898, 4637.559431853969, 5587.3015873015875, 5593.944060559424, 5604.915926261109, 5630.0, 5768.7692492300675, 5891.403166991376, 6095.573309868355, 6098.26820781509, 6134.754609815557, 3960.960390396059, 4525.479569849444, 5046.940142570028, 5537.473939975695, 5564.163825937431, 2260.8530445520987, 2555.083687234574, 2694.6227528146073, 2717.4238941413932, 2727.799768102029, 4851.520286312638, 5246.449122842123, 5273.945210957824, 5291.682499050069, 5295.4439783822145, 4829.082474329866, 4860.736208585793, 5212.582993360522, 5232.790688372498, 5265.736713164327, 4590.42619672541, 4643.003508491382, 5041.0422019816115, 5170.0, 5197.792088316501, 4111.944027986007, 4156.8337266509, 4327.978360108179, 4713.835015774432, 4729.716217026989, 4682.41469816273, 5030.0, 5037.596992240538, 5048.8990220194655, 5082.780132768183, 4480.798364073559, 5129.102232174119, 5191.870203244949, 5253.94921015806, 5292.576593872558, 2959.79281450305, 3387.796732196076, 3450.3271861986673, 4088.8773336799945, 4092.2633925893624, 5070.923936141035, 6044.365341639241, 6120.755169793337, 6190.81427557174, 6192.287886893065, 5754.446299499028, 5793.478586927028, 6027.698615069141, 6028.0, 6044.395560444096, 5065.594752419871, 5308.681479111266, 5865.589408741347, 5929.584929055098, 5937.109433584824, 4680.7893644786745, 5329.148905335627, 5429.375621803385, 5435.646682965595, 5746.425357464387, 6027.29870700854, 6047.609049918456, 6053.334133245251, 6055.788047418319, 6085.1480792689945, 5054.671446355975, 5651.90879374634, 5691.089425691869, 5726.599138060295, 5865.706714664335], "value": 4012.3152657451915, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.4683229999852, 704.5702121705756, 719.4388377065887, 742.9375992330964, 844.8986121665399, 15528.844711552882, 16435.58911027222, 16483.505494835143, 16508.092054936988, 16513.256903439345, 14413.711725765479, 14819.259037048132, 15042.84957150428, 15081.321340539675, 15141.31864066117, 851.1445996773234, 851.2253848997414, 873.0440168016012, 873.4540911930044, 874.0167311774254, 700.2332445971653, 725.2022774947542, 890.3455959869491, 895.6148855991953, 895.6193617712446, 889.8398288308102, 905.3798148268424, 917.0874979395511, 925.6019911438079, 925.9907400926005, 905.0090151284365, 911.3438324406417, 912.7991841794794, 913.8811954445946, 914.0767824497273, 658.8352911772057, 680.6868840333452, 682.3483573187643, 683.5898460923456, 685.1127789512593, 12721.809172862415, 13586.38861251246, 14987.700245995127, 15332.616684582974, 15510.45713400037, 14251.50119745804, 14309.499167529191, 14337.21145337006, 14435.133891966516, 14458.27708614565, 11750.94124529372, 12118.757624847454, 12859.0, 12924.289164095972, 12968.546100886424, 3722.4788529605876, 4456.2647163218035, 4529.456465224194, 4566.977165114186, 4621.098885717286, 101598.9840101597, 102076.0, 102636.48681756618, 103253.0, 106744.4662776689, 9558.474283914313, 11217.775644487065, 11375.715607109889, 11414.315141091562, 11479.514378014597, 22696.65955010676, 22940.426489337566, 22945.54108917812, 22960.425989350068, 23040.193593224158, 7931.524108553505, 8016.879746803803, 8052.186422600981, 8080.4343695940715, 8240.340772738287, 4376.934345984812, 4429.11417716458, 4441.400410944546, 4475.082608065339, 4547.136044151601, 3358.6473420290517, 3429.879954201591, 3447.500112483676, 3452.395830729612, 3452.6202117767025, 602.1750202222983, 613.2351627851657, 627.8524546731514, 637.8437282865727, 638.2628064585411, 526.2579762534884, 582.1733138942711, 605.5821483176594, 619.9429971897903, 621.1924498152474, 9035.322350823715, 9062.773430664287, 9163.954180229057, 9215.216706580015, 9217.354785165107, 588.2294194605081, 616.8797084568466, 620.9099680546295, 632.6678493790797, 635.891898377277, 4271.722338048017, 4297.5702429756775, 4566.97716511422, 4581.289900065495, 4632.351470794091, 105248.42127368096, 151970.24014879856, 155520.55835533357, 158404.53561447177, 164015.3598464024, 118642.35612904454, 145916.0, 147507.0, 148692.0, 152390.47609523984, 102781.0, 108931.0, 120774.0, 123712.0, 125496.0, 8542.40203185771, 8641.783955401166, 8649.52427616462, 8727.607257673293, 8727.65089396417, 67110.0, 67396.32603674097, 67661.66169169123, 68764.0, 69400.0, 87395.56302218448, 102050.0, 102534.0, 104620.0, 105475.0, 11497.82753258685, 11776.11679124052, 11797.587084452003, 11906.642800715992, 12093.637190884288, 12148.27110373345, 12263.38683065861, 12307.753844922872, 12460.563880264148, 12724.300163491096, 11891.28652280866, 11944.880551194725, 11989.520419183484, 12356.949659278702, 12369.072319575887, 11620.593279235525, 12033.0, 12145.635630931087, 12421.006319494238, 12434.191777534432, 10446.112080473245, 10964.616238431616, 11077.501512431798, 11094.445277736242, 11310.264832785842, 9597.808043838944, 9732.70801875945, 9757.0, 10002.749931251778, 10115.645952391631, 8356.791080223044, 8664.870026949475, 9893.65372211969, 9941.961897810786, 9951.203903687832], "value": 7686.469322329297, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 116.0, 115.0, 116.0, 116.0, 115.0, 115.0, 116.0, 114.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 110.0, 199.0, 149.0, 110.0, 151.0, 150.0, 178.0, 125.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 194.0, 204.0, 230.0, 209.0, 197.0, 192.0, 193.0, 208.0, 200.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 60.0, 84.0, 61.0, 62.0, 61.0, 59.0, 80.0, 61.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 75.0, 87.0, 71.0, 69.0, 71.0, 96.0, 87.0, 64.0, 119.0], "value": 82.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 89.0, 99.0, 118.0, 137.0, 79.0, 139.0, 76.0, 128.0, 106.0], "value": 108.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 151.0, 128.0, 97.0, 125.0, 111.0, 151.0, 106.0, 106.0, 150.0], "value": 122.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 121.0, 140.0, 92.0, 88.0, 88.0, 90.0, 91.0, 90.0, 87.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 59.0, 57.0, 59.0, 58.0, 58.0, 58.0, 56.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 80.0, 76.0, 76.0, 69.0, 67.0, 70.0, 71.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 103.0, 107.0, 111.0, 81.0, 116.0, 119.0, 112.0, 97.0, 88.0], "value": 102.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 151.0, 158.0, 162.0, 166.0, 149.0, 153.0, 150.0, 224.0, 154.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 248.0, 181.0, 174.0, 173.0, 172.0, 177.0, 174.0, 207.0, 246.0], "value": 197.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 70.0, 71.0, 73.0, 69.0, 69.0, 70.0, 67.0, 69.0], "value": 70.1, "unit": "score"}]}]} 04:51:51 INFO - Return code: 0 04:51:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:51:51 ERROR - # TBPL SUCCESS # 04:51:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:51:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:51:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:51:51 INFO - 2016-10-17 04:51:51.291 screenresolution[1671:10380] starting screenresolution argv=screenresolution get 04:51:51 INFO - 2016-10-17 04:51:51.294 screenresolution[1671:10380] Display 0: 1600x1200x32@60 04:51:51 INFO - 2016-10-17 04:51:51.300 screenresolution[1672:10383] starting screenresolution argv=screenresolution list 04:51:51 INFO - Available Modes on Display 0 04:51:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:51:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:51:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:51:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:51:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:51:51 INFO - Intel Iris: 04:51:51 INFO - Chipset Model: Intel Iris 04:51:51 INFO - Type: GPU 04:51:51 INFO - Bus: Built-In 04:51:51 INFO - VRAM (Dynamic, Max): 1536 MB 04:51:51 INFO - Vendor: Intel (0x8086) 04:51:51 INFO - Device ID: 0x0a2e 04:51:51 INFO - Revision ID: 0x0009 04:51:51 INFO - Displays: 04:51:51 INFO - Display: 04:51:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:51:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:51:51 INFO - Display Serial Number: 1600x1200 60 04:51:51 INFO - Main Display: Yes 04:51:51 INFO - Mirror: Off 04:51:51 INFO - Online: Yes 04:51:51 INFO - Rotation: Supported 04:51:51 INFO - Return code: 0 04:51:51 INFO - Running post-action listener: _resource_record_post_action 04:51:51 INFO - [mozharness: 2016-10-17 11:51:51.535490Z] Finished run-tests step (success) 04:51:51 INFO - Running post-run listener: _resource_record_post_run 04:51:51 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 39397888; Write bytes: 710713344; Read time: 384; Write time: 2000 04:51:51 INFO - TinderboxPrint: CPU usage
54.3% 04:51:51 INFO - TinderboxPrint: I/O read bytes / time
39,397,888 / 384 04:51:51 INFO - TinderboxPrint: I/O write bytes / time
710,713,344 / 2,000 04:51:51 INFO - TinderboxPrint: CPU idle
2,216.7 (45.7%) 04:51:51 INFO - TinderboxPrint: CPU system
982.8 (20.2%) 04:51:51 INFO - TinderboxPrint: CPU user
1,654.1 (34.1%) 04:51:51 INFO - TinderboxPrint: Swap in / out
446,337,024 / 0 04:51:51 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393546240; Write bytes: 388474880; Read time: 29120; Write time: 726 04:51:51 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 38418944; Write bytes: 300587008; Read time: 369; Write time: 1213 04:51:52 INFO - Running post-run listener: _upload_blobber_files 04:51:52 INFO - Blob upload gear active. 04:51:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:51:52 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:51:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:51:52 INFO - (blobuploader) - INFO - Open directory for files ... 04:51:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:51:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:51:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:53 INFO - (blobuploader) - INFO - Done attempting. 04:51:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:51:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:51:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:54 INFO - (blobuploader) - INFO - Done attempting. 04:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:51:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:56 INFO - (blobuploader) - INFO - Done attempting. 04:51:56 INFO - (blobuploader) - INFO - Iteration through files over. 04:51:56 INFO - Return code: 0 04:51:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:51:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:51:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29ae60a619f5a8c40a9f63e21eb06c4db564e95adb38da455a7ffcdba884c32ceb10d5b8c4311ae2f6c9a54366d79c2ca3841f7daf29295fe1d872823867117a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c065862ec1c43a78590ef3dcded211d13e56949be8330b1f0db20378aaa2f355088b41479152d0afa1843024eef8200e6e55d2607de3e37d42a715706216e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0c8bfa30510b7fe394d98eb5beeb0d4d3faa53d2f98ae37ebb58c7c35197756a45c2d4897c0942c4b7b32823fa54f1ea8c0e57904410f0fae31d6159e3c0b45"} 04:51:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:51:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:51:56 INFO - Contents: 04:51:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29ae60a619f5a8c40a9f63e21eb06c4db564e95adb38da455a7ffcdba884c32ceb10d5b8c4311ae2f6c9a54366d79c2ca3841f7daf29295fe1d872823867117a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c065862ec1c43a78590ef3dcded211d13e56949be8330b1f0db20378aaa2f355088b41479152d0afa1843024eef8200e6e55d2607de3e37d42a715706216e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0c8bfa30510b7fe394d98eb5beeb0d4d3faa53d2f98ae37ebb58c7c35197756a45c2d4897c0942c4b7b32823fa54f1ea8c0e57904410f0fae31d6159e3c0b45"} 04:51:56 INFO - Running post-run listener: copy_logs_to_upload_dir 04:51:56 INFO - Copying logs to upload dir... 04:51:56 INFO - mkdir: /builds/slave/test/build/upload/logs 04:51:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.230572 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-17 04:51:56.322070) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 04:51:56.326011) ========= 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.W49x4S6E1r/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.S1xKbAYRLf/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29ae60a619f5a8c40a9f63e21eb06c4db564e95adb38da455a7ffcdba884c32ceb10d5b8c4311ae2f6c9a54366d79c2ca3841f7daf29295fe1d872823867117a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c065862ec1c43a78590ef3dcded211d13e56949be8330b1f0db20378aaa2f355088b41479152d0afa1843024eef8200e6e55d2607de3e37d42a715706216e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0c8bfa30510b7fe394d98eb5beeb0d4d3faa53d2f98ae37ebb58c7c35197756a45c2d4897c0942c4b7b32823fa54f1ea8c0e57904410f0fae31d6159e3c0b45"} build_url:https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010831 build_url: 'https://queue.taskcluster.net/v1/task/aYSY7WilQtKLBXWnR-s2Cw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29ae60a619f5a8c40a9f63e21eb06c4db564e95adb38da455a7ffcdba884c32ceb10d5b8c4311ae2f6c9a54366d79c2ca3841f7daf29295fe1d872823867117a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c065862ec1c43a78590ef3dcded211d13e56949be8330b1f0db20378aaa2f355088b41479152d0afa1843024eef8200e6e55d2607de3e37d42a715706216e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0c8bfa30510b7fe394d98eb5beeb0d4d3faa53d2f98ae37ebb58c7c35197756a45c2d4897c0942c4b7b32823fa54f1ea8c0e57904410f0fae31d6159e3c0b45"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 04:51:56.358616) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 04:51:56.358975) ========= 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.W49x4S6E1r/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.S1xKbAYRLf/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.005036 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 04:51:56.407506) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 04:51:56.407859) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 04:51:56.408197) ========= ========= Total master_lag: 0.18 =========