builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0282 starttime: 1496324636.18 results: success (0) revision: d96110d766199d42524558fd83e6467906de4daf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:56.183957) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:56.184431) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:56.184709) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:56.207239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:56.207716) ========= 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.vocumtOMNf/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.ScWIR64ElJ/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 06:43:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-06-01 06:43:56 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.367380 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:56.599326) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:56.599895) ========= 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.vocumtOMNf/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.ScWIR64ElJ/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.027224 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:56.649043) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 06:43:56.649408) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d96110d766199d42524558fd83e6467906de4daf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev d96110d766199d42524558fd83e6467906de4daf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vocumtOMNf/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.ScWIR64ElJ/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 06:43:56,747 truncating revision to first 12 chars 2017-06-01 06:43:56,747 Setting DEBUG logging. 2017-06-01 06:43:56,747 attempt 1/10 2017-06-01 06:43:56,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d96110d76619?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 06:43:58,067 unpacking tar archive at: mozilla-central-d96110d76619/testing/mozharness/ program finished with exit code 0 elapsedTime=1.603361 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 06:43:58.275893) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:58.276460) ========= script_repo_revision: d96110d766199d42524558fd83e6467906de4daf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:58.277070) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:58.277540) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 06:43:58.298348) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-01 06:43:58.299030) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vocumtOMNf/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.ScWIR64ElJ/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:43:58 INFO - MultiFileLogger online at 20170601 06:43:58 in /builds/slave/test 06:43:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:43:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:43:58 INFO - {'append_to_log': False, 06:43:58 INFO - 'base_work_dir': '/builds/slave/test', 06:43:58 INFO - 'blob_upload_branch': 'Firefox', 06:43:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:43:58 INFO - 'branch': 'Firefox', 06:43:58 INFO - 'buildbot_json_path': 'buildprops.json', 06:43:58 INFO - 'config_files': ('talos/mac_config.py',), 06:43:58 INFO - 'default_actions': ('clobber', 06:43:58 INFO - 'read-buildbot-config', 06:43:58 INFO - 'download-and-extract', 06:43:58 INFO - 'populate-webroot', 06:43:58 INFO - 'create-virtualenv', 06:43:58 INFO - 'install', 06:43:58 INFO - 'setup-mitmproxy', 06:43:58 INFO - 'run-tests'), 06:43:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:43:58 INFO - 'download_minidump_stackwalk': True, 06:43:58 INFO - 'download_symbols': 'ondemand', 06:43:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:43:58 INFO - 'tooltool.py': '/tools/tooltool.py', 06:43:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:43:58 INFO - '/tools/misc-python/virtualenv.py')}, 06:43:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:43:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:43:58 INFO - 'gecko_profile': False, 06:43:58 INFO - 'gecko_profile_interval': 0, 06:43:58 INFO - 'installer_path': 'installer.exe', 06:43:58 INFO - 'log_level': 'info', 06:43:58 INFO - 'log_name': 'talos', 06:43:58 INFO - 'log_to_console': True, 06:43:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:43:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:43:58 INFO - 'opt_config_files': (), 06:43:58 INFO - 'pip_index': False, 06:43:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:43:58 INFO - 'cmd': ('bash', 06:43:58 INFO - '-c', 06:43:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:43:58 INFO - 'enabled': True, 06:43:58 INFO - 'halt_on_failure': False, 06:43:58 INFO - 'name': 'check_screen_resolution'},), 06:43:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:43:58 INFO - 'cmd': ('bash', 06:43:58 INFO - '-c', 06:43:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:43:58 INFO - 'enabled': True, 06:43:58 INFO - 'halt_on_failure': False, 06:43:58 INFO - 'name': 'check_screen_resolution'},), 06:43:58 INFO - 'run_cmd_checks_enabled': True, 06:43:58 INFO - 'suite': 'dromaeojs-e10s', 06:43:58 INFO - 'system_bits': '32', 06:43:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:43:58 INFO - 'title': 't-yosemite-r7-0282', 06:43:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:43:58 INFO - 'use_talos_json': True, 06:43:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:43:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:43:58 INFO - 'work_dir': 'build'} 06:43:58 INFO - [mozharness: 2017-06-01 13:43:58.509551Z] Running clobber step. 06:43:58 INFO - Running pre-action listener: _resource_record_pre_action 06:43:58 INFO - Running main action method: clobber 06:43:58 INFO - rmtree: /builds/slave/test/build 06:43:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:44:00 INFO - Running post-action listener: _resource_record_post_action 06:44:00 INFO - [mozharness: 2017-06-01 13:44:00.848928Z] Finished clobber step (success) 06:44:00 INFO - [mozharness: 2017-06-01 13:44:00.849256Z] Running read-buildbot-config step. 06:44:00 INFO - Running pre-action listener: _resource_record_pre_action 06:44:00 INFO - Running main action method: read_buildbot_config 06:44:00 INFO - Using buildbot properties: 06:44:00 INFO - { 06:44:00 INFO - "project": "", 06:44:00 INFO - "product": "firefox", 06:44:00 INFO - "who": "cbook@mozilla.com", 06:44:00 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 06:44:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:44:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:44:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:44:00 INFO - "stage_platform": "macosx64", 06:44:00 INFO - "basedir": "/builds/slave/test", 06:44:00 INFO - "buildnumber": 92, 06:44:00 INFO - "platform": "yosemite_r7", 06:44:00 INFO - "parent_task_id": "fD3wKP0GRTarI3Vbx8whLw", 06:44:00 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:44:00 INFO - "slavebuilddir": "test", 06:44:00 INFO - "taskId": "dchYNNazQ-WLQgAk1VcAow", 06:44:00 INFO - "branch": "mozilla-central", 06:44:00 INFO - "script_repo_revision": "production", 06:44:00 INFO - "revision": "d96110d766199d42524558fd83e6467906de4daf", 06:44:00 INFO - "slavename": "t-yosemite-r7-0282", 06:44:00 INFO - "repo_path": "mozilla-central" 06:44:00 INFO - } 06:44:00 INFO - Finding installer, test and symbols from parent task. 06:44:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dchYNNazQ-WLQgAk1VcAow'}, attempt #1 06:44:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dchYNNazQ-WLQgAk1VcAow'}, attempt #1 06:44:02 INFO - Task dependencies: EgwWJyTbR5ut9BL19KImow fD3wKP0GRTarI3Vbx8whLw 06:44:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw'}, attempt #1 06:44:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:44:02 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/test_packages.json 06:44:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/target.crashreporter-symbols.zip 06:44:02 INFO - Running post-action listener: _resource_record_post_action 06:44:02 INFO - [mozharness: 2017-06-01 13:44:02.618988Z] Finished read-buildbot-config step (success) 06:44:02 INFO - [mozharness: 2017-06-01 13:44:02.619130Z] Running download-and-extract step. 06:44:02 INFO - Running pre-action listener: _resource_record_pre_action 06:44:02 INFO - Running main action method: download_and_extract 06:44:02 INFO - mkdir: /builds/slave/test/build/tests 06:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:02 INFO - trying https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 06:44:02 INFO - Downloading https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:44:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 06:44:03 INFO - Downloaded 1325 bytes. 06:44:03 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:44:03 INFO - Using the following test package requirements: 06:44:03 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 06:44:03 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:44:03 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 06:44:03 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 06:44:03 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'jsshell-mac.zip'], 06:44:03 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 06:44:03 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:44:03 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 06:44:03 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 06:44:03 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 06:44:03 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:44:03 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 06:44:03 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:44:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:44:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:44:03 INFO - Fetch https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:44:05 INFO - Content-Length response header: 57630026 06:44:05 INFO - Bytes received: 57630026 06:44:23 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:44:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:44:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:44:23 INFO - Fetch https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:44:25 INFO - Content-Length response header: 57630026 06:44:25 INFO - Bytes received: 57630026 06:44:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 06:44:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:44:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 06:44:43 INFO - Content-Length response header: 14447425 06:44:43 INFO - Bytes received: 14447425 06:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:43 INFO - trying https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:44:43 INFO - Downloading https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 06:44:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 06:44:46 INFO - Downloaded 67862986 bytes. 06:44:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:44:46 INFO - mkdir: /builds/slave/test/properties 06:44:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:44:46 INFO - Writing to file /builds/slave/test/properties/build_url 06:44:46 INFO - Contents: 06:44:46 INFO - build_url:https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:44:46 INFO - Running post-action listener: _resource_record_post_action 06:44:46 INFO - Running post-action listener: set_extra_try_arguments 06:44:46 INFO - [mozharness: 2017-06-01 13:44:46.008157Z] Finished download-and-extract step (success) 06:44:46 INFO - [mozharness: 2017-06-01 13:44:46.008313Z] Running populate-webroot step. 06:44:46 INFO - Running pre-action listener: _resource_record_pre_action 06:44:46 INFO - Running main action method: populate_webroot 06:44:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:44:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:44:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:44:46 INFO - '--tppagecycles', 06:44:46 INFO - '7'], 06:44:46 INFO - 'tests': ['tsvgm']}}, 06:44:46 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:44:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:44:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:44:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:44:46 INFO - 'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:44:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:44:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:44:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:44:46 INFO - 'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['damp', 'tps']}, 06:44:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:44:46 INFO - 'tests': ['damp', 'tps']}, 06:44:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['dromaeo_dom']}, 06:44:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:44:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:44:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:44:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:44:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:44:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['a11yr', 06:44:46 INFO - 'ts_paint', 06:44:46 INFO - 'tpaint', 06:44:46 INFO - 'sessionrestore', 06:44:46 INFO - 'sessionrestore_no_auto_restore', 06:44:46 INFO - 'tabpaint']}, 06:44:46 INFO - 'other-e10s': {'tests': ['a11yr', 06:44:46 INFO - 'ts_paint', 06:44:46 INFO - 'tpaint', 06:44:46 INFO - 'sessionrestore', 06:44:46 INFO - 'sessionrestore_no_auto_restore', 06:44:46 INFO - 'tabpaint']}, 06:44:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:44:46 INFO - 'ts_paint', 06:44:46 INFO - 'tpaint', 06:44:46 INFO - 'sessionrestore', 06:44:46 INFO - 'sessionrestore_no_auto_restore', 06:44:46 INFO - 'tabpaint']}, 06:44:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:44:46 INFO - 'ts_paint', 06:44:46 INFO - 'tpaint', 06:44:46 INFO - 'sessionrestore', 06:44:46 INFO - 'sessionrestore_no_auto_restore', 06:44:46 INFO - 'tabpaint']}, 06:44:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['a11yr', 06:44:46 INFO - 'ts_paint', 06:44:46 INFO - 'tpaint', 06:44:46 INFO - 'sessionrestore', 06:44:46 INFO - 'sessionrestore_no_auto_restore', 06:44:46 INFO - 'tabpaint']}, 06:44:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['a11yr', 06:44:46 INFO - 'ts_paint', 06:44:46 INFO - 'tpaint', 06:44:46 INFO - 'sessionrestore', 06:44:46 INFO - 'sessionrestore_no_auto_restore', 06:44:46 INFO - 'tabpaint']}, 06:44:46 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:44:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:44:46 INFO - 'bloom_basic_ref']}, 06:44:46 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 06:44:46 INFO - 'talos_options': ['--mitmproxy', 06:44:46 INFO - 'mitmproxy-recording-1.mp', 06:44:46 INFO - '--firstNonBlankPaint'], 06:44:46 INFO - 'tests': ['Quantum_1']}, 06:44:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['tsvgx', 06:44:46 INFO - 'tsvgr_opacity', 06:44:46 INFO - 'tart', 06:44:46 INFO - 'tscrollx', 06:44:46 INFO - 'cart', 06:44:46 INFO - 'tsvg_static']}, 06:44:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:44:46 INFO - 'tsvgr_opacity', 06:44:46 INFO - 'tart', 06:44:46 INFO - 'tscrollx', 06:44:46 INFO - 'cart', 06:44:46 INFO - 'tsvg_static']}, 06:44:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:44:46 INFO - 'talos_options': ['--disable-e10s'], 06:44:46 INFO - 'tests': ['tp5o']}, 06:44:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:44:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:44:46 INFO - 'talos_options': ['--disable-e10s', 06:44:46 INFO - '--xperf_path', 06:44:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:44:46 INFO - 'tests': ['tp5n']}, 06:44:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:44:46 INFO - 'talos_options': ['--xperf_path', 06:44:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:44:46 INFO - 'tests': ['tp5n']}}, 06:44:46 INFO - 'talos.zip': {'path': '', 06:44:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:44:46 INFO - Running post-action listener: _resource_record_post_action 06:44:46 INFO - [mozharness: 2017-06-01 13:44:46.030176Z] Finished populate-webroot step (success) 06:44:46 INFO - [mozharness: 2017-06-01 13:44:46.030313Z] Running create-virtualenv step. 06:44:46 INFO - Running pre-action listener: _resource_record_pre_action 06:44:46 INFO - Running main action method: create_virtualenv 06:44:46 INFO - Creating virtualenv /builds/slave/test/build/venv 06:44:46 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 06:44:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:44:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:44:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:44:46 INFO - Using real prefix '/tools/python27' 06:44:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:44:46 INFO - Installing distribute.............................................................................................................................................................................................done. 06:44:49 INFO - Installing pip.................done. 06:44:49 INFO - Return code: 0 06:44:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:44:50 INFO - Reading from file tmpfile_stdout 06:44:50 INFO - Output received: 06:44:50 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) 06:44:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:50 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:44:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 06:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:44:50 INFO - 'HOME': '/Users/cltbld', 06:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:44:50 INFO - 'LOGNAME': 'cltbld', 06:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:50 INFO - 'MOZ_NO_REMOTE': '1', 06:44:50 INFO - 'NO_EM_RESTART': '1', 06:44:50 INFO - 'PAGER': '/bin/cat', 06:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:44:50 INFO - 'PWD': '/builds/slave/test', 06:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:44:50 INFO - 'SHELL': '/bin/bash', 06:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:44:50 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:44:50 INFO - 'USER': 'cltbld', 06:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:50 INFO - 'XPC_FLAGS': '0x0', 06:44:50 INFO - 'XPC_SERVICE_NAME': '0', 06:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:50 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 06:44:50 INFO - Cleaning up... 06:44:50 INFO - Return code: 0 06:44:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:50 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:44:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 06:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:44:50 INFO - 'HOME': '/Users/cltbld', 06:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:44:50 INFO - 'LOGNAME': 'cltbld', 06:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:50 INFO - 'MOZ_NO_REMOTE': '1', 06:44:50 INFO - 'NO_EM_RESTART': '1', 06:44:50 INFO - 'PAGER': '/bin/cat', 06:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:44:50 INFO - 'PWD': '/builds/slave/test', 06:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:44:50 INFO - 'SHELL': '/bin/bash', 06:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:44:50 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:44:50 INFO - 'USER': 'cltbld', 06:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:50 INFO - 'XPC_FLAGS': '0x0', 06:44:50 INFO - 'XPC_SERVICE_NAME': '0', 06:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:50 INFO - Downloading/unpacking psutil>=3.1.1 06:44:50 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 06:44:50 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 06:44:50 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 06:44:50 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 06:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:44:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:44:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:44:52 INFO - Installing collected packages: psutil 06:44:52 INFO - Running setup.py install for psutil 06:44:52 INFO - building 'psutil._psutil_osx' extension 06:44:52 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 06:44:53 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 06:44:53 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 06:44:53 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 06:44:53 INFO - building 'psutil._psutil_posix' extension 06:44:53 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 06:44:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:44:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:44:53 INFO - ^ 06:44:53 INFO - 1 warning generated. 06:44:53 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 06:44:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:44:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:44:53 INFO - Successfully installed psutil 06:44:53 INFO - Cleaning up... 06:44:53 INFO - Return code: 0 06:44:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:54 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 06:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:44:54 INFO - 'HOME': '/Users/cltbld', 06:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:44:54 INFO - 'LOGNAME': 'cltbld', 06:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:54 INFO - 'MOZ_NO_REMOTE': '1', 06:44:54 INFO - 'NO_EM_RESTART': '1', 06:44:54 INFO - 'PAGER': '/bin/cat', 06:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:44:54 INFO - 'PWD': '/builds/slave/test', 06:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:44:54 INFO - 'SHELL': '/bin/bash', 06:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:44:54 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:44:54 INFO - 'USER': 'cltbld', 06:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:54 INFO - 'XPC_FLAGS': '0x0', 06:44:54 INFO - 'XPC_SERVICE_NAME': '0', 06:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:44:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:44:56 INFO - Installing collected packages: mozsystemmonitor 06:44:56 INFO - Running setup.py install for mozsystemmonitor 06:44:56 INFO - Successfully installed mozsystemmonitor 06:44:56 INFO - Cleaning up... 06:44:56 INFO - Return code: 0 06:44:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:56 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:44:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 06:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:44:56 INFO - 'HOME': '/Users/cltbld', 06:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:44:56 INFO - 'LOGNAME': 'cltbld', 06:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:56 INFO - 'MOZ_NO_REMOTE': '1', 06:44:56 INFO - 'NO_EM_RESTART': '1', 06:44:56 INFO - 'PAGER': '/bin/cat', 06:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:44:56 INFO - 'PWD': '/builds/slave/test', 06:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:44:56 INFO - 'SHELL': '/bin/bash', 06:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:44:56 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:44:56 INFO - 'USER': 'cltbld', 06:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:56 INFO - 'XPC_FLAGS': '0x0', 06:44:56 INFO - 'XPC_SERVICE_NAME': '0', 06:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:57 INFO - Downloading/unpacking jsonschema==2.5.1 06:44:57 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 06:44:57 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 06:44:57 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 06:44:57 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 06:44:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:44:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:44:58 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 06:44:58 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 06:44:58 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 06:44:58 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 06:44:58 INFO - Downloading functools32-3.2.3-2.tar.gz 06:44:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:44:58 INFO - warning: no files found matching '*.txt' 06:44:58 INFO - no previously-included directories found matching 'build' 06:44:58 INFO - no previously-included directories found matching 'dist' 06:44:58 INFO - no previously-included directories found matching '.git*' 06:44:58 INFO - Installing collected packages: jsonschema, functools32 06:44:58 INFO - Running setup.py install for functools32 06:44:59 INFO - warning: no files found matching '*.txt' 06:44:59 INFO - no previously-included directories found matching 'build' 06:44:59 INFO - no previously-included directories found matching 'dist' 06:44:59 INFO - no previously-included directories found matching '.git*' 06:44:59 INFO - Successfully installed jsonschema functools32 06:44:59 INFO - Cleaning up... 06:44:59 INFO - Return code: 0 06:44:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:59 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 06:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:44:59 INFO - 'HOME': '/Users/cltbld', 06:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:44:59 INFO - 'LOGNAME': 'cltbld', 06:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:59 INFO - 'MOZ_NO_REMOTE': '1', 06:44:59 INFO - 'NO_EM_RESTART': '1', 06:44:59 INFO - 'PAGER': '/bin/cat', 06:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:44:59 INFO - 'PWD': '/builds/slave/test', 06:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:44:59 INFO - 'SHELL': '/bin/bash', 06:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:44:59 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:44:59 INFO - 'USER': 'cltbld', 06:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:59 INFO - 'XPC_FLAGS': '0x0', 06:44:59 INFO - 'XPC_SERVICE_NAME': '0', 06:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:44:59 INFO - Cleaning up... 06:44:59 INFO - Return code: 0 06:44:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:44:59 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 06:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:44:59 INFO - 'HOME': '/Users/cltbld', 06:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:44:59 INFO - 'LOGNAME': 'cltbld', 06:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:44:59 INFO - 'MOZ_NO_REMOTE': '1', 06:44:59 INFO - 'NO_EM_RESTART': '1', 06:44:59 INFO - 'PAGER': '/bin/cat', 06:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:44:59 INFO - 'PWD': '/builds/slave/test', 06:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:44:59 INFO - 'SHELL': '/bin/bash', 06:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:44:59 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:44:59 INFO - 'USER': 'cltbld', 06:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:44:59 INFO - 'XPC_FLAGS': '0x0', 06:44:59 INFO - 'XPC_SERVICE_NAME': '0', 06:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:44:59 INFO - Downloading/unpacking blobuploader==1.2.4 06:44:59 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 06:44:59 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 06:44:59 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 06:44:59 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 06:45:01 INFO - Downloading blobuploader-1.2.4.tar.gz 06:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:45:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:45:01 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 06:45:01 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 06:45:01 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 06:45:01 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 06:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:45:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:45:01 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 06:45:01 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 06:45:01 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 06:45:01 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 06:45:01 INFO - Downloading docopt-0.6.1.tar.gz 06:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:45:02 INFO - Installing collected packages: blobuploader, requests, docopt 06:45:02 INFO - Running setup.py install for blobuploader 06:45:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:45:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:45:02 INFO - Running setup.py install for requests 06:45:02 INFO - Running setup.py install for docopt 06:45:02 INFO - Successfully installed blobuploader requests docopt 06:45:02 INFO - Cleaning up... 06:45:02 INFO - Return code: 0 06:45:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:02 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:45:02 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:45:02 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:45:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:45:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:45:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:45:02 INFO - 'HOME': '/Users/cltbld', 06:45:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:45:02 INFO - 'LOGNAME': 'cltbld', 06:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:02 INFO - 'MOZ_NO_REMOTE': '1', 06:45:02 INFO - 'NO_EM_RESTART': '1', 06:45:02 INFO - 'PAGER': '/bin/cat', 06:45:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:45:02 INFO - 'PWD': '/builds/slave/test', 06:45:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:45:02 INFO - 'SHELL': '/bin/bash', 06:45:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:45:02 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:45:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:45:02 INFO - 'USER': 'cltbld', 06:45:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:45:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:02 INFO - 'XPC_FLAGS': '0x0', 06:45:02 INFO - 'XPC_SERVICE_NAME': '0', 06:45:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gi2cxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-FDK7Qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-7MFvqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-7bpkV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-pgTse5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-32BkTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gkSw7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-3xeXTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:45:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Avm6f9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:45:04 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-S7PtAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:45:04 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-thMklD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:45:04 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-rShY8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:45:04 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-QHg9aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:45:04 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-w6r2Ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:45:04 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-PG_pPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:45:04 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-sPCOmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:45:04 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-XrBIOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:45:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:45:04 INFO - Running setup.py install for manifestparser 06:45:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:45:05 INFO - Running setup.py install for mozcrash 06:45:05 INFO - Running setup.py install for mozdebug 06:45:05 INFO - Running setup.py install for mozdevice 06:45:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:45:05 INFO - Running setup.py install for mozfile 06:45:05 INFO - Running setup.py install for mozhttpd 06:45:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:45:05 INFO - Running setup.py install for mozinfo 06:45:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:45:05 INFO - Running setup.py install for mozInstall 06:45:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Running setup.py install for mozleak 06:45:06 INFO - Running setup.py install for mozlog 06:45:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Running setup.py install for moznetwork 06:45:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Running setup.py install for mozprocess 06:45:06 INFO - Running setup.py install for mozprofile 06:45:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:45:06 INFO - Running setup.py install for mozrunner 06:45:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:45:07 INFO - Running setup.py install for mozscreenshot 06:45:07 INFO - Running setup.py install for moztest 06:45:07 INFO - Running setup.py install for mozversion 06:45:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:45:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:45:07 INFO - Cleaning up... 06:45:07 INFO - Return code: 0 06:45:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:07 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:45:07 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:45:07 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:45:07 INFO - 'HOME': '/Users/cltbld', 06:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:45:07 INFO - 'LOGNAME': 'cltbld', 06:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:07 INFO - 'MOZ_NO_REMOTE': '1', 06:45:07 INFO - 'NO_EM_RESTART': '1', 06:45:07 INFO - 'PAGER': '/bin/cat', 06:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:45:07 INFO - 'PWD': '/builds/slave/test', 06:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:45:07 INFO - 'SHELL': '/bin/bash', 06:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:45:07 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:45:07 INFO - 'USER': 'cltbld', 06:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:07 INFO - 'XPC_FLAGS': '0x0', 06:45:07 INFO - 'XPC_SERVICE_NAME': '0', 06:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:45:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gvVOVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:45:07 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)) 06:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:45:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-7Rjt9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:45:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-YW9vw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:45:08 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-hx9sfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-WNHbWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:45:08 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-COgxxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:45:08 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Trt39s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:45:08 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-saLE2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:45:08 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-X0I1J2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:45:08 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-upwNWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:45:08 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-3BP8G5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:45:08 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)) 06:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:45:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-nhQ1Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:45:09 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-qpcDwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:45:09 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)) 06:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:45:09 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-VBvewG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:45:09 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-qfhuf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:45:09 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)) 06:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:45:09 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-vACum4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:45:09 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-KjtKgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:45:09 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)) 06:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:45:09 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)) 06:45:09 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:45:09 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:45:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:45:09 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 06:45:09 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 06:45:09 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 06:45:09 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 06:45:10 INFO - Downloading blessings-1.6.tar.gz 06:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:45:11 INFO - Installing collected packages: blessings 06:45:11 INFO - Running setup.py install for blessings 06:45:11 INFO - Successfully installed blessings 06:45:11 INFO - Cleaning up... 06:45:11 INFO - Return code: 0 06:45:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:45:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:45:11 INFO - Reading from file tmpfile_stdout 06:45:11 INFO - Current package versions: 06:45:11 INFO - blessings == 1.6 06:45:11 INFO - blobuploader == 1.2.4 06:45:11 INFO - docopt == 0.6.1 06:45:11 INFO - functools32 == 3.2.3-2 06:45:11 INFO - jsonschema == 2.5.1 06:45:11 INFO - manifestparser == 1.1 06:45:11 INFO - mozInstall == 1.12 06:45:11 INFO - mozcrash == 1.0 06:45:11 INFO - mozdebug == 0.1 06:45:11 INFO - mozdevice == 0.51 06:45:11 INFO - mozfile == 1.2 06:45:11 INFO - mozhttpd == 0.7 06:45:11 INFO - mozinfo == 0.9 06:45:11 INFO - mozleak == 0.1 06:45:11 INFO - mozlog == 3.4 06:45:11 INFO - moznetwork == 0.27 06:45:11 INFO - mozprocess == 0.25 06:45:11 INFO - mozprofile == 0.28 06:45:11 INFO - mozrunner == 6.13 06:45:11 INFO - mozscreenshot == 0.1 06:45:11 INFO - mozsystemmonitor == 0.3 06:45:11 INFO - moztest == 0.8 06:45:11 INFO - mozversion == 1.4 06:45:11 INFO - psutil == 3.1.1 06:45:11 INFO - requests == 1.2.3 06:45:11 INFO - wsgiref == 0.1.2 06:45:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:11 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:45:11 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:45:11 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:45:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:45:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:45:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:45:11 INFO - 'HOME': '/Users/cltbld', 06:45:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:45:11 INFO - 'LOGNAME': 'cltbld', 06:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:11 INFO - 'MOZ_NO_REMOTE': '1', 06:45:11 INFO - 'NO_EM_RESTART': '1', 06:45:11 INFO - 'PAGER': '/bin/cat', 06:45:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:45:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:45:11 INFO - 'PWD': '/builds/slave/test', 06:45:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:45:11 INFO - 'SHELL': '/bin/bash', 06:45:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:45:11 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:45:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:45:11 INFO - 'USER': 'cltbld', 06:45:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:45:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:11 INFO - 'XPC_FLAGS': '0x0', 06:45:11 INFO - 'XPC_SERVICE_NAME': '0', 06:45:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:11 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)) 06:45:11 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)) 06:45:11 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)) 06:45:11 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)) 06:45:11 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)) 06:45:11 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)) 06:45:11 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)) 06:45:11 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)) 06:45:11 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)) 06:45:11 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:45:11 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 06:45:11 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 06:45:11 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 06:45:11 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 06:45:13 INFO - Downloading marionette_driver-1.4.0.tar.gz 06:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 06:45:13 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)) 06:45:13 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)) 06:45:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:45:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:45:13 INFO - Installing collected packages: marionette-driver 06:45:13 INFO - Running setup.py install for marionette-driver 06:45:13 INFO - Successfully installed marionette-driver 06:45:13 INFO - Cleaning up... 06:45:13 INFO - Return code: 0 06:45:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:45:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:45:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:45:13 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/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 0x108681c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108685768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874357f0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, '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.vocumtOMNf/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.ScWIR64ElJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 06:45:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:45:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:45:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:45:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:45:13 INFO - 'HOME': '/Users/cltbld', 06:45:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:45:13 INFO - 'LOGNAME': 'cltbld', 06:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:13 INFO - 'MOZ_NO_REMOTE': '1', 06:45:13 INFO - 'NO_EM_RESTART': '1', 06:45:13 INFO - 'PAGER': '/bin/cat', 06:45:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:45:13 INFO - 'PWD': '/builds/slave/test', 06:45:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:45:13 INFO - 'SHELL': '/bin/bash', 06:45:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:45:13 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:45:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:45:13 INFO - 'USER': 'cltbld', 06:45:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:45:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:13 INFO - 'XPC_FLAGS': '0x0', 06:45:13 INFO - 'XPC_SERVICE_NAME': '0', 06:45:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:45:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:45:13 INFO - Cleaning up... 06:45:13 INFO - Return code: 0 06:45:13 INFO - Running post-action listener: _resource_record_post_action 06:45:13 INFO - Running post-action listener: _start_resource_monitoring 06:45:13 INFO - Starting resource monitoring. 06:45:13 INFO - [mozharness: 2017-06-01 13:45:13.979860Z] Finished create-virtualenv step (success) 06:45:13 INFO - [mozharness: 2017-06-01 13:45:13.980827Z] Running install step. 06:45:13 INFO - Running pre-action listener: _resource_record_pre_action 06:45:13 INFO - Running main action method: install 06:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:45:14 INFO - Reading from file tmpfile_stdout 06:45:14 INFO - Detecting whether we're running mozinstall >=1.0... 06:45:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:45:14 INFO - Reading from file tmpfile_stdout 06:45:14 INFO - Output received: 06:45:14 INFO - Usage: mozinstall [options] installer 06:45:14 INFO - Options: 06:45:14 INFO - -h, --help show this help message and exit 06:45:14 INFO - -d DEST, --destination=DEST 06:45:14 INFO - Directory to install application into. [default: 06:45:14 INFO - "/builds/slave/test"] 06:45:14 INFO - --app=APP Application being installed. [default: firefox] 06:45:14 INFO - mkdir: /builds/slave/test/build/application 06:45:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:45:35 INFO - Reading from file tmpfile_stdout 06:45:35 INFO - Output received: 06:45:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:45:35 INFO - Running post-action listener: _resource_record_post_action 06:45:35 INFO - [mozharness: 2017-06-01 13:45:35.667157Z] Finished install step (success) 06:45:35 INFO - [mozharness: 2017-06-01 13:45:35.667311Z] Running setup-mitmproxy step. 06:45:35 INFO - Running pre-action listener: _resource_record_pre_action 06:45:35 INFO - Running main action method: setup_mitmproxy 06:45:35 INFO - Skipping: mitmproxy is not required 06:45:35 INFO - Running post-action listener: _resource_record_post_action 06:45:35 INFO - [mozharness: 2017-06-01 13:45:35.667875Z] Finished setup-mitmproxy step (success) 06:45:35 INFO - [mozharness: 2017-06-01 13:45:35.667987Z] Running run-tests step. 06:45:35 INFO - Running pre-action listener: _resource_record_pre_action 06:45:35 INFO - Running main action method: run_tests 06:45:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:45:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:45:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:45:35 INFO - 2017-06-01 06:45:35.682 screenresolution[1620:7441] starting screenresolution argv=screenresolution get 06:45:35 INFO - 2017-06-01 06:45:35.685 screenresolution[1620:7441] Display 0: 1600x1200x32@60 06:45:35 INFO - 2017-06-01 06:45:35.691 screenresolution[1621:7443] starting screenresolution argv=screenresolution list 06:45:35 INFO - Available Modes on Display 0 06:45:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:45:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:45:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:45:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:45:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:45:35 INFO - Intel Iris: 06:45:35 INFO - Chipset Model: Intel Iris 06:45:35 INFO - Type: GPU 06:45:35 INFO - Bus: Built-In 06:45:35 INFO - VRAM (Dynamic, Max): 1536 MB 06:45:35 INFO - Vendor: Intel (0x8086) 06:45:35 INFO - Device ID: 0x0a2e 06:45:35 INFO - Revision ID: 0x0009 06:45:35 INFO - Displays: 06:45:35 INFO - Display: 06:45:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:45:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:45:35 INFO - Display Serial Number: 1600x1200 60 06:45:35 INFO - Main Display: Yes 06:45:35 INFO - Mirror: Off 06:45:35 INFO - Online: Yes 06:45:35 INFO - Rotation: Supported 06:45:35 INFO - Return code: 0 06:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:45:35 INFO - Python 2.7.3 06:45:35 INFO - Return code: 0 06:45:35 INFO - grabbing minidump binary from tooltool 06:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 06:45:35 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 0x10860ac60>, '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 0x10839fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:45:35 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 06:45:35 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 06:45:35 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 06:45:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:45:36 INFO - Return code: 0 06:45:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:45:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:45:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:45:36 INFO - ENV: RUST_BACKTRACE is now 1 06:45:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:45:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0282 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vocumtOMNf/Render', 06:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:45:36 INFO - 'HOME': '/Users/cltbld', 06:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:45:36 INFO - 'LOGNAME': 'cltbld', 06:45:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:45:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:45:36 INFO - 'MOZ_NO_REMOTE': '1', 06:45:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:45:36 INFO - 'NO_EM_RESTART': '1', 06:45:36 INFO - 'PAGER': '/bin/cat', 06:45:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:45:36 INFO - 'PWD': '/builds/slave/test', 06:45:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:45:36 INFO - 'RUST_BACKTRACE': '1', 06:45:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:45:36 INFO - 'SHELL': '/bin/bash', 06:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ScWIR64ElJ/Listeners', 06:45:36 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 06:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:45:36 INFO - 'USER': 'cltbld', 06:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:45:36 INFO - 'XPC_FLAGS': '0x0', 06:45:36 INFO - 'XPC_SERVICE_NAME': '0', 06:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:45:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:45:36 INFO - mozversion application_buildid: 20170601045602 06:45:36 INFO - mozversion application_changeset: d96110d766199d42524558fd83e6467906de4daf 06:45:36 INFO - mozversion application_display_name: Nightly 06:45:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:45:36 INFO - mozversion application_name: Firefox 06:45:36 INFO - mozversion application_remotingname: firefox 06:45:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:45:36 INFO - mozversion application_vendor: Mozilla 06:45:36 INFO - mozversion application_version: 55.0a1 06:45:36 INFO - mozversion platform_buildid: 20170601045602 06:45:36 INFO - mozversion platform_changeset: d96110d766199d42524558fd83e6467906de4daf 06:45:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:45:36 INFO - mozversion platform_version: 55.0a1 06:45:36 INFO - using testdate: 1496324736 06:45:36 INFO - actual date: 1496324736 06:45:36 INFO - starting webserver on 'localhost:49228' 06:45:36 INFO - SUITE-START | Running 2 tests 06:45:36 INFO - TEST-START | dromaeo_css 06:45:36 INFO - operating with platform_type : mac_ 06:45:36 INFO - Initialising browser for dromaeo_css test... 06:45:36 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmp1z_KAT/profile 06:45:36 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 06:46:10 INFO - TEST-INFO | 1631: killed by SIGTERM 06:46:10 INFO - Browser initialized. 06:46:10 INFO - Running cycle 1/1 for dromaeo_css test... 06:46:10 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmp1z_KAT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:46:12 INFO - PID 1641 | 2017-06-01 06:46:12.136 plugin-container[1642:8033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 06:46:12 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:46:12 INFO - PID 1641 | 2017-06-01 06:46:12.137 plugin-container[1642:8033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0f, name = 'com.apple.CFPasteboardClient' 06:46:12 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:46:12 INFO - PID 1641 | 2017-06-01 06:46:12.137 plugin-container[1642:8033] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:46:12 INFO - PID 1641 | 2017-06-01 06:46:12.655 plugin-container[1643:8082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.tsm.portname' 06:46:12 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:46:12 INFO - PID 1641 | 2017-06-01 06:46:12.655 plugin-container[1643:8082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 06:46:12 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:46:12 INFO - PID 1641 | 2017-06-01 06:46:12.655 plugin-container[1643:8082] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:46:22 INFO - PID 1641 | RSS: Main: 268533760 06:46:22 INFO - PID 1641 | 06:49:24 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 06:49:26 INFO - PID 1641 | RSS: Main: 271364096 06:49:26 INFO - PID 1641 | 06:52:28 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 06:52:28 INFO - PID 1641 | RSS: Main: 282992640 06:52:28 INFO - PID 1641 | 06:55:30 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 06:55:31 INFO - PID 1641 | RSS: Main: 281899008 06:55:31 INFO - PID 1641 | 06:58:32 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 06:58:33 INFO - PID 1641 | RSS: Main: 285593600 06:58:33 INFO - PID 1641 | 07:01:36 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 07:01:36 INFO - PID 1641 | RSS: Main: 285642752 07:01:36 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 07:04:38 INFO - PID 1641 | RSS: Main: 285569024 07:04:38 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | __start_tp_report 07:04:38 INFO - PID 1641 | _x_x_mozilla_page_load 07:04:38 INFO - PID 1641 | _x_x_mozilla_page_load_details 07:04:38 INFO - PID 1641 | |i|pagename|runs| 07:04:38 INFO - PID 1641 | |0;dojo.html;3228.305914228441;3563.4832949222346;3575.1240945968243;3585.067882350588;3599.766015209011;6115.422885572139;6151.41561551653;6152.477039451652;6167.7532898684;6215.09881067245;6108.129596501685;6118.923762351598;6177.567570270083;6249.062640603912;6263.029230469273;4942.53045960635;5221.295125158112;5382.085045542267;5384.488473595002;5398.649087809299;5438.265834112403;5510.283663123809;5525.475079867426;5528.391876893538;5542.889142217154;5304;5388.380336261341;5393.406725260238;5429.4570542945785;5499.642523236017;5392.56859451243;5435.864103397407;5466.562674986031;5466.863328416782;5481.75332110056;5365.409804921455;5413.891722165535;5419.756110975016;5447.210154527611;5450.727463626842;5324.680519168863;6064.06007068905;6200.62796232223;6265.8120256392385;6265.966115590922;6494.155759751249;6551.475881929482;6626.768063117768;6644.833879153011;6650;5693.28833895763;6745.8650826983185;6752.628605426699;6773.830654233634;6774.593524388552;5185.662931909414;6259.749610015594;6288.622682639057;6295.118683384329;6302.086197501382;6704.363085506869;6798.082258894986;6817.761378351734;6916.170059592881;6922.480813938974;6280.434760871543;6361.554691171574;6378.425941665272;6474.5791523550815;6531.379518945692;6567.967160164264;6656.234533028667;6670.766523171763;6672.5329226953645;6680.3987641112535;6567.802965911031;6622.139121914121;6622.63575503352;6623.834404139935;6648.900266496006;6382.744690212432;6437.613743175424;6559.6064236145985;6600.933990660128;6649.63427011519;5310.548603368757;5328.147496400557;5335.3864305604575;5337.226102215157;5354.892902141935;4713.6936099153445;4722.173619616553;4736.1474934511425;4749.2876068589985;4766.6901651392545;5303.628745987744;6226.719797609118;6268.373162683786;6315.621062736251;6348.333424990309;6725.495587830835;6763.966180169166;6795.116634837439;6801.931980680229;6813.148356455443;6284.937150628435;6339.587926784744;6369.681515924185;6404.455621272244;6407.455366293824;5846.1230815377185;5859.765609375662;5921.111833225051;5927.170196172539;5985.34161242263;6835.93164068353;6915.861682766216;6930.064441300461;6945.652717364213;6968.79093627192;5283;6766.695498702668;6776.762813301708;6780.491463140383;6793.694283757144;6714.3957043866285;6876.828079298058;6911.550749201286;6933.965330173317;6934.202566704894;5301.5493683037375;6451.322611125952;6515.511336649675;6637.303083176197;6643.169603799525;5037.017781532566;5777.537796976316;6729.932700672931;6807.21717002551;6810.148731408574;6047.729976704765;6117.602355846856;6327.462165715966;6574.04676321921;6576.342365763386;6489.383508566679;6509.088727578238;6516;6543;6545.181852268466;6348.428641422294;6404.359564043559;6418.775342862978;6421.389967953037;6423.068655044899;6281;6561.80314590563;6562.901556476561;6579.111819904443;6581.80254592363;5392.703401312965;5451;6329.810105696836;6368.363163683594;6374.107624932421 07:04:38 INFO - PID 1641 | |1;ext.html;785.7249962513121;825.8843761873337;845.2984023260692;1228.3796682675247;1283.178765590022;38564.80717596412;39250;39281.41077883834;39315.21369572607;39539.20921581567;39587.010324741896;39933.8003309983;39966.00084997869;40048;40131.79934100333;7255.238199989004;7739.535627862332;7911.050673919109;7987.08148562914;8196.3033142183;8370.162983701643;8470.66117355305;8473.36449766267;8481.491110533358;8495.575221238943;8436.45163064399;8461.111583283759;8467.745967620982;8470.152984701512;8583.914160858405;8412.87380689284;8432.283255923223;8476.83046339076;8496.575171241413;8533.189347012023;7178.964105179493;8323.66705331786;8327.417080804285;8334.541600211985;8343.95828020862;36173.81913090418;36435.453468198;36470.63529364699;36484.27031459383;36561.81719091388;3606.1705807664384;3626.8730594429067;3654.963450365489;3663.285659296438;3672.375696131637;29989.25026874324;30062.398752024837;30134.849325753232;30135.397292054255;30226;3041.254892551317;3095.1024202981107;3107.5960125183806;3114.5639610454327;3119.672434394379;248674;251953.74023129768;286972.9999999979;304320;304718;47532.7623361881;47782.522174777805;47789.761051194524;47841;47843.52156478391;49814.50185498171;49896.750516247186;49969.500304997215;50112.74943625258;54637.45362546403;14518;14583.197924114063;14601.561953141423;14669.633259168606;14679.85320146785;11664;11705.648830535098;11765.235259708092;11798.46906889192;11808.88191118095;13853;13872.861271387157;13941.58175254744;13976.371063302176;14019.789703154462;6226.377362263737;6229.0967809667345;6266;6288.779892703734;6290.685465726695;927.7634203278255;965.3628605120587;986.8273052215834;990.8810942686923;1055.9472026398648;23730.18569972196;24708.091939859543;24775.805430518292;24838.5032299353;24892.004319827003;1039.8024375368657;1122.1247427006947;1142.497301187475;1151.4185336405062;1171.4260012593768;12191.45138468771;12606.621801345975;12779.297138657463;12833.486660533661;12835.422405991567;338727;339680;340676;340882;340919;54174;54549.72725136349;54665;54793;54849.45150548444;1390.2075816784336;1520.156313246159;1545.0266332210635;1567.0441030971099;1571.2772124822482;24047.398815029064;24114;24143.879280603483;24216.094068711867;24359.391015224053;37862.86411407662;37970.050748731504;37990.81004594959;38003;38054;284168;284632.15367846057;284717;285115;285408.1459185465;34470.65529344675;34638;35720.64279357173;36000.63999360077;36060.81969590135;42768.1446371077;43696;45077;45090.323645146;45304.54695453003;27191.184264472096;29559;30286.394272113994;30336.938207162642;31216.843915780275;30394.84802575973;30461.847690761406;30756.077317680505;30947.91682291109;31032;36529.452058218616;36811.815940920125;37442.62557374391;37489.81255093707;37583.06042348963;28736.856315718287;29986.70013299839;30527.931522396608;31575.84212078925;31597;20044.498887527927;20744.170233191107;22805;23304.834758262357;24197.215046940837 07:04:38 INFO - PID 1641 | |2;jquery.html;428.5971187084141;431.72153960695334;440.2405849908907;448.3409388199346;455.4807519427853;21275.893620531875;21953.121875124994;22706.432339191528;22833.771662283394;24083;24679.506409871792;25012.74987250127;25165.99336026557;25212.747872521315;25236;6988.231294557608;7085.362317391434;7133.8216544586285;7148.463865210104;7149.392301654366;6736.259011508755;6879.552829066095;6987.755428560002;7036.296370362974;7082.504224704273;6879.3120687931305;6904;6906.447484201277;6913.55061920976;6919.308069193065;6890.7588234411805;6909.032735417046;6924.272951340113;6929.3763561279475;6939.4448444124855;6744.527883048188;6751.25736169021;6777.661116944133;6792.49056320773;6806.863862722767;29395.853020734972;29546.40907181844;30006;30343.848280758455;30682.23294417635;2311.711036120485;2346.495503466763;2353.2587235020915;2359.4809141988735;2360.386299562109;19224.71162932557;19797.406077817544;19825.603487930304;19835.50411239716;19839.60320793576;2284.4060544258446;2334.2180369576213;2387.0451819272257;2392.545416370884;2406.1578447543393;148119;148250.25874870663;149775.99999999892;186618;190681;20004.49988750264;25139.497210056063;25260;26256.73743262581;26276;34370;41838;42192.57807421948;42224.03303454957;42317.94205144896;11258.718532036764;11265.098792096613;11436.199466037298;11442.771144577062;11546.653600392001;9073.183413492685;9086.454812711127;9099.954500227457;9130.269578433843;9148.222401095982;10406;11655.44508924769;11955;12029.736877627724;12064.819027714591;4534.45586529612;4650.767461626905;4655.301704744315;4664.207084795593;4666.486686464486;907.3829795738961;929.9305798331837;935.5462600638684;951.7192428233687;958.3291695812959;20537.897310513352;21046.473838153863;21121.894390528258;21254.149834006777;21306.680399794015;855.2858363266618;875.8861348024843;968.7868668892833;978.2320878110648;1017.4251547875426;9418.764530886783;9682.934877163507;10134.695959121238;10386.688399348031;11201.71995700114;480775;553545;565211;574867;577225;143383.28308358393;145500;145610;146611;147646.99999999785;868.9652413903373;1130.5421304371744;1154.249737670521;1207.0705556721275;1209.0810983652486;18760.90619546948;20993.95030248451;23407.648885266393;23418.882905585364;23840.284791456284;62605.06092408708;64758.35241647523;64831;64994.35005649883;65340.67329663511;85194.57402712946;85433.14566854252;85778.57110714406;85813.57093214494;85947;12471.376431178223;16047.438339658056;16065.37910802223;16519.752203716715;16620.3351865924;17823.376181833573;17933.73099403484;18156.364527241483;18175.45473635793;18183.727244091613;14843.554693359216;14915.627109322353;14941.925290373478;15007.924960375129;15188.240587970779;13278.601641950758;13379.866201337862;13391.665208369868;13442.260675662541;13949.511767088103;14380.137191768526;15297.929144959746;15342.386304547694;15492.612684682974;15568.910176288004;10290.897091028994;10379.792404152025;10399.58401663925;10470.162387009173;10495;12542.874571254171;14611.634709132357;14729.705405892037;14869.553913382615;14911.85088149148 07:04:38 INFO - PID 1641 | |3;mootools.html;1142.6857614156106;1388.2503448138007;1394.1286695815115;1399.3283224052448;1409.0981771666136;11393.601223957163;11561.24851884628;12040.458179381938;12060.09549283805;12101.515939362425;11815.586454474094;11933.16467847251;11986.760264794699;12004.879951200508;12008.399580021007;2292.6446400807818;2341.0401735288533;2565.8845351959208;2568.3193953602217;2570.434504409027;2503.123906632673;2526.431552900601;2550.7194208637034;2570.5116027954723;2571.8585477798715;2503.8622875741826;2535.1253817432957;2542.6821647294087;2546.567083595793;2548;2499.9125030624023;2513.3716570857287;2520.432902596901;2527.153403609793;2531.620256961461;2328.511012687338;2336.719593648773;2337.3338598499518;2465.90136394544;2575.0214918331103;11597.884021159765;11669.883301167049;11674.241174323726;11695.181337306392;11701.239419437708;6464.51516136287;6663.533552651316;6731.562448440835;6739;6740.865182696368;4091.7340372875965;4285.871423857289;4333.439961613816;4358.956410435919;4373.234683930331;1209.1898428053225;1209.6552482542522;1210.9212901161484;1229.471327329248;1232.7857060795109;15296.235188240544;15784.368625254861;16513.752293715603;16654;16744;5514.944850551443;5524.889502209933;5645.858853528694;5710.571707121982;5726.656400615976;12109.212901161573;12131.575394861138;12133;12134.757304853854;12138.514459421523;5131.692098474104;6099.810536945253;6445.581037232565;6540.803775886731;6546.509011824132;6038.426349496791;6124.908126378108;6189.473894718909;6206.534509911684;6227.06594010902;4247.5540068293;4265.253580623378;4297.290946993711;4301.827926882952;4328.28583283761;1271.9809202861964;1291.4963164365904;1296.8378952630922;1303.3483258370813;1308.385059022258;740.256042677106;749.8237914090184;775.1124961918572;812.4921923797626;818.0510607695045;6414.743410263631;6740.595564266159;7090.361867431956;7101.218865924744;7211.531250468703;913.36064754672;944.3578366710702;947.6067432015774;957.9281553883486;961.0101595356793;3786.413105968579;3868.497095377583;3901.7268791184347;4005.839766409369;4076.286649836267;14442.711145777026;14995.850041499445;15073.397064117531;15185.316660750072;15236.390544378322;12051.819222711667;13810;14888.478903238334;15095;15123.243837808286;6739.865202696017;7280.745173918887;8667.783305417415;8673.34949878749;8694.739157825275;6097.573169878244;6192.225971753531;7021.613811240431;7284.725173094581;7357.301056399633;13064.562898081012;13103.2138071716;13107.934460327637;13474.258915759727;13541.86458135406;13318.40067196998;13695.657608559866;13698.246596436893;13773.311334433438;13839.446422143;9555.569999350186;9642.855357169508;9702;9733.659321923698;9795.85306220393;10917.672469825919;10992.725181870517;11060.004599586076;11073.335599864035;11074.501647425725;8017.478863873829;9135.086491350812;9145.542722863696;9294.581743821409;9324;8896;9007.549622518716;9040.502772347583;9090.999990000962;9219.170274675043;8186.631601577824;8322;8331.916680833114;8340.41617086798;8351.498910065415;6074.42292982166;6103.755849765959;6164.137020817179;6215.409536094064;6259.593126446766;6171.753129874754;6200.627962322275;6257.8122656318565;6328.715207815567;6379.74481020754 07:04:38 INFO - PID 1641 | |4;prototype.html;397.9880603581893;400.015960736588;404.3227593780419;405.9756414615125;406.5751289902052;6447.54867159299;6470.70881810319;6476.41712245899;6513.641749703765;6545.869082618351;5417;5475.1802821546835;5829.417058294169;6306.180196574439;6310.810675679725;3996.1208534122593;4523.389342438778;4689.147786624281;4692.155412025834;4695.624350051988;4309.332053531708;4417.4919884213405;4657.278121891112;4673.813047478096;4679.438467383919;4558.84044058458;4610.608098311647;4614.284785858197;4627.629789616823;4666.323383109447;4581.915228570992;4624.653151013654;4630.837920672777;4637.860864174047;4647.860564183046;3572.4462708280257;3813.9427908581392;3992.900177495557;4002;4017.879463616096;5503;5968.224130863004;6292.653904035276;6296.905546416808;6297.149884765589;5568.7215639217875;5573.247611572427;5576.804811831607;5586.497215250606;5595.916061259084;6838.350356716104;6842.589444633288;6848.14398200225;6858.691358888862;6879.58722476653;4331.7834108294455;4538.3873177120995;4552.656966194947;4552.795124219418;4556.498785133633;6056.757729690807;6402.775902843378;7129.9305104233745;7135.286471352875;7262.31008054234;4089.3048181809163;4306.397104405386;4574.81700731967;4593.28804035375;4609.492955774862;4678.8596342109795;4692.3342213513715;4693.929591056137;4712.293156026555;4719.5044520325555;4613.815447382134;5515.420880807537;5516.310461192351;5536.8062117826485;5545.528630066409;6783.18601767791;6819.829504262334;6824.658767061627;6825.180978282638;6827.1807383113355;1465.3113036872867;1600.5998500374906;1605.7591361295665;1605.9437919672755;1606.89555178913;984.3847595252967;1001.8346972749415;1055.6569115037644;1067.813132701774;1070.1492313610697;5712.800311934458;6463.612183269019;6519.380658837403;6524.282328943812;6559.704813283413;7105.218425973138;7105.71577136919;7109.964450177716;7120.439780109945;7131.679074441663;5703.287089113861;5960.433758792908;6488.805335839932;6508.381703738138;6574.112494813235;3653.9451908220867;3664.992127165051;3669.3028324618235;3688.0411093115445;3709.8516059357326;6831.369922579843;7021.5787052777;7274.563526188445;7278.454115941326;7349.77950661481;5954.702264886739;7249.891251631229;7265.2371500992695;7271.67277472515;7290.245523555373;5846.713722980938;5962.493188078975;7171.928280717126;7272.8909066362985;7354.191038985708;7047.506674532733;7052.506324557232;7082.362587367162;7086.787396378117;7104.893426598708;6069.544784141119;6292.350436746585;6682.766103186365;6915.446764258948;6937.439076208015;6714.328567143247;7228.168760592599;7296.59868707205;7321.570449877748;7358.632068396666;3950.7036972226624;4598.88502787433;4600.953990460186;4614;4619.445666520039;5482.890342193214;6749.873789756211;6929;6949.4440444765305;7114.466415018997;5351;6048.939510605013;6852.588844669336;6866.382025617719;6871.2624958160495;5976.342602313655;6567.704453299518;6720.495962802712;6823.89764153548;6836.350546698056;6642.667866606747;6646.202455705346;6661.866762664623;6674.9332506676255;6675.7329706813125;6791.762288319885;6819.283975182733;6821.386075253251;6854.383105520527;6861.828454288683;5983.850403739942;6114.61530003308;6607.2071351438135;6646.8005959821285;6699.598024118569 07:04:38 INFO - PID 1641 | |5;yui.html;854.6367793687685;856.8372009318231;858.7209157023968;872.7076429396152;881.4490943160523;13975.650608734786;14433.639159021004;16733.581660458494;16901.070441125732;17165.227564759585;16013.679726405466;17646.735298970525;17668.64662706745;17734.379296724615;17741.290348386083;942.8208640358328;944.471096186138;973.7662960889406;984.2421335571605;990.9950450247738;988.0415996483435;991.7173605522391;992.0625009366152;992.2607657295325;1006.104566935428;923.2567782934735;1029.8661174047402;1036.9637062702807;1041.281515754131;1041.463646222196;1011.0294117647031;1028.1671845804847;1028.228828378716;1032.8399098139762;1042.0517329230402;714.2143641993765;758.5297115788192;760.3878877503607;767.2672597669213;769.6075001749092;17449;17784.46646600604;17935;17955.922644641363;18125.728114078298;15143.394264229528;17458.30166793327;17525.123743812757;17862.46412607624;17891.37380191687;13559.25424101674;14804.111753294837;14849.851501484954;14902.031367961048;14914.030588011745;3573.3746594346144;3647.2705458908326;3666.7983260920637;3670.2659468106217;3672.59601443841;139458.60541394557;141569;141826.29086854603;142605;142618;13543.864561354458;13643.317834108255;13803.654908627164;13851.653708657364;13875.722485550232;26210.73789262083;26356;26803.731962680515;26857;26871.596926046124;6369.140166077613;6575.079488871562;7739.110002349802;7846.960765196138;7929.04851417822;3668.9082772930356;3821.6369444903244;3822.8279727411777;3824.0631045394057;3833.2333533293454;4220.556841531657;4286.657067434598;4315.913681726348;4320.006398528293;4323.373110898902;923.9907600924039;947.4367293965013;953.142172045151;960.0687333286701;975.7609385700549;647.1102234427661;654.9476041916636;656.8489247473012;663.4526515624628;675.9556485230331;11382.943085284685;11691.123165762601;12187.268763874195;12262.141650084408;12319.260844349368;562.5612022622361;562.5752556819657;565.2199964049586;575.323994306688;589.7523040323075;5729.682173100127;5733.168690539932;5824.417558244227;5835.912461312999;5843.678597677169;148155;148838;150307;150673.24663376613;151224;118195.227071594;124381;126013.36993314975;128901;132571;98638;110154.44922775334;110346.44826775815;112045;112516.87483125228;11408.885911140782;11683;11700.648980530597;11727.76544469123;11863.288202707865;46993.29510057283;47678.331258405786;48417.5158248413;48471.27293090536;49029.754851225516;58525;63238.68380658067;63649.363506364345;64426;64657;14623.341949612506;14742.410303587736;14882.627934301729;14915.627109322353;15044.398224071352;16020;16386.262618181972;16389.754153687467;16618.833811661727;16737.497875063767;14992.700145997238;15277.6180595486;15413.460528881435;15483.612909677347;15560.377584896478;14867.256637168315;14995.850041499445;15251.389944402099;15365.078095314317;15378.84621153774;14629.707405852036;14718.264086795403;15016.023958442665;15134.772978405554;15192.392304308147;10817.51321190564;10883.346999180074;11034.558617655204;11146.219764616724;11189.272697274977;11281.605143819927;11952.820707689218;11960.282383057045;12067;12286.201396909173 07:04:38 INFO - PID 1641 | __end_tp_report 07:04:38 INFO - PID 1641 | __start_cc_report 07:04:38 INFO - PID 1641 | _x_x_mozilla_cycle_collect,454 07:04:38 INFO - PID 1641 | __end_cc_report 07:04:38 INFO - PID 1641 | __startTimestamp1496325878920__endTimestamp 07:04:38 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | ------- Summary: start ------- 07:04:38 INFO - PID 1641 | Number of tests: 6 07:04:38 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6089.06 Median:6329.81 stddev:743.42 (11.7%) stddev-sans-first:711.02 07:04:38 INFO - PID 1641 | Values: 3228.3 3563.5 3575.1 3585.1 3599.8 6115.4 6151.4 6152.5 6167.8 6215.1 6108.1 6118.9 6177.6 6249.1 6263.0 4942.5 5221.3 5382.1 5384.5 5398.6 5438.3 5510.3 5525.5 5528.4 5542.9 5304.0 5388.4 5393.4 5429.5 5499.6 5392.6 5435.9 5466.6 5466.9 5481.8 5365.4 5413.9 5419.8 5447.2 5450.7 5324.7 6064.1 6200.6 6265.8 6266.0 6494.2 6551.5 6626.8 6644.8 6650.0 5693.3 6745.9 6752.6 6773.8 6774.6 5185.7 6259.7 6288.6 6295.1 6302.1 6704.4 6798.1 6817.8 6916.2 6922.5 6280.4 6361.6 6378.4 6474.6 6531.4 6568.0 6656.2 6670.8 6672.5 6680.4 6567.8 6622.1 6622.6 6623.8 6648.9 6382.7 6437.6 6559.6 6600.9 6649.6 5310.5 5328.1 5335.4 5337.2 5354.9 4713.7 4722.2 4736.1 4749.3 4766.7 5303.6 6226.7 6268.4 6315.6 6348.3 6725.5 6764.0 6795.1 6801.9 6813.1 6284.9 6339.6 6369.7 6404.5 6407.5 5846.1 5859.8 5921.1 5927.2 5985.3 6835.9 6915.9 6930.1 6945.7 6968.8 5283.0 6766.7 6776.8 6780.5 6793.7 6714.4 6876.8 6911.6 6934.0 6934.2 5301.5 6451.3 6515.5 6637.3 6643.2 5037.0 5777.5 6729.9 6807.2 6810.1 6047.7 6117.6 6327.5 6574.0 6576.3 6489.4 6509.1 6516.0 6543.0 6545.2 6348.4 6404.4 6418.8 6421.4 6423.1 6281.0 6561.8 6562.9 6579.1 6581.8 5392.7 5451.0 6329.8 6368.4 6374.1 07:04:38 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:45331.57 Median:35860.64 stddev:79508.50 (221.7%) stddev-sans-first:79661.20 07:04:38 INFO - PID 1641 | Values: 785.7 825.9 845.3 1228.4 1283.2 38564.8 39250.0 39281.4 39315.2 39539.2 39587.0 39933.8 39966.0 40048.0 40131.8 7255.2 7739.5 7911.1 7987.1 8196.3 8370.2 8470.7 8473.4 8481.5 8495.6 8436.5 8461.1 8467.7 8470.2 8583.9 8412.9 8432.3 8476.8 8496.6 8533.2 7179.0 8323.7 8327.4 8334.5 8344.0 36173.8 36435.5 36470.6 36484.3 36561.8 3606.2 3626.9 3655.0 3663.3 3672.4 29989.3 30062.4 30134.8 30135.4 30226.0 3041.3 3095.1 3107.6 3114.6 3119.7 248674.0 251953.7 286973.0 304320.0 304718.0 47532.8 47782.5 47789.8 47841.0 47843.5 49814.5 49896.8 49969.5 50112.7 54637.5 14518.0 14583.2 14601.6 14669.6 14679.9 11664.0 11705.6 11765.2 11798.5 11808.9 13853.0 13872.9 13941.6 13976.4 14019.8 6226.4 6229.1 6266.0 6288.8 6290.7 927.8 965.4 986.8 990.9 1055.9 23730.2 24708.1 24775.8 24838.5 24892.0 1039.8 1122.1 1142.5 1151.4 1171.4 12191.5 12606.6 12779.3 12833.5 12835.4 338727.0 339680.0 340676.0 340882.0 340919.0 54174.0 54549.7 54665.0 54793.0 54849.5 1390.2 1520.2 1545.0 1567.0 1571.3 24047.4 24114.0 24143.9 24216.1 24359.4 37862.9 37970.1 37990.8 38003.0 38054.0 284168.0 284632.2 284717.0 285115.0 285408.1 34470.7 34638.0 35720.6 36000.6 36060.8 42768.1 43696.0 45077.0 45090.3 45304.5 27191.2 29559.0 30286.4 30336.9 31216.8 30394.8 30461.8 30756.1 30947.9 31032.0 36529.5 36811.8 37442.6 37489.8 37583.1 28736.9 29986.7 30527.9 31575.8 31597.0 20044.5 20744.2 22805.0 23304.8 24197.2 07:04:38 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:39118.65 Median:2389.80 stddev:94125.35 (3938.6%) stddev-sans-first:94344.57 07:04:38 INFO - PID 1641 | Values: 428.6 431.7 440.2 448.3 455.5 21275.9 21953.1 22706.4 22833.8 24083.0 24679.5 25012.7 25166.0 25212.7 25236.0 6988.2 7085.4 7133.8 7148.5 7149.4 6736.3 6879.6 6987.8 7036.3 7082.5 6879.3 6904.0 6906.4 6913.6 6919.3 6890.8 6909.0 6924.3 6929.4 6939.4 6744.5 6751.3 6777.7 6792.5 6806.9 29395.9 29546.4 30006.0 30343.8 30682.2 2311.7 2346.5 2353.3 2359.5 2360.4 19224.7 19797.4 19825.6 19835.5 19839.6 2284.4 2334.2 2387.0 2392.5 2406.2 148119.0 148250.3 149776.0 186618.0 190681.0 20004.5 25139.5 25260.0 26256.7 26276.0 34370.0 41838.0 42192.6 42224.0 42317.9 11258.7 11265.1 11436.2 11442.8 11546.7 9073.2 9086.5 9100.0 9130.3 9148.2 10406.0 11655.4 11955.0 12029.7 12064.8 4534.5 4650.8 4655.3 4664.2 4666.5 907.4 929.9 935.5 951.7 958.3 20537.9 21046.5 21121.9 21254.1 21306.7 855.3 875.9 968.8 978.2 1017.4 9418.8 9682.9 10134.7 10386.7 11201.7 480775.0 553545.0 565211.0 574867.0 577225.0 143383.3 145500.0 145610.0 146611.0 147647.0 869.0 1130.5 1154.2 1207.1 1209.1 18760.9 20994.0 23407.6 23418.9 23840.3 62605.1 64758.4 64831.0 64994.4 65340.7 85194.6 85433.1 85778.6 85813.6 85947.0 12471.4 16047.4 16065.4 16519.8 16620.3 17823.4 17933.7 18156.4 18175.5 18183.7 14843.6 14915.6 14941.9 15007.9 15188.2 13278.6 13379.9 13391.7 13442.3 13949.5 14380.1 15297.9 15342.4 15492.6 15568.9 10290.9 10379.8 10399.6 10470.2 10495.0 12542.9 14611.6 14729.7 14869.6 14911.9 07:04:38 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:7117.80 Median:3827.46 stddev:4487.04 (117.2%) stddev-sans-first:4477.15 07:04:38 INFO - PID 1641 | Values: 1142.7 1388.3 1394.1 1399.3 1409.1 11393.6 11561.2 12040.5 12060.1 12101.5 11815.6 11933.2 11986.8 12004.9 12008.4 2292.6 2341.0 2565.9 2568.3 2570.4 2503.1 2526.4 2550.7 2570.5 2571.9 2503.9 2535.1 2542.7 2546.6 2548.0 2499.9 2513.4 2520.4 2527.2 2531.6 2328.5 2336.7 2337.3 2465.9 2575.0 11597.9 11669.9 11674.2 11695.2 11701.2 6464.5 6663.5 6731.6 6739.0 6740.9 4091.7 4285.9 4333.4 4359.0 4373.2 1209.2 1209.7 1210.9 1229.5 1232.8 15296.2 15784.4 16513.8 16654.0 16744.0 5514.9 5524.9 5645.9 5710.6 5726.7 12109.2 12131.6 12133.0 12134.8 12138.5 5131.7 6099.8 6445.6 6540.8 6546.5 6038.4 6124.9 6189.5 6206.5 6227.1 4247.6 4265.3 4297.3 4301.8 4328.3 1272.0 1291.5 1296.8 1303.3 1308.4 740.3 749.8 775.1 812.5 818.1 6414.7 6740.6 7090.4 7101.2 7211.5 913.4 944.4 947.6 957.9 961.0 3786.4 3868.5 3901.7 4005.8 4076.3 14442.7 14995.9 15073.4 15185.3 15236.4 12051.8 13810.0 14888.5 15095.0 15123.2 6739.9 7280.7 8667.8 8673.3 8694.7 6097.6 6192.2 7021.6 7284.7 7357.3 13064.6 13103.2 13107.9 13474.3 13541.9 13318.4 13695.7 13698.2 13773.3 13839.4 9555.6 9642.9 9702.0 9733.7 9795.9 10917.7 10992.7 11060.0 11073.3 11074.5 8017.5 9135.1 9145.5 9294.6 9324.0 8896.0 9007.5 9040.5 9091.0 9219.2 8186.6 8322.0 8331.9 8340.4 8351.5 6074.4 6103.8 6164.1 6215.4 6259.6 6171.8 6200.6 6257.8 6328.7 6379.7 07:04:38 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5493.98 Median:6063.15 stddev:1738.84 (28.7%) stddev-sans-first:1701.13 07:04:38 INFO - PID 1641 | Values: 398.0 400.0 404.3 406.0 406.6 6447.5 6470.7 6476.4 6513.6 6545.9 5417.0 5475.2 5829.4 6306.2 6310.8 3996.1 4523.4 4689.1 4692.2 4695.6 4309.3 4417.5 4657.3 4673.8 4679.4 4558.8 4610.6 4614.3 4627.6 4666.3 4581.9 4624.7 4630.8 4637.9 4647.9 3572.4 3813.9 3992.9 4002.0 4017.9 5503.0 5968.2 6292.7 6296.9 6297.1 5568.7 5573.2 5576.8 5586.5 5595.9 6838.4 6842.6 6848.1 6858.7 6879.6 4331.8 4538.4 4552.7 4552.8 4556.5 6056.8 6402.8 7129.9 7135.3 7262.3 4089.3 4306.4 4574.8 4593.3 4609.5 4678.9 4692.3 4693.9 4712.3 4719.5 4613.8 5515.4 5516.3 5536.8 5545.5 6783.2 6819.8 6824.7 6825.2 6827.2 1465.3 1600.6 1605.8 1605.9 1606.9 984.4 1001.8 1055.7 1067.8 1070.1 5712.8 6463.6 6519.4 6524.3 6559.7 7105.2 7105.7 7110.0 7120.4 7131.7 5703.3 5960.4 6488.8 6508.4 6574.1 3653.9 3665.0 3669.3 3688.0 3709.9 6831.4 7021.6 7274.6 7278.5 7349.8 5954.7 7249.9 7265.2 7271.7 7290.2 5846.7 5962.5 7171.9 7272.9 7354.2 7047.5 7052.5 7082.4 7086.8 7104.9 6069.5 6292.4 6682.8 6915.4 6937.4 6714.3 7228.2 7296.6 7321.6 7358.6 3950.7 4598.9 4601.0 4614.0 4619.4 5482.9 6749.9 6929.0 6949.4 7114.5 5351.0 6048.9 6852.6 6866.4 6871.3 5976.3 6567.7 6720.5 6823.9 6836.4 6642.7 6646.2 6661.9 6674.9 6675.7 6791.8 6819.3 6821.4 6854.4 6861.8 5983.9 6114.6 6607.2 6646.8 6699.6 07:04:38 INFO - PID 1641 | 07:04:38 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:25454.01 Median:17307.11 stddev:40027.28 (231.3%) stddev-sans-first:40096.96 07:04:38 INFO - PID 1641 | Values: 854.6 856.8 858.7 872.7 881.4 13975.7 14433.6 16733.6 16901.1 17165.2 16013.7 17646.7 17668.6 17734.4 17741.3 942.8 944.5 973.8 984.2 991.0 988.0 991.7 992.1 992.3 1006.1 923.3 1029.9 1037.0 1041.3 1041.5 1011.0 1028.2 1028.2 1032.8 1042.1 714.2 758.5 760.4 767.3 769.6 17449.0 17784.5 17935.0 17955.9 18125.7 15143.4 17458.3 17525.1 17862.5 17891.4 13559.3 14804.1 14849.9 14902.0 14914.0 3573.4 3647.3 3666.8 3670.3 3672.6 139458.6 141569.0 141826.3 142605.0 142618.0 13543.9 13643.3 13803.7 13851.7 13875.7 26210.7 26356.0 26803.7 26857.0 26871.6 6369.1 6575.1 7739.1 7847.0 7929.0 3668.9 3821.6 3822.8 3824.1 3833.2 4220.6 4286.7 4315.9 4320.0 4323.4 924.0 947.4 953.1 960.1 975.8 647.1 654.9 656.8 663.5 676.0 11382.9 11691.1 12187.3 12262.1 12319.3 562.6 562.6 565.2 575.3 589.8 5729.7 5733.2 5824.4 5835.9 5843.7 148155.0 148838.0 150307.0 150673.2 151224.0 118195.2 124381.0 126013.4 128901.0 132571.0 98638.0 110154.4 110346.4 112045.0 112516.9 11408.9 11683.0 11700.6 11727.8 11863.3 46993.3 47678.3 48417.5 48471.3 49029.8 58525.0 63238.7 63649.4 64426.0 64657.0 14623.3 14742.4 14882.6 14915.6 15044.4 16020.0 16386.3 16389.8 16618.8 16737.5 14992.7 15277.6 15413.5 15483.6 15560.4 14867.3 14995.9 15251.4 15365.1 15378.8 14629.7 14718.3 15016.0 15134.8 15192.4 10817.5 10883.3 11034.6 11146.2 11189.3 11281.6 11952.8 11960.3 12067.0 12286.2 07:04:38 INFO - PID 1641 | -------- Summary: end -------- 07:04:38 INFO - PID 1641 | 07:04:39 INFO - TEST-INFO | 1641: exit 0 07:04:39 INFO - TEST-OK | dromaeo_css | took 1143145ms 07:04:39 INFO - TEST-START | kraken 07:04:39 INFO - operating with platform_type : mac_ 07:04:39 INFO - Initialising browser for kraken test... 07:04:39 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmp8tq2w9/profile 07:04:39 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 07:05:13 INFO - TEST-INFO | 1650: killed by SIGTERM 07:05:13 INFO - Browser initialized. 07:05:13 INFO - Running cycle 1/1 for kraken test... 07:05:13 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmp8tq2w9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:05:15 INFO - PID 1654 | 2017-06-01 07:05:15.273 plugin-container[1655:10641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 07:05:15 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:05:15 INFO - PID 1654 | 2017-06-01 07:05:15.274 plugin-container[1655:10641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00f, name = 'com.apple.CFPasteboardClient' 07:05:15 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:05:15 INFO - PID 1654 | 2017-06-01 07:05:15.274 plugin-container[1655:10641] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:05:15 INFO - PID 1654 | 2017-06-01 07:05:15.716 plugin-container[1656:10687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 07:05:15 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:05:15 INFO - PID 1654 | 2017-06-01 07:05:15.716 plugin-container[1656:10687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 07:05:15 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:05:15 INFO - PID 1654 | 2017-06-01 07:05:15.716 plugin-container[1656:10687] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:05:25 INFO - PID 1654 | RSS: Main: 268079104 07:05:25 INFO - PID 1654 | 07:05:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 07:05:28 INFO - PID 1654 | RSS: Main: 276426752 07:05:28 INFO - PID 1654 | 07:05:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 07:05:31 INFO - PID 1654 | RSS: Main: 284237824 07:05:31 INFO - PID 1654 | 07:05:35 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 07:05:36 INFO - PID 1654 | RSS: Main: 281899008 07:05:36 INFO - PID 1654 | 07:05:37 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 07:05:38 INFO - PID 1654 | RSS: Main: 282992640 07:05:38 INFO - PID 1654 | 07:05:40 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:05:41 INFO - PID 1654 | RSS: Main: 284643328 07:05:41 INFO - PID 1654 | 07:05:43 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 07:05:44 INFO - PID 1654 | RSS: Main: 288919552 07:05:44 INFO - PID 1654 | 07:05:47 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 07:05:48 INFO - PID 1654 | RSS: Main: 291536896 07:05:48 INFO - PID 1654 | 07:05:51 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 07:05:52 INFO - PID 1654 | RSS: Main: 290832384 07:05:52 INFO - PID 1654 | 07:05:53 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:05:54 INFO - PID 1654 | RSS: Main: 290357248 07:05:54 INFO - PID 1654 | 07:05:56 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:05:56 INFO - PID 1654 | RSS: Main: 290258944 07:05:56 INFO - PID 1654 | 07:05:59 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:06:00 INFO - PID 1654 | RSS: Main: 290074624 07:06:00 INFO - PID 1654 | 07:06:02 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:06:03 INFO - PID 1654 | RSS: Main: 290156544 07:06:03 INFO - PID 1654 | 07:06:06 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:06:07 INFO - PID 1654 | RSS: Main: 289964032 07:06:07 INFO - PID 1654 | 07:06:08 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 07:06:09 INFO - PID 1654 | RSS: Main: 291872768 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | __start_tp_report 07:06:09 INFO - PID 1654 | _x_x_mozilla_page_load 07:06:09 INFO - PID 1654 | _x_x_mozilla_page_load_details 07:06:09 INFO - PID 1654 | |i|pagename|runs| 07:06:09 INFO - PID 1654 | |0;ai-astar;101;116;115;114;114;115;116;115;117;117 07:06:09 INFO - PID 1654 | |1;audio-beat-detection;136;110;110;149;100;116;138;150;151;143 07:06:09 INFO - PID 1654 | |2;audio-dft;199;219;275;248;213;214;208;199;198;196 07:06:09 INFO - PID 1654 | |3;audio-fft;76;58;57;81;57;61;59;60;82;72 07:06:09 INFO - PID 1654 | |4;audio-oscillator;80;70;85;71;68;67;84;67;69;98 07:06:09 INFO - PID 1654 | |5;imaging-gaussian-blur;88;89;88;94;109;88;90;88;89;88 07:06:09 INFO - PID 1654 | |6;imaging-darkroom;99;97;96;96;99;96;96;97;99;94 07:06:09 INFO - PID 1654 | |7;imaging-desaturate;95;141;140;95;94;143;80;141;111;86 07:06:09 INFO - PID 1654 | |8;json-parse-financial;51;49;77;73;82;64;51;51;51;49 07:06:09 INFO - PID 1654 | |9;json-stringify-tinderbox;46;76;91;64;65;80;78;65;56;62 07:06:09 INFO - PID 1654 | |10;stanford-crypto-aes;88;89;85;110;76;98;108;109;127;89 07:06:09 INFO - PID 1654 | |11;stanford-crypto-ccm;135;128;155;145;155;143;158;131;147;153 07:06:09 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;186;188;188;188;187;184;186;184;187;193 07:06:09 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;68;70;67;67;67;67;65;66;67;71 07:06:09 INFO - PID 1654 | __end_tp_report 07:06:09 INFO - PID 1654 | __start_cc_report 07:06:09 INFO - PID 1654 | _x_x_mozilla_cycle_collect,1200 07:06:09 INFO - PID 1654 | __end_cc_report 07:06:09 INFO - PID 1654 | __startTimestamp1496325969253__endTimestamp 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | ------- Summary: start ------- 07:06:09 INFO - PID 1654 | Number of tests: 14 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.50 stddev:4.69 (4.1%) stddev-sans-first:1.13 07:06:09 INFO - PID 1654 | Values: 101.0 116.0 115.0 114.0 114.0 115.0 116.0 115.0 117.0 117.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:140.50 stddev:19.34 (13.8%) stddev-sans-first:20.40 07:06:09 INFO - PID 1654 | Values: 136.0 110.0 110.0 149.0 100.0 116.0 138.0 150.0 151.0 143.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:216.90 Median:213.50 stddev:25.57 (12.0%) stddev-sans-first:26.29 07:06:09 INFO - PID 1654 | Values: 199.0 219.0 275.0 248.0 213.0 214.0 208.0 199.0 198.0 196.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:66.30 Median:66.50 stddev:10.29 (15.5%) stddev-sans-first:10.29 07:06:09 INFO - PID 1654 | Values: 76.0 58.0 57.0 81.0 57.0 61.0 59.0 60.0 82.0 72.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:75.50 stddev:10.44 (13.8%) stddev-sans-first:10.97 07:06:09 INFO - PID 1654 | Values: 80.0 70.0 85.0 71.0 68.0 67.0 84.0 67.0 69.0 98.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:88.50 stddev:6.56 (7.4%) stddev-sans-first:6.86 07:06:09 INFO - PID 1654 | Values: 88.0 89.0 88.0 94.0 109.0 88.0 90.0 88.0 89.0 88.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:97.00 stddev:1.66 (1.7%) stddev-sans-first:1.58 07:06:09 INFO - PID 1654 | Values: 99.0 97.0 96.0 96.0 99.0 96.0 96.0 97.0 99.0 94.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:112.60 Median:83.00 stddev:25.88 (31.2%) stddev-sans-first:26.65 07:06:09 INFO - PID 1654 | Values: 95.0 141.0 140.0 95.0 94.0 143.0 80.0 141.0 111.0 86.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:57.50 stddev:13.01 (22.6%) stddev-sans-first:13.41 07:06:09 INFO - PID 1654 | Values: 51.0 49.0 77.0 73.0 82.0 64.0 51.0 51.0 51.0 49.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:70.50 stddev:13.06 (18.5%) stddev-sans-first:11.08 07:06:09 INFO - PID 1654 | Values: 46.0 76.0 91.0 64.0 65.0 80.0 78.0 65.0 56.0 62.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:97.90 Median:86.50 stddev:15.35 (17.7%) stddev-sans-first:15.86 07:06:09 INFO - PID 1654 | Values: 88.0 89.0 85.0 110.0 76.0 98.0 108.0 109.0 127.0 89.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:150.00 stddev:10.68 (7.1%) stddev-sans-first:10.69 07:06:09 INFO - PID 1654 | Values: 135.0 128.0 155.0 145.0 155.0 143.0 158.0 131.0 147.0 153.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.10 Median:187.50 stddev:2.56 (1.4%) stddev-sans-first:2.68 07:06:09 INFO - PID 1654 | Values: 186.0 188.0 188.0 188.0 187.0 184.0 186.0 184.0 187.0 193.0 07:06:09 INFO - PID 1654 | 07:06:09 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:1.78 (2.7%) stddev-sans-first:1.88 07:06:09 INFO - PID 1654 | Values: 68.0 70.0 67.0 67.0 67.0 67.0 65.0 66.0 67.0 71.0 07:06:09 INFO - PID 1654 | -------- Summary: end -------- 07:06:09 INFO - PID 1654 | 07:06:10 INFO - TEST-INFO | 1654: exit 0 07:06:10 INFO - TEST-OK | kraken | took 90749ms 07:06:10 INFO - SUITE-END | took 1233s 07:06:10 INFO - Completed test suite (00:20:34) 07:06:10 INFO - javascript benchmark 07:06:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8195.61320738984, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3228.305914228441, 3563.4832949222346, 3575.1240945968243, 3585.067882350588, 3599.766015209011, 6115.422885572139, 6151.41561551653, 6152.477039451652, 6167.7532898684, 6215.09881067245, 6108.129596501685, 6118.923762351598, 6177.567570270083, 6249.062640603912, 6263.029230469273, 4942.53045960635, 5221.295125158112, 5382.085045542267, 5384.488473595002, 5398.649087809299, 5438.265834112403, 5510.283663123809, 5525.475079867426, 5528.391876893538, 5542.889142217154, 5304.0, 5388.380336261341, 5393.406725260238, 5429.4570542945785, 5499.642523236017, 5392.56859451243, 5435.864103397407, 5466.562674986031, 5466.863328416782, 5481.75332110056, 5365.409804921455, 5413.891722165535, 5419.756110975016, 5447.210154527611, 5450.727463626842, 5324.680519168863, 6064.06007068905, 6200.62796232223, 6265.8120256392385, 6265.966115590922, 6494.155759751249, 6551.475881929482, 6626.768063117768, 6644.833879153011, 6650.0, 5693.28833895763, 6745.8650826983185, 6752.628605426699, 6773.830654233634, 6774.593524388552, 5185.662931909414, 6259.749610015594, 6288.622682639057, 6295.118683384329, 6302.086197501382, 6704.363085506869, 6798.082258894986, 6817.761378351734, 6916.170059592881, 6922.480813938974, 6280.434760871543, 6361.554691171574, 6378.425941665272, 6474.5791523550815, 6531.379518945692, 6567.967160164264, 6656.234533028667, 6670.766523171763, 6672.5329226953645, 6680.3987641112535, 6567.802965911031, 6622.139121914121, 6622.63575503352, 6623.834404139935, 6648.900266496006, 6382.744690212432, 6437.613743175424, 6559.6064236145985, 6600.933990660128, 6649.63427011519, 5310.548603368757, 5328.147496400557, 5335.3864305604575, 5337.226102215157, 5354.892902141935, 4713.6936099153445, 4722.173619616553, 4736.1474934511425, 4749.2876068589985, 4766.6901651392545, 5303.628745987744, 6226.719797609118, 6268.373162683786, 6315.621062736251, 6348.333424990309, 6725.495587830835, 6763.966180169166, 6795.116634837439, 6801.931980680229, 6813.148356455443, 6284.937150628435, 6339.587926784744, 6369.681515924185, 6404.455621272244, 6407.455366293824, 5846.1230815377185, 5859.765609375662, 5921.111833225051, 5927.170196172539, 5985.34161242263, 6835.93164068353, 6915.861682766216, 6930.064441300461, 6945.652717364213, 6968.79093627192, 5283.0, 6766.695498702668, 6776.762813301708, 6780.491463140383, 6793.694283757144, 6714.3957043866285, 6876.828079298058, 6911.550749201286, 6933.965330173317, 6934.202566704894, 5301.5493683037375, 6451.322611125952, 6515.511336649675, 6637.303083176197, 6643.169603799525, 5037.017781532566, 5777.537796976316, 6729.932700672931, 6807.21717002551, 6810.148731408574, 6047.729976704765, 6117.602355846856, 6327.462165715966, 6574.04676321921, 6576.342365763386, 6489.383508566679, 6509.088727578238, 6516.0, 6543.0, 6545.181852268466, 6348.428641422294, 6404.359564043559, 6418.775342862978, 6421.389967953037, 6423.068655044899, 6281.0, 6561.80314590563, 6562.901556476561, 6579.111819904443, 6581.80254592363, 5392.703401312965, 5451.0, 6329.810105696836, 6368.363163683594, 6374.107624932421], "value": 6041.8737932590275, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.7249962513121, 825.8843761873337, 845.2984023260692, 1228.3796682675247, 1283.178765590022, 38564.80717596412, 39250.0, 39281.41077883834, 39315.21369572607, 39539.20921581567, 39587.010324741896, 39933.8003309983, 39966.00084997869, 40048.0, 40131.79934100333, 7255.238199989004, 7739.535627862332, 7911.050673919109, 7987.08148562914, 8196.3033142183, 8370.162983701643, 8470.66117355305, 8473.36449766267, 8481.491110533358, 8495.575221238943, 8436.45163064399, 8461.111583283759, 8467.745967620982, 8470.152984701512, 8583.914160858405, 8412.87380689284, 8432.283255923223, 8476.83046339076, 8496.575171241413, 8533.189347012023, 7178.964105179493, 8323.66705331786, 8327.417080804285, 8334.541600211985, 8343.95828020862, 36173.81913090418, 36435.453468198, 36470.63529364699, 36484.27031459383, 36561.81719091388, 3606.1705807664384, 3626.8730594429067, 3654.963450365489, 3663.285659296438, 3672.375696131637, 29989.25026874324, 30062.398752024837, 30134.849325753232, 30135.397292054255, 30226.0, 3041.254892551317, 3095.1024202981107, 3107.5960125183806, 3114.5639610454327, 3119.672434394379, 248674.0, 251953.74023129768, 286972.9999999979, 304320.0, 304718.0, 47532.7623361881, 47782.522174777805, 47789.761051194524, 47841.0, 47843.52156478391, 49814.50185498171, 49896.750516247186, 49969.500304997215, 50112.74943625258, 54637.45362546403, 14518.0, 14583.197924114063, 14601.561953141423, 14669.633259168606, 14679.85320146785, 11664.0, 11705.648830535098, 11765.235259708092, 11798.46906889192, 11808.88191118095, 13853.0, 13872.861271387157, 13941.58175254744, 13976.371063302176, 14019.789703154462, 6226.377362263737, 6229.0967809667345, 6266.0, 6288.779892703734, 6290.685465726695, 927.7634203278255, 965.3628605120587, 986.8273052215834, 990.8810942686923, 1055.9472026398648, 23730.18569972196, 24708.091939859543, 24775.805430518292, 24838.5032299353, 24892.004319827003, 1039.8024375368657, 1122.1247427006947, 1142.497301187475, 1151.4185336405062, 1171.4260012593768, 12191.45138468771, 12606.621801345975, 12779.297138657463, 12833.486660533661, 12835.422405991567, 338727.0, 339680.0, 340676.0, 340882.0, 340919.0, 54174.0, 54549.72725136349, 54665.0, 54793.0, 54849.45150548444, 1390.2075816784336, 1520.156313246159, 1545.0266332210635, 1567.0441030971099, 1571.2772124822482, 24047.398815029064, 24114.0, 24143.879280603483, 24216.094068711867, 24359.391015224053, 37862.86411407662, 37970.050748731504, 37990.81004594959, 38003.0, 38054.0, 284168.0, 284632.15367846057, 284717.0, 285115.0, 285408.1459185465, 34470.65529344675, 34638.0, 35720.64279357173, 36000.63999360077, 36060.81969590135, 42768.1446371077, 43696.0, 45077.0, 45090.323645146, 45304.54695453003, 27191.184264472096, 29559.0, 30286.394272113994, 30336.938207162642, 31216.843915780275, 30394.84802575973, 30461.847690761406, 30756.077317680505, 30947.91682291109, 31032.0, 36529.452058218616, 36811.815940920125, 37442.62557374391, 37489.81255093707, 37583.06042348963, 28736.856315718287, 29986.70013299839, 30527.931522396608, 31575.84212078925, 31597.0, 20044.498887527927, 20744.170233191107, 22805.0, 23304.834758262357, 24197.215046940837], "value": 17603.315118870156, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.5971187084141, 431.72153960695334, 440.2405849908907, 448.3409388199346, 455.4807519427853, 21275.893620531875, 21953.121875124994, 22706.432339191528, 22833.771662283394, 24083.0, 24679.506409871792, 25012.74987250127, 25165.99336026557, 25212.747872521315, 25236.0, 6988.231294557608, 7085.362317391434, 7133.8216544586285, 7148.463865210104, 7149.392301654366, 6736.259011508755, 6879.552829066095, 6987.755428560002, 7036.296370362974, 7082.504224704273, 6879.3120687931305, 6904.0, 6906.447484201277, 6913.55061920976, 6919.308069193065, 6890.7588234411805, 6909.032735417046, 6924.272951340113, 6929.3763561279475, 6939.4448444124855, 6744.527883048188, 6751.25736169021, 6777.661116944133, 6792.49056320773, 6806.863862722767, 29395.853020734972, 29546.40907181844, 30006.0, 30343.848280758455, 30682.23294417635, 2311.711036120485, 2346.495503466763, 2353.2587235020915, 2359.4809141988735, 2360.386299562109, 19224.71162932557, 19797.406077817544, 19825.603487930304, 19835.50411239716, 19839.60320793576, 2284.4060544258446, 2334.2180369576213, 2387.0451819272257, 2392.545416370884, 2406.1578447543393, 148119.0, 148250.25874870663, 149775.99999999892, 186618.0, 190681.0, 20004.49988750264, 25139.497210056063, 25260.0, 26256.73743262581, 26276.0, 34370.0, 41838.0, 42192.57807421948, 42224.03303454957, 42317.94205144896, 11258.718532036764, 11265.098792096613, 11436.199466037298, 11442.771144577062, 11546.653600392001, 9073.183413492685, 9086.454812711127, 9099.954500227457, 9130.269578433843, 9148.222401095982, 10406.0, 11655.44508924769, 11955.0, 12029.736877627724, 12064.819027714591, 4534.45586529612, 4650.767461626905, 4655.301704744315, 4664.207084795593, 4666.486686464486, 907.3829795738961, 929.9305798331837, 935.5462600638684, 951.7192428233687, 958.3291695812959, 20537.897310513352, 21046.473838153863, 21121.894390528258, 21254.149834006777, 21306.680399794015, 855.2858363266618, 875.8861348024843, 968.7868668892833, 978.2320878110648, 1017.4251547875426, 9418.764530886783, 9682.934877163507, 10134.695959121238, 10386.688399348031, 11201.71995700114, 480775.0, 553545.0, 565211.0, 574867.0, 577225.0, 143383.28308358393, 145500.0, 145610.0, 146611.0, 147646.99999999785, 868.9652413903373, 1130.5421304371744, 1154.249737670521, 1207.0705556721275, 1209.0810983652486, 18760.90619546948, 20993.95030248451, 23407.648885266393, 23418.882905585364, 23840.284791456284, 62605.06092408708, 64758.35241647523, 64831.0, 64994.35005649883, 65340.67329663511, 85194.57402712946, 85433.14566854252, 85778.57110714406, 85813.57093214494, 85947.0, 12471.376431178223, 16047.438339658056, 16065.37910802223, 16519.752203716715, 16620.3351865924, 17823.376181833573, 17933.73099403484, 18156.364527241483, 18175.45473635793, 18183.727244091613, 14843.554693359216, 14915.627109322353, 14941.925290373478, 15007.924960375129, 15188.240587970779, 13278.601641950758, 13379.866201337862, 13391.665208369868, 13442.260675662541, 13949.511767088103, 14380.137191768526, 15297.929144959746, 15342.386304547694, 15492.612684682974, 15568.910176288004, 10290.897091028994, 10379.792404152025, 10399.58401663925, 10470.162387009173, 10495.0, 12542.874571254171, 14611.634709132357, 14729.705405892037, 14869.553913382615, 14911.85088149148], "value": 12510.586317681986, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1142.6857614156106, 1388.2503448138007, 1394.1286695815115, 1399.3283224052448, 1409.0981771666136, 11393.601223957163, 11561.24851884628, 12040.458179381938, 12060.09549283805, 12101.515939362425, 11815.586454474094, 11933.16467847251, 11986.760264794699, 12004.879951200508, 12008.399580021007, 2292.6446400807818, 2341.0401735288533, 2565.8845351959208, 2568.3193953602217, 2570.434504409027, 2503.123906632673, 2526.431552900601, 2550.7194208637034, 2570.5116027954723, 2571.8585477798715, 2503.8622875741826, 2535.1253817432957, 2542.6821647294087, 2546.567083595793, 2548.0, 2499.9125030624023, 2513.3716570857287, 2520.432902596901, 2527.153403609793, 2531.620256961461, 2328.511012687338, 2336.719593648773, 2337.3338598499518, 2465.90136394544, 2575.0214918331103, 11597.884021159765, 11669.883301167049, 11674.241174323726, 11695.181337306392, 11701.239419437708, 6464.51516136287, 6663.533552651316, 6731.562448440835, 6739.0, 6740.865182696368, 4091.7340372875965, 4285.871423857289, 4333.439961613816, 4358.956410435919, 4373.234683930331, 1209.1898428053225, 1209.6552482542522, 1210.9212901161484, 1229.471327329248, 1232.7857060795109, 15296.235188240544, 15784.368625254861, 16513.752293715603, 16654.0, 16744.0, 5514.944850551443, 5524.889502209933, 5645.858853528694, 5710.571707121982, 5726.656400615976, 12109.212901161573, 12131.575394861138, 12133.0, 12134.757304853854, 12138.514459421523, 5131.692098474104, 6099.810536945253, 6445.581037232565, 6540.803775886731, 6546.509011824132, 6038.426349496791, 6124.908126378108, 6189.473894718909, 6206.534509911684, 6227.06594010902, 4247.5540068293, 4265.253580623378, 4297.290946993711, 4301.827926882952, 4328.28583283761, 1271.9809202861964, 1291.4963164365904, 1296.8378952630922, 1303.3483258370813, 1308.385059022258, 740.256042677106, 749.8237914090184, 775.1124961918572, 812.4921923797626, 818.0510607695045, 6414.743410263631, 6740.595564266159, 7090.361867431956, 7101.218865924744, 7211.531250468703, 913.36064754672, 944.3578366710702, 947.6067432015774, 957.9281553883486, 961.0101595356793, 3786.413105968579, 3868.497095377583, 3901.7268791184347, 4005.839766409369, 4076.286649836267, 14442.711145777026, 14995.850041499445, 15073.397064117531, 15185.316660750072, 15236.390544378322, 12051.819222711667, 13810.0, 14888.478903238334, 15095.0, 15123.243837808286, 6739.865202696017, 7280.745173918887, 8667.783305417415, 8673.34949878749, 8694.739157825275, 6097.573169878244, 6192.225971753531, 7021.613811240431, 7284.725173094581, 7357.301056399633, 13064.562898081012, 13103.2138071716, 13107.934460327637, 13474.258915759727, 13541.86458135406, 13318.40067196998, 13695.657608559866, 13698.246596436893, 13773.311334433438, 13839.446422143, 9555.569999350186, 9642.855357169508, 9702.0, 9733.659321923698, 9795.85306220393, 10917.672469825919, 10992.725181870517, 11060.004599586076, 11073.335599864035, 11074.501647425725, 8017.478863873829, 9135.086491350812, 9145.542722863696, 9294.581743821409, 9324.0, 8896.0, 9007.549622518716, 9040.502772347583, 9090.999990000962, 9219.170274675043, 8186.631601577824, 8322.0, 8331.916680833114, 8340.41617086798, 8351.498910065415, 6074.42292982166, 6103.755849765959, 6164.137020817179, 6215.409536094064, 6259.593126446766, 6171.753129874754, 6200.627962322275, 6257.8122656318565, 6328.715207815567, 6379.74481020754], "value": 5380.086524043212, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.9880603581893, 400.015960736588, 404.3227593780419, 405.9756414615125, 406.5751289902052, 6447.54867159299, 6470.70881810319, 6476.41712245899, 6513.641749703765, 6545.869082618351, 5417.0, 5475.1802821546835, 5829.417058294169, 6306.180196574439, 6310.810675679725, 3996.1208534122593, 4523.389342438778, 4689.147786624281, 4692.155412025834, 4695.624350051988, 4309.332053531708, 4417.4919884213405, 4657.278121891112, 4673.813047478096, 4679.438467383919, 4558.84044058458, 4610.608098311647, 4614.284785858197, 4627.629789616823, 4666.323383109447, 4581.915228570992, 4624.653151013654, 4630.837920672777, 4637.860864174047, 4647.860564183046, 3572.4462708280257, 3813.9427908581392, 3992.900177495557, 4002.0, 4017.879463616096, 5503.0, 5968.224130863004, 6292.653904035276, 6296.905546416808, 6297.149884765589, 5568.7215639217875, 5573.247611572427, 5576.804811831607, 5586.497215250606, 5595.916061259084, 6838.350356716104, 6842.589444633288, 6848.14398200225, 6858.691358888862, 6879.58722476653, 4331.7834108294455, 4538.3873177120995, 4552.656966194947, 4552.795124219418, 4556.498785133633, 6056.757729690807, 6402.775902843378, 7129.9305104233745, 7135.286471352875, 7262.31008054234, 4089.3048181809163, 4306.397104405386, 4574.81700731967, 4593.28804035375, 4609.492955774862, 4678.8596342109795, 4692.3342213513715, 4693.929591056137, 4712.293156026555, 4719.5044520325555, 4613.815447382134, 5515.420880807537, 5516.310461192351, 5536.8062117826485, 5545.528630066409, 6783.18601767791, 6819.829504262334, 6824.658767061627, 6825.180978282638, 6827.1807383113355, 1465.3113036872867, 1600.5998500374906, 1605.7591361295665, 1605.9437919672755, 1606.89555178913, 984.3847595252967, 1001.8346972749415, 1055.6569115037644, 1067.813132701774, 1070.1492313610697, 5712.800311934458, 6463.612183269019, 6519.380658837403, 6524.282328943812, 6559.704813283413, 7105.218425973138, 7105.71577136919, 7109.964450177716, 7120.439780109945, 7131.679074441663, 5703.287089113861, 5960.433758792908, 6488.805335839932, 6508.381703738138, 6574.112494813235, 3653.9451908220867, 3664.992127165051, 3669.3028324618235, 3688.0411093115445, 3709.8516059357326, 6831.369922579843, 7021.5787052777, 7274.563526188445, 7278.454115941326, 7349.77950661481, 5954.702264886739, 7249.891251631229, 7265.2371500992695, 7271.67277472515, 7290.245523555373, 5846.713722980938, 5962.493188078975, 7171.928280717126, 7272.8909066362985, 7354.191038985708, 7047.506674532733, 7052.506324557232, 7082.362587367162, 7086.787396378117, 7104.893426598708, 6069.544784141119, 6292.350436746585, 6682.766103186365, 6915.446764258948, 6937.439076208015, 6714.328567143247, 7228.168760592599, 7296.59868707205, 7321.570449877748, 7358.632068396666, 3950.7036972226624, 4598.88502787433, 4600.953990460186, 4614.0, 4619.445666520039, 5482.890342193214, 6749.873789756211, 6929.0, 6949.4440444765305, 7114.466415018997, 5351.0, 6048.939510605013, 6852.588844669336, 6866.382025617719, 6871.2624958160495, 5976.342602313655, 6567.704453299518, 6720.495962802712, 6823.89764153548, 6836.350546698056, 6642.667866606747, 6646.202455705346, 6661.866762664623, 6674.9332506676255, 6675.7329706813125, 6791.762288319885, 6819.283975182733, 6821.386075253251, 6854.383105520527, 6861.828454288683, 5983.850403739942, 6114.61530003308, 6607.2071351438135, 6646.8005959821285, 6699.598024118569], "value": 4946.503718088789, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [854.6367793687685, 856.8372009318231, 858.7209157023968, 872.7076429396152, 881.4490943160523, 13975.650608734786, 14433.639159021004, 16733.581660458494, 16901.070441125732, 17165.227564759585, 16013.679726405466, 17646.735298970525, 17668.64662706745, 17734.379296724615, 17741.290348386083, 942.8208640358328, 944.471096186138, 973.7662960889406, 984.2421335571605, 990.9950450247738, 988.0415996483435, 991.7173605522391, 992.0625009366152, 992.2607657295325, 1006.104566935428, 923.2567782934735, 1029.8661174047402, 1036.9637062702807, 1041.281515754131, 1041.463646222196, 1011.0294117647031, 1028.1671845804847, 1028.228828378716, 1032.8399098139762, 1042.0517329230402, 714.2143641993765, 758.5297115788192, 760.3878877503607, 767.2672597669213, 769.6075001749092, 17449.0, 17784.46646600604, 17935.0, 17955.922644641363, 18125.728114078298, 15143.394264229528, 17458.30166793327, 17525.123743812757, 17862.46412607624, 17891.37380191687, 13559.25424101674, 14804.111753294837, 14849.851501484954, 14902.031367961048, 14914.030588011745, 3573.3746594346144, 3647.2705458908326, 3666.7983260920637, 3670.2659468106217, 3672.59601443841, 139458.60541394557, 141569.0, 141826.29086854603, 142605.0, 142618.0, 13543.864561354458, 13643.317834108255, 13803.654908627164, 13851.653708657364, 13875.722485550232, 26210.73789262083, 26356.0, 26803.731962680515, 26857.0, 26871.596926046124, 6369.140166077613, 6575.079488871562, 7739.110002349802, 7846.960765196138, 7929.04851417822, 3668.9082772930356, 3821.6369444903244, 3822.8279727411777, 3824.0631045394057, 3833.2333533293454, 4220.556841531657, 4286.657067434598, 4315.913681726348, 4320.006398528293, 4323.373110898902, 923.9907600924039, 947.4367293965013, 953.142172045151, 960.0687333286701, 975.7609385700549, 647.1102234427661, 654.9476041916636, 656.8489247473012, 663.4526515624628, 675.9556485230331, 11382.943085284685, 11691.123165762601, 12187.268763874195, 12262.141650084408, 12319.260844349368, 562.5612022622361, 562.5752556819657, 565.2199964049586, 575.323994306688, 589.7523040323075, 5729.682173100127, 5733.168690539932, 5824.417558244227, 5835.912461312999, 5843.678597677169, 148155.0, 148838.0, 150307.0, 150673.24663376613, 151224.0, 118195.227071594, 124381.0, 126013.36993314975, 128901.0, 132571.0, 98638.0, 110154.44922775334, 110346.44826775815, 112045.0, 112516.87483125228, 11408.885911140782, 11683.0, 11700.648980530597, 11727.76544469123, 11863.288202707865, 46993.29510057283, 47678.331258405786, 48417.5158248413, 48471.27293090536, 49029.754851225516, 58525.0, 63238.68380658067, 63649.363506364345, 64426.0, 64657.0, 14623.341949612506, 14742.410303587736, 14882.627934301729, 14915.627109322353, 15044.398224071352, 16020.0, 16386.262618181972, 16389.754153687467, 16618.833811661727, 16737.497875063767, 14992.700145997238, 15277.6180595486, 15413.460528881435, 15483.612909677347, 15560.377584896478, 14867.256637168315, 14995.850041499445, 15251.389944402099, 15365.078095314317, 15378.84621153774, 14629.707405852036, 14718.264086795403, 15016.023958442665, 15134.772978405554, 15192.392304308147, 10817.51321190564, 10883.346999180074, 11034.558617655204, 11146.219764616724, 11189.272697274977, 11281.605143819927, 11952.820707689218, 11960.282383057045, 12067.0, 12286.201396909173], "value": 8557.063610707812, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 115.0, 114.0, 114.0, 115.0, 116.0, 115.0, 117.0, 117.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 110.0, 110.0, 149.0, 100.0, 116.0, 138.0, 150.0, 151.0, 143.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 219.0, 275.0, 248.0, 213.0, 214.0, 208.0, 199.0, 198.0, 196.0], "value": 216.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 57.0, 81.0, 57.0, 61.0, 59.0, 60.0, 82.0, 72.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 70.0, 85.0, 71.0, 68.0, 67.0, 84.0, 67.0, 69.0, 98.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 94.0, 109.0, 88.0, 90.0, 88.0, 89.0, 88.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 96.0, 96.0, 99.0, 96.0, 96.0, 97.0, 99.0, 94.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 141.0, 140.0, 95.0, 94.0, 143.0, 80.0, 141.0, 111.0, 86.0], "value": 112.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 77.0, 73.0, 82.0, 64.0, 51.0, 51.0, 51.0, 49.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 76.0, 91.0, 64.0, 65.0, 80.0, 78.0, 65.0, 56.0, 62.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 85.0, 110.0, 76.0, 98.0, 108.0, 109.0, 127.0, 89.0], "value": 97.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 128.0, 155.0, 145.0, 155.0, 143.0, 158.0, 131.0, 147.0, 153.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 188.0, 188.0, 188.0, 187.0, 184.0, 186.0, 184.0, 187.0, 193.0], "value": 187.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 67.0, 67.0, 67.0, 67.0, 65.0, 66.0, 67.0, 71.0], "value": 67.5, "unit": "score"}]}]} 07:06:10 INFO - Return code: 0 07:06:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:06:10 INFO - # TBPL SUCCESS # 07:06:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:06:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:06:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:06:10 INFO - 2017-06-01 07:06:10.585 screenresolution[1662:11072] starting screenresolution argv=screenresolution get 07:06:10 INFO - 2017-06-01 07:06:10.589 screenresolution[1662:11072] Display 0: 1600x1200x32@60 07:06:10 INFO - 2017-06-01 07:06:10.595 screenresolution[1663:11075] starting screenresolution argv=screenresolution list 07:06:10 INFO - Available Modes on Display 0 07:06:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:06:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:06:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:06:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:06:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:06:10 INFO - Intel Iris: 07:06:10 INFO - Chipset Model: Intel Iris 07:06:10 INFO - Type: GPU 07:06:10 INFO - Bus: Built-In 07:06:10 INFO - VRAM (Dynamic, Max): 1536 MB 07:06:10 INFO - Vendor: Intel (0x8086) 07:06:10 INFO - Device ID: 0x0a2e 07:06:10 INFO - Revision ID: 0x0009 07:06:10 INFO - Displays: 07:06:10 INFO - Display: 07:06:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:06:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:06:10 INFO - Display Serial Number: 1600x1200 60 07:06:10 INFO - Main Display: Yes 07:06:10 INFO - Mirror: Off 07:06:10 INFO - Online: Yes 07:06:10 INFO - Rotation: Supported 07:06:10 INFO - Return code: 0 07:06:10 INFO - Running post-action listener: _resource_record_post_action 07:06:10 INFO - [mozharness: 2017-06-01 14:06:10.840180Z] Finished run-tests step (success) 07:06:10 INFO - Running post-run listener: _resource_record_post_run 07:06:10 INFO - Total resource usage - Wall time: 1256s; CPU: 54.0%; Read bytes: 41126912; Write bytes: 570045440; Read time: 271; Write time: 1771 07:06:10 INFO - TinderboxPrint: CPU usage
53.6% 07:06:10 INFO - TinderboxPrint: I/O read bytes / time
41,126,912 / 271 07:06:10 INFO - TinderboxPrint: I/O write bytes / time
570,045,440 / 1,771 07:06:10 INFO - TinderboxPrint: CPU idle
2,329.9 (46.4%) 07:06:10 INFO - TinderboxPrint: CPU system
1,026.5 (20.4%) 07:06:10 INFO - TinderboxPrint: CPU user
1,669.3 (33.2%) 07:06:10 INFO - TinderboxPrint: Swap in / out
286,117,888 / 0 07:06:10 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230869504; Write bytes: 231229440; Read time: 16844; Write time: 478 07:06:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:06:10 INFO - run-tests - Wall time: 1235s; CPU: 54.0%; Read bytes: 40119296; Write bytes: 320351232; Read time: 259; Write time: 1246 07:06:11 INFO - Running post-run listener: _upload_blobber_files 07:06:11 INFO - Blob upload gear active. 07:06:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:06:11 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 07:06:11 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'] 07:06:11 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 07:06:11 INFO - (blobuploader) - INFO - Open directory for files ... 07:06:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:06:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:06:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:06:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:06:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:06:12 INFO - (blobuploader) - INFO - Done attempting. 07:06:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:06:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:06:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:06:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:06:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:06:13 INFO - (blobuploader) - INFO - Done attempting. 07:06:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:06:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:06:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:06:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:06:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:06:14 INFO - (blobuploader) - INFO - Done attempting. 07:06:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:06:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:06:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:06:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:06:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:06:16 INFO - (blobuploader) - INFO - Done attempting. 07:06:16 INFO - (blobuploader) - INFO - Iteration through files over. 07:06:16 INFO - Return code: 0 07:06:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:06:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:06:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9047c377cacd31a4f5e19a44b75b3508f0159942b476842ca7a4b5efedadc3d7b26f0963b06bac5d2aacda68260315933a09338556504e2f671f0a43875479bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b60636442dfb849b036fecdbe5e5366b7a93b7dda49e4f2ae68ccf0e764f89567202f77ee06bb98f0925103f96dce56d4c258f9ed66028db680a9b91d2f3db83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a28f97cfac39a9cc885194b6cf0229166d9700f4e0603899d82f692af98b3923c78d2c78e1e120e692d01357b6dafeb2f2dd0bd0aa6ade17d3ba1d524bb764bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d6c6f68c724f8f4b841ed2ad2a99531f43b7f2e7e42b7a2e4529d69173d39a370e1115baaf7fc27261a3e7c732d0a27e4fe1c18df3a20c14d9aab6b652b741e"} 07:06:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:06:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:06:16 INFO - Contents: 07:06:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9047c377cacd31a4f5e19a44b75b3508f0159942b476842ca7a4b5efedadc3d7b26f0963b06bac5d2aacda68260315933a09338556504e2f671f0a43875479bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b60636442dfb849b036fecdbe5e5366b7a93b7dda49e4f2ae68ccf0e764f89567202f77ee06bb98f0925103f96dce56d4c258f9ed66028db680a9b91d2f3db83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a28f97cfac39a9cc885194b6cf0229166d9700f4e0603899d82f692af98b3923c78d2c78e1e120e692d01357b6dafeb2f2dd0bd0aa6ade17d3ba1d524bb764bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d6c6f68c724f8f4b841ed2ad2a99531f43b7f2e7e42b7a2e4529d69173d39a370e1115baaf7fc27261a3e7c732d0a27e4fe1c18df3a20c14d9aab6b652b741e"} 07:06:16 INFO - Running post-run listener: copy_logs_to_upload_dir 07:06:16 INFO - Copying logs to upload dir... 07:06:16 INFO - mkdir: /builds/slave/test/build/upload/logs 07:06:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.120183 ========= master_lag: -0.30 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-01 07:06:16.119007) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 07:06:16.122428) ========= 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.vocumtOMNf/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.ScWIR64ElJ/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9047c377cacd31a4f5e19a44b75b3508f0159942b476842ca7a4b5efedadc3d7b26f0963b06bac5d2aacda68260315933a09338556504e2f671f0a43875479bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b60636442dfb849b036fecdbe5e5366b7a93b7dda49e4f2ae68ccf0e764f89567202f77ee06bb98f0925103f96dce56d4c258f9ed66028db680a9b91d2f3db83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a28f97cfac39a9cc885194b6cf0229166d9700f4e0603899d82f692af98b3923c78d2c78e1e120e692d01357b6dafeb2f2dd0bd0aa6ade17d3ba1d524bb764bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d6c6f68c724f8f4b841ed2ad2a99531f43b7f2e7e42b7a2e4529d69173d39a370e1115baaf7fc27261a3e7c732d0a27e4fe1c18df3a20c14d9aab6b652b741e"} build_url:https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011281 build_url: 'https://queue.taskcluster.net/v1/task/fD3wKP0GRTarI3Vbx8whLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9047c377cacd31a4f5e19a44b75b3508f0159942b476842ca7a4b5efedadc3d7b26f0963b06bac5d2aacda68260315933a09338556504e2f671f0a43875479bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b60636442dfb849b036fecdbe5e5366b7a93b7dda49e4f2ae68ccf0e764f89567202f77ee06bb98f0925103f96dce56d4c258f9ed66028db680a9b91d2f3db83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a28f97cfac39a9cc885194b6cf0229166d9700f4e0603899d82f692af98b3923c78d2c78e1e120e692d01357b6dafeb2f2dd0bd0aa6ade17d3ba1d524bb764bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d6c6f68c724f8f4b841ed2ad2a99531f43b7f2e7e42b7a2e4529d69173d39a370e1115baaf7fc27261a3e7c732d0a27e4fe1c18df3a20c14d9aab6b652b741e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 07:06:16.156553) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 07:06:16.156871) ========= 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.vocumtOMNf/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.ScWIR64ElJ/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.005076 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 07:06:16.205659) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 07:06:16.205956) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 07:06:16.206278) ========= ========= Total master_lag: -0.16 =========