builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0350 starttime: 1475333206.26 results: success (0) buildid: 20161001055158 builduid: 7d5e9f4357884735804ad4fee81e7e24 revision: 344920af45b92da6d4f5b84738e1c7a3fb582461 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:46.267009) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:46.267461) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:46.267742) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:46.294677) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:46.295011) ========= 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.uSx9Lfosxd/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.rbUESadM1e/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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-01 07:46:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-10-01 07:46:46 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.631856 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:46.968207) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:46.968524) ========= 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.uSx9Lfosxd/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.rbUESadM1e/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.031114 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:47.023727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-01 07:46:47.024073) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 344920af45b92da6d4f5b84738e1c7a3fb582461 --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 344920af45b92da6d4f5b84738e1c7a3fb582461 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uSx9Lfosxd/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.rbUESadM1e/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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-01 07:46:47,104 truncating revision to first 12 chars 2016-10-01 07:46:47,104 Setting DEBUG logging. 2016-10-01 07:46:47,104 attempt 1/10 2016-10-01 07:46:47,104 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/344920af45b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-01 07:46:48,217 unpacking tar archive at: mozilla-central-344920af45b9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.378571 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-01 07:46:48.424195) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:48.424474) ========= script_repo_revision: 344920af45b92da6d4f5b84738e1c7a3fb582461 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:48.424878) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:48.425157) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 07:46:48.443128) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-01 07:46:48.443399) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--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.uSx9Lfosxd/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.rbUESadM1e/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:46:48 INFO - MultiFileLogger online at 20161001 07:46:48 in /builds/slave/test 07:46:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:46:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:46:48 INFO - {'append_to_log': False, 07:46:48 INFO - 'base_work_dir': '/builds/slave/test', 07:46:48 INFO - 'blob_upload_branch': 'Firefox', 07:46:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:46:48 INFO - 'branch': 'Firefox', 07:46:48 INFO - 'buildbot_json_path': 'buildprops.json', 07:46:48 INFO - 'config_files': ('talos/mac_config.py',), 07:46:48 INFO - 'default_actions': ('clobber', 07:46:48 INFO - 'read-buildbot-config', 07:46:48 INFO - 'download-and-extract', 07:46:48 INFO - 'populate-webroot', 07:46:48 INFO - 'create-virtualenv', 07:46:48 INFO - 'install', 07:46:48 INFO - 'run-tests'), 07:46:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:46:48 INFO - 'download_minidump_stackwalk': True, 07:46:48 INFO - 'download_symbols': 'ondemand', 07:46:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:46:48 INFO - 'tooltool.py': '/tools/tooltool.py', 07:46:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:46:48 INFO - '/tools/misc-python/virtualenv.py')}, 07:46:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:46:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:46:48 INFO - 'installer_path': 'installer.exe', 07:46:48 INFO - 'log_level': 'info', 07:46:48 INFO - 'log_name': 'talos', 07:46:48 INFO - 'log_to_console': True, 07:46:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:46:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:46:48 INFO - 'opt_config_files': (), 07:46:48 INFO - 'pip_index': False, 07:46:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:46:48 INFO - 'cmd': ('bash', 07:46:48 INFO - '-c', 07:46:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:46:48 INFO - 'enabled': True, 07:46:48 INFO - 'halt_on_failure': False, 07:46:48 INFO - 'name': 'check_screen_resolution'},), 07:46:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:46:48 INFO - 'cmd': ('bash', 07:46:48 INFO - '-c', 07:46:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:46:48 INFO - 'enabled': True, 07:46:48 INFO - 'halt_on_failure': False, 07:46:48 INFO - 'name': 'check_screen_resolution'},), 07:46:48 INFO - 'run_cmd_checks_enabled': True, 07:46:48 INFO - 'sps_profile': False, 07:46:48 INFO - 'sps_profile_interval': 0, 07:46:48 INFO - 'suite': 'dromaeojs', 07:46:48 INFO - 'system_bits': '32', 07:46:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:46:48 INFO - 'title': 't-yosemite-r7-0350', 07:46:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:46:48 INFO - 'use_talos_json': True, 07:46:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:46:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:46:48 INFO - 'work_dir': 'build'} 07:46:48 INFO - [mozharness: 2016-10-01 14:46:48.643664Z] Running clobber step. 07:46:48 INFO - Running pre-action listener: _resource_record_pre_action 07:46:48 INFO - Running main action method: clobber 07:46:48 INFO - rmtree: /builds/slave/test/build 07:46:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:46:50 INFO - Running post-action listener: _resource_record_post_action 07:46:50 INFO - [mozharness: 2016-10-01 14:46:50.969045Z] Finished clobber step (success) 07:46:50 INFO - [mozharness: 2016-10-01 14:46:50.969231Z] Running read-buildbot-config step. 07:46:50 INFO - Running pre-action listener: _resource_record_pre_action 07:46:50 INFO - Running main action method: read_buildbot_config 07:46:50 INFO - Using buildbot properties: 07:46:50 INFO - { 07:46:50 INFO - "project": "", 07:46:50 INFO - "product": "firefox", 07:46:50 INFO - "script_repo_revision": "production", 07:46:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:46:50 INFO - "repository": "", 07:46:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:46:50 INFO - "buildid": "20161001055158", 07:46:50 INFO - "pgo_build": "False", 07:46:50 INFO - "basedir": "/builds/slave/test", 07:46:50 INFO - "buildnumber": 46, 07:46:50 INFO - "slavename": "t-yosemite-r7-0350", 07:46:50 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:46:50 INFO - "platform": "yosemite_r7", 07:46:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:46:50 INFO - "branch": "mozilla-central", 07:46:50 INFO - "repo_path": "mozilla-central", 07:46:50 INFO - "revision": "344920af45b92da6d4f5b84738e1c7a3fb582461", 07:46:50 INFO - "stage_platform": "macosx64", 07:46:50 INFO - "builduid": "7d5e9f4357884735804ad4fee81e7e24", 07:46:50 INFO - "slavebuilddir": "test" 07:46:50 INFO - } 07:46:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:46:50 INFO - Running post-action listener: _resource_record_post_action 07:46:50 INFO - [mozharness: 2016-10-01 14:46:50.974674Z] Finished read-buildbot-config step (success) 07:46:50 INFO - [mozharness: 2016-10-01 14:46:50.974821Z] Running download-and-extract step. 07:46:50 INFO - Running pre-action listener: _resource_record_pre_action 07:46:50 INFO - Running main action method: download_and_extract 07:46:50 INFO - mkdir: /builds/slave/test/build/tests 07:46:50 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')]} 07:46:50 INFO - trying https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:46:50 INFO - Downloading https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/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 07:46:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/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 07:46:51 INFO - Downloaded 1199 bytes. 07:46:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:46:51 INFO - Using the following test package requirements: 07:46:51 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:46:51 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:46:51 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:46:51 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:46:51 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:46:51 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:46:51 INFO - u'jsshell-mac.zip'], 07:46:51 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:46:51 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:46:51 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:46:51 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:46:51 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:46:51 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:46:51 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:46:51 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:46:51 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:46:51 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:46:51 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:46:51 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:46:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:46:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:46:51 INFO - Fetch https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:46:52 INFO - Http code: 200 07:46:52 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 07:46:52 INFO - x-amz-cf-id: lh07Ci9wJVC3akAf8HO-sI2XmY1bTX-J99kDqGhPk9FJY2b99Aq68A== 07:46:52 INFO - x-amz-version-id: OeObvMcYREXsQQ.8x8J16wdc1os4rTcY 07:46:52 INFO - Expected file size: 16475655 07:46:52 INFO - Obtained file size: 16475655 07:46:59 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 07:46:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:46:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:46:59 INFO - Fetch https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:46:59 INFO - Http code: 200 07:46:59 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 07:46:59 INFO - x-amz-cf-id: c1q5gqNiV7kbT5qzrOa625gbVmayE2xpJqm06hTcE47MSTaUMJ8eAQ== 07:46:59 INFO - x-amz-version-id: OeObvMcYREXsQQ.8x8J16wdc1os4rTcY 07:47:00 INFO - Expected file size: 16475655 07:47:00 INFO - Obtained file size: 16475655 07:47:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:47:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:47:06 INFO - Fetch https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:47:07 INFO - Http code: 200 07:47:07 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 07:47:07 INFO - x-amz-cf-id: 5ZpJTlPBXydMxE9DAc511F9Ee50ugE4C1dOoSEJM3ewLN19Ooig2iA== 07:47:07 INFO - x-amz-version-id: iMmWgeyktfXjV5gfg3OKejs4QsG6JPH8 07:47:07 INFO - Expected file size: 14408522 07:47:07 INFO - Obtained file size: 14408522 07:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:08 INFO - trying https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:47:08 INFO - Downloading https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/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 07:47:11 INFO - Downloaded 111827062 bytes. 07:47:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:47:11 INFO - mkdir: /builds/slave/test/properties 07:47:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:47:11 INFO - Writing to file /builds/slave/test/properties/build_url 07:47:11 INFO - Contents: 07:47:11 INFO - build_url:https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:47:12 INFO - Running post-action listener: _resource_record_post_action 07:47:12 INFO - Running post-action listener: set_extra_try_arguments 07:47:12 INFO - [mozharness: 2016-10-01 14:47:12.477354Z] Finished download-and-extract step (success) 07:47:12 INFO - [mozharness: 2016-10-01 14:47:12.477473Z] Running populate-webroot step. 07:47:12 INFO - Running pre-action listener: _resource_record_pre_action 07:47:12 INFO - Running main action method: populate_webroot 07:47:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:47:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:47:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:47:12 INFO - '--tppagecycles', 07:47:12 INFO - '7'], 07:47:12 INFO - 'tests': ['tsvgm']}}, 07:47:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:47:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:47:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:47:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:47:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:47:12 INFO - 'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:47:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:47:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:47:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:47:12 INFO - 'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['damp', 'tps']}, 07:47:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:47:12 INFO - 'tests': ['damp', 'tps']}, 07:47:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['dromaeo_dom']}, 07:47:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:47:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['basic_compositor_video']}, 07:47:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:47:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['a11yr', 07:47:12 INFO - 'ts_paint', 07:47:12 INFO - 'tpaint', 07:47:12 INFO - 'sessionrestore', 07:47:12 INFO - 'sessionrestore_no_auto_restore', 07:47:12 INFO - 'tabpaint']}, 07:47:12 INFO - 'other-e10s': {'tests': ['a11yr', 07:47:12 INFO - 'ts_paint', 07:47:12 INFO - 'tpaint', 07:47:12 INFO - 'sessionrestore', 07:47:12 INFO - 'sessionrestore_no_auto_restore', 07:47:12 INFO - 'tabpaint']}, 07:47:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:47:12 INFO - 'ts_paint', 07:47:12 INFO - 'tpaint', 07:47:12 INFO - 'sessionrestore', 07:47:12 INFO - 'sessionrestore_no_auto_restore', 07:47:12 INFO - 'tabpaint']}, 07:47:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:47:12 INFO - 'ts_paint', 07:47:12 INFO - 'tpaint', 07:47:12 INFO - 'sessionrestore', 07:47:12 INFO - 'sessionrestore_no_auto_restore', 07:47:12 INFO - 'tabpaint']}, 07:47:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['a11yr', 07:47:12 INFO - 'ts_paint', 07:47:12 INFO - 'tpaint', 07:47:12 INFO - 'sessionrestore', 07:47:12 INFO - 'sessionrestore_no_auto_restore', 07:47:12 INFO - 'tabpaint']}, 07:47:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['a11yr', 07:47:12 INFO - 'ts_paint', 07:47:12 INFO - 'tpaint', 07:47:12 INFO - 'sessionrestore', 07:47:12 INFO - 'sessionrestore_no_auto_restore', 07:47:12 INFO - 'tabpaint']}, 07:47:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['tsvgx', 07:47:12 INFO - 'tsvgr_opacity', 07:47:12 INFO - 'tart', 07:47:12 INFO - 'tscrollx', 07:47:12 INFO - 'cart']}, 07:47:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:47:12 INFO - 'tsvgr_opacity', 07:47:12 INFO - 'tart', 07:47:12 INFO - 'tscrollx', 07:47:12 INFO - 'cart']}, 07:47:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:47:12 INFO - 'talos_options': ['--disable-e10s'], 07:47:12 INFO - 'tests': ['tp5o']}, 07:47:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:47:12 INFO - 'tests': ['tp5o']}, 07:47:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:47:12 INFO - 'talos_options': ['--disable-e10s', 07:47:12 INFO - '--xperf_path', 07:47:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:47:12 INFO - 'tests': ['tp5n']}, 07:47:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:47:12 INFO - 'talos_options': ['--xperf_path', 07:47:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:47:12 INFO - 'tests': ['tp5n']}}, 07:47:12 INFO - 'talos.zip': {'path': '', 07:47:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:47:12 INFO - Running post-action listener: _resource_record_post_action 07:47:12 INFO - [mozharness: 2016-10-01 14:47:12.496033Z] Finished populate-webroot step (success) 07:47:12 INFO - [mozharness: 2016-10-01 14:47:12.496139Z] Running create-virtualenv step. 07:47:12 INFO - Running pre-action listener: _resource_record_pre_action 07:47:12 INFO - Running main action method: create_virtualenv 07:47:12 INFO - Creating virtualenv /builds/slave/test/build/venv 07:47:12 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 07:47:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:47:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:47:12 INFO - Using real prefix '/tools/python27' 07:47:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:47:13 INFO - Installing distribute.............................................................................................................................................................................................done. 07:47:16 INFO - Installing pip.................done. 07:47:16 INFO - Return code: 0 07:47:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:47:16 INFO - Reading from file tmpfile_stdout 07:47:16 INFO - Output received: 07:47:16 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) 07:47:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:16 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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:16 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 07:47:16 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 07:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:16 INFO - 'HOME': '/Users/cltbld', 07:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:16 INFO - 'LOGNAME': 'cltbld', 07:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:16 INFO - 'MOZ_NO_REMOTE': '1', 07:47:16 INFO - 'NO_EM_RESTART': '1', 07:47:16 INFO - 'PAGER': '/bin/cat', 07:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:16 INFO - 'PWD': '/builds/slave/test', 07:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:16 INFO - 'SHELL': '/bin/bash', 07:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:16 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:16 INFO - 'USER': 'cltbld', 07:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:16 INFO - 'XPC_FLAGS': '0x0', 07:47:16 INFO - 'XPC_SERVICE_NAME': '0', 07:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:16 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 07:47:16 INFO - Cleaning up... 07:47:16 INFO - Return code: 0 07:47:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:16 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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:16 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 07:47:16 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 07:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:16 INFO - 'HOME': '/Users/cltbld', 07:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:16 INFO - 'LOGNAME': 'cltbld', 07:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:16 INFO - 'MOZ_NO_REMOTE': '1', 07:47:16 INFO - 'NO_EM_RESTART': '1', 07:47:16 INFO - 'PAGER': '/bin/cat', 07:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:16 INFO - 'PWD': '/builds/slave/test', 07:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:16 INFO - 'SHELL': '/bin/bash', 07:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:16 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:16 INFO - 'USER': 'cltbld', 07:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:16 INFO - 'XPC_FLAGS': '0x0', 07:47:16 INFO - 'XPC_SERVICE_NAME': '0', 07:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:16 INFO - Downloading/unpacking psutil>=3.1.1 07:47:16 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 07:47:16 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 07:47:16 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 07:47:16 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 07:47:16 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 07:47:16 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 07:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:47:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:47:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:47:20 INFO - Installing collected packages: psutil 07:47:20 INFO - Running setup.py install for psutil 07:47:21 INFO - building 'psutil._psutil_osx' extension 07:47:21 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 07:47:21 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 07:47:21 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 07:47:21 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 07:47:21 INFO - building 'psutil._psutil_posix' extension 07:47:21 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 07:47:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:47:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:47:21 INFO - ^ 07:47:21 INFO - 1 warning generated. 07:47:21 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 07:47:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:47:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:47:22 INFO - Successfully installed psutil 07:47:22 INFO - Cleaning up... 07:47:22 INFO - Return code: 0 07:47:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:22 INFO - 'HOME': '/Users/cltbld', 07:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:22 INFO - 'LOGNAME': 'cltbld', 07:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:22 INFO - 'MOZ_NO_REMOTE': '1', 07:47:22 INFO - 'NO_EM_RESTART': '1', 07:47:22 INFO - 'PAGER': '/bin/cat', 07:47:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:22 INFO - 'PWD': '/builds/slave/test', 07:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:22 INFO - 'SHELL': '/bin/bash', 07:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:22 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:22 INFO - 'USER': 'cltbld', 07:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:22 INFO - 'XPC_FLAGS': '0x0', 07:47:22 INFO - 'XPC_SERVICE_NAME': '0', 07:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:47:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:47:24 INFO - Installing collected packages: mozsystemmonitor 07:47:24 INFO - Running setup.py install for mozsystemmonitor 07:47:25 INFO - Successfully installed mozsystemmonitor 07:47:25 INFO - Cleaning up... 07:47:25 INFO - Return code: 0 07:47:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:25 INFO - 'HOME': '/Users/cltbld', 07:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:25 INFO - 'LOGNAME': 'cltbld', 07:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:25 INFO - 'MOZ_NO_REMOTE': '1', 07:47:25 INFO - 'NO_EM_RESTART': '1', 07:47:25 INFO - 'PAGER': '/bin/cat', 07:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:25 INFO - 'PWD': '/builds/slave/test', 07:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:25 INFO - 'SHELL': '/bin/bash', 07:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:25 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:25 INFO - 'USER': 'cltbld', 07:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:25 INFO - 'XPC_FLAGS': '0x0', 07:47:25 INFO - 'XPC_SERVICE_NAME': '0', 07:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:25 INFO - Downloading/unpacking jsonschema==2.5.1 07:47: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 07:47: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 07:47: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 07:47: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 07:47: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 07:47: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 07:47:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:47:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:28 INFO - Downloading functools32-3.2.3-2.tar.gz 07:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:47:28 INFO - warning: no files found matching '*.txt' 07:47:28 INFO - no previously-included directories found matching 'build' 07:47:28 INFO - no previously-included directories found matching 'dist' 07:47:28 INFO - no previously-included directories found matching '.git*' 07:47:28 INFO - Installing collected packages: jsonschema, functools32 07:47:28 INFO - Running setup.py install for functools32 07:47:28 INFO - warning: no files found matching '*.txt' 07:47:28 INFO - no previously-included directories found matching 'build' 07:47:28 INFO - no previously-included directories found matching 'dist' 07:47:28 INFO - no previously-included directories found matching '.git*' 07:47:28 INFO - Successfully installed jsonschema functools32 07:47:28 INFO - Cleaning up... 07:47:28 INFO - Return code: 0 07:47:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:28 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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:28 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 07:47:28 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 07:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:28 INFO - 'HOME': '/Users/cltbld', 07:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:28 INFO - 'LOGNAME': 'cltbld', 07:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:28 INFO - 'MOZ_NO_REMOTE': '1', 07:47:28 INFO - 'NO_EM_RESTART': '1', 07:47:28 INFO - 'PAGER': '/bin/cat', 07:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:28 INFO - 'PWD': '/builds/slave/test', 07:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:28 INFO - 'SHELL': '/bin/bash', 07:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:28 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:28 INFO - 'USER': 'cltbld', 07:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:28 INFO - 'XPC_FLAGS': '0x0', 07:47:28 INFO - 'XPC_SERVICE_NAME': '0', 07:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:47:28 INFO - Cleaning up... 07:47:28 INFO - Return code: 0 07:47:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:28 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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:28 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 07:47:28 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 07:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:28 INFO - 'HOME': '/Users/cltbld', 07:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:28 INFO - 'LOGNAME': 'cltbld', 07:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:28 INFO - 'MOZ_NO_REMOTE': '1', 07:47:28 INFO - 'NO_EM_RESTART': '1', 07:47:28 INFO - 'PAGER': '/bin/cat', 07:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:28 INFO - 'PWD': '/builds/slave/test', 07:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:28 INFO - 'SHELL': '/bin/bash', 07:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:28 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:28 INFO - 'USER': 'cltbld', 07:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:28 INFO - 'XPC_FLAGS': '0x0', 07:47:28 INFO - 'XPC_SERVICE_NAME': '0', 07:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:29 INFO - Downloading/unpacking blobuploader==1.2.4 07:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:31 INFO - Downloading blobuploader-1.2.4.tar.gz 07:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:47:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:47:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:47:32 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 07:47:32 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 07:47:32 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 07:47:32 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 07:47:32 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 07:47:32 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 07:47:32 INFO - Downloading docopt-0.6.1.tar.gz 07:47:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:47:32 INFO - Installing collected packages: blobuploader, requests, docopt 07:47:32 INFO - Running setup.py install for blobuploader 07:47:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:47:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:47:33 INFO - Running setup.py install for requests 07:47:33 INFO - Running setup.py install for docopt 07:47:33 INFO - Successfully installed blobuploader requests docopt 07:47:33 INFO - Cleaning up... 07:47:33 INFO - Return code: 0 07:47:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:47:33 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')]} 07:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:33 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')]} 07:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:33 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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:33 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 07:47:33 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 07:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:33 INFO - 'HOME': '/Users/cltbld', 07:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:33 INFO - 'LOGNAME': 'cltbld', 07:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:33 INFO - 'MOZ_NO_REMOTE': '1', 07:47:33 INFO - 'NO_EM_RESTART': '1', 07:47:33 INFO - 'PAGER': '/bin/cat', 07:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:33 INFO - 'PWD': '/builds/slave/test', 07:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:33 INFO - 'SHELL': '/bin/bash', 07:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:33 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:33 INFO - 'USER': 'cltbld', 07:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:33 INFO - 'XPC_FLAGS': '0x0', 07:47:33 INFO - 'XPC_SERVICE_NAME': '0', 07:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-doSavY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-OUYcFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-r_EcQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-nv6BJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-4FEHOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-QR6UeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-R7RUmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-t0k1FJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-B_XByz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-pTGFI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-VbldqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-s3miuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-4D8dIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-mTO4rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-WOsodj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-JOycQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-CNtlF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:47:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:47:35 INFO - Running setup.py install for manifestparser 07:47:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:47:36 INFO - Running setup.py install for mozcrash 07:47:36 INFO - Running setup.py install for mozdebug 07:47:36 INFO - Running setup.py install for mozdevice 07:47:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:47:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:47:36 INFO - Running setup.py install for mozfile 07:47:36 INFO - Running setup.py install for mozhttpd 07:47:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:47:36 INFO - Running setup.py install for mozinfo 07:47:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:47:36 INFO - Running setup.py install for mozInstall 07:47:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Running setup.py install for mozleak 07:47:37 INFO - Running setup.py install for mozlog 07:47:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Running setup.py install for moznetwork 07:47:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Running setup.py install for mozprocess 07:47:37 INFO - Running setup.py install for mozprofile 07:47:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:47:37 INFO - Running setup.py install for mozrunner 07:47:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:47:38 INFO - Running setup.py install for mozscreenshot 07:47:38 INFO - Running setup.py install for moztest 07:47:38 INFO - Running setup.py install for mozversion 07:47:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:47:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:47:38 INFO - Cleaning up... 07:47:38 INFO - Return code: 0 07:47:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:47:38 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')]} 07:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:38 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')]} 07:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:38 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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:38 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 07:47:38 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 07:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:38 INFO - 'HOME': '/Users/cltbld', 07:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:38 INFO - 'LOGNAME': 'cltbld', 07:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:38 INFO - 'MOZ_NO_REMOTE': '1', 07:47:38 INFO - 'NO_EM_RESTART': '1', 07:47:38 INFO - 'PAGER': '/bin/cat', 07:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:38 INFO - 'PWD': '/builds/slave/test', 07:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:38 INFO - 'SHELL': '/bin/bash', 07:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:38 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:38 INFO - 'USER': 'cltbld', 07:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:38 INFO - 'XPC_FLAGS': '0x0', 07:47:38 INFO - 'XPC_SERVICE_NAME': '0', 07:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:47:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-jzugsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:47:38 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)) 07:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:47:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-BDFPQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:47:38 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)) 07:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:47:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-8l4Hku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-mxhz_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-GO73Mi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-S0LB2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-XSjNs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-UtLGO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-XlHLlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-bVy7Vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-l9uC5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-7kM39B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:47:39 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)) 07:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:47:39 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-mXodYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:47:40 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)) 07:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:47:40 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ygYjWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:47:40 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)) 07:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:47:40 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-_kZqnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:47:40 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)) 07:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:47:40 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-_LBlWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:47:40 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)) 07:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:47:40 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-VNdjuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:47:40 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)) 07:47:40 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)) 07:47:40 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)) 07:47:40 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)) 07:47:40 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)) 07:47:40 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)) 07:47:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:47:42 INFO - Downloading blessings-1.6.tar.gz 07:47:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:47:43 INFO - Installing collected packages: blessings 07:47:43 INFO - Running setup.py install for blessings 07:47:43 INFO - Successfully installed blessings 07:47:43 INFO - Cleaning up... 07:47:43 INFO - Return code: 0 07:47:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:47:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:47:43 INFO - Reading from file tmpfile_stdout 07:47:43 INFO - Current package versions: 07:47:43 INFO - blessings == 1.6 07:47:43 INFO - blobuploader == 1.2.4 07:47:43 INFO - docopt == 0.6.1 07:47:43 INFO - functools32 == 3.2.3-2 07:47:43 INFO - jsonschema == 2.5.1 07:47:43 INFO - manifestparser == 1.1 07:47:43 INFO - mozInstall == 1.12 07:47:43 INFO - mozcrash == 0.17 07:47:43 INFO - mozdebug == 0.1 07:47:43 INFO - mozdevice == 0.48 07:47:43 INFO - mozfile == 1.2 07:47:43 INFO - mozhttpd == 0.7 07:47:43 INFO - mozinfo == 0.9 07:47:43 INFO - mozleak == 0.1 07:47:43 INFO - mozlog == 3.4 07:47:43 INFO - moznetwork == 0.27 07:47:43 INFO - mozprocess == 0.23 07:47:43 INFO - mozprofile == 0.28 07:47:43 INFO - mozrunner == 6.12 07:47:43 INFO - mozscreenshot == 0.1 07:47:43 INFO - mozsystemmonitor == 0.3 07:47:43 INFO - moztest == 0.7 07:47:43 INFO - mozversion == 1.4 07:47:43 INFO - psutil == 3.1.1 07:47:43 INFO - requests == 1.2.3 07:47:43 INFO - wsgiref == 0.1.2 07:47:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:47: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')]} 07:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47: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')]} 07:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:43 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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:43 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 07:47:43 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 07:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:43 INFO - 'HOME': '/Users/cltbld', 07:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:43 INFO - 'LOGNAME': 'cltbld', 07:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:43 INFO - 'MOZ_NO_REMOTE': '1', 07:47:43 INFO - 'NO_EM_RESTART': '1', 07:47:43 INFO - 'PAGER': '/bin/cat', 07:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:43 INFO - 'PWD': '/builds/slave/test', 07:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:43 INFO - 'SHELL': '/bin/bash', 07:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:43 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:43 INFO - 'USER': 'cltbld', 07:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:43 INFO - 'XPC_FLAGS': '0x0', 07:47:43 INFO - 'XPC_SERVICE_NAME': '0', 07:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 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)) 07:47:43 INFO - Cleaning up... 07:47:43 INFO - Return code: 0 07:47:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:47: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')]} 07:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47: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')]} 07:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:47:43 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 0x10b6c0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b636570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a0ac75ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, '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.uSx9Lfosxd/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.rbUESadM1e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 07:47:43 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 07:47:43 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 07:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:43 INFO - 'HOME': '/Users/cltbld', 07:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:43 INFO - 'LOGNAME': 'cltbld', 07:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:43 INFO - 'MOZ_NO_REMOTE': '1', 07:47:43 INFO - 'NO_EM_RESTART': '1', 07:47:43 INFO - 'PAGER': '/bin/cat', 07:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:43 INFO - 'PWD': '/builds/slave/test', 07:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:43 INFO - 'SHELL': '/bin/bash', 07:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:47:43 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:43 INFO - 'USER': 'cltbld', 07:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:43 INFO - 'XPC_FLAGS': '0x0', 07:47:43 INFO - 'XPC_SERVICE_NAME': '0', 07:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:47:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:47:44 INFO - Cleaning up... 07:47:44 INFO - Return code: 0 07:47:44 INFO - Running post-action listener: _resource_record_post_action 07:47:44 INFO - Running post-action listener: _start_resource_monitoring 07:47:44 INFO - Starting resource monitoring. 07:47:44 INFO - [mozharness: 2016-10-01 14:47:44.222562Z] Finished create-virtualenv step (success) 07:47:44 INFO - [mozharness: 2016-10-01 14:47:44.223371Z] Running install step. 07:47:44 INFO - Running pre-action listener: _resource_record_pre_action 07:47:44 INFO - Running main action method: install 07:47:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:47:44 INFO - Reading from file tmpfile_stdout 07:47:44 INFO - Detecting whether we're running mozinstall >=1.0... 07:47:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:47:44 INFO - Reading from file tmpfile_stdout 07:47:44 INFO - Output received: 07:47:44 INFO - Usage: mozinstall [options] installer 07:47:44 INFO - Options: 07:47:44 INFO - -h, --help show this help message and exit 07:47:44 INFO - -d DEST, --destination=DEST 07:47:44 INFO - Directory to install application into. [default: 07:47:44 INFO - "/builds/slave/test"] 07:47:44 INFO - --app=APP Application being installed. [default: firefox] 07:47:44 INFO - mkdir: /builds/slave/test/build/application 07:47:44 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'] 07:47:44 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 07:48:19 INFO - Reading from file tmpfile_stdout 07:48:19 INFO - Output received: 07:48:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:48:19 INFO - Running post-action listener: _resource_record_post_action 07:48:19 INFO - [mozharness: 2016-10-01 14:48:19.116594Z] Finished install step (success) 07:48:19 INFO - [mozharness: 2016-10-01 14:48:19.116746Z] Running run-tests step. 07:48:19 INFO - Running pre-action listener: _resource_record_pre_action 07:48:19 INFO - Running main action method: run_tests 07:48:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:48:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:48:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:48:19 INFO - 2016-10-01 07:48:19.131 screenresolution[1651:8644] starting screenresolution argv=screenresolution get 07:48:19 INFO - 2016-10-01 07:48:19.134 screenresolution[1651:8644] Display 0: 1600x1200x32@60 07:48:19 INFO - 2016-10-01 07:48:19.140 screenresolution[1652:8648] starting screenresolution argv=screenresolution list 07:48:19 INFO - Available Modes on Display 0 07:48:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:48:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:48:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:48:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:48:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:48:19 INFO - Intel Iris: 07:48:19 INFO - Chipset Model: Intel Iris 07:48:19 INFO - Type: GPU 07:48:19 INFO - Bus: Built-In 07:48:19 INFO - VRAM (Dynamic, Max): 1536 MB 07:48:19 INFO - Vendor: Intel (0x8086) 07:48:19 INFO - Device ID: 0x0a2e 07:48:19 INFO - Revision ID: 0x0009 07:48:19 INFO - Displays: 07:48:19 INFO - Display: 07:48:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:48:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:48:19 INFO - Display Serial Number: 1600x1200 60 07:48:19 INFO - Main Display: Yes 07:48:19 INFO - Mirror: Off 07:48:19 INFO - Online: Yes 07:48:19 INFO - Rotation: Supported 07:48:19 INFO - Return code: 0 07:48:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:48:19 INFO - Python 2.7.3 07:48:19 INFO - Return code: 0 07:48:19 INFO - grabbing minidump binary from tooltool 07:48:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:19 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 0x10b4d4d30>, '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 0x10b2fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b4ccb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:48:19 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 07:48:19 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 07:48:19 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 07:48:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:48:19 INFO - Return code: 0 07:48:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:48:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:48:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:48:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:48:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:48:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0350 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uSx9Lfosxd/Render', 07:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:19 INFO - 'HOME': '/Users/cltbld', 07:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:19 INFO - 'LOGNAME': 'cltbld', 07:48:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:48:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:19 INFO - 'MOZ_NO_REMOTE': '1', 07:48:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:48:19 INFO - 'NO_EM_RESTART': '1', 07:48:19 INFO - 'PAGER': '/bin/cat', 07:48:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:19 INFO - 'PWD': '/builds/slave/test', 07:48:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:19 INFO - 'SHELL': '/bin/bash', 07:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rbUESadM1e/Listeners', 07:48:19 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 07:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:19 INFO - 'USER': 'cltbld', 07:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:19 INFO - 'XPC_FLAGS': '0x0', 07:48:19 INFO - 'XPC_SERVICE_NAME': '0', 07:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:48:19 INFO - mozversion application_buildid: 20161001055158 07:48:19 INFO - mozversion application_changeset: 344920af45b92da6d4f5b84738e1c7a3fb582461 07:48:19 INFO - mozversion application_display_name: Nightly 07:48:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:48:19 INFO - mozversion application_name: Firefox 07:48:19 INFO - mozversion application_remotingname: firefox 07:48:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:48:19 INFO - mozversion application_vendor: Mozilla 07:48:19 INFO - mozversion application_version: 52.0a1 07:48:19 INFO - mozversion platform_buildid: 20161001055158 07:48:19 INFO - mozversion platform_changeset: 344920af45b92da6d4f5b84738e1c7a3fb582461 07:48:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:48:19 INFO - mozversion platform_version: 52.0a1 07:48:19 INFO - using testdate: 1475333299 07:48:19 INFO - actual date: 1475333299 07:48:19 INFO - starting webserver on 'localhost:49271' 07:48:19 INFO - SUITE-START | Running 2 tests 07:48:19 INFO - TEST-START | dromaeo_css 07:48:19 INFO - operating with platform_type : mac_ 07:48:19 INFO - Initialising browser for dromaeo_css test... 07:48:19 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpufJ_CP/profile http://localhost:49271/getInfo.html) 07:48:22 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 07:48:22 INFO - PROCESS | 1662 | colorDepth:24 07:48:22 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 07:48:22 INFO - PROCESS | 1662 | __metrics 07:48:22 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:48:22 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:48:23 INFO - TEST-INFO | 1662: exit 0 07:48:23 INFO - Browser initialized. 07:48:23 INFO - Running cycle 1/1 for dromaeo_css test... 07:48:23 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpufJ_CP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:48:35 INFO - PROCESS | 1666 | RSS: Main: 236355584 07:48:35 INFO - PROCESS | 1666 | 07:49:26 INFO - PROCESS | 1666 | 1475333366976 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] 07:51:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 07:51:40 INFO - PROCESS | 1666 | RSS: Main: 397934592 07:51:40 INFO - PROCESS | 1666 | 07:54:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 07:54:43 INFO - PROCESS | 1666 | RSS: Main: 375472128 07:54:43 INFO - PROCESS | 1666 | 07:57:46 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 07:57:46 INFO - PROCESS | 1666 | RSS: Main: 340393984 07:57:46 INFO - PROCESS | 1666 | 08:00:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 08:00:49 INFO - PROCESS | 1666 | RSS: Main: 359456768 08:00:49 INFO - PROCESS | 1666 | 08:03:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 08:03:53 INFO - PROCESS | 1666 | RSS: Main: 339869696 08:03:53 INFO - PROCESS | 1666 | 08:06:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 08:06:57 INFO - PROCESS | 1666 | RSS: Main: 519446528 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - PROCESS | 1666 | __start_tp_report 08:06:57 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 08:06:57 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 08:06:57 INFO - PROCESS | 1666 | |i|pagename|runs| 08:06:57 INFO - PROCESS | 1666 | |0;dojo.html;3248.740100791936;3252.203210213498;3262.657420970798;3268.50972354147;3315.3535060663166;4352.499462561803;5423.83728488145;5480.588955828309;5512.476314750103;5520.585956053297;5445.564354851612;5520.613557051008;5546.140348246018;5569.443055694429;5592.468715472034;4950.207966725325;4968.801247950078;4993.925091123636;4995.475475075119;5086.643934924557;5059.11465493539;5135.794568217267;5146.6140039497;5146.665466744668;5172.974135129319;5136;5136.5890728741615;5181.5854731621375;5205.037068142406;5210.7655155517905;4988.950110498885;5012.523810238022;5044.495550444963;5046.21783623539;5050.343455350818;4980.05378977993;4982.676126051795;5010.098182327216;5041.1682072458;5096;5500.4224556421395;5518.641288316247;5527.143292789624;5551.2228288039705;5590.049691552405;5788.97105514474;5863.736131874033;5880.911786323209;5941.673207973559;5950.7322170502;6030.728617212192;6040.728167232485;6075.210222671069;6090.469698847441;6101.389861013908;5581.553475721932;5585.832425027255;5587.770690448055;5598.580106491948;5600.887982240414;6091.360407157274;6092.070292355901;6092.664903430265;6250.812475625738;6262.12330273762;5781.566382521328;5820.767169313265;5843.649381037151;5846.356900740915;5847.795327163529;6005.189299444519;6010.609310394811;6011.248593925759;6011.308699499614;6069.757209711562;5866;5885.352611212763;5910.202122713465;5917.497012753965;5958.910616340671;5814.244148260699;5867.471927526543;5879.911801322984;5943.524518038546;5955.612885162451;4117.238310912491;4734.976325118352;4770.0936822004405;4790.736509491941;4794.95205047952;3625.3111908737255;4018.578049304781;4227.725197862129;4295.420118284054;4344.196323680129;5533.778648854081;5580.135079062752;5580.414056524088;5581.944180558224;5588.748506317225;6072.210612620331;6097.451229389376;6100.908486372708;6101.908471372933;6113.449789518964;5453;5489.1217405214975;5500.28496295479;5534.612577119644;5576.163575463632;4388.978055109704;5220.504052115043;5238.371395432572;5240.68555886648;5267.473252674778;6257.4994000480765;6313.621182729051;6315.147455093504;6318.715657795318;6338.239411270677;5878;5926.585139040226;5949.8215053548465;6087.36082711309;6090.390960903874;6110.666315250211;6170.753169873155;6179.5056395489155;6239;6270.9372906270355;5377.650452720561;5464.672119672832;5866.50134738552;5869.530437564899;5870.148828419769;5726.656400615976;5887.793927212527;5923.585349025527;5931.6441013539325;5953.493953014043;5651.067573850269;5736.340320863154;5759.625624334405;5780.132980053026;5829.125631155191;5819.883602328015;5825.70871456417;5841.561882858888;5851.61964472308;5863.648181109147;5687.8862422752145;5710.914336285042;5715.17130016137;5718.79984200551;5726.856828579319;5705.7147142643535;5776.075827867521;5778.335491418373;5787.94212057874;5810.186573879576;5698.0598201296325;5711.143328500758;5715.514181294637;5732.7706891723865;5762.164486149569 08:06:57 INFO - PROCESS | 1666 | |1;ext.html;519.5609709795223;524.874030232744;531.0997858629622;537.0226188337225;546.4016901492864;24932;40164;41208;41370.37944430836;43379.56620433795;38961.41557876634;41014.58985410145;42230.15539689204;42538.57461425385;42560.787196064055;8621.827563448727;9684.612615495389;9882.604695812159;9889.604415823358;9897.356671816346;8741.344399170088;11800.7639847203;12114.757704845897;12127.939360303186;12296.446659900326;11947.223430476992;11971.70070748234;12012.939935300312;12029.278243305429;12043.397830108503;8652.437591556529;8883.733487995305;12085.818712719316;12166;12201.755964880653;11487.655370338904;11849.407529623484;11884.405779711067;12023.458944347525;12034.398280085961;40147;40438.989025274306;40464;40502;40580.39129413061;3395.609504906918;3643.599204087548;3926.6662333701383;3973.9006524836823;4104.220198162339;24359;31092.844535777178;31495.37009259802;31513;31528.842355788303;3067.217859445849;3194.833885631751;3198.552202691625;3236.4821628539557;3239.4492936200904;465486;466450.33549664746;466633;467617;483613.9999999965;58102.70948645314;58383.41616583865;58482;58516;58681;60848.39151608516;61047.38952610417;61065.38934610686;61153.2154855221;61164;15652;15829.287682054335;15855.445059422866;15863.127527986071;15905.840941590435;11084.113270938306;11352.886471135183;11383.715407114723;12316.13787034899;12382.752344953231;15700.842991570167;15900.76148857768;16009.599760006093;16113.355465781238;16171.353145874033;5611.663300202001;5624.409437009055;6007.48936340416;6110;6179.907301390483;834.9791255218668;868.769776009358;897.6409436225431;899.4191044568437;999.9300048996646;28275.858620706764;29127;29136;29778.553321699757;30002.69997299999;1027.7379268286688;1051.8685164354456;1080.1574771678104;1084.2247792828166;1166.2244607336027;14567;14827.703445931236;15350.07899526032;15625.653225565786;15789.921050394903;628570;631321;631704;633831;634658.9204647735;56430.717846411324;56557.71721141368;56645;56706.716466417405;56713.43286567164;1309.312610879296;1363.5636596289091;1371.6570857285678;1372.108129715673;1372.3755691160698;23771.40571485727;29235.83056677709;29281.85359073276;29304.56043159397;29954;49214.015719686126;49694.25458618051;49731.75134124306;49808.25287620761;49834.00331993267;533959;537206;538084;538267;538636;39373.0432370306;40057;40427.797861010506;40609.39085913655;40614;45368.546314536434;46290.53709462862;46473.83815404642;47036.52963470321;47099.52900470951;38248.426273605364;38283.042923927125;38475.807620961714;39481.80259098686;40262;37833.432498512;38197.80901095477;38764;39148.629797956935;39694.404583930685;37006;37377.25245495129;37403.81298093492;37434.812825935696;37756;32707.672923271413;33788.98633041012;34488.137796554285;34619.82690086534;34738.82630586831;21610.91945402755;23123.421914452272;23550.411239719146;23859.761402386448;24851.751482485666 08:06:57 INFO - PROCESS | 1666 | |2;jquery.html;313.7098184179634;353.47508949210436;372.1904856936163;376.70616918803336;407.0556309362279;15070.171140587263;15191.46829860955;16123.758143627854;20444.897775511137;20929.953502324897;15744.055356678607;19628.214871405162;19787.010649467542;19951.800481995175;20110;5388.865278368043;5696.572757043238;5702.45826646468;5703.885922281552;5705.144228365737;4814.542618451259;5518.806841760539;5560.916586251209;5963.701814909259;6149.446549810516;6014.2181516403025;6217.626942383449;6242.687865606724;6249.9062514062325;6251.843703907416;6103.45068943795;6232.9065064023625;6233.407826256498;6246.968765156168;6248.937510624881;5840;6000.399960004008;6082.635041897501;6091.2995005574485;6091.817245482643;13288.86711132886;13425.66435839102;13427.731445371135;13453.461861525528;24966;2162.967555486669;2171.29432934297;2222.5110475695483;2249.4376405898524;2285.908563657468;10522.473876306154;11492.712682183012;12905;14788.630284242872;14792.421203867269;1787.6603445345472;1816.6275913437714;1846.3906910719431;1856.2482194711163;1858.5725283184809;111886;121689;123028;123702;125406;11218.887811121947;11328.830067548994;11367.545298188144;11484.483198256099;11707.253678822695;14550.563483095524;14559.563213103624;14566.271686415637;14572.635684107981;14582.56252312411;8062.919370806217;8328.6668533258;8357.665693372319;8487.236148746642;8547.188017138362;8218.589070546448;8250.257476827115;8259.091499935003;8398;8412.663493460193;3700.7779533227554;4395.230834603995;4498.437695288089;4581.702189357681;4598.609118224921;1741.3818094576495;1849.5838436351714;1919.8752081114683;1920.6350793349218;1923.8460923126397;727.2109760909367;753.310720690562;771.5910567399287;812.7642983534827;914.4513292024732;19592.412227633195;20380.694289585666;20406.387808365773;21289;21388.786112138678;919.1819280839924;924.5469719837231;942.7407462947745;952.361977246924;988.3970777825521;8611.440256383315;10480.109190718875;10860.67417977462;10971.067459266053;11398.829017564743;557146;572755.8173254726;572978;580210;595732;105762;120889;124872.37563812123;131147.34426327999;131344.68655313517;913.2283220678516;914.4193437167446;945.9952700236593;947.3984020147257;948.8766460360109;16336.628714801474;17964.820351796316;18554.907225463787;19194.424167275003;19343.8065619342;51377.48622513727;51553;51903.22145167901;52164;52471;54403.82384704726;68488.3151168482;68712.6564367175;69858.65070674614;78751;7294.963525182552;7747.496412733154;7770.92229077702;7964.681412743425;7989.560574168244;11274.379909105079;12172.817407738714;12214.38928053576;12216.205946613441;12303.200291980993;9994.850077248993;10031;10383.273170878269;10401.427921464392;10648.254622176373;10204.795904082026;10312.742181445525;10417.531211095671;10536.367817930948;10773.515191816543;10199.541020654236;10383.792324153626;10623.36259824413;10793.460326983777;10835.295705779099;7481.88777168332;7551.358134558624;7628.542287462548;7715.614219288901;8007.479513831582;7975.880361794461;9486.383385079947;9906.504674766376;10102.848457273294;10385.584576617153 08:06:57 INFO - PROCESS | 1666 | |3;mootools.html;1391.9512817051404;1534.2788889222065;1734.8872323298986;1878.1454438230605;1931.3529967460909;8330.625121869516;8871.1572400622;8871.467711937281;8885.378023538347;8896.777580560489;7783.299503044725;8047;8078.071021832491;8460.57697115145;8674.221784534171;2326.1858349577683;2351.0830775997374;2355.2345487716475;2359.0327965534134;2360;2319.874860692571;2322.63999080142;2354.5526349993534;2359.504504054151;2359.575276450238;2346.495503466754;2356.2931120663734;2365.113082594027;2375.3824005758543;2376.66726658268;2339.6139636959883;2347.788699017088;2357.3752955466903;2358.6108292131776;2364.869932153731;2338.041403024769;2348.7298960619405;2349.8120150387927;2349.835511514195;2351.706036745407;8777.956110219407;8781.209691127766;8858.069902660192;8905.688300909436;8911;4311.676624253162;5285.709285989269;5365.3293338332705;5383.946160538384;5393.406725260218;3469.3755124077657;3497.24809166028;3535.7878527288444;3557.092941299952;3558.7508874378805;1038.5897570459626;1073.4632683658172;1718.8710846686424;1788.3011139417606;1836.4307064809818;11591.884081159165;11634.476448559835;11668.06655467569;11669.883301166965;11709.941450292694;4466.977665111654;4723.740194289278;4884.48712885142;4919.87700307488;4933.851984440473;9162.496062716615;9210.953945230365;9226.907730922605;9235.307351948632;9246.982831888487;5252.9737351313;5256.6057545684225;5263.947360526422;5271.65734227274;5271.657342272817;4432.645388368923;4498.482674492409;4808.543188397097;4893.7063776173545;4943.777530011158;3671.0822294426393;3718.8698395556567;3788.4885540452237;3824.808759562011;3829.195868867569;1151.746615744535;1156.859443558526;1158.8261760735957;1160.6227975907864;1174.4656181437597;783.9294463498312;842.6713581703141;848.4654667559398;903.509209803829;939.1969865764672;5115.462876397999;5555.2500412444615;6038.762053778964;6066.939330606726;6180.845478862974;997.1225321716988;1022.3814592171777;1034.9327293725885;1053.2732414633879;1086.8369744538381;3747.269282489888;3759.605241449663;3857.40210267409;3875.418687196887;4072.714909956334;10395.116415104801;10671.35971841692;10743.83884241737;10779.838302425469;10801.67594972152;10702.197335199891;10722.249442538947;10738.570457181626;10759.515821788038;10768.030877220932;6561.311062338481;7905.683772649145;8129.796755081052;8351.707690230813;8356.623951922178;5668.149777533403;5729.885402291848;5765.279340082489;5799.304083509837;5937.821865344046;9797.118259356692;9998.800023999334;10074.597016119273;10079.848802267825;10142.695719128438;9637.469939153414;10190.43952582615;10321.535530900977;10334.276600637882;10345.896541034794;6584.539082264196;6810.931890681228;6819.045333653194;7045.823854403681;7187.065681461377;8089.919100809152;8285.710000150208;8415.368847336596;8492.872606911025;8507.149285071444;7403.370713489414;7496.437767167376;7522;7573.772786816405;7669.156392796899;7246.855062898818;7275.345218930322;7354.374878135419;7370.6314684266645;7455.291747283999;6838.452923765987;6991.5529564654;6994.971458277246;6997.930020699728;7005.684744186626;4875.1224779540025;4922.803087876445;5034.546890779848;5070.543651071273;5076.263941728354;4835.395575553056;4985;5055.483354993414;5160.48395160481;5202.531772140525 08:06:57 INFO - PROCESS | 1666 | |4;prototype.html;291.7826219466498;299.9655039670438;308.3509213106412;313.59702781925233;316.96671849455794;5050.520200580943;5053.418856831466;5103.081445339842;5128.974355128219;5152.458991805863;4327.870163895081;4781.617470602352;4877.853704379477;5012.471196285132;5065.670731402456;3698.7965661888584;3896.032503585692;3920.8039598019986;3928.8821335359985;3934.704897132712;3948.2430318508277;3960.4455376247347;3965.702572307074;3968.7618742875375;3969.8610548630804;3494.301139772043;3534.876279330224;3613.620569840168;3926.1362500249897;3941.1329507508303;3038.6657467678538;3687.225682606629;3798.4682144499793;3847.211321679049;3876.6317199865966;3188.8724451022163;3230.660780618048;3315.6187038490643;3320.6513316101946;3341.365140623298;3883.563081659776;4070.6946978976694;4898.485659005789;4940.357753492059;4957.628177886637;3584.8386822593047;3819.350710379242;4244;4358.65130789536;4373.825046998117;4382.430284063052;4928.67963582366;5003.374578177728;5263.605229607795;5296.894062118775;3374.156460884779;3663.725220608465;3667.8543052314426;3675.209829886587;3682.650148235913;4943.9258411123865;4951.665142037749;5100.699321672987;5322.33470816148;5532.446755324436;3091.7062879026457;3708.2583483302906;3717.1636381814415;3723.678094276547;3724.3854763964187;3768.7550309229814;3774.8678796241998;3776.4146557283666;3779.508663873679;3799.297130030953;3278.7868788528667;4153.418521407006;4409.250427427281;4433.29067349229;4445.533219011957;4840.733759643183;5171.450639023381;5265.3944796348915;5268.4994925482015;5268.81559145428;1226.9754604907853;1266.7086570088932;1274.3882936190496;1286.9099163058495;1288.6391810293132;711.9181294151239;758.3705524414722;798.0503201190564;804.1676864445271;805.295366554265;3983;4048.5910883024553;4051.642699695635;4146.481689788777;4206.810693518799;4461.193216747222;4528.932066019012;4545.204589196877;4567.990084362869;4586.770661466913;3813.121093574334;3829.7702137871815;4087.5678971012794;4140;4256.978715106405;2111.546017606222;2259.977400225977;2348.6829278047244;2408.7952524035304;2414.855108693484;4043.5754245804355;4573.428321459817;4682.885880889171;4698.68436837686;4702.95297047032;3833.485773119658;4467.955320446819;4631.282151266491;4632.768361581908;4633.748887800275;4361.672874534423;4993.875153121128;5307.708076055699;5309.920351194735;5545.584081193927;5443.265159203457;5444.292242008513;5452.2466154591675;5457;5463.918041229305;4721.551452611996;5383.380911195262;5416.458354164552;5423.891522169613;5437.537809286255;4131.450705985256;5497.367802702581;5543.445655434424;5665.461781130786;5714.771409143754;3727.235916637137;3734.4398340248094;3735.768560195466;3736.468048100266;3740.85036598533;4949.208126699711;5077.847664570068;5392.811251606332;5438.918416223681;5468;4493.550644935481;5249.186376111708;5313.973430132824;5327.493888080626;5334.866628334323;4628.722276663277;5008.974955125347;5310.840674779763;5325.600579956596;5345.54562862161;4568.146411630894;5195.896082078413;5199.948000519947;5207.114790485551;5224.634275600672;5195.363245728081;5249.9737501313775;5284.524392804666;5295.629305948547;5321.36143662763;5140.614453916046;5154.1237989542615;5192.584593232608;5197.297405349122;5230.895382092413 08:06:57 INFO - PROCESS | 1666 | |5;yui.html;693.6635731670141;736.1828370508738;748.4012789768184;777.7977725791293;805.3034125481461;15870.52388428346;16194.757078643801;16195.433159839407;16198.83801161988;16230.834987177504;14263;14393.78409323861;14394.280285985686;14401;14409.991300608961;675.5845155229523;700.6986995591893;702.332783855337;835.5446281776447;855.2516548020482;835.6532039203723;856.3363393370157;866.1122349591687;870.6038752367689;871.2681347667958;924.865894445305;925.4031149908305;925.6898938855458;928.642472648032;928.9210417114554;917.7934964632971;919.3656377099813;919.7332773495679;920.1902325953151;920.9263258939269;440.2889333726009;540.8323419739893;578.9174244163399;647.6308504152635;653.7058323754283;14923.253837308092;14943.402263909431;14954.850451495455;14955.551333460013;14962.775558366633;13492.527761528398;13960.441582336694;14118.082324648865;14152.716945661132;14370.065945713495;11260.099192064616;12537.185082969578;12784.360781960864;12786.488540458371;12802.48790048397;4151.584841515854;4162.334026555767;4182.435371224876;4340.045190058182;4348.130373925196;102490.97509024963;103089;103553.48223258911;104128;106669;10612;10772.946135269274;10798.51406686701;10917.945410272898;11249.268797528135;18720.063996800378;20721.896390518254;20801.479963000744;21171.576568468543;21201.15195392167;7417.962910185414;7512.586807725518;7682.1549629540705;7687.2697093776005;7787.922120778833;4367.0127795526905;4484.618807401343;4496.595306422438;4645.651576131803;4648.744319062416;3596.6583174598372;3746.7189960752507;3748.0442487165597;3753.5871054184017;3754.887353379403;681.5093132944371;683.7982795075465;702.3819039245432;706.703184662443;707.1620130145793;597.6892016151577;619.170311782214;621.2079598511933;689.582802404548;696.9405407022238;9065.456072635663;9444.338896277195;9444.386114902509;9470.933050817779;9507.191888689538;651.8859199640139;669.6250099944046;683.8222062263848;686.2931180883697;689.217737867524;3913.745606535566;4027.1140349123148;4348.217320882209;4640.767961601907;4674.766261686902;139315;139383.30308348394;140675;142965;143392.28303858623;86666.56666716626;86805.565972171;87164.6925296121;124937;138664;92115;115229;116366;117416;119538;8845;9027.774305642411;9061.68284110053;9076.909230907606;9329.860052099088;57968.420315796306;66007;66225;67176;69391.30608693848;73550.48371372276;82182.58908705418;91821;92314;95543.52228239093;12943.935280323538;13488;13510.527131550349;13581.592552223448;13710.17738935667;14909;15021.624459388602;15030;15252;15682.372705091668;13766.587002389944;13810.516631917835;14005.299735013004;14219.004669673024;14284.500382379518;12016.519339226334;12380.81428778551;13076.044052555479;13318.667033324244;14268.785968210277;13688.178709277476;13719.382627781573;13748.175109493062;13777.173369597856;14331.570052898429;10828.458577071273;11166.38584877807;11427.542898283975;11682.70793230142;11727.706807329885;10319.432431216355;11016.614418495314;11192.608258710907;11272.380019099028;11403.885961140282 08:06:57 INFO - PROCESS | 1666 | __end_tp_report 08:06:57 INFO - PROCESS | 1666 | __start_cc_report 08:06:57 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1729 08:06:57 INFO - PROCESS | 1666 | __end_cc_report 08:06:57 INFO - PROCESS | 1666 | __startTimestamp1475334417157__endTimestamp 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - PROCESS | 1666 | ------- Summary: start ------- 08:06:57 INFO - PROCESS | 1666 | Number of tests: 6 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5520.16 Median:5711.14 stddev:636.33 (11.1%) stddev-sans-first:612.82 08:06:57 INFO - PROCESS | 1666 | Values: 3248.7 3252.2 3262.7 3268.5 3315.4 4352.5 5423.8 5480.6 5512.5 5520.6 5445.6 5520.6 5546.1 5569.4 5592.5 4950.2 4968.8 4993.9 4995.5 5086.6 5059.1 5135.8 5146.6 5146.7 5173.0 5136.0 5136.6 5181.6 5205.0 5210.8 4989.0 5012.5 5044.5 5046.2 5050.3 4980.1 4982.7 5010.1 5041.2 5096.0 5500.4 5518.6 5527.1 5551.2 5590.0 5789.0 5863.7 5880.9 5941.7 5950.7 6030.7 6040.7 6075.2 6090.5 6101.4 5581.6 5585.8 5587.8 5598.6 5600.9 6091.4 6092.1 6092.7 6250.8 6262.1 5781.6 5820.8 5843.6 5846.4 5847.8 6005.2 6010.6 6011.2 6011.3 6069.8 5866.0 5885.4 5910.2 5917.5 5958.9 5814.2 5867.5 5879.9 5943.5 5955.6 4117.2 4735.0 4770.1 4790.7 4795.0 3625.3 4018.6 4227.7 4295.4 4344.2 5533.8 5580.1 5580.4 5581.9 5588.7 6072.2 6097.5 6100.9 6101.9 6113.4 5453.0 5489.1 5500.3 5534.6 5576.2 4389.0 5220.5 5238.4 5240.7 5267.5 6257.5 6313.6 6315.1 6318.7 6338.2 5878.0 5926.6 5949.8 6087.4 6090.4 6110.7 6170.8 6179.5 6239.0 6270.9 5377.7 5464.7 5866.5 5869.5 5870.1 5726.7 5887.8 5923.6 5931.6 5953.5 5651.1 5736.3 5759.6 5780.1 5829.1 5819.9 5825.7 5841.6 5851.6 5863.6 5687.9 5710.9 5715.2 5718.8 5726.9 5705.7 5776.1 5778.3 5787.9 5810.2 5698.1 5711.1 5715.5 5732.8 5762.2 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:68417.27 Median:38862.71 stddev:146864.82 (377.9%) stddev-sans-first:147188.34 08:06:57 INFO - PROCESS | 1666 | Values: 519.6 524.9 531.1 537.0 546.4 24932.0 40164.0 41208.0 41370.4 43379.6 38961.4 41014.6 42230.2 42538.6 42560.8 8621.8 9684.6 9882.6 9889.6 9897.4 8741.3 11800.8 12114.8 12127.9 12296.4 11947.2 11971.7 12012.9 12029.3 12043.4 8652.4 8883.7 12085.8 12166.0 12201.8 11487.7 11849.4 11884.4 12023.5 12034.4 40147.0 40439.0 40464.0 40502.0 40580.4 3395.6 3643.6 3926.7 3973.9 4104.2 24359.0 31092.8 31495.4 31513.0 31528.8 3067.2 3194.8 3198.6 3236.5 3239.4 465486.0 466450.3 466633.0 467617.0 483614.0 58102.7 58383.4 58482.0 58516.0 58681.0 60848.4 61047.4 61065.4 61153.2 61164.0 15652.0 15829.3 15855.4 15863.1 15905.8 11084.1 11352.9 11383.7 12316.1 12382.8 15700.8 15900.8 16009.6 16113.4 16171.4 5611.7 5624.4 6007.5 6110.0 6179.9 835.0 868.8 897.6 899.4 999.9 28275.9 29127.0 29136.0 29778.6 30002.7 1027.7 1051.9 1080.2 1084.2 1166.2 14567.0 14827.7 15350.1 15625.7 15789.9 628570.0 631321.0 631704.0 633831.0 634658.9 56430.7 56557.7 56645.0 56706.7 56713.4 1309.3 1363.6 1371.7 1372.1 1372.4 23771.4 29235.8 29281.9 29304.6 29954.0 49214.0 49694.3 49731.8 49808.3 49834.0 533959.0 537206.0 538084.0 538267.0 538636.0 39373.0 40057.0 40427.8 40609.4 40614.0 45368.5 46290.5 46473.8 47036.5 47099.5 38248.4 38283.0 38475.8 39481.8 40262.0 37833.4 38197.8 38764.0 39148.6 39694.4 37006.0 37377.3 37403.8 37434.8 37756.0 32707.7 33789.0 34488.1 34619.8 34738.8 21610.9 23123.4 23550.4 23859.8 24851.8 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:33561.00 Median:333.59 stddev:96260.76 (28855.8%) stddev-sans-first:96498.43 08:06:57 INFO - PROCESS | 1666 | Values: 313.7 353.5 372.2 376.7 407.1 15070.2 15191.5 16123.8 20444.9 20930.0 15744.1 19628.2 19787.0 19951.8 20110.0 5388.9 5696.6 5702.5 5703.9 5705.1 4814.5 5518.8 5560.9 5963.7 6149.4 6014.2 6217.6 6242.7 6249.9 6251.8 6103.5 6232.9 6233.4 6247.0 6248.9 5840.0 6000.4 6082.6 6091.3 6091.8 13288.9 13425.7 13427.7 13453.5 24966.0 2163.0 2171.3 2222.5 2249.4 2285.9 10522.5 11492.7 12905.0 14788.6 14792.4 1787.7 1816.6 1846.4 1856.2 1858.6 111886.0 121689.0 123028.0 123702.0 125406.0 11218.9 11328.8 11367.5 11484.5 11707.3 14550.6 14559.6 14566.3 14572.6 14582.6 8062.9 8328.7 8357.7 8487.2 8547.2 8218.6 8250.3 8259.1 8398.0 8412.7 3700.8 4395.2 4498.4 4581.7 4598.6 1741.4 1849.6 1919.9 1920.6 1923.8 727.2 753.3 771.6 812.8 914.5 19592.4 20380.7 20406.4 21289.0 21388.8 919.2 924.5 942.7 952.4 988.4 8611.4 10480.1 10860.7 10971.1 11398.8 557146.0 572755.8 572978.0 580210.0 595732.0 105762.0 120889.0 124872.4 131147.3 131344.7 913.2 914.4 946.0 947.4 948.9 16336.6 17964.8 18554.9 19194.4 19343.8 51377.5 51553.0 51903.2 52164.0 52471.0 54403.8 68488.3 68712.7 69858.7 78751.0 7295.0 7747.5 7770.9 7964.7 7989.6 11274.4 12172.8 12214.4 12216.2 12303.2 9994.9 10031.0 10383.3 10401.4 10648.3 10204.8 10312.7 10417.5 10536.4 10773.5 10199.5 10383.8 10623.4 10793.5 10835.3 7481.9 7551.4 7628.5 7715.6 8007.5 7975.9 9486.4 9906.5 10102.8 10385.6 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5664.84 Median:4906.79 stddev:3149.17 (64.2%) stddev-sans-first:3141.63 08:06:57 INFO - PROCESS | 1666 | Values: 1392.0 1534.3 1734.9 1878.1 1931.4 8330.6 8871.2 8871.5 8885.4 8896.8 7783.3 8047.0 8078.1 8460.6 8674.2 2326.2 2351.1 2355.2 2359.0 2360.0 2319.9 2322.6 2354.6 2359.5 2359.6 2346.5 2356.3 2365.1 2375.4 2376.7 2339.6 2347.8 2357.4 2358.6 2364.9 2338.0 2348.7 2349.8 2349.8 2351.7 8778.0 8781.2 8858.1 8905.7 8911.0 4311.7 5285.7 5365.3 5383.9 5393.4 3469.4 3497.2 3535.8 3557.1 3558.8 1038.6 1073.5 1718.9 1788.3 1836.4 11591.9 11634.5 11668.1 11669.9 11709.9 4467.0 4723.7 4884.5 4919.9 4933.9 9162.5 9211.0 9226.9 9235.3 9247.0 5253.0 5256.6 5263.9 5271.7 5271.7 4432.6 4498.5 4808.5 4893.7 4943.8 3671.1 3718.9 3788.5 3824.8 3829.2 1151.7 1156.9 1158.8 1160.6 1174.5 783.9 842.7 848.5 903.5 939.2 5115.5 5555.3 6038.8 6066.9 6180.8 997.1 1022.4 1034.9 1053.3 1086.8 3747.3 3759.6 3857.4 3875.4 4072.7 10395.1 10671.4 10743.8 10779.8 10801.7 10702.2 10722.2 10738.6 10759.5 10768.0 6561.3 7905.7 8129.8 8351.7 8356.6 5668.1 5729.9 5765.3 5799.3 5937.8 9797.1 9998.8 10074.6 10079.8 10142.7 9637.5 10190.4 10321.5 10334.3 10345.9 6584.5 6810.9 6819.0 7045.8 7187.1 8089.9 8285.7 8415.4 8492.9 8507.1 7403.4 7496.4 7522.0 7573.8 7669.2 7246.9 7275.3 7354.4 7370.6 7455.3 6838.5 6991.6 6995.0 6997.9 7005.7 4875.1 4922.8 5034.5 5070.5 5076.3 4835.4 4985.0 5055.5 5160.5 5202.5 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4157.70 Median:4511.24 stddev:1277.59 (28.3%) stddev-sans-first:1247.79 08:06:57 INFO - PROCESS | 1666 | Values: 291.8 300.0 308.4 313.6 317.0 5050.5 5053.4 5103.1 5129.0 5152.5 4327.9 4781.6 4877.9 5012.5 5065.7 3698.8 3896.0 3920.8 3928.9 3934.7 3948.2 3960.4 3965.7 3968.8 3969.9 3494.3 3534.9 3613.6 3926.1 3941.1 3038.7 3687.2 3798.5 3847.2 3876.6 3188.9 3230.7 3315.6 3320.7 3341.4 3883.6 4070.7 4898.5 4940.4 4957.6 3584.8 3819.4 4244.0 4358.7 4373.8 4382.4 4928.7 5003.4 5263.6 5296.9 3374.2 3663.7 3667.9 3675.2 3682.7 4943.9 4951.7 5100.7 5322.3 5532.4 3091.7 3708.3 3717.2 3723.7 3724.4 3768.8 3774.9 3776.4 3779.5 3799.3 3278.8 4153.4 4409.3 4433.3 4445.5 4840.7 5171.5 5265.4 5268.5 5268.8 1227.0 1266.7 1274.4 1286.9 1288.6 711.9 758.4 798.1 804.2 805.3 3983.0 4048.6 4051.6 4146.5 4206.8 4461.2 4528.9 4545.2 4568.0 4586.8 3813.1 3829.8 4087.6 4140.0 4257.0 2111.5 2260.0 2348.7 2408.8 2414.9 4043.6 4573.4 4682.9 4698.7 4703.0 3833.5 4468.0 4631.3 4632.8 4633.7 4361.7 4993.9 5307.7 5309.9 5545.6 5443.3 5444.3 5452.2 5457.0 5463.9 4721.6 5383.4 5416.5 5423.9 5437.5 4131.5 5497.4 5543.4 5665.5 5714.8 3727.2 3734.4 3735.8 3736.5 3740.9 4949.2 5077.8 5392.8 5438.9 5468.0 4493.6 5249.2 5314.0 5327.5 5334.9 4628.7 5009.0 5310.8 5325.6 5345.5 4568.1 5195.9 5199.9 5207.1 5224.6 5195.4 5250.0 5284.5 5295.6 5321.4 5140.6 5154.1 5192.6 5197.3 5230.9 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:23873.60 Median:3834.32 stddev:37161.08 (969.2%) stddev-sans-first:37224.57 08:06:57 INFO - PROCESS | 1666 | Values: 693.7 736.2 748.4 777.8 805.3 15870.5 16194.8 16195.4 16198.8 16230.8 14263.0 14393.8 14394.3 14401.0 14410.0 675.6 700.7 702.3 835.5 855.3 835.7 856.3 866.1 870.6 871.3 924.9 925.4 925.7 928.6 928.9 917.8 919.4 919.7 920.2 920.9 440.3 540.8 578.9 647.6 653.7 14923.3 14943.4 14954.9 14955.6 14962.8 13492.5 13960.4 14118.1 14152.7 14370.1 11260.1 12537.2 12784.4 12786.5 12802.5 4151.6 4162.3 4182.4 4340.0 4348.1 102491.0 103089.0 103553.5 104128.0 106669.0 10612.0 10772.9 10798.5 10917.9 11249.3 18720.1 20721.9 20801.5 21171.6 21201.2 7418.0 7512.6 7682.2 7687.3 7787.9 4367.0 4484.6 4496.6 4645.7 4648.7 3596.7 3746.7 3748.0 3753.6 3754.9 681.5 683.8 702.4 706.7 707.2 597.7 619.2 621.2 689.6 696.9 9065.5 9444.3 9444.4 9470.9 9507.2 651.9 669.6 683.8 686.3 689.2 3913.7 4027.1 4348.2 4640.8 4674.8 139315.0 139383.3 140675.0 142965.0 143392.3 86666.6 86805.6 87164.7 124937.0 138664.0 92115.0 115229.0 116366.0 117416.0 119538.0 8845.0 9027.8 9061.7 9076.9 9329.9 57968.4 66007.0 66225.0 67176.0 69391.3 73550.5 82182.6 91821.0 92314.0 95543.5 12943.9 13488.0 13510.5 13581.6 13710.2 14909.0 15021.6 15030.0 15252.0 15682.4 13766.6 13810.5 14005.3 14219.0 14284.5 12016.5 12380.8 13076.0 13318.7 14268.8 13688.2 13719.4 13748.2 13777.2 14331.6 10828.5 11166.4 11427.5 11682.7 11727.7 10319.4 11016.6 11192.6 11272.4 11403.9 08:06:57 INFO - PROCESS | 1666 | -------- Summary: end -------- 08:06:57 INFO - PROCESS | 1666 | 08:06:57 INFO - TEST-INFO | 1666: exit 0 08:06:57 INFO - TEST-OK | dromaeo_css | took 1118070ms 08:06:57 INFO - TEST-START | kraken 08:06:57 INFO - operating with platform_type : mac_ 08:06:57 INFO - Initialising browser for kraken test... 08:06:57 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmp7Qqgi9/profile http://localhost:49271/getInfo.html) 08:07:00 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 08:07:00 INFO - PROCESS | 1672 | colorDepth:24 08:07:00 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 08:07:00 INFO - PROCESS | 1672 | __metrics 08:07:00 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:07:00 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:07:01 INFO - TEST-INFO | 1672: exit 0 08:07:01 INFO - Browser initialized. 08:07:01 INFO - Running cycle 1/1 for kraken test... 08:07:01 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmp7Qqgi9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:07:13 INFO - PROCESS | 1673 | RSS: Main: 235933696 08:07:13 INFO - PROCESS | 1673 | 08:07:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 08:07:16 INFO - PROCESS | 1673 | RSS: Main: 296337408 08:07:16 INFO - PROCESS | 1673 | 08:07:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 08:07:20 INFO - PROCESS | 1673 | RSS: Main: 294965248 08:07:20 INFO - PROCESS | 1673 | 08:07:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 08:07:24 INFO - PROCESS | 1673 | RSS: Main: 385847296 08:07:24 INFO - PROCESS | 1673 | 08:07:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 08:07:26 INFO - PROCESS | 1673 | RSS: Main: 285155328 08:07:26 INFO - PROCESS | 1673 | 08:07:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:07:29 INFO - PROCESS | 1673 | RSS: Main: 288886784 08:07:29 INFO - PROCESS | 1673 | 08:07:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 08:07:33 INFO - PROCESS | 1673 | RSS: Main: 326602752 08:07:33 INFO - PROCESS | 1673 | 08:07:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 08:07:36 INFO - PROCESS | 1673 | RSS: Main: 328396800 08:07:36 INFO - PROCESS | 1673 | 08:07:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 08:07:40 INFO - PROCESS | 1673 | RSS: Main: 324927488 08:07:40 INFO - PROCESS | 1673 | 08:07:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:07:42 INFO - PROCESS | 1673 | RSS: Main: 303173632 08:07:42 INFO - PROCESS | 1673 | 08:07:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:07:44 INFO - PROCESS | 1673 | RSS: Main: 304304128 08:07:44 INFO - PROCESS | 1673 | 08:07:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:07:47 INFO - PROCESS | 1673 | RSS: Main: 311558144 08:07:47 INFO - PROCESS | 1673 | 08:07:50 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:07:51 INFO - PROCESS | 1673 | RSS: Main: 315891712 08:07:51 INFO - PROCESS | 1673 | 08:07:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:07:55 INFO - PROCESS | 1673 | RSS: Main: 318205952 08:07:55 INFO - PROCESS | 1673 | 08:07:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 08:07:57 INFO - PROCESS | 1673 | RSS: Main: 360906752 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | __start_tp_report 08:07:57 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 08:07:57 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 08:07:57 INFO - PROCESS | 1673 | |i|pagename|runs| 08:07:57 INFO - PROCESS | 1673 | |0;ai-astar;113;113;111;114;114;113;116;163;167;113 08:07:57 INFO - PROCESS | 1673 | |1;audio-beat-detection;154;110;113;148;113;114;126;101;173;140 08:07:57 INFO - PROCESS | 1673 | |2;audio-dft;185;184;184;188;186;183;182;180;183;184 08:07:57 INFO - PROCESS | 1673 | |3;audio-fft;79;59;59;90;65;61;58;59;79;58 08:07:57 INFO - PROCESS | 1673 | |4;audio-oscillator;87;71;91;70;68;69;92;66;91;116 08:07:57 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;89;86;88;86;90;86;81;115;137;86 08:07:57 INFO - PROCESS | 1673 | |6;imaging-darkroom;115;151;152;95;94;94;94;94;94;93 08:07:57 INFO - PROCESS | 1673 | |7;imaging-desaturate;133;130;95;84;84;85;83;86;85;85 08:07:57 INFO - PROCESS | 1673 | |8;json-parse-financial;56;56;56;57;56;56;55;56;55;55 08:07:57 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;65;73;71;75;71;70;68;70;69;69 08:07:57 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;91;90;86;89;86;89;91;89;91;129 08:07:57 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;145;147;146;148;152;157;141;139;154;157 08:07:57 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;173;171;171;172;172;172;174;226;230;171 08:07:57 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;69;66;83;87;73;68;68;69;67;65 08:07:57 INFO - PROCESS | 1673 | __end_tp_report 08:07:57 INFO - PROCESS | 1673 | __start_cc_report 08:07:57 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1988 08:07:57 INFO - PROCESS | 1673 | __end_cc_report 08:07:57 INFO - PROCESS | 1673 | __startTimestamp1475334477187__endTimestamp 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | ------- Summary: start ------- 08:07:57 INFO - PROCESS | 1673 | Number of tests: 14 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:123.70 Median:114.00 stddev:21.82 (19.1%) stddev-sans-first:22.80 08:07:57 INFO - PROCESS | 1673 | Values: 113.0 113.0 111.0 114.0 114.0 113.0 116.0 163.0 167.0 113.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:133.00 stddev:23.41 (17.6%) stddev-sans-first:23.05 08:07:57 INFO - PROCESS | 1673 | Values: 154.0 110.0 113.0 148.0 113.0 114.0 126.0 101.0 173.0 140.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:2.18 (1.2%) stddev-sans-first:2.28 08:07:57 INFO - PROCESS | 1673 | Values: 185.0 184.0 184.0 188.0 186.0 183.0 182.0 180.0 183.0 184.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:66.70 Median:63.00 stddev:11.60 (18.4%) stddev-sans-first:11.41 08:07:57 INFO - PROCESS | 1673 | Values: 79.0 59.0 59.0 90.0 65.0 61.0 58.0 59.0 79.0 58.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:79.00 stddev:16.09 (20.4%) stddev-sans-first:16.96 08:07:57 INFO - PROCESS | 1673 | Values: 87.0 71.0 91.0 70.0 68.0 69.0 92.0 66.0 91.0 116.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:86.00 stddev:17.61 (20.5%) stddev-sans-first:18.57 08:07:57 INFO - PROCESS | 1673 | Values: 89.0 86.0 88.0 86.0 90.0 86.0 81.0 115.0 137.0 86.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:107.60 Median:94.00 stddev:24.05 (25.6%) stddev-sans-first:25.36 08:07:57 INFO - PROCESS | 1673 | Values: 115.0 151.0 152.0 95.0 94.0 94.0 94.0 94.0 94.0 93.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:85.00 stddev:19.54 (23.0%) stddev-sans-first:15.13 08:07:57 INFO - PROCESS | 1673 | Values: 133.0 130.0 95.0 84.0 84.0 85.0 83.0 86.0 85.0 85.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 08:07:57 INFO - PROCESS | 1673 | Values: 56.0 56.0 56.0 57.0 56.0 56.0 55.0 56.0 55.0 55.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.50 stddev:2.73 (3.9%) stddev-sans-first:2.18 08:07:57 INFO - PROCESS | 1673 | Values: 65.0 73.0 71.0 75.0 71.0 70.0 68.0 70.0 69.0 69.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:93.10 Median:89.50 stddev:12.75 (14.2%) stddev-sans-first:13.50 08:07:57 INFO - PROCESS | 1673 | Values: 91.0 90.0 86.0 89.0 86.0 89.0 91.0 89.0 91.0 129.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:150.00 stddev:6.28 (4.2%) stddev-sans-first:6.52 08:07:57 INFO - PROCESS | 1673 | Values: 145.0 147.0 146.0 148.0 152.0 157.0 141.0 139.0 154.0 157.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.20 Median:172.50 stddev:23.65 (13.7%) stddev-sans-first:24.79 08:07:57 INFO - PROCESS | 1673 | Values: 173.0 171.0 171.0 172.0 172.0 172.0 174.0 226.0 230.0 171.0 08:07:57 INFO - PROCESS | 1673 | 08:07:57 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:69.00 stddev:7.49 (10.9%) stddev-sans-first:7.89 08:07:57 INFO - PROCESS | 1673 | Values: 69.0 66.0 83.0 87.0 73.0 68.0 68.0 69.0 67.0 65.0 08:07:57 INFO - PROCESS | 1673 | -------- Summary: end -------- 08:07:57 INFO - PROCESS | 1673 | 08:07:58 INFO - TEST-INFO | 1673: exit 0 08:07:58 INFO - TEST-OK | kraken | took 60264ms 08:07:58 INFO - SUITE-END | took 1178s 08:07:58 INFO - Completed test suite (00:19:38) 08:07:58 INFO - javascript benchmark 08:07:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3248.740100791936, 3252.203210213498, 3262.657420970798, 3268.50972354147, 3315.3535060663166, 4352.499462561803, 5423.83728488145, 5480.588955828309, 5512.476314750103, 5520.585956053297, 5445.564354851612, 5520.613557051008, 5546.140348246018, 5569.443055694429, 5592.468715472034, 4950.207966725325, 4968.801247950078, 4993.925091123636, 4995.475475075119, 5086.643934924557, 5059.11465493539, 5135.794568217267, 5146.6140039497, 5146.665466744668, 5172.974135129319, 5136.0, 5136.5890728741615, 5181.5854731621375, 5205.037068142406, 5210.7655155517905, 4988.950110498885, 5012.523810238022, 5044.495550444963, 5046.21783623539, 5050.343455350818, 4980.05378977993, 4982.676126051795, 5010.098182327216, 5041.1682072458, 5096.0, 5500.4224556421395, 5518.641288316247, 5527.143292789624, 5551.2228288039705, 5590.049691552405, 5788.97105514474, 5863.736131874033, 5880.911786323209, 5941.673207973559, 5950.7322170502, 6030.728617212192, 6040.728167232485, 6075.210222671069, 6090.469698847441, 6101.389861013908, 5581.553475721932, 5585.832425027255, 5587.770690448055, 5598.580106491948, 5600.887982240414, 6091.360407157274, 6092.070292355901, 6092.664903430265, 6250.812475625738, 6262.12330273762, 5781.566382521328, 5820.767169313265, 5843.649381037151, 5846.356900740915, 5847.795327163529, 6005.189299444519, 6010.609310394811, 6011.248593925759, 6011.308699499614, 6069.757209711562, 5866.0, 5885.352611212763, 5910.202122713465, 5917.497012753965, 5958.910616340671, 5814.244148260699, 5867.471927526543, 5879.911801322984, 5943.524518038546, 5955.612885162451, 4117.238310912491, 4734.976325118352, 4770.0936822004405, 4790.736509491941, 4794.95205047952, 3625.3111908737255, 4018.578049304781, 4227.725197862129, 4295.420118284054, 4344.196323680129, 5533.778648854081, 5580.135079062752, 5580.414056524088, 5581.944180558224, 5588.748506317225, 6072.210612620331, 6097.451229389376, 6100.908486372708, 6101.908471372933, 6113.449789518964, 5453.0, 5489.1217405214975, 5500.28496295479, 5534.612577119644, 5576.163575463632, 4388.978055109704, 5220.504052115043, 5238.371395432572, 5240.68555886648, 5267.473252674778, 6257.4994000480765, 6313.621182729051, 6315.147455093504, 6318.715657795318, 6338.239411270677, 5878.0, 5926.585139040226, 5949.8215053548465, 6087.36082711309, 6090.390960903874, 6110.666315250211, 6170.753169873155, 6179.5056395489155, 6239.0, 6270.9372906270355, 5377.650452720561, 5464.672119672832, 5866.50134738552, 5869.530437564899, 5870.148828419769, 5726.656400615976, 5887.793927212527, 5923.585349025527, 5931.6441013539325, 5953.493953014043, 5651.067573850269, 5736.340320863154, 5759.625624334405, 5780.132980053026, 5829.125631155191, 5819.883602328015, 5825.70871456417, 5841.561882858888, 5851.61964472308, 5863.648181109147, 5687.8862422752145, 5710.914336285042, 5715.17130016137, 5718.79984200551, 5726.856828579319, 5705.7147142643535, 5776.075827867521, 5778.335491418373, 5787.94212057874, 5810.186573879576, 5698.0598201296325, 5711.143328500758, 5715.514181294637, 5732.7706891723865, 5762.164486149569], "value": 5478.867760438216, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.5609709795223, 524.874030232744, 531.0997858629622, 537.0226188337225, 546.4016901492864, 24932.0, 40164.0, 41208.0, 41370.37944430836, 43379.56620433795, 38961.41557876634, 41014.58985410145, 42230.15539689204, 42538.57461425385, 42560.787196064055, 8621.827563448727, 9684.612615495389, 9882.604695812159, 9889.604415823358, 9897.356671816346, 8741.344399170088, 11800.7639847203, 12114.757704845897, 12127.939360303186, 12296.446659900326, 11947.223430476992, 11971.70070748234, 12012.939935300312, 12029.278243305429, 12043.397830108503, 8652.437591556529, 8883.733487995305, 12085.818712719316, 12166.0, 12201.755964880653, 11487.655370338904, 11849.407529623484, 11884.405779711067, 12023.458944347525, 12034.398280085961, 40147.0, 40438.989025274306, 40464.0, 40502.0, 40580.39129413061, 3395.609504906918, 3643.599204087548, 3926.6662333701383, 3973.9006524836823, 4104.220198162339, 24359.0, 31092.844535777178, 31495.37009259802, 31513.0, 31528.842355788303, 3067.217859445849, 3194.833885631751, 3198.552202691625, 3236.4821628539557, 3239.4492936200904, 465486.0, 466450.33549664746, 466633.0, 467617.0, 483613.9999999965, 58102.70948645314, 58383.41616583865, 58482.0, 58516.0, 58681.0, 60848.39151608516, 61047.38952610417, 61065.38934610686, 61153.2154855221, 61164.0, 15652.0, 15829.287682054335, 15855.445059422866, 15863.127527986071, 15905.840941590435, 11084.113270938306, 11352.886471135183, 11383.715407114723, 12316.13787034899, 12382.752344953231, 15700.842991570167, 15900.76148857768, 16009.599760006093, 16113.355465781238, 16171.353145874033, 5611.663300202001, 5624.409437009055, 6007.48936340416, 6110.0, 6179.907301390483, 834.9791255218668, 868.769776009358, 897.6409436225431, 899.4191044568437, 999.9300048996646, 28275.858620706764, 29127.0, 29136.0, 29778.553321699757, 30002.69997299999, 1027.7379268286688, 1051.8685164354456, 1080.1574771678104, 1084.2247792828166, 1166.2244607336027, 14567.0, 14827.703445931236, 15350.07899526032, 15625.653225565786, 15789.921050394903, 628570.0, 631321.0, 631704.0, 633831.0, 634658.9204647735, 56430.717846411324, 56557.71721141368, 56645.0, 56706.716466417405, 56713.43286567164, 1309.312610879296, 1363.5636596289091, 1371.6570857285678, 1372.108129715673, 1372.3755691160698, 23771.40571485727, 29235.83056677709, 29281.85359073276, 29304.56043159397, 29954.0, 49214.015719686126, 49694.25458618051, 49731.75134124306, 49808.25287620761, 49834.00331993267, 533959.0, 537206.0, 538084.0, 538267.0, 538636.0, 39373.0432370306, 40057.0, 40427.797861010506, 40609.39085913655, 40614.0, 45368.546314536434, 46290.53709462862, 46473.83815404642, 47036.52963470321, 47099.52900470951, 38248.426273605364, 38283.042923927125, 38475.807620961714, 39481.80259098686, 40262.0, 37833.432498512, 38197.80901095477, 38764.0, 39148.629797956935, 39694.404583930685, 37006.0, 37377.25245495129, 37403.81298093492, 37434.812825935696, 37756.0, 32707.672923271413, 33788.98633041012, 34488.137796554285, 34619.82690086534, 34738.82630586831, 21610.91945402755, 23123.421914452272, 23550.411239719146, 23859.761402386448, 24851.751482485666], "value": 20100.065776347703, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.7098184179634, 353.47508949210436, 372.1904856936163, 376.70616918803336, 407.0556309362279, 15070.171140587263, 15191.46829860955, 16123.758143627854, 20444.897775511137, 20929.953502324897, 15744.055356678607, 19628.214871405162, 19787.010649467542, 19951.800481995175, 20110.0, 5388.865278368043, 5696.572757043238, 5702.45826646468, 5703.885922281552, 5705.144228365737, 4814.542618451259, 5518.806841760539, 5560.916586251209, 5963.701814909259, 6149.446549810516, 6014.2181516403025, 6217.626942383449, 6242.687865606724, 6249.9062514062325, 6251.843703907416, 6103.45068943795, 6232.9065064023625, 6233.407826256498, 6246.968765156168, 6248.937510624881, 5840.0, 6000.399960004008, 6082.635041897501, 6091.2995005574485, 6091.817245482643, 13288.86711132886, 13425.66435839102, 13427.731445371135, 13453.461861525528, 24966.0, 2162.967555486669, 2171.29432934297, 2222.5110475695483, 2249.4376405898524, 2285.908563657468, 10522.473876306154, 11492.712682183012, 12905.0, 14788.630284242872, 14792.421203867269, 1787.6603445345472, 1816.6275913437714, 1846.3906910719431, 1856.2482194711163, 1858.5725283184809, 111886.0, 121689.0, 123028.0, 123702.0, 125406.0, 11218.887811121947, 11328.830067548994, 11367.545298188144, 11484.483198256099, 11707.253678822695, 14550.563483095524, 14559.563213103624, 14566.271686415637, 14572.635684107981, 14582.56252312411, 8062.919370806217, 8328.6668533258, 8357.665693372319, 8487.236148746642, 8547.188017138362, 8218.589070546448, 8250.257476827115, 8259.091499935003, 8398.0, 8412.663493460193, 3700.7779533227554, 4395.230834603995, 4498.437695288089, 4581.702189357681, 4598.609118224921, 1741.3818094576495, 1849.5838436351714, 1919.8752081114683, 1920.6350793349218, 1923.8460923126397, 727.2109760909367, 753.310720690562, 771.5910567399287, 812.7642983534827, 914.4513292024732, 19592.412227633195, 20380.694289585666, 20406.387808365773, 21289.0, 21388.786112138678, 919.1819280839924, 924.5469719837231, 942.7407462947745, 952.361977246924, 988.3970777825521, 8611.440256383315, 10480.109190718875, 10860.67417977462, 10971.067459266053, 11398.829017564743, 557146.0, 572755.8173254726, 572978.0, 580210.0, 595732.0, 105762.0, 120889.0, 124872.37563812123, 131147.34426327999, 131344.68655313517, 913.2283220678516, 914.4193437167446, 945.9952700236593, 947.3984020147257, 948.8766460360109, 16336.628714801474, 17964.820351796316, 18554.907225463787, 19194.424167275003, 19343.8065619342, 51377.48622513727, 51553.0, 51903.22145167901, 52164.0, 52471.0, 54403.82384704726, 68488.3151168482, 68712.6564367175, 69858.65070674614, 78751.0, 7294.963525182552, 7747.496412733154, 7770.92229077702, 7964.681412743425, 7989.560574168244, 11274.379909105079, 12172.817407738714, 12214.38928053576, 12216.205946613441, 12303.200291980993, 9994.850077248993, 10031.0, 10383.273170878269, 10401.427921464392, 10648.254622176373, 10204.795904082026, 10312.742181445525, 10417.531211095671, 10536.367817930948, 10773.515191816543, 10199.541020654236, 10383.792324153626, 10623.36259824413, 10793.460326983777, 10835.295705779099, 7481.88777168332, 7551.358134558624, 7628.542287462548, 7715.614219288901, 8007.479513831582, 7975.880361794461, 9486.383385079947, 9906.504674766376, 10102.848457273294, 10385.584576617153], "value": 9177.43504111219, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.9512817051404, 1534.2788889222065, 1734.8872323298986, 1878.1454438230605, 1931.3529967460909, 8330.625121869516, 8871.1572400622, 8871.467711937281, 8885.378023538347, 8896.777580560489, 7783.299503044725, 8047.0, 8078.071021832491, 8460.57697115145, 8674.221784534171, 2326.1858349577683, 2351.0830775997374, 2355.2345487716475, 2359.0327965534134, 2360.0, 2319.874860692571, 2322.63999080142, 2354.5526349993534, 2359.504504054151, 2359.575276450238, 2346.495503466754, 2356.2931120663734, 2365.113082594027, 2375.3824005758543, 2376.66726658268, 2339.6139636959883, 2347.788699017088, 2357.3752955466903, 2358.6108292131776, 2364.869932153731, 2338.041403024769, 2348.7298960619405, 2349.8120150387927, 2349.835511514195, 2351.706036745407, 8777.956110219407, 8781.209691127766, 8858.069902660192, 8905.688300909436, 8911.0, 4311.676624253162, 5285.709285989269, 5365.3293338332705, 5383.946160538384, 5393.406725260218, 3469.3755124077657, 3497.24809166028, 3535.7878527288444, 3557.092941299952, 3558.7508874378805, 1038.5897570459626, 1073.4632683658172, 1718.8710846686424, 1788.3011139417606, 1836.4307064809818, 11591.884081159165, 11634.476448559835, 11668.06655467569, 11669.883301166965, 11709.941450292694, 4466.977665111654, 4723.740194289278, 4884.48712885142, 4919.87700307488, 4933.851984440473, 9162.496062716615, 9210.953945230365, 9226.907730922605, 9235.307351948632, 9246.982831888487, 5252.9737351313, 5256.6057545684225, 5263.947360526422, 5271.65734227274, 5271.657342272817, 4432.645388368923, 4498.482674492409, 4808.543188397097, 4893.7063776173545, 4943.777530011158, 3671.0822294426393, 3718.8698395556567, 3788.4885540452237, 3824.808759562011, 3829.195868867569, 1151.746615744535, 1156.859443558526, 1158.8261760735957, 1160.6227975907864, 1174.4656181437597, 783.9294463498312, 842.6713581703141, 848.4654667559398, 903.509209803829, 939.1969865764672, 5115.462876397999, 5555.2500412444615, 6038.762053778964, 6066.939330606726, 6180.845478862974, 997.1225321716988, 1022.3814592171777, 1034.9327293725885, 1053.2732414633879, 1086.8369744538381, 3747.269282489888, 3759.605241449663, 3857.40210267409, 3875.418687196887, 4072.714909956334, 10395.116415104801, 10671.35971841692, 10743.83884241737, 10779.838302425469, 10801.67594972152, 10702.197335199891, 10722.249442538947, 10738.570457181626, 10759.515821788038, 10768.030877220932, 6561.311062338481, 7905.683772649145, 8129.796755081052, 8351.707690230813, 8356.623951922178, 5668.149777533403, 5729.885402291848, 5765.279340082489, 5799.304083509837, 5937.821865344046, 9797.118259356692, 9998.800023999334, 10074.597016119273, 10079.848802267825, 10142.695719128438, 9637.469939153414, 10190.43952582615, 10321.535530900977, 10334.276600637882, 10345.896541034794, 6584.539082264196, 6810.931890681228, 6819.045333653194, 7045.823854403681, 7187.065681461377, 8089.919100809152, 8285.710000150208, 8415.368847336596, 8492.872606911025, 8507.149285071444, 7403.370713489414, 7496.437767167376, 7522.0, 7573.772786816405, 7669.156392796899, 7246.855062898818, 7275.345218930322, 7354.374878135419, 7370.6314684266645, 7455.291747283999, 6838.452923765987, 6991.5529564654, 6994.971458277246, 6997.930020699728, 7005.684744186626, 4875.1224779540025, 4922.803087876445, 5034.546890779848, 5070.543651071273, 5076.263941728354, 4835.395575553056, 4985.0, 5055.483354993414, 5160.48395160481, 5202.531772140525], "value": 4595.706694440351, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.7826219466498, 299.9655039670438, 308.3509213106412, 313.59702781925233, 316.96671849455794, 5050.520200580943, 5053.418856831466, 5103.081445339842, 5128.974355128219, 5152.458991805863, 4327.870163895081, 4781.617470602352, 4877.853704379477, 5012.471196285132, 5065.670731402456, 3698.7965661888584, 3896.032503585692, 3920.8039598019986, 3928.8821335359985, 3934.704897132712, 3948.2430318508277, 3960.4455376247347, 3965.702572307074, 3968.7618742875375, 3969.8610548630804, 3494.301139772043, 3534.876279330224, 3613.620569840168, 3926.1362500249897, 3941.1329507508303, 3038.6657467678538, 3687.225682606629, 3798.4682144499793, 3847.211321679049, 3876.6317199865966, 3188.8724451022163, 3230.660780618048, 3315.6187038490643, 3320.6513316101946, 3341.365140623298, 3883.563081659776, 4070.6946978976694, 4898.485659005789, 4940.357753492059, 4957.628177886637, 3584.8386822593047, 3819.350710379242, 4244.0, 4358.65130789536, 4373.825046998117, 4382.430284063052, 4928.67963582366, 5003.374578177728, 5263.605229607795, 5296.894062118775, 3374.156460884779, 3663.725220608465, 3667.8543052314426, 3675.209829886587, 3682.650148235913, 4943.9258411123865, 4951.665142037749, 5100.699321672987, 5322.33470816148, 5532.446755324436, 3091.7062879026457, 3708.2583483302906, 3717.1636381814415, 3723.678094276547, 3724.3854763964187, 3768.7550309229814, 3774.8678796241998, 3776.4146557283666, 3779.508663873679, 3799.297130030953, 3278.7868788528667, 4153.418521407006, 4409.250427427281, 4433.29067349229, 4445.533219011957, 4840.733759643183, 5171.450639023381, 5265.3944796348915, 5268.4994925482015, 5268.81559145428, 1226.9754604907853, 1266.7086570088932, 1274.3882936190496, 1286.9099163058495, 1288.6391810293132, 711.9181294151239, 758.3705524414722, 798.0503201190564, 804.1676864445271, 805.295366554265, 3983.0, 4048.5910883024553, 4051.642699695635, 4146.481689788777, 4206.810693518799, 4461.193216747222, 4528.932066019012, 4545.204589196877, 4567.990084362869, 4586.770661466913, 3813.121093574334, 3829.7702137871815, 4087.5678971012794, 4140.0, 4256.978715106405, 2111.546017606222, 2259.977400225977, 2348.6829278047244, 2408.7952524035304, 2414.855108693484, 4043.5754245804355, 4573.428321459817, 4682.885880889171, 4698.68436837686, 4702.95297047032, 3833.485773119658, 4467.955320446819, 4631.282151266491, 4632.768361581908, 4633.748887800275, 4361.672874534423, 4993.875153121128, 5307.708076055699, 5309.920351194735, 5545.584081193927, 5443.265159203457, 5444.292242008513, 5452.2466154591675, 5457.0, 5463.918041229305, 4721.551452611996, 5383.380911195262, 5416.458354164552, 5423.891522169613, 5437.537809286255, 4131.450705985256, 5497.367802702581, 5543.445655434424, 5665.461781130786, 5714.771409143754, 3727.235916637137, 3734.4398340248094, 3735.768560195466, 3736.468048100266, 3740.85036598533, 4949.208126699711, 5077.847664570068, 5392.811251606332, 5438.918416223681, 5468.0, 4493.550644935481, 5249.186376111708, 5313.973430132824, 5327.493888080626, 5334.866628334323, 4628.722276663277, 5008.974955125347, 5310.840674779763, 5325.600579956596, 5345.54562862161, 4568.146411630894, 5195.896082078413, 5199.948000519947, 5207.114790485551, 5224.634275600672, 5195.363245728081, 5249.9737501313775, 5284.524392804666, 5295.629305948547, 5321.36143662763, 5140.614453916046, 5154.1237989542615, 5192.584593232608, 5197.297405349122, 5230.895382092413], "value": 3756.5253153805797, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.6635731670141, 736.1828370508738, 748.4012789768184, 777.7977725791293, 805.3034125481461, 15870.52388428346, 16194.757078643801, 16195.433159839407, 16198.83801161988, 16230.834987177504, 14263.0, 14393.78409323861, 14394.280285985686, 14401.0, 14409.991300608961, 675.5845155229523, 700.6986995591893, 702.332783855337, 835.5446281776447, 855.2516548020482, 835.6532039203723, 856.3363393370157, 866.1122349591687, 870.6038752367689, 871.2681347667958, 924.865894445305, 925.4031149908305, 925.6898938855458, 928.642472648032, 928.9210417114554, 917.7934964632971, 919.3656377099813, 919.7332773495679, 920.1902325953151, 920.9263258939269, 440.2889333726009, 540.8323419739893, 578.9174244163399, 647.6308504152635, 653.7058323754283, 14923.253837308092, 14943.402263909431, 14954.850451495455, 14955.551333460013, 14962.775558366633, 13492.527761528398, 13960.441582336694, 14118.082324648865, 14152.716945661132, 14370.065945713495, 11260.099192064616, 12537.185082969578, 12784.360781960864, 12786.488540458371, 12802.48790048397, 4151.584841515854, 4162.334026555767, 4182.435371224876, 4340.045190058182, 4348.130373925196, 102490.97509024963, 103089.0, 103553.48223258911, 104128.0, 106669.0, 10612.0, 10772.946135269274, 10798.51406686701, 10917.945410272898, 11249.268797528135, 18720.063996800378, 20721.896390518254, 20801.479963000744, 21171.576568468543, 21201.15195392167, 7417.962910185414, 7512.586807725518, 7682.1549629540705, 7687.2697093776005, 7787.922120778833, 4367.0127795526905, 4484.618807401343, 4496.595306422438, 4645.651576131803, 4648.744319062416, 3596.6583174598372, 3746.7189960752507, 3748.0442487165597, 3753.5871054184017, 3754.887353379403, 681.5093132944371, 683.7982795075465, 702.3819039245432, 706.703184662443, 707.1620130145793, 597.6892016151577, 619.170311782214, 621.2079598511933, 689.582802404548, 696.9405407022238, 9065.456072635663, 9444.338896277195, 9444.386114902509, 9470.933050817779, 9507.191888689538, 651.8859199640139, 669.6250099944046, 683.8222062263848, 686.2931180883697, 689.217737867524, 3913.745606535566, 4027.1140349123148, 4348.217320882209, 4640.767961601907, 4674.766261686902, 139315.0, 139383.30308348394, 140675.0, 142965.0, 143392.28303858623, 86666.56666716626, 86805.565972171, 87164.6925296121, 124937.0, 138664.0, 92115.0, 115229.0, 116366.0, 117416.0, 119538.0, 8845.0, 9027.774305642411, 9061.68284110053, 9076.909230907606, 9329.860052099088, 57968.420315796306, 66007.0, 66225.0, 67176.0, 69391.30608693848, 73550.48371372276, 82182.58908705418, 91821.0, 92314.0, 95543.52228239093, 12943.935280323538, 13488.0, 13510.527131550349, 13581.592552223448, 13710.17738935667, 14909.0, 15021.624459388602, 15030.0, 15252.0, 15682.372705091668, 13766.587002389944, 13810.516631917835, 14005.299735013004, 14219.004669673024, 14284.500382379518, 12016.519339226334, 12380.81428778551, 13076.044052555479, 13318.667033324244, 14268.785968210277, 13688.178709277476, 13719.382627781573, 13748.175109493062, 13777.173369597856, 14331.570052898429, 10828.458577071273, 11166.38584877807, 11427.542898283975, 11682.70793230142, 11727.706807329885, 10319.432431216355, 11016.614418495314, 11192.608258710907, 11272.380019099028, 11403.885961140282], "value": 7790.787929280091, "unit": "score"}], "name": "dromaeo_css", "value": 7170.730132345969, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 111.0, 114.0, 114.0, 113.0, 116.0, 163.0, 167.0, 113.0], "value": 123.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 110.0, 113.0, 148.0, 113.0, 114.0, 126.0, 101.0, 173.0, 140.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 188.0, 186.0, 183.0, 182.0, 180.0, 183.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 90.0, 65.0, 61.0, 58.0, 59.0, 79.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 91.0, 70.0, 68.0, 69.0, 92.0, 66.0, 91.0, 116.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 90.0, 86.0, 81.0, 115.0, 137.0, 86.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 151.0, 152.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 107.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 130.0, 95.0, 84.0, 84.0, 85.0, 83.0, 86.0, 85.0, 85.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 56.0, 56.0, 55.0, 56.0, 55.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 71.0, 75.0, 71.0, 70.0, 68.0, 70.0, 69.0, 69.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 86.0, 89.0, 86.0, 89.0, 91.0, 89.0, 91.0, 129.0], "value": 93.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 148.0, 152.0, 157.0, 141.0, 139.0, 154.0, 157.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 171.0, 172.0, 172.0, 172.0, 174.0, 226.0, 230.0, 171.0], "value": 183.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 83.0, 87.0, 73.0, 68.0, 68.0, 69.0, 67.0, 65.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1504.8999999999999, "alertThreshold": 2.0}]} 08:07:58 INFO - Return code: 0 08:07:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:07:58 INFO - # TBPL SUCCESS # 08:07:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:07:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:07:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:07:58 INFO - 2016-10-01 08:07:58.507 screenresolution[1677:10757] starting screenresolution argv=screenresolution get 08:07:58 INFO - 2016-10-01 08:07:58.509 screenresolution[1677:10757] Display 0: 1600x1200x32@60 08:07:58 INFO - 2016-10-01 08:07:58.515 screenresolution[1678:10760] starting screenresolution argv=screenresolution list 08:07:58 INFO - Available Modes on Display 0 08:07:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:07:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:07:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:07:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:07:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:07:58 INFO - Intel Iris: 08:07:58 INFO - Chipset Model: Intel Iris 08:07:58 INFO - Type: GPU 08:07:58 INFO - Bus: Built-In 08:07:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:07:58 INFO - Vendor: Intel (0x8086) 08:07:58 INFO - Device ID: 0x0a2e 08:07:58 INFO - Revision ID: 0x0009 08:07:58 INFO - Displays: 08:07:58 INFO - Display: 08:07:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:07:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:07:58 INFO - Display Serial Number: 1600x1200 60 08:07:58 INFO - Main Display: Yes 08:07:58 INFO - Mirror: Off 08:07:58 INFO - Online: Yes 08:07:58 INFO - Rotation: Supported 08:07:58 INFO - Return code: 0 08:07:58 INFO - Running post-action listener: _resource_record_post_action 08:07:58 INFO - [mozharness: 2016-10-01 15:07:58.752820Z] Finished run-tests step (success) 08:07:58 INFO - Running post-run listener: _resource_record_post_run 08:07:58 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 32037888; Write bytes: 707309056; Read time: 200; Write time: 1968 08:07:58 INFO - TinderboxPrint: CPU usage
53.4% 08:07:58 INFO - TinderboxPrint: I/O read bytes / time
32,037,888 / 200 08:07:58 INFO - TinderboxPrint: I/O write bytes / time
707,309,056 / 1,968 08:07:58 INFO - TinderboxPrint: CPU idle
2,263.6 (46.6%) 08:07:58 INFO - TinderboxPrint: CPU system
968.4 (19.9%) 08:07:58 INFO - TinderboxPrint: CPU user
1,625.7 (33.5%) 08:07:58 INFO - TinderboxPrint: Swap in / out
450,285,568 / 0 08:07:58 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387475968; Write bytes: 381990400; Read time: 29316; Write time: 786 08:07:58 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31108096; Write bytes: 296818688; Read time: 188; Write time: 1116 08:07:59 INFO - Running post-run listener: _upload_blobber_files 08:07:59 INFO - Blob upload gear active. 08:07:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:07:59 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 08:07:59 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'] 08:07:59 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 08:07:59 INFO - (blobuploader) - INFO - Open directory for files ... 08:07:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:07:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:07:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:08:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:08:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:08:00 INFO - (blobuploader) - INFO - Done attempting. 08:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:08:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:08:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:08:03 INFO - (blobuploader) - INFO - Done attempting. 08:08:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:08:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:08:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:08:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:08:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:08:05 INFO - (blobuploader) - INFO - Done attempting. 08:08:05 INFO - (blobuploader) - INFO - Iteration through files over. 08:08:05 INFO - Return code: 0 08:08:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:08:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:08:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33928abf751839daaa7f1376468c38b41143ff32b4d2d12d370b336c462df0b6580ca093b9f65fcebc3bd20b1d0999999fdad00e5e7f3379c5921f2b3dee5863", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89f96db2d85fd2955e7180380b4f242ba839c8bfee49f5c59494083c66f91db86bffac6bf957cf3649e883f9ae5ec307c315a9d8b7e41858e1e1aee34b81ac46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3675b38e763a89375f2d98b327d91c4805d0bd03ca2176664667efa6a56ff381306c081e18084f594b1fe4287e56f6f7e7a945fa97f5e8ce8058e7d6dd99fb0d"} 08:08:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:08:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:08:05 INFO - Contents: 08:08:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33928abf751839daaa7f1376468c38b41143ff32b4d2d12d370b336c462df0b6580ca093b9f65fcebc3bd20b1d0999999fdad00e5e7f3379c5921f2b3dee5863", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89f96db2d85fd2955e7180380b4f242ba839c8bfee49f5c59494083c66f91db86bffac6bf957cf3649e883f9ae5ec307c315a9d8b7e41858e1e1aee34b81ac46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3675b38e763a89375f2d98b327d91c4805d0bd03ca2176664667efa6a56ff381306c081e18084f594b1fe4287e56f6f7e7a945fa97f5e8ce8058e7d6dd99fb0d"} 08:08:05 INFO - Running post-run listener: copy_logs_to_upload_dir 08:08:05 INFO - Copying logs to upload dir... 08:08:05 INFO - mkdir: /builds/slave/test/build/upload/logs 08:08:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.447486 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-01 08:08:05.939218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 08:08:05.943471) ========= 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.uSx9Lfosxd/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.rbUESadM1e/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33928abf751839daaa7f1376468c38b41143ff32b4d2d12d370b336c462df0b6580ca093b9f65fcebc3bd20b1d0999999fdad00e5e7f3379c5921f2b3dee5863", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89f96db2d85fd2955e7180380b4f242ba839c8bfee49f5c59494083c66f91db86bffac6bf957cf3649e883f9ae5ec307c315a9d8b7e41858e1e1aee34b81ac46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3675b38e763a89375f2d98b327d91c4805d0bd03ca2176664667efa6a56ff381306c081e18084f594b1fe4287e56f6f7e7a945fa97f5e8ce8058e7d6dd99fb0d"} build_url:https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010954 build_url: 'https://queue.taskcluster.net/v1/task/RjCM3d1zS6KfhCiAVaqtEg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33928abf751839daaa7f1376468c38b41143ff32b4d2d12d370b336c462df0b6580ca093b9f65fcebc3bd20b1d0999999fdad00e5e7f3379c5921f2b3dee5863", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89f96db2d85fd2955e7180380b4f242ba839c8bfee49f5c59494083c66f91db86bffac6bf957cf3649e883f9ae5ec307c315a9d8b7e41858e1e1aee34b81ac46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3675b38e763a89375f2d98b327d91c4805d0bd03ca2176664667efa6a56ff381306c081e18084f594b1fe4287e56f6f7e7a945fa97f5e8ce8058e7d6dd99fb0d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 08:08:05.976603) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 08:08:05.977010) ========= 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.uSx9Lfosxd/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.rbUESadM1e/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.005133 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 08:08:06.025956) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 08:08:06.026295) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 08:08:06.026670) ========= ========= Total master_lag: 0.20 =========