builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0169 starttime: 1496663612.79 results: success (0) revision: 6d4b65abc362bd5f7b6406d3b98fa538a1f492eb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:32.786817) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:32.787227) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:32.787668) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:32.868880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:32.869137) ========= 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.HGDVt12tvo/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.FeiAbD2sN5/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-05 04:53:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 33.1M=0s 2017-06-05 04:53:33 (33.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.530824 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:33.424102) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:33.424445) ========= 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.HGDVt12tvo/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.FeiAbD2sN5/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.028499 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:33.473982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:33.474327) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6d4b65abc362bd5f7b6406d3b98fa538a1f492eb --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 6d4b65abc362bd5f7b6406d3b98fa538a1f492eb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HGDVt12tvo/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.FeiAbD2sN5/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-05 04:53:33,571 truncating revision to first 12 chars 2017-06-05 04:53:33,572 Setting DEBUG logging. 2017-06-05 04:53:33,572 attempt 1/10 2017-06-05 04:53:33,572 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6d4b65abc362?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 04:53:34,231 unpacking tar archive at: mozilla-central-6d4b65abc362/testing/mozharness/ program finished with exit code 0 elapsedTime=0.939173 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:34.436337) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:34.436706) ========= script_repo_revision: 6d4b65abc362bd5f7b6406d3b98fa538a1f492eb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:34.437176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:34.437488) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 04:53:34.459968) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-05 04:53:34.460358) ========= /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.HGDVt12tvo/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.FeiAbD2sN5/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:53:34 INFO - MultiFileLogger online at 20170605 04:53:34 in /builds/slave/test 04:53:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:53:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:53:34 INFO - {'append_to_log': False, 04:53:34 INFO - 'base_work_dir': '/builds/slave/test', 04:53:34 INFO - 'blob_upload_branch': 'Firefox', 04:53:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:53:34 INFO - 'branch': 'Firefox', 04:53:34 INFO - 'buildbot_json_path': 'buildprops.json', 04:53:34 INFO - 'config_files': ('talos/mac_config.py',), 04:53:34 INFO - 'default_actions': ('clobber', 04:53:34 INFO - 'read-buildbot-config', 04:53:34 INFO - 'download-and-extract', 04:53:34 INFO - 'populate-webroot', 04:53:34 INFO - 'create-virtualenv', 04:53:34 INFO - 'install', 04:53:34 INFO - 'setup-mitmproxy', 04:53:34 INFO - 'run-tests'), 04:53:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:53:34 INFO - 'download_minidump_stackwalk': True, 04:53:34 INFO - 'download_symbols': 'ondemand', 04:53:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:53:34 INFO - 'tooltool.py': '/tools/tooltool.py', 04:53:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:53:34 INFO - '/tools/misc-python/virtualenv.py')}, 04:53:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:53:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:53:34 INFO - 'gecko_profile': False, 04:53:34 INFO - 'gecko_profile_interval': 0, 04:53:34 INFO - 'installer_path': 'installer.exe', 04:53:34 INFO - 'log_level': 'info', 04:53:34 INFO - 'log_name': 'talos', 04:53:34 INFO - 'log_to_console': True, 04:53:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:53:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:53:34 INFO - 'opt_config_files': (), 04:53:34 INFO - 'pip_index': False, 04:53:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:53:34 INFO - 'cmd': ('bash', 04:53:34 INFO - '-c', 04:53:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:53:34 INFO - 'enabled': True, 04:53:34 INFO - 'halt_on_failure': False, 04:53:34 INFO - 'name': 'check_screen_resolution'},), 04:53:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:53:34 INFO - 'cmd': ('bash', 04:53:34 INFO - '-c', 04:53:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:53:34 INFO - 'enabled': True, 04:53:34 INFO - 'halt_on_failure': False, 04:53:34 INFO - 'name': 'check_screen_resolution'},), 04:53:34 INFO - 'run_cmd_checks_enabled': True, 04:53:34 INFO - 'suite': 'dromaeojs-e10s', 04:53:34 INFO - 'system_bits': '32', 04:53:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:53:34 INFO - 'title': 't-yosemite-r7-0169', 04:53:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:53:34 INFO - 'use_talos_json': True, 04:53:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:53:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:53:34 INFO - 'work_dir': 'build'} 04:53:34 INFO - [mozharness: 2017-06-05 11:53:34.675539Z] Running clobber step. 04:53:34 INFO - Running pre-action listener: _resource_record_pre_action 04:53:34 INFO - Running main action method: clobber 04:53:34 INFO - rmtree: /builds/slave/test/build 04:53:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:53:37 INFO - Running post-action listener: _resource_record_post_action 04:53:37 INFO - [mozharness: 2017-06-05 11:53:37.205685Z] Finished clobber step (success) 04:53:37 INFO - [mozharness: 2017-06-05 11:53:37.205845Z] Running read-buildbot-config step. 04:53:37 INFO - Running pre-action listener: _resource_record_pre_action 04:53:37 INFO - Running main action method: read_buildbot_config 04:53:37 INFO - Using buildbot properties: 04:53:37 INFO - { 04:53:37 INFO - "project": "", 04:53:37 INFO - "product": "firefox", 04:53:37 INFO - "who": "archaeopteryx@coole-files.de", 04:53:37 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 04:53:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:53:37 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:53:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:53:37 INFO - "stage_platform": "macosx64", 04:53:37 INFO - "basedir": "/builds/slave/test", 04:53:37 INFO - "buildnumber": 87, 04:53:37 INFO - "platform": "yosemite_r7", 04:53:37 INFO - "parent_task_id": "dMhod4R_QDyxL0Ri2vzEAw", 04:53:37 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:53:37 INFO - "slavebuilddir": "test", 04:53:37 INFO - "taskId": "OWgDBkmGT5i23G0K84C1rA", 04:53:37 INFO - "branch": "mozilla-central", 04:53:37 INFO - "script_repo_revision": "production", 04:53:37 INFO - "revision": "6d4b65abc362bd5f7b6406d3b98fa538a1f492eb", 04:53:37 INFO - "slavename": "t-yosemite-r7-0169", 04:53:37 INFO - "repo_path": "mozilla-central" 04:53:37 INFO - } 04:53:37 INFO - Finding installer, test and symbols from parent task. 04:53:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OWgDBkmGT5i23G0K84C1rA'}, attempt #1 04:53:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OWgDBkmGT5i23G0K84C1rA'}, attempt #1 04:53:38 INFO - Task dependencies: elK-ZPDLSeGoMOl-hD0K1Q dMhod4R_QDyxL0Ri2vzEAw 04:53:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw'}, attempt #1 04:53:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:53:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/test_packages.json 04:53:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/target.crashreporter-symbols.zip 04:53:38 INFO - Running post-action listener: _resource_record_post_action 04:53:38 INFO - [mozharness: 2017-06-05 11:53:38.613696Z] Finished read-buildbot-config step (success) 04:53:38 INFO - [mozharness: 2017-06-05 11:53:38.613811Z] Running download-and-extract step. 04:53:38 INFO - Running pre-action listener: _resource_record_pre_action 04:53:38 INFO - Running main action method: download_and_extract 04:53:38 INFO - mkdir: /builds/slave/test/build/tests 04:53:38 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')]} 04:53:38 INFO - trying https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:53:38 INFO - Downloading https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/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 04:53:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/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 04:53:39 INFO - Downloaded 1328 bytes. 04:53:39 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:53:39 INFO - Using the following test package requirements: 04:53:39 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:53:39 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:53:39 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:53:39 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:53:39 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'jsshell-mac.zip'], 04:53:39 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:53:39 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:53:39 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:53:39 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:53:39 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 04:53:39 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:53:39 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:53:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:53:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:53:39 INFO - Fetch https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:53:41 INFO - Content-Length response header: 57632098 04:53:41 INFO - Bytes received: 57632098 04:53:59 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 04:53:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:53:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:53:59 INFO - Fetch https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:54:01 INFO - Content-Length response header: 57632098 04:54:01 INFO - Bytes received: 57632098 04:54:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:54:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:54:18 INFO - Fetch https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:54:19 INFO - Content-Length response header: 14447425 04:54:19 INFO - Bytes received: 14447425 04:54:19 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')]} 04:54:19 INFO - trying https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:54:19 INFO - Downloading https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:54:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/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 04:54:21 INFO - Downloaded 67886996 bytes. 04:54:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:54:21 INFO - mkdir: /builds/slave/test/properties 04:54:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:54:21 INFO - Writing to file /builds/slave/test/properties/build_url 04:54:21 INFO - Contents: 04:54:21 INFO - build_url:https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:54:21 INFO - Running post-action listener: _resource_record_post_action 04:54:21 INFO - Running post-action listener: set_extra_try_arguments 04:54:21 INFO - [mozharness: 2017-06-05 11:54:21.603499Z] Finished download-and-extract step (success) 04:54:21 INFO - [mozharness: 2017-06-05 11:54:21.603651Z] Running populate-webroot step. 04:54:21 INFO - Running pre-action listener: _resource_record_pre_action 04:54:21 INFO - Running main action method: populate_webroot 04:54:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:54:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:54:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:54:21 INFO - '--tppagecycles', 04:54:21 INFO - '7'], 04:54:21 INFO - 'tests': ['tsvgm']}}, 04:54:21 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:54:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:54:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:54:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:54:21 INFO - 'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:54:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:54:21 INFO - 'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['damp', 'tps']}, 04:54:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:54:21 INFO - 'tests': ['damp', 'tps']}, 04:54:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['dromaeo_dom']}, 04:54:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:54:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:54:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:54:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:54:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:54:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['a11yr', 04:54:21 INFO - 'ts_paint', 04:54:21 INFO - 'tpaint', 04:54:21 INFO - 'sessionrestore', 04:54:21 INFO - 'sessionrestore_no_auto_restore', 04:54:21 INFO - 'tabpaint']}, 04:54:21 INFO - 'other-e10s': {'tests': ['a11yr', 04:54:21 INFO - 'ts_paint', 04:54:21 INFO - 'tpaint', 04:54:21 INFO - 'sessionrestore', 04:54:21 INFO - 'sessionrestore_no_auto_restore', 04:54:21 INFO - 'tabpaint']}, 04:54:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:54:21 INFO - 'ts_paint', 04:54:21 INFO - 'tpaint', 04:54:21 INFO - 'sessionrestore', 04:54:21 INFO - 'sessionrestore_no_auto_restore', 04:54:21 INFO - 'tabpaint']}, 04:54:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:54:21 INFO - 'ts_paint', 04:54:21 INFO - 'tpaint', 04:54:21 INFO - 'sessionrestore', 04:54:21 INFO - 'sessionrestore_no_auto_restore', 04:54:21 INFO - 'tabpaint']}, 04:54:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['a11yr', 04:54:21 INFO - 'ts_paint', 04:54:21 INFO - 'tpaint', 04:54:21 INFO - 'sessionrestore', 04:54:21 INFO - 'sessionrestore_no_auto_restore', 04:54:21 INFO - 'tabpaint']}, 04:54:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['a11yr', 04:54:21 INFO - 'ts_paint', 04:54:21 INFO - 'tpaint', 04:54:21 INFO - 'sessionrestore', 04:54:21 INFO - 'sessionrestore_no_auto_restore', 04:54:21 INFO - 'tabpaint']}, 04:54:21 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:54:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:54:21 INFO - 'bloom_basic_ref']}, 04:54:21 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 04:54:21 INFO - 'talos_options': ['--mitmproxy', 04:54:21 INFO - 'mitmproxy-recording-1.mp', 04:54:21 INFO - '--firstNonBlankPaint'], 04:54:21 INFO - 'tests': ['Quantum_1']}, 04:54:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['tsvgx', 04:54:21 INFO - 'tsvgr_opacity', 04:54:21 INFO - 'tart', 04:54:21 INFO - 'tscrollx', 04:54:21 INFO - 'cart', 04:54:21 INFO - 'tsvg_static']}, 04:54:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:54:21 INFO - 'tsvgr_opacity', 04:54:21 INFO - 'tart', 04:54:21 INFO - 'tscrollx', 04:54:21 INFO - 'cart', 04:54:21 INFO - 'tsvg_static']}, 04:54:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:54:21 INFO - 'talos_options': ['--disable-e10s'], 04:54:21 INFO - 'tests': ['tp5o']}, 04:54:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:54:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:54:21 INFO - 'talos_options': ['--disable-e10s', 04:54:21 INFO - '--xperf_path', 04:54:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:21 INFO - 'tests': ['tp5n']}, 04:54:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:54:21 INFO - 'talos_options': ['--xperf_path', 04:54:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:21 INFO - 'tests': ['tp5n']}}, 04:54:21 INFO - 'talos.zip': {'path': '', 04:54:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:54:21 INFO - Running post-action listener: _resource_record_post_action 04:54:21 INFO - [mozharness: 2017-06-05 11:54:21.625329Z] Finished populate-webroot step (success) 04:54:21 INFO - [mozharness: 2017-06-05 11:54:21.625458Z] Running create-virtualenv step. 04:54:21 INFO - Running pre-action listener: _resource_record_pre_action 04:54:21 INFO - Running main action method: create_virtualenv 04:54:21 INFO - Creating virtualenv /builds/slave/test/build/venv 04:54:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:54:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:54:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:54:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:54:21 INFO - Using real prefix '/tools/python27' 04:54:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:54:22 INFO - Installing distribute.............................................................................................................................................................................................done. 04:54:25 INFO - Installing pip.................done. 04:54:25 INFO - Return code: 0 04:54:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:54:25 INFO - Reading from file tmpfile_stdout 04:54:25 INFO - Output received: 04:54:25 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:54:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:54:25 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')]} 04:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:25 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')]} 04:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:25 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 04:54:25 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 04:54:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:25 INFO - 'HOME': '/Users/cltbld', 04:54:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:25 INFO - 'LOGNAME': 'cltbld', 04:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:25 INFO - 'MOZ_NO_REMOTE': '1', 04:54:25 INFO - 'NO_EM_RESTART': '1', 04:54:25 INFO - 'PAGER': '/bin/cat', 04:54:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:25 INFO - 'PWD': '/builds/slave/test', 04:54:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:25 INFO - 'SHELL': '/bin/bash', 04:54:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:25 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:25 INFO - 'USER': 'cltbld', 04:54:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:25 INFO - 'XPC_FLAGS': '0x0', 04:54:25 INFO - 'XPC_SERVICE_NAME': '0', 04:54:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:54:25 INFO - Cleaning up... 04:54:25 INFO - Return code: 0 04:54:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:54:25 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')]} 04:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:25 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')]} 04:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:25 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 04:54:25 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 04:54:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:25 INFO - 'HOME': '/Users/cltbld', 04:54:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:25 INFO - 'LOGNAME': 'cltbld', 04:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:25 INFO - 'MOZ_NO_REMOTE': '1', 04:54:25 INFO - 'NO_EM_RESTART': '1', 04:54:25 INFO - 'PAGER': '/bin/cat', 04:54:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:25 INFO - 'PWD': '/builds/slave/test', 04:54:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:25 INFO - 'SHELL': '/bin/bash', 04:54:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:25 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:25 INFO - 'USER': 'cltbld', 04:54:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:25 INFO - 'XPC_FLAGS': '0x0', 04:54:25 INFO - 'XPC_SERVICE_NAME': '0', 04:54:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:26 INFO - Downloading/unpacking psutil>=3.1.1 04:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:54:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:28 INFO - Installing collected packages: psutil 04:54:28 INFO - Running setup.py install for psutil 04:54:28 INFO - building 'psutil._psutil_osx' extension 04:54:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:54:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:54:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:54:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:54:29 INFO - building 'psutil._psutil_posix' extension 04:54:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:54:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:54:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:54:29 INFO - ^ 04:54:29 INFO - 1 warning generated. 04:54:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:54:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:29 INFO - Successfully installed psutil 04:54:29 INFO - Cleaning up... 04:54:29 INFO - Return code: 0 04:54:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:54:29 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')]} 04:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:29 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')]} 04:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:29 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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:29 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 04:54:29 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 04:54:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:29 INFO - 'HOME': '/Users/cltbld', 04:54:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:29 INFO - 'LOGNAME': 'cltbld', 04:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:29 INFO - 'MOZ_NO_REMOTE': '1', 04:54:29 INFO - 'NO_EM_RESTART': '1', 04:54:29 INFO - 'PAGER': '/bin/cat', 04:54:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:29 INFO - 'PWD': '/builds/slave/test', 04:54:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:29 INFO - 'SHELL': '/bin/bash', 04:54:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:29 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:29 INFO - 'USER': 'cltbld', 04:54:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:29 INFO - 'XPC_FLAGS': '0x0', 04:54:29 INFO - 'XPC_SERVICE_NAME': '0', 04:54:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:54:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:54:31 INFO - Installing collected packages: mozsystemmonitor 04:54:31 INFO - Running setup.py install for mozsystemmonitor 04:54:31 INFO - Successfully installed mozsystemmonitor 04:54:31 INFO - Cleaning up... 04:54:31 INFO - Return code: 0 04:54:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:54:31 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')]} 04:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:31 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')]} 04:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:31 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 04:54:31 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 04:54:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:31 INFO - 'HOME': '/Users/cltbld', 04:54:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:31 INFO - 'LOGNAME': 'cltbld', 04:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:31 INFO - 'MOZ_NO_REMOTE': '1', 04:54:31 INFO - 'NO_EM_RESTART': '1', 04:54:31 INFO - 'PAGER': '/bin/cat', 04:54:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:31 INFO - 'PWD': '/builds/slave/test', 04:54:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:31 INFO - 'SHELL': '/bin/bash', 04:54:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:31 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:31 INFO - 'USER': 'cltbld', 04:54:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:31 INFO - 'XPC_FLAGS': '0x0', 04:54:31 INFO - 'XPC_SERVICE_NAME': '0', 04:54:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:31 INFO - Downloading/unpacking jsonschema==2.5.1 04:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:54:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:33 INFO - Downloading functools32-3.2.3-2.tar.gz 04:54:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:54:33 INFO - warning: no files found matching '*.txt' 04:54:33 INFO - no previously-included directories found matching 'build' 04:54:33 INFO - no previously-included directories found matching 'dist' 04:54:33 INFO - no previously-included directories found matching '.git*' 04:54:33 INFO - Installing collected packages: jsonschema, functools32 04:54:33 INFO - Running setup.py install for functools32 04:54:33 INFO - warning: no files found matching '*.txt' 04:54:33 INFO - no previously-included directories found matching 'build' 04:54:33 INFO - no previously-included directories found matching 'dist' 04:54:33 INFO - no previously-included directories found matching '.git*' 04:54:33 INFO - Successfully installed jsonschema functools32 04:54:33 INFO - Cleaning up... 04:54:33 INFO - Return code: 0 04:54:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:54:33 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')]} 04:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:33 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')]} 04:54:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:33 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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:33 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 04:54:33 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 04:54:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:33 INFO - 'HOME': '/Users/cltbld', 04:54:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:33 INFO - 'LOGNAME': 'cltbld', 04:54:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:33 INFO - 'MOZ_NO_REMOTE': '1', 04:54:33 INFO - 'NO_EM_RESTART': '1', 04:54:33 INFO - 'PAGER': '/bin/cat', 04:54:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:33 INFO - 'PWD': '/builds/slave/test', 04:54:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:33 INFO - 'SHELL': '/bin/bash', 04:54:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:33 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:33 INFO - 'USER': 'cltbld', 04:54:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:33 INFO - 'XPC_FLAGS': '0x0', 04:54:33 INFO - 'XPC_SERVICE_NAME': '0', 04:54:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:54:34 INFO - Cleaning up... 04:54:34 INFO - Return code: 0 04:54:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:54:34 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')]} 04:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:34 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')]} 04:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:34 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 04:54:34 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 04:54:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:34 INFO - 'HOME': '/Users/cltbld', 04:54:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:34 INFO - 'LOGNAME': 'cltbld', 04:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:34 INFO - 'MOZ_NO_REMOTE': '1', 04:54:34 INFO - 'NO_EM_RESTART': '1', 04:54:34 INFO - 'PAGER': '/bin/cat', 04:54:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:34 INFO - 'PWD': '/builds/slave/test', 04:54:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:34 INFO - 'SHELL': '/bin/bash', 04:54:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:34 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:34 INFO - 'USER': 'cltbld', 04:54:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:34 INFO - 'XPC_FLAGS': '0x0', 04:54:34 INFO - 'XPC_SERVICE_NAME': '0', 04:54:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:34 INFO - Downloading/unpacking blobuploader==1.2.4 04:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:35 INFO - Downloading blobuploader-1.2.4.tar.gz 04:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:54:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:54:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:36 INFO - Downloading docopt-0.6.1.tar.gz 04:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:54:36 INFO - Installing collected packages: blobuploader, requests, docopt 04:54:36 INFO - Running setup.py install for blobuploader 04:54:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:54:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:54:36 INFO - Running setup.py install for requests 04:54:37 INFO - Running setup.py install for docopt 04:54:37 INFO - Successfully installed blobuploader requests docopt 04:54:37 INFO - Cleaning up... 04:54:37 INFO - Return code: 0 04:54:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:37 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')]} 04:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:37 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')]} 04:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:37 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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:37 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 04:54:37 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 04:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:37 INFO - 'HOME': '/Users/cltbld', 04:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:37 INFO - 'LOGNAME': 'cltbld', 04:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:37 INFO - 'MOZ_NO_REMOTE': '1', 04:54:37 INFO - 'NO_EM_RESTART': '1', 04:54:37 INFO - 'PAGER': '/bin/cat', 04:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:37 INFO - 'PWD': '/builds/slave/test', 04:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:37 INFO - 'SHELL': '/bin/bash', 04:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:37 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:37 INFO - 'USER': 'cltbld', 04:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:37 INFO - 'XPC_FLAGS': '0x0', 04:54:37 INFO - 'XPC_SERVICE_NAME': '0', 04:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:54:37 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-UvsqJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:54:37 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-dN7QEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:54:37 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-qO0ZiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-luUoPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-jdsQma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-C_50Qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-q3Ry0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-uk2qkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-34hd10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-oKRKPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-abzFJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-RF2MNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:54:38 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-lAw49S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:54:39 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-PkKljs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:39 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-NlvNpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:54:39 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-9DdGov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:54:39 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-wWVGYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:54:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:54:39 INFO - Running setup.py install for manifestparser 04:54:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:54:39 INFO - Running setup.py install for mozcrash 04:54:39 INFO - Running setup.py install for mozdebug 04:54:39 INFO - Running setup.py install for mozdevice 04:54:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:54:40 INFO - Running setup.py install for mozfile 04:54:40 INFO - Running setup.py install for mozhttpd 04:54:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:54:40 INFO - Running setup.py install for mozinfo 04:54:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:54:40 INFO - Running setup.py install for mozInstall 04:54:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:54:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:54:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:54:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:54:40 INFO - Running setup.py install for mozleak 04:54:40 INFO - Running setup.py install for mozlog 04:54:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:54:40 INFO - Running setup.py install for moznetwork 04:54:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:54:41 INFO - Running setup.py install for mozprocess 04:54:41 INFO - Running setup.py install for mozprofile 04:54:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:54:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:54:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:54:41 INFO - Running setup.py install for mozrunner 04:54:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:54:41 INFO - Running setup.py install for mozscreenshot 04:54:41 INFO - Running setup.py install for moztest 04:54:41 INFO - Running setup.py install for mozversion 04:54:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:54:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:54:42 INFO - Cleaning up... 04:54:42 INFO - Return code: 0 04:54:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:42 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')]} 04:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:42 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')]} 04:54:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:42 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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:42 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 04:54:42 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 04:54:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:42 INFO - 'HOME': '/Users/cltbld', 04:54:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:42 INFO - 'LOGNAME': 'cltbld', 04:54:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:42 INFO - 'MOZ_NO_REMOTE': '1', 04:54:42 INFO - 'NO_EM_RESTART': '1', 04:54:42 INFO - 'PAGER': '/bin/cat', 04:54:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:42 INFO - 'PWD': '/builds/slave/test', 04:54:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:42 INFO - 'SHELL': '/bin/bash', 04:54:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:42 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:42 INFO - 'USER': 'cltbld', 04:54:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:42 INFO - 'XPC_FLAGS': '0x0', 04:54:42 INFO - 'XPC_SERVICE_NAME': '0', 04:54:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:54:42 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-fU30UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:54:42 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-m19Zpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:54:42 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)) 04:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:54:42 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-csphxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:54:42 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-eZ8u9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:54:42 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)) 04:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:54:42 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-51woaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:54:42 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-HFgHsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:54:42 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-neuYY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-h4se1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-NbFHmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-4p5uX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-nOfw1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-EVuH_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:54:43 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)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-59m8p_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-6FBUzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:54:43 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)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-fVAiXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:54:43 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-CeOQN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:54:43 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)) 04:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:54:44 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-O2cFTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:54:44 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)) 04:54:44 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)) 04:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:54:44 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)) 04:54:44 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)) 04:54:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:45 INFO - Downloading blessings-1.6.tar.gz 04:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:54:45 INFO - Installing collected packages: blessings 04:54:45 INFO - Running setup.py install for blessings 04:54:45 INFO - Successfully installed blessings 04:54:45 INFO - Cleaning up... 04:54:45 INFO - Return code: 0 04:54:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:54:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:54:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:54:46 INFO - Reading from file tmpfile_stdout 04:54:46 INFO - Current package versions: 04:54:46 INFO - blessings == 1.6 04:54:46 INFO - blobuploader == 1.2.4 04:54:46 INFO - docopt == 0.6.1 04:54:46 INFO - functools32 == 3.2.3-2 04:54:46 INFO - jsonschema == 2.5.1 04:54:46 INFO - manifestparser == 1.1 04:54:46 INFO - mozInstall == 1.12 04:54:46 INFO - mozcrash == 1.0 04:54:46 INFO - mozdebug == 0.1 04:54:46 INFO - mozdevice == 0.51 04:54:46 INFO - mozfile == 1.2 04:54:46 INFO - mozhttpd == 0.7 04:54:46 INFO - mozinfo == 0.9 04:54:46 INFO - mozleak == 0.1 04:54:46 INFO - mozlog == 3.4 04:54:46 INFO - moznetwork == 0.27 04:54:46 INFO - mozprocess == 0.25 04:54:46 INFO - mozprofile == 0.28 04:54:46 INFO - mozrunner == 6.13 04:54:46 INFO - mozscreenshot == 0.1 04:54:46 INFO - mozsystemmonitor == 0.3 04:54:46 INFO - moztest == 0.8 04:54:46 INFO - mozversion == 1.4 04:54:46 INFO - psutil == 3.1.1 04:54:46 INFO - requests == 1.2.3 04:54:46 INFO - wsgiref == 0.1.2 04:54:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:46 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')]} 04:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:46 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')]} 04:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:46 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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:46 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 04:54:46 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 04:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:46 INFO - 'HOME': '/Users/cltbld', 04:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:46 INFO - 'LOGNAME': 'cltbld', 04:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:46 INFO - 'MOZ_NO_REMOTE': '1', 04:54:46 INFO - 'NO_EM_RESTART': '1', 04:54:46 INFO - 'PAGER': '/bin/cat', 04:54:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:46 INFO - 'PWD': '/builds/slave/test', 04:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:46 INFO - 'SHELL': '/bin/bash', 04:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:46 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:46 INFO - 'USER': 'cltbld', 04:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:46 INFO - 'XPC_FLAGS': '0x0', 04:54:46 INFO - 'XPC_SERVICE_NAME': '0', 04:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:54:46 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:47 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:54:47 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)) 04:54:47 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)) 04:54:47 INFO - Installing collected packages: marionette-driver 04:54:47 INFO - Running setup.py install for marionette-driver 04:54:48 INFO - Successfully installed marionette-driver 04:54:48 INFO - Cleaning up... 04:54:48 INFO - Return code: 0 04:54:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:54:48 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')]} 04:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:48 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')]} 04:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:48 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 0x10db71c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db75768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c5d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, '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.HGDVt12tvo/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.FeiAbD2sN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:48 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 04:54:48 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 04:54:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:54:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:48 INFO - 'HOME': '/Users/cltbld', 04:54:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:48 INFO - 'LOGNAME': 'cltbld', 04:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:48 INFO - 'MOZ_NO_REMOTE': '1', 04:54:48 INFO - 'NO_EM_RESTART': '1', 04:54:48 INFO - 'PAGER': '/bin/cat', 04:54:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:48 INFO - 'PWD': '/builds/slave/test', 04:54:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:48 INFO - 'SHELL': '/bin/bash', 04:54:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:54:48 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:48 INFO - 'USER': 'cltbld', 04:54:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:48 INFO - 'XPC_FLAGS': '0x0', 04:54:48 INFO - 'XPC_SERVICE_NAME': '0', 04:54:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:54:48 INFO - Cleaning up... 04:54:48 INFO - Return code: 0 04:54:48 INFO - Running post-action listener: _resource_record_post_action 04:54:48 INFO - Running post-action listener: _start_resource_monitoring 04:54:48 INFO - Starting resource monitoring. 04:54:48 INFO - [mozharness: 2017-06-05 11:54:48.367514Z] Finished create-virtualenv step (success) 04:54:48 INFO - [mozharness: 2017-06-05 11:54:48.368491Z] Running install step. 04:54:48 INFO - Running pre-action listener: _resource_record_pre_action 04:54:48 INFO - Running main action method: install 04:54:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:54:48 INFO - Reading from file tmpfile_stdout 04:54:48 INFO - Detecting whether we're running mozinstall >=1.0... 04:54:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:54:48 INFO - Reading from file tmpfile_stdout 04:54:48 INFO - Output received: 04:54:48 INFO - Usage: mozinstall [options] installer 04:54:48 INFO - Options: 04:54:48 INFO - -h, --help show this help message and exit 04:54:48 INFO - -d DEST, --destination=DEST 04:54:48 INFO - Directory to install application into. [default: 04:54:48 INFO - "/builds/slave/test"] 04:54:48 INFO - --app=APP Application being installed. [default: firefox] 04:54:48 INFO - mkdir: /builds/slave/test/build/application 04:54:48 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'] 04:54:48 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 04:55:10 INFO - Reading from file tmpfile_stdout 04:55:10 INFO - Output received: 04:55:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:55:10 INFO - Running post-action listener: _resource_record_post_action 04:55:10 INFO - [mozharness: 2017-06-05 11:55:10.262907Z] Finished install step (success) 04:55:10 INFO - [mozharness: 2017-06-05 11:55:10.263044Z] Running setup-mitmproxy step. 04:55:10 INFO - Running pre-action listener: _resource_record_pre_action 04:55:10 INFO - Running main action method: setup_mitmproxy 04:55:10 INFO - Skipping: mitmproxy is not required 04:55:10 INFO - Running post-action listener: _resource_record_post_action 04:55:10 INFO - [mozharness: 2017-06-05 11:55:10.263713Z] Finished setup-mitmproxy step (success) 04:55:10 INFO - [mozharness: 2017-06-05 11:55:10.263814Z] Running run-tests step. 04:55:10 INFO - Running pre-action listener: _resource_record_pre_action 04:55:10 INFO - Running main action method: run_tests 04:55:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:55:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:55:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:55:10 INFO - 2017-06-05 04:55:10.278 screenresolution[1633:8588] starting screenresolution argv=screenresolution get 04:55:10 INFO - 2017-06-05 04:55:10.281 screenresolution[1633:8588] Display 0: 1600x1200x32@60 04:55:10 INFO - 2017-06-05 04:55:10.287 screenresolution[1634:8590] starting screenresolution argv=screenresolution list 04:55:10 INFO - Available Modes on Display 0 04:55:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:55:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:55:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:55:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:55:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:55:10 INFO - Intel Iris: 04:55:10 INFO - Chipset Model: Intel Iris 04:55:10 INFO - Type: GPU 04:55:10 INFO - Bus: Built-In 04:55:10 INFO - VRAM (Dynamic, Max): 1536 MB 04:55:10 INFO - Vendor: Intel (0x8086) 04:55:10 INFO - Device ID: 0x0a2e 04:55:10 INFO - Revision ID: 0x0009 04:55:10 INFO - Displays: 04:55:10 INFO - Display: 04:55:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:55:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:55:10 INFO - Display Serial Number: 1600x1200 60 04:55:10 INFO - Main Display: Yes 04:55:10 INFO - Mirror: Off 04:55:10 INFO - Online: Yes 04:55:10 INFO - Rotation: Supported 04:55:10 INFO - Return code: 0 04:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:55:10 INFO - Python 2.7.3 04:55:10 INFO - Return code: 0 04:55:10 INFO - grabbing minidump binary from tooltool 04:55:10 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')]} 04:55:10 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 0x10dafac60>, '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 0x10d88fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8b8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:55:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:55:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:55:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:55:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:55:10 INFO - Return code: 0 04:55:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:55:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:55:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:55:10 INFO - ENV: RUST_BACKTRACE is now 1 04:55:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:55:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:55:10 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/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:55:10 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/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0169 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGDVt12tvo/Render', 04:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:10 INFO - 'HOME': '/Users/cltbld', 04:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:10 INFO - 'LOGNAME': 'cltbld', 04:55:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:55:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:10 INFO - 'MOZ_NO_REMOTE': '1', 04:55:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:55:10 INFO - 'NO_EM_RESTART': '1', 04:55:10 INFO - 'PAGER': '/bin/cat', 04:55:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:10 INFO - 'PWD': '/builds/slave/test', 04:55:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:10 INFO - 'RUST_BACKTRACE': '1', 04:55:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:55:10 INFO - 'SHELL': '/bin/bash', 04:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FeiAbD2sN5/Listeners', 04:55:10 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:10 INFO - 'USER': 'cltbld', 04:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:10 INFO - 'XPC_FLAGS': '0x0', 04:55:10 INFO - 'XPC_SERVICE_NAME': '0', 04:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:10 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/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:55:10 INFO - mozversion application_buildid: 20170605033123 04:55:10 INFO - mozversion application_changeset: 6d4b65abc362bd5f7b6406d3b98fa538a1f492eb 04:55:10 INFO - mozversion application_display_name: Nightly 04:55:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:55:10 INFO - mozversion application_name: Firefox 04:55:10 INFO - mozversion application_remotingname: firefox 04:55:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:55:10 INFO - mozversion application_vendor: Mozilla 04:55:10 INFO - mozversion application_version: 55.0a1 04:55:10 INFO - mozversion platform_buildid: 20170605033123 04:55:10 INFO - mozversion platform_changeset: 6d4b65abc362bd5f7b6406d3b98fa538a1f492eb 04:55:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:55:10 INFO - mozversion platform_version: 55.0a1 04:55:10 INFO - using testdate: 1496663710 04:55:10 INFO - actual date: 1496663710 04:55:10 INFO - starting webserver on 'localhost:49231' 04:55:10 INFO - SUITE-START | Running 2 tests 04:55:10 INFO - TEST-START | dromaeo_css 04:55:10 INFO - operating with platform_type : mac_ 04:55:10 INFO - Initialising browser for dromaeo_css test... 04:55:10 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmp6ysRdk/profile 04:55:10 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground) 04:55:44 INFO - TEST-INFO | 1644: killed by SIGTERM 04:55:44 INFO - Browser initialized. 04:55:44 INFO - Running cycle 1/1 for dromaeo_css test... 04:55:45 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmp6ysRdk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:55:47 INFO - PID 1652 | 2017-06-05 04:55:47.134 plugin-container[1653:9250] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 04:55:47 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:55:47 INFO - PID 1652 | 2017-06-05 04:55:47.136 plugin-container[1653:9250] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 04:55:47 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:55:47 INFO - PID 1652 | 2017-06-05 04:55:47.136 plugin-container[1653:9250] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:55:47 INFO - PID 1652 | 2017-06-05 04:55:47.668 plugin-container[1654:9296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 04:55:47 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:55:47 INFO - PID 1652 | 2017-06-05 04:55:47.669 plugin-container[1654:9296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 04:55:47 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:55:47 INFO - PID 1652 | 2017-06-05 04:55:47.669 plugin-container[1654:9296] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:55:57 INFO - PID 1652 | RSS: Main: 267964416 04:55:57 INFO - PID 1652 | 04:58:59 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49231/tests/dromaeo/cssquery-ext.html) 04:59:01 INFO - PID 1652 | RSS: Main: 267214848 04:59:01 INFO - PID 1652 | 05:02:03 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-ext.html (next: http://localhost:49231/tests/dromaeo/cssquery-jquery.html) 05:02:03 INFO - PID 1652 | RSS: Main: 285634560 05:02:03 INFO - PID 1652 | 05:05:05 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49231/tests/dromaeo/cssquery-mootools.html) 05:05:06 INFO - PID 1652 | RSS: Main: 284516352 05:05:06 INFO - PID 1652 | 05:08:07 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49231/tests/dromaeo/cssquery-prototype.html) 05:08:08 INFO - PID 1652 | RSS: Main: 288223232 05:08:08 INFO - PID 1652 | 05:11:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49231/tests/dromaeo/cssquery-yui.html) 05:11:12 INFO - PID 1652 | RSS: Main: 288313344 05:11:12 INFO - PID 1652 | 05:14:13 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-yui.html (next: http://localhost:49231/tests/dromaeo/cssquery-dojo.html) 05:14:14 INFO - PID 1652 | RSS: Main: 288198656 05:14:14 INFO - PID 1652 | 05:14:14 INFO - PID 1652 | __start_tp_report 05:14:14 INFO - PID 1652 | _x_x_mozilla_page_load 05:14:14 INFO - PID 1652 | _x_x_mozilla_page_load_details 05:14:14 INFO - PID 1652 | |i|pagename|runs| 05:14:14 INFO - PID 1652 | |0;dojo.html;3556.608773034966;3582.444721068234;3632.309861126386;3633.10988807742;3640.6541378569027;4685.062987402524;6049.395060493948;6112.327643959161;6179.969100154504;6186.381361863817;5788.5369170466365;6134.4478996890275;6155.446009859112;6176.227971503562;6190.50475961922;4635.980084381431;5419.783208671648;5422.213779002042;5438.619296649236;5452.863678408042;5200.7399630018535;5524.281843360358;5533.612647114704;5564.944350556483;5569.164625306196;5339.172428273585;5440;5484.808031718911;5532.917006244909;5535.916961245584;5492.615516913817;5504.669719816824;5506.119020956668;5520.171974203901;5522.420145884665;5399.433059528771;5438.91841622376;5451.727413629303;5461.1262198048125;5472.288602481652;6091.664958427285;6122.663253521055;6200.937990620081;6208.161898143738;6216.378362163793;6470.45001174896;6556.93443065568;6645.900311495331;6650;6662.367075127855;6725.05849181115;6756.223034351064;6768.357006084414;6773.525853190278;6796.6941487632685;6278.121063051177;6294.244690637107;6305.527085468608;6310.652914089677;6332.715027823759;6600.669966501656;6695.564788288746;6742.123523941857;6837.9658101710165;6890.586564806128;6167.5682702210415;6361.840953976188;6475.061116138134;6483.578567393104;6514.413702766774;5642.605017648725;6369.681515924185;6630.801075967729;6634.170728658918;6685.498587605949;6397.072424498421;6470.22357317125;6541;6586.37429444202;6616.139901812733;6339.651319177489;6482.35176482348;6503.219613646393;6631.3700198481065;6660.333966603398;5269.868253293622;5308.522232999048;5316.043112239757;5322.308099946982;5377.300950876361;4740.19416699162;4749.145153872336;4767.356406885096;4783.6651434399255;4785.875319299962;6141.056449558148;6181.938180618136;6201.937980620226;6203.689815509297;6283.717232724538;6687.732490700318;6718.260991290954;6734.8653026940165;6736.696848641823;6785.762498312634;6256.343083976117;6260.186175797117;6268.310485846553;6276.184096067482;6277.05844123376;5785.305763308345;5827.067669172912;5942.435468630473;5991.850203744854;6016.639001659914;6791.117154769947;6859.07402500656;6862.519623626298;6864.7940561782225;6902.689378978059;6748.358905903931;6765.661716914036;6775.1531058617675;6779.559328643622;6792.694328755317;6705.362990515893;6886.035954966409;6900.102986611709;6912.861742765016;6914.377706006484;6354.682265886595;6517.250516190698;6575.967120164369;6579.638119903451;6651.7006734698025;6753.797386078426;6758.4931130166415;6770.93229067703;6803.353681400259;6830.658467076726;6253.530985176221;6375.139356186855;6509.4792416605615;6522.673866306761;6534.215894092739;6322.178116844781;6411.326810684811;6458.38645328693;6492.772752953624;6503.154589903283;5462.863428414322;6316.368363163647;6341.778037768656;6350.872982540416;6351.301356850835;6285.1515045470105;6466.773662921775;6496.285408604956;6501.9674901625185;6506.186726659168;6193.659348735682;6211.16149319836;6273.404026617464;6313.273973493107;6328.303886572566 05:14:14 INFO - PID 1652 | |1;ext.html;763.2672634271099;806.3549160671463;831.6008316008316;1191.7854786138491;1257.6667183196455;39199.60800391995;39847.60152398479;39965;40032.59967400325;40056;40314.39528407069;40532.59467405325;40698.779036628875;40706;40826.18347633046;6844.794656160323;8012.639431225609;8070.435069545134;8437.071922088566;8440.83118337633;7022.683979220922;8397.454165479256;8509.191626795444;8547.700830470934;8572.571371431435;8484.830303393928;8519.701810436636;8570.828583428327;8572.485650860937;8576.185262400062;8478.06741258461;8482.72759086136;8517.48895066298;8530.530820804854;8542;8224.177582241788;8268.37987150966;8288.709895153701;8315.251627353568;8322.292605128572;29556.66994985231;30353.24116897095;37207;37523.812380938194;37644.494220231434;3235.530848027023;3402.268512269849;3591.8204089795668;3593.5148754918014;3605.4591811228265;30362.696373036208;30643.233919151975;30653.69346306531;30694;30705.925292614873;2996.6703662597315;3024.0927721683406;3037.726604605596;3047.101105173979;3058.4341896749174;230470;232576;262053.68973155203;296103;296146;47598;48001.75999119982;48024.519754802706;48031;48110.759446202544;49937.50062499401;50012.74993625009;50078.749606252466;50142.74928625407;50399;14336.354864031144;14450.132992020512;14476.565703028926;14478.420863165566;14529.636759081108;11479.540818367172;11542.961133498025;11552.480138393792;11576.24754390962;11587.304761714324;13964.790528142086;13964.930175349058;13980.58058258254;14008.649783755487;14074.085184462978;6248.50011999039;6272.372762723691;6281.120643109968;6292.653904035322;6332.968335158294;943.3867985809279;965.9374687843457;969.2263158420216;1007.3552926127285;1025.5436330832856;22559.661605075937;23427.414314642272;23840.403989900045;23950.64074038891;24090.759092409204;1070.7323169207698;1079.9352038877691;1099.0161710954637;1104.5195340027115;1120.6021862238952;11268.366086917351;11792.823107653392;11863.525458981716;12186.756264874653;12303.4463449145;315948;319154;319977;320006;321706;48004;51886;54275.91448171093;54657.72671136698;54753.45246547563;1344.6369480240476;1357.7352416278732;1489.4489039055402;1493.1638282561582;1508.8642022218053;21983.560328793013;23229;23617.763822361558;23688.40778980539;23724.52550949006;36658.63341366552;37034.629653703116;37066.258674826895;37073.44389834101;37140.07149821168;271471;273142;273152;273555;274249;28898.566521501773;35463;35725.28549428945;35888.28223435464;35908.4613730789;43284.78357608192;43824.51453407218;43889;44897.775511122236;45230.32154517619;22963.88518057399;29652.703472964993;29803.701962980093;30721.846390767903;31364.843175783975;26826.865865671327;28239;29656.555151673172;30585.388292234475;31310.373792523565;29964.850175748983;36760.26479470449;37167;37731.49074037008;37753.24493510059;29856.552151717307;29982;30870.69129308768;31386.21534461657;31686.207844804063;19795.90102049538;24195.36282093353;24253.15113971073;24393.756062439857;24553.75446245515 05:14:14 INFO - PID 1652 | |2;jquery.html;430.4468757646423;433.4711651784821;438.9890252743681;448.27827198210895;454.73170829210767;14818.184999825005;15498.6051255387;22266.332010039703;23069.077236910525;23195.188168414108;20788.688169677465;23576;23676.76323236767;23729.5254094918;23784.88107559464;5999.800039992008;6149.538784591151;6189.102580125879;6201.658908760016;6207.037909124093;5365.731713414333;6029.577929544912;6121.693915304239;6143.815685529419;6148.969255153718;5961.374055724152;5966.910496342558;5983;5994.8801023979495;6000.069989151667;5937.88124237515;5981.671008094553;5995.340512543617;5997.790077347294;5998.5800993930225;5302.946970530284;5303.045451818671;5328.5470734987575;5337.759800808973;5339.599530035263;27909.162725118076;28424.715752842414;28476.715232847615;28583.856645734144;30026.24934376636;2184.7159869217057;2251.684764133023;2261.604219261623;2266.5013696986803;2272.8863556822093;19810.900945495323;19920.70118948217;20108;20110.29613963519;20127.67255040159;2315.6758053872472;2320.825938054653;2390.748971358017;2392.964105538418;2398.4963157736897;185329;190638;191662.9999999986;192034.03982979996;193099;23440.882795585912;23739.05043798229;23945.204109691844;24308.270751877473;25160.245192643884;28452;32181.03456896297;32828.67171328256;37256.254874902894;41997.37003944943;11080.668499853273;11111.888881111247;11277.210595258253;11317.60388386419;11418.31490110596;8819.250363719028;8971.282297416192;9014.233790127913;9048;9067.68263110788;11412.372319522507;11774.470148843322;11903;11968.880311196777;12026.338551379758;4418.977905110518;4561.543845615412;4640.257558790645;4830.686005409637;5171.482851714798;776.7786180938406;916.9679061232824;947.673052796784;963.691618682029;991.7669347703284;19950.90270035133;20653.380398588066;21234.938253087592;21504.139834406447;21546.56906861854;969.6315400147898;969.8787651543557;974.848898420746;1000.1348833259178;1019.1897441533998;8812.295016398673;9700.805983880284;9825.722656054668;10190.796184076276;10712.625058122929;496451;546159;550701;558729;566453;137584;140479.5952040487;140710;141068.29465852812;142182.2890885539;1151.8790526994712;1160.5879912630894;1160.988390116105;1161.4076820821442;1171.1333613126396;17841.46475605734;18863.43409697711;18956.810431895505;19251.03744812726;21093.789062109183;67796.30509237111;68975;69734;70512;70964.64517677379;86616;86626.56686716527;87039;87479;87569;9937.453440060866;10265.794684106128;10282.640107596199;10355;10366.585336586451;17776.200070996576;17884.55288617795;17943.01313427774;18183;18233.726494102866;14839;14937.253137343307;14943.178125203218;15037.849621503645;15131.167785771888;13198;13282.206902068097;13618.86381136176;13777.478111707154;13869.306534673427;15064.774028389802;15235.619109522351;15264.542063738105;15343.386264549294;15489.14809685478;9428.905710942803;9603.519824008632;10122.139618132542;10329.535170917177;10354.5340459678;13295.335233238493;13319.267440290878;13424.261665608095;13494.865051349361;14856.33146508388 05:14:14 INFO - PID 1652 | |3;mootools.html;1414.5544153591618;1452.7845036319616;1471.6688745032363;1474.2333986327103;1487.2340744516575;11633.941830290847;12005.399730013509;12166.148369614142;12269.938650306747;12360.567380141696;11978.82031769522;12215.816762748565;12229.38853057348;12240.387980600957;12253.571125010627;2200.526886719355;2224.555088982206;2476.8513889166616;2540.2379286214064;2554.770070693637;2492.7881130103965;2522.318973877052;2536.5941449368106;2555.696594736679;2556.156468365435;2517.2196619048063;2524.5960646296594;2525.4949010197943;2543.4531575711217;2551.400420901087;2457.324235835151;2484.8757562121823;2491.825572209946;2495.588227942387;2499.262717498333;2453.092355828337;2454.1533171055958;2466.802655787533;2467.222824810179;2474;11758.941205294004;11829.645110646608;11831.585894493652;11834.994025507842;11869.881301186964;6385.680715964229;6621.602703837785;6629.237637671681;6633.6683165842;6643.1363922690225;3476.7566270361085;3935.9372969298415;4094.467719196515;4168.416421700964;4170.374443833419;1091.683411810582;1191.6186820217447;1203.18784820246;1265.7784887644718;1266.645339304996;15265;15681.843181568152;15719.68560628781;15824;15825.892187546877;5508.20131080991;5647.1529270609735;5653.660780353192;5748.252727145445;5786.913196302059;12064.577739779066;12114.515419383126;12293.815592766116;12294.385280736105;12295.56965506203;6412.4549413299455;6529.2817790043055;6548.541602087808;6557.147570815859;6828.283030281848;6039.486643635252;6150.569460137837;6196.783112591038;6208.844778880564;6210.658413787195;3816.6565009148753;4160.9597600599855;4399.736015838996;4437.889052773642;4504.887377815581;1282.6472720002075;1283.5186804948144;1297.4874924404996;1308.188922867828;1308.6793735534854;801.4590151647615;816.5957850863861;836.2891542189091;842.3177226446597;867.1025488619375;6354.586951848115;6364.809055728336;6681.599104053676;6734.259231484532;6931.653417329113;589.9911501327483;595.1400226672416;601.1853937914063;607.0833042106362;613.3590398034065;4011.338129208648;4036.737612055207;4151.46031015972;4154.397612346193;4265.402843601898;13981.580552583438;14517.346719397652;14942.850571494364;15078.321475533627;15082.54752357431;11707;14730.263486825615;14786.556403307917;14814.555563333117;15163.393464261526;7914.404375507957;8011.599420028975;8062.355011599058;8189.8771518427275;8199.71301004462;7020;7095.219525852255;7208.783736487913;7468.9626551866895;7558.659860306189;13116.803247951479;13251.00617453714;13277.402516886956;13323.866761332263;13491.920646348071;13598.980076494501;14013.43946242139;14088.225147616979;14119.576412707635;14134.293285335898;8512.319014478951;8955.910440895508;10764.677059688222;10833.566657333933;10843.078338341329;10642.521086550969;10668.83996740034;10772.192085593768;10773.976472235125;10786.838197427202;9077.954610226907;9264.305177111879;9277.304202184998;9313.674021409219;9350.158485736316;8985.101489850962;9017.323700722603;9028.503432311287;9046.004939456523;9061.592228349871;8093.676252949816;8283.875741863998;8289.709860155106;8291.08798032228;8299;5433.429489903661;5940.087382624925;6011.128386384037;6298.244210694746;6534.379233972765;6153.784617538365;6182.598131121463;6482.967585162232;6483.513736469689;6606.273309935999 05:14:14 INFO - PID 1652 | |4;prototype.html;350.85614897891867;380.77534254789686;391.86945661765805;398.69499832628026;407.93269110596754;5356.9732151339285;6468.838279043026;6516.934830651698;6524.575902566336;6542.378474044958;5811.593188476798;5988.7305071271785;6225.8132256032395;6268.84327891803;6328.335524769902;4042.4946881639794;4642.7910744016435;4663.137319595869;4664.183767840631;4674.602658774;4678.181318269306;4678.204705200107;4691.0617876424685;4691.765411729417;4702.764861756898;3867.4972253606993;4150.107726838729;4233.449651545295;4492.113312408796;4631;4643.024964757405;4650.046740418206;4660.5339466053465;4665.384327146488;4674.392328997209;3997.5802540733384;4002.1795531915886;4004.199160167949;4011.1777085697363;4016.6987475939422;5450.5367043801325;6244.781432649881;6248.250209974832;6262.843428914268;6282.748690052393;4807.61539076873;4883.12103821312;5176.990486855065;5507.0637991541535;5516.7517461713915;6501.28485866559;6797.694103765293;6821.69302381389;6830.965845170742;6845.178578570553;3499.842507087187;4186.723049469941;4191;4465.620422264112;4534.9319760203625;6921.307869213038;7118.644067796641;7144.999700041998;7217.675204615805;7231;4286.357046442996;4593.563611456906;4623.283391074388;4629.629629629621;4632.096741135446;3851.788151651686;4705.388299521109;4706.529347065334;4707.458642256116;4708.586274950633;5294.364676238799;5464.191511469881;5472.840641112783;5492.368377636623;5503.091989821714;6056.879477296714;6840.897386539206;6850.794476165723;6861.759838405632;6873.278305777922;1500.752375857993;1607.3570571771386;1608.8230294667578;1609.6539244062349;1612.6129728864987;941.5762906692016;1024.7540590258284;1066.2376400873413;1074.6453670288786;1077.466654006272;6376.043593460925;6378.585391949508;6430.260520040161;6432.77485288022;6440.742370305135;6648.371149068509;7143.785686429415;7163.856722865514;7197.89203161953;7228.096487939008;5743.396943320975;5871.089981052944;6328.7468501260355;6385.936140638534;6439.227292724903;3652.6164752700583;3661.9450708239397;3728.5577946445123;3741.7754934704003;3747.5315585551807;5543.556515478833;6544.273760459598;7273.709051637981;7287.052683151156;7290.854182916312;5801.477866991991;6650.501212409089;7245.456590755714;7258.745943891939;7290.781276561712;6519.011701431014;6869.656517174221;7008.964955175192;7271.78210645737;7277.636118194069;6184.103305020657;6393.680315984089;7040.345518802975;7125;7128.786136415916;6928.7228510859;7017.719291228498;7018.5788852669;7041.887433769746;7111.7866464006165;6586.769463068769;6754;7193.956876253019;7276.272372762682;7281.599512026889;3879.553851307136;4570.931436028529;4579.977100114478;4593.793279302373;4648.023914977893;6154.815355539162;7054;7079.858402831812;7080.38025930409;7083.5041547091205;6378.744850206125;6468.902966455413;6706.792777300133;6858.279880612663;6865.382115609619;5948.315943666534;6272.560920735505;6715.429188518835;6840.794776156723;6843.418309443554;4945.4312754033745;6685.498587606046;6718.428933540704;6719.529632925648;6728.629925354153;5878.853028674317;6823.590584564942;6839.452843772586;6878.65606719672;6994.790156295319;6139.508839292936;6147.323794382703;6686.3313668632745;6705.09481220029;6707.496937729592 05:14:14 INFO - PID 1652 | |5;yui.html;724.9963750181248;746.734909107267;752.3680108708684;870.7518357268178;873.66800615766;13927.37326820293;14441.133531988087;15337.38650453982;16391.262393192304;16416.34334626615;16182.433614823483;17318.913405432955;17566.209520571574;17599.384021559246;17658.117094145306;986.6053578568558;989.7080361293399;998.4458625462865;1000.8598796168542;1002.4887307473205;884.681514654727;991.3655260633201;1012.5544760305479;1014.077189757321;1014.9746256343576;970.1898914406479;1057.6456886942883;1075.4192735922632;1088.4231357380602;1088.515610553304;1069.6149386220955;1076.48328802175;1078.1428764132454;1082.0045558086579;1083.4636854756866;776.1307335783902;776.2509178642637;776.3672606825419;777.7161336112334;782.1279273609941;17365.652686946316;17420.47738567845;17717.822821771875;17776.022318772462;18095.819041809544;14631.70736585273;17832.91083544574;18247.817521824745;18279.72580411295;18333.908330458395;14615.196164210964;14827.555173344817;14856.851431485762;14875.925620371938;14876.256187190596;3598.3702852000797;3624.7281453891064;3636.672699457035;3636.690881275095;3844.115853353744;126163;127154;129080;129785;129859.35070324587;13437.86562134366;13666.248356340497;13709.108907920952;13838.377273022537;13848;25816;26482.735172648412;26561.601575976376;26742.064027758934;27034.594481082408;6496.740130394731;7651.540907545454;7674.539527628249;7699;7701.037370328709;2923.2107331020507;3610.945835812465;3747.1194269346684;3770.830312635938;3774.6791522720328;4333.285007973712;4382.539833317455;4428.955710442919;4430.689851710349;4444.577765112309;903.8553831386946;931.0456781798711;936.6768464879606;945.5130607737021;950.7528042708946;649.2857856357969;666.5534092158266;671.7212356871844;683.9145106861642;693.5318659904544;9973.601055957826;11309;11557.711057223469;11755.787167234908;12375.381230938418;645.1516256123183;653.911721917535;663.4327649859397;683.3849535418065;692.1244625548685;4976.8755781105765;5430.859519500949;5561.805336813192;5749.798757043483;5858.062709966385;106079.93920060855;135415;135543.32228338794;137364;137648.31175844258;108992.40845415316;109057.45471272751;116330;116766.8323316773;116781.24828127585;99363.50318248362;104735;104901.95098048921;105923.94076059294;106334;10735.892641073802;10737.926207379513;11106.278091924;11301.265417748149;11809.409529523662;41173.86715691309;43753.56246437495;43839.78080109579;44460.77769611131;44527;59046.704766475894;59363;60178.69910650419;60393.698031509564;60586.39413605984;14387.496437624633;14639.560813175622;14729;14762.704745904806;14823.258837057889;15868.04791712501;16304;16394.754078689068;16516.50450486487;16562.171891405622;15012.924935375253;15087;15193.240337983278;15291.158986255417;15305;14655.48705795292;14750.778738318719;15129.940904136605;15267;15297.46458873934;12123.818142728043;14796.48212312607;14819.925900370428;16373.83626163723;18178.90910545439;11589.6523104307;11868.643940681794;12214.38928053576;12297.262164270172;12523.248605083723;13075.476980920657;13834.58496245074;14062.507812226522;14550.199739014455;14593.270336483345 05:14:14 INFO - PID 1652 | __end_tp_report 05:14:14 INFO - PID 1652 | __start_cc_report 05:14:14 INFO - PID 1652 | _x_x_mozilla_cycle_collect,452 05:14:14 INFO - PID 1652 | __end_cc_report 05:14:14 INFO - PID 1652 | __startTimestamp1496664854178__endTimestamp 05:14:14 INFO - PID 1652 | 05:14:14 INFO - PID 1652 | ------- Summary: start ------- 05:14:14 INFO - PID 1652 | Number of tests: 6 05:14:14 INFO - PID 1652 | 05:14:14 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6118.37 Median:6313.27 stddev:711.29 (11.3%) stddev-sans-first:684.49 05:14:14 INFO - PID 1652 | Values: 3556.6 3582.4 3632.3 3633.1 3640.7 4685.1 6049.4 6112.3 6180.0 6186.4 5788.5 6134.4 6155.4 6176.2 6190.5 4636.0 5419.8 5422.2 5438.6 5452.9 5200.7 5524.3 5533.6 5564.9 5569.2 5339.2 5440.0 5484.8 5532.9 5535.9 5492.6 5504.7 5506.1 5520.2 5522.4 5399.4 5438.9 5451.7 5461.1 5472.3 6091.7 6122.7 6200.9 6208.2 6216.4 6470.5 6556.9 6645.9 6650.0 6662.4 6725.1 6756.2 6768.4 6773.5 6796.7 6278.1 6294.2 6305.5 6310.7 6332.7 6600.7 6695.6 6742.1 6838.0 6890.6 6167.6 6361.8 6475.1 6483.6 6514.4 5642.6 6369.7 6630.8 6634.2 6685.5 6397.1 6470.2 6541.0 6586.4 6616.1 6339.7 6482.4 6503.2 6631.4 6660.3 5269.9 5308.5 5316.0 5322.3 5377.3 4740.2 4749.1 4767.4 4783.7 4785.9 6141.1 6181.9 6201.9 6203.7 6283.7 6687.7 6718.3 6734.9 6736.7 6785.8 6256.3 6260.2 6268.3 6276.2 6277.1 5785.3 5827.1 5942.4 5991.9 6016.6 6791.1 6859.1 6862.5 6864.8 6902.7 6748.4 6765.7 6775.2 6779.6 6792.7 6705.4 6886.0 6900.1 6912.9 6914.4 6354.7 6517.3 6576.0 6579.6 6651.7 6753.8 6758.5 6770.9 6803.4 6830.7 6253.5 6375.1 6509.5 6522.7 6534.2 6322.2 6411.3 6458.4 6492.8 6503.2 5462.9 6316.4 6341.8 6350.9 6351.3 6285.2 6466.8 6496.3 6502.0 6506.2 6193.7 6211.2 6273.4 6313.3 6328.3 05:14:14 INFO - PID 1652 | 05:14:14 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:43711.86 Median:319991.50 stddev:75158.22 (23.5%) stddev-sans-first:75299.88 05:14:14 INFO - PID 1652 | Values: 763.3 806.4 831.6 1191.8 1257.7 39199.6 39847.6 39965.0 40032.6 40056.0 40314.4 40532.6 40698.8 40706.0 40826.2 6844.8 8012.6 8070.4 8437.1 8440.8 7022.7 8397.5 8509.2 8547.7 8572.6 8484.8 8519.7 8570.8 8572.5 8576.2 8478.1 8482.7 8517.5 8530.5 8542.0 8224.2 8268.4 8288.7 8315.3 8322.3 29556.7 30353.2 37207.0 37523.8 37644.5 3235.5 3402.3 3591.8 3593.5 3605.5 30362.7 30643.2 30653.7 30694.0 30705.9 2996.7 3024.1 3037.7 3047.1 3058.4 230470.0 232576.0 262053.7 296103.0 296146.0 47598.0 48001.8 48024.5 48031.0 48110.8 49937.5 50012.7 50078.7 50142.7 50399.0 14336.4 14450.1 14476.6 14478.4 14529.6 11479.5 11543.0 11552.5 11576.2 11587.3 13964.8 13964.9 13980.6 14008.6 14074.1 6248.5 6272.4 6281.1 6292.7 6333.0 943.4 965.9 969.2 1007.4 1025.5 22559.7 23427.4 23840.4 23950.6 24090.8 1070.7 1079.9 1099.0 1104.5 1120.6 11268.4 11792.8 11863.5 12186.8 12303.4 315948.0 319154.0 319977.0 320006.0 321706.0 48004.0 51886.0 54275.9 54657.7 54753.5 1344.6 1357.7 1489.4 1493.2 1508.9 21983.6 23229.0 23617.8 23688.4 23724.5 36658.6 37034.6 37066.3 37073.4 37140.1 271471.0 273142.0 273152.0 273555.0 274249.0 28898.6 35463.0 35725.3 35888.3 35908.5 43284.8 43824.5 43889.0 44897.8 45230.3 22963.9 29652.7 29803.7 30721.8 31364.8 26826.9 28239.0 29656.6 30585.4 31310.4 29964.9 36760.3 37167.0 37731.5 37753.2 29856.6 29982.0 30870.7 31386.2 31686.2 19795.9 24195.4 24253.2 24393.8 24553.8 05:14:14 INFO - PID 1652 | 05:14:14 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:38932.49 Median:23626.38 stddev:94083.37 (398.2%) stddev-sans-first:94302.89 05:14:14 INFO - PID 1652 | Values: 430.4 433.5 439.0 448.3 454.7 14818.2 15498.6 22266.3 23069.1 23195.2 20788.7 23576.0 23676.8 23729.5 23784.9 5999.8 6149.5 6189.1 6201.7 6207.0 5365.7 6029.6 6121.7 6143.8 6149.0 5961.4 5966.9 5983.0 5994.9 6000.1 5937.9 5981.7 5995.3 5997.8 5998.6 5302.9 5303.0 5328.5 5337.8 5339.6 27909.2 28424.7 28476.7 28583.9 30026.2 2184.7 2251.7 2261.6 2266.5 2272.9 19810.9 19920.7 20108.0 20110.3 20127.7 2315.7 2320.8 2390.7 2393.0 2398.5 185329.0 190638.0 191663.0 192034.0 193099.0 23440.9 23739.1 23945.2 24308.3 25160.2 28452.0 32181.0 32828.7 37256.3 41997.4 11080.7 11111.9 11277.2 11317.6 11418.3 8819.3 8971.3 9014.2 9048.0 9067.7 11412.4 11774.5 11903.0 11968.9 12026.3 4419.0 4561.5 4640.3 4830.7 5171.5 776.8 917.0 947.7 963.7 991.8 19950.9 20653.4 21234.9 21504.1 21546.6 969.6 969.9 974.8 1000.1 1019.2 8812.3 9700.8 9825.7 10190.8 10712.6 496451.0 546159.0 550701.0 558729.0 566453.0 137584.0 140479.6 140710.0 141068.3 142182.3 1151.9 1160.6 1161.0 1161.4 1171.1 17841.5 18863.4 18956.8 19251.0 21093.8 67796.3 68975.0 69734.0 70512.0 70964.6 86616.0 86626.6 87039.0 87479.0 87569.0 9937.5 10265.8 10282.6 10355.0 10366.6 17776.2 17884.6 17943.0 18183.0 18233.7 14839.0 14937.3 14943.2 15037.8 15131.2 13198.0 13282.2 13618.9 13777.5 13869.3 15064.8 15235.6 15264.5 15343.4 15489.1 9428.9 9603.5 10122.1 10329.5 10354.5 13295.3 13319.3 13424.3 13494.9 14856.3 05:14:14 INFO - PID 1652 | 05:14:14 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7138.87 Median:2555.23 stddev:4495.41 (175.9%) stddev-sans-first:4487.44 05:14:14 INFO - PID 1652 | Values: 1414.6 1452.8 1471.7 1474.2 1487.2 11633.9 12005.4 12166.1 12269.9 12360.6 11978.8 12215.8 12229.4 12240.4 12253.6 2200.5 2224.6 2476.9 2540.2 2554.8 2492.8 2522.3 2536.6 2555.7 2556.2 2517.2 2524.6 2525.5 2543.5 2551.4 2457.3 2484.9 2491.8 2495.6 2499.3 2453.1 2454.2 2466.8 2467.2 2474.0 11758.9 11829.6 11831.6 11835.0 11869.9 6385.7 6621.6 6629.2 6633.7 6643.1 3476.8 3935.9 4094.5 4168.4 4170.4 1091.7 1191.6 1203.2 1265.8 1266.6 15265.0 15681.8 15719.7 15824.0 15825.9 5508.2 5647.2 5653.7 5748.3 5786.9 12064.6 12114.5 12293.8 12294.4 12295.6 6412.5 6529.3 6548.5 6557.1 6828.3 6039.5 6150.6 6196.8 6208.8 6210.7 3816.7 4161.0 4399.7 4437.9 4504.9 1282.6 1283.5 1297.5 1308.2 1308.7 801.5 816.6 836.3 842.3 867.1 6354.6 6364.8 6681.6 6734.3 6931.7 590.0 595.1 601.2 607.1 613.4 4011.3 4036.7 4151.5 4154.4 4265.4 13981.6 14517.3 14942.9 15078.3 15082.5 11707.0 14730.3 14786.6 14814.6 15163.4 7914.4 8011.6 8062.4 8189.9 8199.7 7020.0 7095.2 7208.8 7469.0 7558.7 13116.8 13251.0 13277.4 13323.9 13491.9 13599.0 14013.4 14088.2 14119.6 14134.3 8512.3 8955.9 10764.7 10833.6 10843.1 10642.5 10668.8 10772.2 10774.0 10786.8 9078.0 9264.3 9277.3 9313.7 9350.2 8985.1 9017.3 9028.5 9046.0 9061.6 8093.7 8283.9 8289.7 8291.1 8299.0 5433.4 5940.1 6011.1 6298.2 6534.4 6153.8 6182.6 6483.0 6483.5 6606.3 05:14:14 INFO - PID 1652 | 05:14:14 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5471.89 Median:6235.30 stddev:1743.91 (28.0%) stddev-sans-first:1705.92 05:14:14 INFO - PID 1652 | Values: 350.9 380.8 391.9 398.7 407.9 5357.0 6468.8 6516.9 6524.6 6542.4 5811.6 5988.7 6225.8 6268.8 6328.3 4042.5 4642.8 4663.1 4664.2 4674.6 4678.2 4678.2 4691.1 4691.8 4702.8 3867.5 4150.1 4233.4 4492.1 4631.0 4643.0 4650.0 4660.5 4665.4 4674.4 3997.6 4002.2 4004.2 4011.2 4016.7 5450.5 6244.8 6248.3 6262.8 6282.7 4807.6 4883.1 5177.0 5507.1 5516.8 6501.3 6797.7 6821.7 6831.0 6845.2 3499.8 4186.7 4191.0 4465.6 4534.9 6921.3 7118.6 7145.0 7217.7 7231.0 4286.4 4593.6 4623.3 4629.6 4632.1 3851.8 4705.4 4706.5 4707.5 4708.6 5294.4 5464.2 5472.8 5492.4 5503.1 6056.9 6840.9 6850.8 6861.8 6873.3 1500.8 1607.4 1608.8 1609.7 1612.6 941.6 1024.8 1066.2 1074.6 1077.5 6376.0 6378.6 6430.3 6432.8 6440.7 6648.4 7143.8 7163.9 7197.9 7228.1 5743.4 5871.1 6328.7 6385.9 6439.2 3652.6 3661.9 3728.6 3741.8 3747.5 5543.6 6544.3 7273.7 7287.1 7290.9 5801.5 6650.5 7245.5 7258.7 7290.8 6519.0 6869.7 7009.0 7271.8 7277.6 6184.1 6393.7 7040.3 7125.0 7128.8 6928.7 7017.7 7018.6 7041.9 7111.8 6586.8 6754.0 7194.0 7276.3 7281.6 3879.6 4570.9 4580.0 4593.8 4648.0 6154.8 7054.0 7079.9 7080.4 7083.5 6378.7 6468.9 6706.8 6858.3 6865.4 5948.3 6272.6 6715.4 6840.8 6843.4 4945.4 6685.5 6718.4 6719.5 6728.6 5878.9 6823.6 6839.5 6878.7 6994.8 6139.5 6147.3 6686.3 6705.1 6707.5 05:14:14 INFO - PID 1652 | 05:14:14 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:23903.63 Median:16466.42 stddev:36052.14 (218.9%) stddev-sans-first:36111.26 05:14:14 INFO - PID 1652 | Values: 725.0 746.7 752.4 870.8 873.7 13927.4 14441.1 15337.4 16391.3 16416.3 16182.4 17318.9 17566.2 17599.4 17658.1 986.6 989.7 998.4 1000.9 1002.5 884.7 991.4 1012.6 1014.1 1015.0 970.2 1057.6 1075.4 1088.4 1088.5 1069.6 1076.5 1078.1 1082.0 1083.5 776.1 776.3 776.4 777.7 782.1 17365.7 17420.5 17717.8 17776.0 18095.8 14631.7 17832.9 18247.8 18279.7 18333.9 14615.2 14827.6 14856.9 14875.9 14876.3 3598.4 3624.7 3636.7 3636.7 3844.1 126163.0 127154.0 129080.0 129785.0 129859.4 13437.9 13666.2 13709.1 13838.4 13848.0 25816.0 26482.7 26561.6 26742.1 27034.6 6496.7 7651.5 7674.5 7699.0 7701.0 2923.2 3610.9 3747.1 3770.8 3774.7 4333.3 4382.5 4429.0 4430.7 4444.6 903.9 931.0 936.7 945.5 950.8 649.3 666.6 671.7 683.9 693.5 9973.6 11309.0 11557.7 11755.8 12375.4 645.2 653.9 663.4 683.4 692.1 4976.9 5430.9 5561.8 5749.8 5858.1 106079.9 135415.0 135543.3 137364.0 137648.3 108992.4 109057.5 116330.0 116766.8 116781.2 99363.5 104735.0 104902.0 105923.9 106334.0 10735.9 10737.9 11106.3 11301.3 11809.4 41173.9 43753.6 43839.8 44460.8 44527.0 59046.7 59363.0 60178.7 60393.7 60586.4 14387.5 14639.6 14729.0 14762.7 14823.3 15868.0 16304.0 16394.8 16516.5 16562.2 15012.9 15087.0 15193.2 15291.2 15305.0 14655.5 14750.8 15129.9 15267.0 15297.5 12123.8 14796.5 14819.9 16373.8 18178.9 11589.7 11868.6 12214.4 12297.3 12523.2 13075.5 13834.6 14062.5 14550.2 14593.3 05:14:14 INFO - PID 1652 | -------- Summary: end -------- 05:14:14 INFO - PID 1652 | 05:14:14 INFO - TEST-INFO | 1652: exit 0 05:14:14 INFO - TEST-OK | dromaeo_css | took 1143880ms 05:14:14 INFO - TEST-START | kraken 05:14:14 INFO - operating with platform_type : mac_ 05:14:14 INFO - Initialising browser for kraken test... 05:14:14 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpN6HBsK/profile 05:14:14 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground) 05:14:48 INFO - TEST-INFO | 1662: killed by SIGTERM 05:14:48 INFO - Browser initialized. 05:14:48 INFO - Running cycle 1/1 for kraken test... 05:14:48 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpN6HBsK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:14:50 INFO - PID 1666 | 2017-06-05 05:14:50.742 plugin-container[1667:12047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 05:14:50 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:14:50 INFO - PID 1666 | 2017-06-05 05:14:50.743 plugin-container[1667:12047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 05:14:50 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:14:50 INFO - PID 1666 | 2017-06-05 05:14:50.743 plugin-container[1667:12047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:14:51 INFO - PID 1666 | 2017-06-05 05:14:51.459 plugin-container[1668:12093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 05:14:51 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:14:51 INFO - PID 1666 | 2017-06-05 05:14:51.460 plugin-container[1668:12093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 05:14:51 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:14:51 INFO - PID 1666 | 2017-06-05 05:14:51.460 plugin-container[1668:12093] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:15:00 INFO - PID 1666 | RSS: Main: 261144576 05:15:00 INFO - PID 1666 | 05:15:03 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection) 05:15:04 INFO - PID 1666 | RSS: Main: 270462976 05:15:04 INFO - PID 1666 | 05:15:06 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-dft) 05:15:07 INFO - PID 1666 | RSS: Main: 280698880 05:15:07 INFO - PID 1666 | 05:15:11 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-fft) 05:15:11 INFO - PID 1666 | RSS: Main: 282988544 05:15:11 INFO - PID 1666 | 05:15:13 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator) 05:15:14 INFO - PID 1666 | RSS: Main: 284176384 05:15:14 INFO - PID 1666 | 05:15:16 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:15:16 INFO - PID 1666 | RSS: Main: 285908992 05:15:16 INFO - PID 1666 | 05:15:20 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom) 05:15:20 INFO - PID 1666 | RSS: Main: 290435072 05:15:20 INFO - PID 1666 | 05:15:23 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate) 05:15:24 INFO - PID 1666 | RSS: Main: 294084608 05:15:24 INFO - PID 1666 | 05:15:27 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial) 05:15:28 INFO - PID 1666 | RSS: Main: 292851712 05:15:28 INFO - PID 1666 | 05:15:29 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:15:30 INFO - PID 1666 | RSS: Main: 292745216 05:15:30 INFO - PID 1666 | 05:15:32 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:15:32 INFO - PID 1666 | RSS: Main: 292642816 05:15:32 INFO - PID 1666 | 05:15:34 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:15:35 INFO - PID 1666 | RSS: Main: 292573184 05:15:35 INFO - PID 1666 | 05:15:38 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:15:39 INFO - PID 1666 | RSS: Main: 292229120 05:15:39 INFO - PID 1666 | 05:15:42 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:15:42 INFO - PID 1666 | RSS: Main: 292691968 05:15:42 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49231/tests/kraken/driver.html?testName=ai-astar) 05:15:44 INFO - PID 1666 | RSS: Main: 294039552 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | __start_tp_report 05:15:44 INFO - PID 1666 | _x_x_mozilla_page_load 05:15:44 INFO - PID 1666 | _x_x_mozilla_page_load_details 05:15:44 INFO - PID 1666 | |i|pagename|runs| 05:15:44 INFO - PID 1666 | |0;ai-astar;101;121;113;111;113;114;114;113;113;114 05:15:44 INFO - PID 1666 | |1;audio-beat-detection;133;109;112;144;142;92;94;139;158;148 05:15:44 INFO - PID 1666 | |2;audio-dft;216;280;238;210;208;211;245;225;198;199 05:15:44 INFO - PID 1666 | |3;audio-fft;80;58;60;92;72;59;54;56;92;87 05:15:44 INFO - PID 1666 | |4;audio-oscillator;82;68;84;73;72;71;86;80;55;92 05:15:44 INFO - PID 1666 | |5;imaging-gaussian-blur;131;89;137;136;92;112;94;82;136;90 05:15:44 INFO - PID 1666 | |6;imaging-darkroom;99;98;96;98;97;98;98;97;97;100 05:15:44 INFO - PID 1666 | |7;imaging-desaturate;91;94;88;90;100;105;142;86;147;143 05:15:44 INFO - PID 1666 | |8;json-parse-financial;50;50;50;52;50;54;49;44;67;76 05:15:44 INFO - PID 1666 | |9;json-stringify-tinderbox;55;56;60;63;63;61;55;57;56;54 05:15:44 INFO - PID 1666 | |10;stanford-crypto-aes;88;87;90;88;89;87;88;90;119;122 05:15:44 INFO - PID 1666 | |11;stanford-crypto-ccm;138;136;136;135;139;141;136;164;150;154 05:15:44 INFO - PID 1666 | |12;stanford-crypto-pbkdf2;191;179;187;188;202;235;241;187;188;187 05:15:44 INFO - PID 1666 | |13;stanford-crypto-sha256-iterative;68;69;66;64;66;67;68;65;70;65 05:15:44 INFO - PID 1666 | __end_tp_report 05:15:44 INFO - PID 1666 | __start_cc_report 05:15:44 INFO - PID 1666 | _x_x_mozilla_cycle_collect,1206 05:15:44 INFO - PID 1666 | __end_cc_report 05:15:44 INFO - PID 1666 | __startTimestamp1496664944911__endTimestamp 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | ------- Summary: start ------- 05:15:44 INFO - PID 1666 | Number of tests: 14 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.50 stddev:4.88 (4.3%) stddev-sans-first:2.78 05:15:44 INFO - PID 1666 | Values: 101.0 121.0 113.0 111.0 113.0 114.0 114.0 113.0 113.0 114.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#1] audio-beat-detection Cycles:10 Average:127.10 Median:146.00 stddev:23.47 (16.1%) stddev-sans-first:24.80 05:15:44 INFO - PID 1666 | Values: 133.0 109.0 112.0 144.0 142.0 92.0 94.0 139.0 158.0 148.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#2] audio-dft Cycles:10 Average:223.00 Median:220.50 stddev:25.28 (11.5%) stddev-sans-first:26.68 05:15:44 INFO - PID 1666 | Values: 216.0 280.0 238.0 210.0 208.0 211.0 245.0 225.0 198.0 199.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#3] audio-fft Cycles:10 Average:71.00 Median:76.00 stddev:15.52 (20.4%) stddev-sans-first:16.12 05:15:44 INFO - PID 1666 | Values: 80.0 58.0 60.0 92.0 72.0 59.0 54.0 56.0 92.0 87.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:81.00 stddev:10.68 (13.2%) stddev-sans-first:11.12 05:15:44 INFO - PID 1666 | Values: 82.0 68.0 84.0 73.0 72.0 71.0 86.0 80.0 55.0 92.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:85.50 stddev:22.93 (26.8%) stddev-sans-first:23.01 05:15:44 INFO - PID 1666 | Values: 131.0 89.0 137.0 136.0 92.0 112.0 94.0 82.0 136.0 90.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:98.00 stddev:1.14 (1.2%) stddev-sans-first:1.12 05:15:44 INFO - PID 1666 | Values: 99.0 98.0 96.0 98.0 97.0 98.0 98.0 97.0 97.0 100.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:87.00 stddev:25.09 (28.8%) stddev-sans-first:25.79 05:15:44 INFO - PID 1666 | Values: 91.0 94.0 88.0 90.0 100.0 105.0 142.0 86.0 147.0 143.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:51.00 stddev:9.69 (19.0%) stddev-sans-first:10.16 05:15:44 INFO - PID 1666 | Values: 50.0 50.0 50.0 52.0 50.0 54.0 49.0 44.0 67.0 76.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:58.00 Median:58.50 stddev:3.43 (5.9%) stddev-sans-first:3.46 05:15:44 INFO - PID 1666 | Values: 55.0 56.0 60.0 63.0 63.0 61.0 55.0 57.0 56.0 54.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:88.00 stddev:13.60 (15.5%) stddev-sans-first:14.20 05:15:44 INFO - PID 1666 | Values: 88.0 87.0 90.0 88.0 89.0 87.0 88.0 90.0 119.0 122.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:140.00 stddev:9.81 (7.0%) stddev-sans-first:10.25 05:15:44 INFO - PID 1666 | Values: 138.0 136.0 136.0 135.0 139.0 141.0 136.0 164.0 150.0 154.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.50 Median:189.50 stddev:21.61 (11.4%) stddev-sans-first:22.75 05:15:44 INFO - PID 1666 | Values: 191.0 179.0 187.0 188.0 202.0 235.0 241.0 187.0 188.0 187.0 05:15:44 INFO - PID 1666 | 05:15:44 INFO - PID 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:1.93 (2.9%) stddev-sans-first:2.00 05:15:44 INFO - PID 1666 | Values: 68.0 69.0 66.0 64.0 66.0 67.0 68.0 65.0 70.0 65.0 05:15:44 INFO - PID 1666 | -------- Summary: end -------- 05:15:44 INFO - PID 1666 | 05:15:45 INFO - TEST-INFO | 1666: exit 0 05:15:45 INFO - TEST-OK | kraken | took 91145ms 05:15:45 INFO - SUITE-END | took 1235s 05:15:46 INFO - Completed test suite (00:20:35) 05:15:46 INFO - javascript benchmark 05:15:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8089.236695005172, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3556.608773034966, 3582.444721068234, 3632.309861126386, 3633.10988807742, 3640.6541378569027, 4685.062987402524, 6049.395060493948, 6112.327643959161, 6179.969100154504, 6186.381361863817, 5788.5369170466365, 6134.4478996890275, 6155.446009859112, 6176.227971503562, 6190.50475961922, 4635.980084381431, 5419.783208671648, 5422.213779002042, 5438.619296649236, 5452.863678408042, 5200.7399630018535, 5524.281843360358, 5533.612647114704, 5564.944350556483, 5569.164625306196, 5339.172428273585, 5440.0, 5484.808031718911, 5532.917006244909, 5535.916961245584, 5492.615516913817, 5504.669719816824, 5506.119020956668, 5520.171974203901, 5522.420145884665, 5399.433059528771, 5438.91841622376, 5451.727413629303, 5461.1262198048125, 5472.288602481652, 6091.664958427285, 6122.663253521055, 6200.937990620081, 6208.161898143738, 6216.378362163793, 6470.45001174896, 6556.93443065568, 6645.900311495331, 6650.0, 6662.367075127855, 6725.05849181115, 6756.223034351064, 6768.357006084414, 6773.525853190278, 6796.6941487632685, 6278.121063051177, 6294.244690637107, 6305.527085468608, 6310.652914089677, 6332.715027823759, 6600.669966501656, 6695.564788288746, 6742.123523941857, 6837.9658101710165, 6890.586564806128, 6167.5682702210415, 6361.840953976188, 6475.061116138134, 6483.578567393104, 6514.413702766774, 5642.605017648725, 6369.681515924185, 6630.801075967729, 6634.170728658918, 6685.498587605949, 6397.072424498421, 6470.22357317125, 6541.0, 6586.37429444202, 6616.139901812733, 6339.651319177489, 6482.35176482348, 6503.219613646393, 6631.3700198481065, 6660.333966603398, 5269.868253293622, 5308.522232999048, 5316.043112239757, 5322.308099946982, 5377.300950876361, 4740.19416699162, 4749.145153872336, 4767.356406885096, 4783.6651434399255, 4785.875319299962, 6141.056449558148, 6181.938180618136, 6201.937980620226, 6203.689815509297, 6283.717232724538, 6687.732490700318, 6718.260991290954, 6734.8653026940165, 6736.696848641823, 6785.762498312634, 6256.343083976117, 6260.186175797117, 6268.310485846553, 6276.184096067482, 6277.05844123376, 5785.305763308345, 5827.067669172912, 5942.435468630473, 5991.850203744854, 6016.639001659914, 6791.117154769947, 6859.07402500656, 6862.519623626298, 6864.7940561782225, 6902.689378978059, 6748.358905903931, 6765.661716914036, 6775.1531058617675, 6779.559328643622, 6792.694328755317, 6705.362990515893, 6886.035954966409, 6900.102986611709, 6912.861742765016, 6914.377706006484, 6354.682265886595, 6517.250516190698, 6575.967120164369, 6579.638119903451, 6651.7006734698025, 6753.797386078426, 6758.4931130166415, 6770.93229067703, 6803.353681400259, 6830.658467076726, 6253.530985176221, 6375.139356186855, 6509.4792416605615, 6522.673866306761, 6534.215894092739, 6322.178116844781, 6411.326810684811, 6458.38645328693, 6492.772752953624, 6503.154589903283, 5462.863428414322, 6316.368363163647, 6341.778037768656, 6350.872982540416, 6351.301356850835, 6285.1515045470105, 6466.773662921775, 6496.285408604956, 6501.9674901625185, 6506.186726659168, 6193.659348735682, 6211.16149319836, 6273.404026617464, 6313.273973493107, 6328.303886572566], "value": 6072.593169574664, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.2672634271099, 806.3549160671463, 831.6008316008316, 1191.7854786138491, 1257.6667183196455, 39199.60800391995, 39847.60152398479, 39965.0, 40032.59967400325, 40056.0, 40314.39528407069, 40532.59467405325, 40698.779036628875, 40706.0, 40826.18347633046, 6844.794656160323, 8012.639431225609, 8070.435069545134, 8437.071922088566, 8440.83118337633, 7022.683979220922, 8397.454165479256, 8509.191626795444, 8547.700830470934, 8572.571371431435, 8484.830303393928, 8519.701810436636, 8570.828583428327, 8572.485650860937, 8576.185262400062, 8478.06741258461, 8482.72759086136, 8517.48895066298, 8530.530820804854, 8542.0, 8224.177582241788, 8268.37987150966, 8288.709895153701, 8315.251627353568, 8322.292605128572, 29556.66994985231, 30353.24116897095, 37207.0, 37523.812380938194, 37644.494220231434, 3235.530848027023, 3402.268512269849, 3591.8204089795668, 3593.5148754918014, 3605.4591811228265, 30362.696373036208, 30643.233919151975, 30653.69346306531, 30694.0, 30705.925292614873, 2996.6703662597315, 3024.0927721683406, 3037.726604605596, 3047.101105173979, 3058.4341896749174, 230470.0, 232576.0, 262053.68973155203, 296103.0, 296146.0, 47598.0, 48001.75999119982, 48024.519754802706, 48031.0, 48110.759446202544, 49937.50062499401, 50012.74993625009, 50078.749606252466, 50142.74928625407, 50399.0, 14336.354864031144, 14450.132992020512, 14476.565703028926, 14478.420863165566, 14529.636759081108, 11479.540818367172, 11542.961133498025, 11552.480138393792, 11576.24754390962, 11587.304761714324, 13964.790528142086, 13964.930175349058, 13980.58058258254, 14008.649783755487, 14074.085184462978, 6248.50011999039, 6272.372762723691, 6281.120643109968, 6292.653904035322, 6332.968335158294, 943.3867985809279, 965.9374687843457, 969.2263158420216, 1007.3552926127285, 1025.5436330832856, 22559.661605075937, 23427.414314642272, 23840.403989900045, 23950.64074038891, 24090.759092409204, 1070.7323169207698, 1079.9352038877691, 1099.0161710954637, 1104.5195340027115, 1120.6021862238952, 11268.366086917351, 11792.823107653392, 11863.525458981716, 12186.756264874653, 12303.4463449145, 315948.0, 319154.0, 319977.0, 320006.0, 321706.0, 48004.0, 51886.0, 54275.91448171093, 54657.72671136698, 54753.45246547563, 1344.6369480240476, 1357.7352416278732, 1489.4489039055402, 1493.1638282561582, 1508.8642022218053, 21983.560328793013, 23229.0, 23617.763822361558, 23688.40778980539, 23724.52550949006, 36658.63341366552, 37034.629653703116, 37066.258674826895, 37073.44389834101, 37140.07149821168, 271471.0, 273142.0, 273152.0, 273555.0, 274249.0, 28898.566521501773, 35463.0, 35725.28549428945, 35888.28223435464, 35908.4613730789, 43284.78357608192, 43824.51453407218, 43889.0, 44897.775511122236, 45230.32154517619, 22963.88518057399, 29652.703472964993, 29803.701962980093, 30721.846390767903, 31364.843175783975, 26826.865865671327, 28239.0, 29656.555151673172, 30585.388292234475, 31310.373792523565, 29964.850175748983, 36760.26479470449, 37167.0, 37731.49074037008, 37753.24493510059, 29856.552151717307, 29982.0, 30870.69129308768, 31386.21534461657, 31686.207844804063, 19795.90102049538, 24195.36282093353, 24253.15113971073, 24393.756062439857, 24553.75446245515], "value": 17294.837891767635, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.4468757646423, 433.4711651784821, 438.9890252743681, 448.27827198210895, 454.73170829210767, 14818.184999825005, 15498.6051255387, 22266.332010039703, 23069.077236910525, 23195.188168414108, 20788.688169677465, 23576.0, 23676.76323236767, 23729.5254094918, 23784.88107559464, 5999.800039992008, 6149.538784591151, 6189.102580125879, 6201.658908760016, 6207.037909124093, 5365.731713414333, 6029.577929544912, 6121.693915304239, 6143.815685529419, 6148.969255153718, 5961.374055724152, 5966.910496342558, 5983.0, 5994.8801023979495, 6000.069989151667, 5937.88124237515, 5981.671008094553, 5995.340512543617, 5997.790077347294, 5998.5800993930225, 5302.946970530284, 5303.045451818671, 5328.5470734987575, 5337.759800808973, 5339.599530035263, 27909.162725118076, 28424.715752842414, 28476.715232847615, 28583.856645734144, 30026.24934376636, 2184.7159869217057, 2251.684764133023, 2261.604219261623, 2266.5013696986803, 2272.8863556822093, 19810.900945495323, 19920.70118948217, 20108.0, 20110.29613963519, 20127.67255040159, 2315.6758053872472, 2320.825938054653, 2390.748971358017, 2392.964105538418, 2398.4963157736897, 185329.0, 190638.0, 191662.9999999986, 192034.03982979996, 193099.0, 23440.882795585912, 23739.05043798229, 23945.204109691844, 24308.270751877473, 25160.245192643884, 28452.0, 32181.03456896297, 32828.67171328256, 37256.254874902894, 41997.37003944943, 11080.668499853273, 11111.888881111247, 11277.210595258253, 11317.60388386419, 11418.31490110596, 8819.250363719028, 8971.282297416192, 9014.233790127913, 9048.0, 9067.68263110788, 11412.372319522507, 11774.470148843322, 11903.0, 11968.880311196777, 12026.338551379758, 4418.977905110518, 4561.543845615412, 4640.257558790645, 4830.686005409637, 5171.482851714798, 776.7786180938406, 916.9679061232824, 947.673052796784, 963.691618682029, 991.7669347703284, 19950.90270035133, 20653.380398588066, 21234.938253087592, 21504.139834406447, 21546.56906861854, 969.6315400147898, 969.8787651543557, 974.848898420746, 1000.1348833259178, 1019.1897441533998, 8812.295016398673, 9700.805983880284, 9825.722656054668, 10190.796184076276, 10712.625058122929, 496451.0, 546159.0, 550701.0, 558729.0, 566453.0, 137584.0, 140479.5952040487, 140710.0, 141068.29465852812, 142182.2890885539, 1151.8790526994712, 1160.5879912630894, 1160.988390116105, 1161.4076820821442, 1171.1333613126396, 17841.46475605734, 18863.43409697711, 18956.810431895505, 19251.03744812726, 21093.789062109183, 67796.30509237111, 68975.0, 69734.0, 70512.0, 70964.64517677379, 86616.0, 86626.56686716527, 87039.0, 87479.0, 87569.0, 9937.453440060866, 10265.794684106128, 10282.640107596199, 10355.0, 10366.585336586451, 17776.200070996576, 17884.55288617795, 17943.01313427774, 18183.0, 18233.726494102866, 14839.0, 14937.253137343307, 14943.178125203218, 15037.849621503645, 15131.167785771888, 13198.0, 13282.206902068097, 13618.86381136176, 13777.478111707154, 13869.306534673427, 15064.774028389802, 15235.619109522351, 15264.542063738105, 15343.386264549294, 15489.14809685478, 9428.905710942803, 9603.519824008632, 10122.139618132542, 10329.535170917177, 10354.5340459678, 13295.335233238493, 13319.267440290878, 13424.261665608095, 13494.865051349361, 14856.33146508388], "value": 11934.565537527205, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.5544153591618, 1452.7845036319616, 1471.6688745032363, 1474.2333986327103, 1487.2340744516575, 11633.941830290847, 12005.399730013509, 12166.148369614142, 12269.938650306747, 12360.567380141696, 11978.82031769522, 12215.816762748565, 12229.38853057348, 12240.387980600957, 12253.571125010627, 2200.526886719355, 2224.555088982206, 2476.8513889166616, 2540.2379286214064, 2554.770070693637, 2492.7881130103965, 2522.318973877052, 2536.5941449368106, 2555.696594736679, 2556.156468365435, 2517.2196619048063, 2524.5960646296594, 2525.4949010197943, 2543.4531575711217, 2551.400420901087, 2457.324235835151, 2484.8757562121823, 2491.825572209946, 2495.588227942387, 2499.262717498333, 2453.092355828337, 2454.1533171055958, 2466.802655787533, 2467.222824810179, 2474.0, 11758.941205294004, 11829.645110646608, 11831.585894493652, 11834.994025507842, 11869.881301186964, 6385.680715964229, 6621.602703837785, 6629.237637671681, 6633.6683165842, 6643.1363922690225, 3476.7566270361085, 3935.9372969298415, 4094.467719196515, 4168.416421700964, 4170.374443833419, 1091.683411810582, 1191.6186820217447, 1203.18784820246, 1265.7784887644718, 1266.645339304996, 15265.0, 15681.843181568152, 15719.68560628781, 15824.0, 15825.892187546877, 5508.20131080991, 5647.1529270609735, 5653.660780353192, 5748.252727145445, 5786.913196302059, 12064.577739779066, 12114.515419383126, 12293.815592766116, 12294.385280736105, 12295.56965506203, 6412.4549413299455, 6529.2817790043055, 6548.541602087808, 6557.147570815859, 6828.283030281848, 6039.486643635252, 6150.569460137837, 6196.783112591038, 6208.844778880564, 6210.658413787195, 3816.6565009148753, 4160.9597600599855, 4399.736015838996, 4437.889052773642, 4504.887377815581, 1282.6472720002075, 1283.5186804948144, 1297.4874924404996, 1308.188922867828, 1308.6793735534854, 801.4590151647615, 816.5957850863861, 836.2891542189091, 842.3177226446597, 867.1025488619375, 6354.586951848115, 6364.809055728336, 6681.599104053676, 6734.259231484532, 6931.653417329113, 589.9911501327483, 595.1400226672416, 601.1853937914063, 607.0833042106362, 613.3590398034065, 4011.338129208648, 4036.737612055207, 4151.46031015972, 4154.397612346193, 4265.402843601898, 13981.580552583438, 14517.346719397652, 14942.850571494364, 15078.321475533627, 15082.54752357431, 11707.0, 14730.263486825615, 14786.556403307917, 14814.555563333117, 15163.393464261526, 7914.404375507957, 8011.599420028975, 8062.355011599058, 8189.8771518427275, 8199.71301004462, 7020.0, 7095.219525852255, 7208.783736487913, 7468.9626551866895, 7558.659860306189, 13116.803247951479, 13251.00617453714, 13277.402516886956, 13323.866761332263, 13491.920646348071, 13598.980076494501, 14013.43946242139, 14088.225147616979, 14119.576412707635, 14134.293285335898, 8512.319014478951, 8955.910440895508, 10764.677059688222, 10833.566657333933, 10843.078338341329, 10642.521086550969, 10668.83996740034, 10772.192085593768, 10773.976472235125, 10786.838197427202, 9077.954610226907, 9264.305177111879, 9277.304202184998, 9313.674021409219, 9350.158485736316, 8985.101489850962, 9017.323700722603, 9028.503432311287, 9046.004939456523, 9061.592228349871, 8093.676252949816, 8283.875741863998, 8289.709860155106, 8291.08798032228, 8299.0, 5433.429489903661, 5940.087382624925, 6011.128386384037, 6298.244210694746, 6534.379233972765, 6153.784617538365, 6182.598131121463, 6482.967585162232, 6483.513736469689, 6606.273309935999], "value": 5347.522080186721, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.85614897891867, 380.77534254789686, 391.86945661765805, 398.69499832628026, 407.93269110596754, 5356.9732151339285, 6468.838279043026, 6516.934830651698, 6524.575902566336, 6542.378474044958, 5811.593188476798, 5988.7305071271785, 6225.8132256032395, 6268.84327891803, 6328.335524769902, 4042.4946881639794, 4642.7910744016435, 4663.137319595869, 4664.183767840631, 4674.602658774, 4678.181318269306, 4678.204705200107, 4691.0617876424685, 4691.765411729417, 4702.764861756898, 3867.4972253606993, 4150.107726838729, 4233.449651545295, 4492.113312408796, 4631.0, 4643.024964757405, 4650.046740418206, 4660.5339466053465, 4665.384327146488, 4674.392328997209, 3997.5802540733384, 4002.1795531915886, 4004.199160167949, 4011.1777085697363, 4016.6987475939422, 5450.5367043801325, 6244.781432649881, 6248.250209974832, 6262.843428914268, 6282.748690052393, 4807.61539076873, 4883.12103821312, 5176.990486855065, 5507.0637991541535, 5516.7517461713915, 6501.28485866559, 6797.694103765293, 6821.69302381389, 6830.965845170742, 6845.178578570553, 3499.842507087187, 4186.723049469941, 4191.0, 4465.620422264112, 4534.9319760203625, 6921.307869213038, 7118.644067796641, 7144.999700041998, 7217.675204615805, 7231.0, 4286.357046442996, 4593.563611456906, 4623.283391074388, 4629.629629629621, 4632.096741135446, 3851.788151651686, 4705.388299521109, 4706.529347065334, 4707.458642256116, 4708.586274950633, 5294.364676238799, 5464.191511469881, 5472.840641112783, 5492.368377636623, 5503.091989821714, 6056.879477296714, 6840.897386539206, 6850.794476165723, 6861.759838405632, 6873.278305777922, 1500.752375857993, 1607.3570571771386, 1608.8230294667578, 1609.6539244062349, 1612.6129728864987, 941.5762906692016, 1024.7540590258284, 1066.2376400873413, 1074.6453670288786, 1077.466654006272, 6376.043593460925, 6378.585391949508, 6430.260520040161, 6432.77485288022, 6440.742370305135, 6648.371149068509, 7143.785686429415, 7163.856722865514, 7197.89203161953, 7228.096487939008, 5743.396943320975, 5871.089981052944, 6328.7468501260355, 6385.936140638534, 6439.227292724903, 3652.6164752700583, 3661.9450708239397, 3728.5577946445123, 3741.7754934704003, 3747.5315585551807, 5543.556515478833, 6544.273760459598, 7273.709051637981, 7287.052683151156, 7290.854182916312, 5801.477866991991, 6650.501212409089, 7245.456590755714, 7258.745943891939, 7290.781276561712, 6519.011701431014, 6869.656517174221, 7008.964955175192, 7271.78210645737, 7277.636118194069, 6184.103305020657, 6393.680315984089, 7040.345518802975, 7125.0, 7128.786136415916, 6928.7228510859, 7017.719291228498, 7018.5788852669, 7041.887433769746, 7111.7866464006165, 6586.769463068769, 6754.0, 7193.956876253019, 7276.272372762682, 7281.599512026889, 3879.553851307136, 4570.931436028529, 4579.977100114478, 4593.793279302373, 4648.023914977893, 6154.815355539162, 7054.0, 7079.858402831812, 7080.38025930409, 7083.5041547091205, 6378.744850206125, 6468.902966455413, 6706.792777300133, 6858.279880612663, 6865.382115609619, 5948.315943666534, 6272.560920735505, 6715.429188518835, 6840.794776156723, 6843.418309443554, 4945.4312754033745, 6685.498587606046, 6718.428933540704, 6719.529632925648, 6728.629925354153, 5878.853028674317, 6823.590584564942, 6839.452843772586, 6878.65606719672, 6994.790156295319, 6139.508839292936, 6147.323794382703, 6686.3313668632745, 6705.09481220029, 6707.496937729592], "value": 4921.012836675536, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.9963750181248, 746.734909107267, 752.3680108708684, 870.7518357268178, 873.66800615766, 13927.37326820293, 14441.133531988087, 15337.38650453982, 16391.262393192304, 16416.34334626615, 16182.433614823483, 17318.913405432955, 17566.209520571574, 17599.384021559246, 17658.117094145306, 986.6053578568558, 989.7080361293399, 998.4458625462865, 1000.8598796168542, 1002.4887307473205, 884.681514654727, 991.3655260633201, 1012.5544760305479, 1014.077189757321, 1014.9746256343576, 970.1898914406479, 1057.6456886942883, 1075.4192735922632, 1088.4231357380602, 1088.515610553304, 1069.6149386220955, 1076.48328802175, 1078.1428764132454, 1082.0045558086579, 1083.4636854756866, 776.1307335783902, 776.2509178642637, 776.3672606825419, 777.7161336112334, 782.1279273609941, 17365.652686946316, 17420.47738567845, 17717.822821771875, 17776.022318772462, 18095.819041809544, 14631.70736585273, 17832.91083544574, 18247.817521824745, 18279.72580411295, 18333.908330458395, 14615.196164210964, 14827.555173344817, 14856.851431485762, 14875.925620371938, 14876.256187190596, 3598.3702852000797, 3624.7281453891064, 3636.672699457035, 3636.690881275095, 3844.115853353744, 126163.0, 127154.0, 129080.0, 129785.0, 129859.35070324587, 13437.86562134366, 13666.248356340497, 13709.108907920952, 13838.377273022537, 13848.0, 25816.0, 26482.735172648412, 26561.601575976376, 26742.064027758934, 27034.594481082408, 6496.740130394731, 7651.540907545454, 7674.539527628249, 7699.0, 7701.037370328709, 2923.2107331020507, 3610.945835812465, 3747.1194269346684, 3770.830312635938, 3774.6791522720328, 4333.285007973712, 4382.539833317455, 4428.955710442919, 4430.689851710349, 4444.577765112309, 903.8553831386946, 931.0456781798711, 936.6768464879606, 945.5130607737021, 950.7528042708946, 649.2857856357969, 666.5534092158266, 671.7212356871844, 683.9145106861642, 693.5318659904544, 9973.601055957826, 11309.0, 11557.711057223469, 11755.787167234908, 12375.381230938418, 645.1516256123183, 653.911721917535, 663.4327649859397, 683.3849535418065, 692.1244625548685, 4976.8755781105765, 5430.859519500949, 5561.805336813192, 5749.798757043483, 5858.062709966385, 106079.93920060855, 135415.0, 135543.32228338794, 137364.0, 137648.31175844258, 108992.40845415316, 109057.45471272751, 116330.0, 116766.8323316773, 116781.24828127585, 99363.50318248362, 104735.0, 104901.95098048921, 105923.94076059294, 106334.0, 10735.892641073802, 10737.926207379513, 11106.278091924, 11301.265417748149, 11809.409529523662, 41173.86715691309, 43753.56246437495, 43839.78080109579, 44460.77769611131, 44527.0, 59046.704766475894, 59363.0, 60178.69910650419, 60393.698031509564, 60586.39413605984, 14387.496437624633, 14639.560813175622, 14729.0, 14762.704745904806, 14823.258837057889, 15868.04791712501, 16304.0, 16394.754078689068, 16516.50450486487, 16562.171891405622, 15012.924935375253, 15087.0, 15193.240337983278, 15291.158986255417, 15305.0, 14655.48705795292, 14750.778738318719, 15129.940904136605, 15267.0, 15297.46458873934, 12123.818142728043, 14796.48212312607, 14819.925900370428, 16373.83626163723, 18178.90910545439, 11589.6523104307, 11868.643940681794, 12214.38928053576, 12297.262164270172, 12523.248605083723, 13075.476980920657, 13834.58496245074, 14062.507812226522, 14550.199739014455, 14593.270336483345], "value": 8493.944119537255, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 121.0, 113.0, 111.0, 113.0, 114.0, 114.0, 113.0, 113.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 109.0, 112.0, 144.0, 142.0, 92.0, 94.0, 139.0, 158.0, 148.0], "value": 127.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 280.0, 238.0, 210.0, 208.0, 211.0, 245.0, 225.0, 198.0, 199.0], "value": 223.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 60.0, 92.0, 72.0, 59.0, 54.0, 56.0, 92.0, 87.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 68.0, 84.0, 73.0, 72.0, 71.0, 86.0, 80.0, 55.0, 92.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 89.0, 137.0, 136.0, 92.0, 112.0, 94.0, 82.0, 136.0, 90.0], "value": 109.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 96.0, 98.0, 97.0, 98.0, 98.0, 97.0, 97.0, 100.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 88.0, 90.0, 100.0, 105.0, 142.0, 86.0, 147.0, 143.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 52.0, 50.0, 54.0, 49.0, 44.0, 67.0, 76.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 60.0, 63.0, 63.0, 61.0, 55.0, 57.0, 56.0, 54.0], "value": 58.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 90.0, 88.0, 89.0, 87.0, 88.0, 90.0, 119.0, 122.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 136.0, 135.0, 139.0, 141.0, 136.0, 164.0, 150.0, 154.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 179.0, 187.0, 188.0, 202.0, 235.0, 241.0, 187.0, 188.0, 187.0], "value": 198.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 64.0, 66.0, 67.0, 68.0, 65.0, 70.0, 65.0], "value": 66.8, "unit": "score"}]}]} 05:15:46 INFO - Return code: 0 05:15:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:15:46 INFO - # TBPL SUCCESS # 05:15:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:15:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:15:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:15:46 INFO - 2017-06-05 05:15:46.331 screenresolution[1674:12498] starting screenresolution argv=screenresolution get 05:15:46 INFO - 2017-06-05 05:15:46.335 screenresolution[1674:12498] Display 0: 1600x1200x32@60 05:15:46 INFO - 2017-06-05 05:15:46.341 screenresolution[1675:12501] starting screenresolution argv=screenresolution list 05:15:46 INFO - Available Modes on Display 0 05:15:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:15:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:15:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:15:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:15:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:15:46 INFO - Intel Iris: 05:15:46 INFO - Chipset Model: Intel Iris 05:15:46 INFO - Type: GPU 05:15:46 INFO - Bus: Built-In 05:15:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:15:46 INFO - Vendor: Intel (0x8086) 05:15:46 INFO - Device ID: 0x0a2e 05:15:46 INFO - Revision ID: 0x0009 05:15:46 INFO - Displays: 05:15:46 INFO - Display: 05:15:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:15:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:15:46 INFO - Display Serial Number: 1600x1200 60 05:15:46 INFO - Main Display: Yes 05:15:46 INFO - Mirror: Off 05:15:46 INFO - Online: Yes 05:15:46 INFO - Rotation: Supported 05:15:46 INFO - Return code: 0 05:15:46 INFO - Running post-action listener: _resource_record_post_action 05:15:46 INFO - [mozharness: 2017-06-05 12:15:46.578829Z] Finished run-tests step (success) 05:15:46 INFO - Running post-run listener: _resource_record_post_run 05:15:46 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 35746816; Write bytes: 572487168; Read time: 335; Write time: 1828 05:15:46 INFO - TinderboxPrint: CPU usage
53.3% 05:15:46 INFO - TinderboxPrint: I/O read bytes / time
35,746,816 / 335 05:15:46 INFO - TinderboxPrint: I/O write bytes / time
572,487,168 / 1,828 05:15:46 INFO - TinderboxPrint: CPU idle
2,348.1 (46.7%) 05:15:46 INFO - TinderboxPrint: CPU system
1,020.9 (20.3%) 05:15:46 INFO - TinderboxPrint: CPU user
1,660.6 (33.0%) 05:15:46 INFO - TinderboxPrint: Swap in / out
278,929,408 / 0 05:15:46 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229227008; Write bytes: 228783104; Read time: 16782; Write time: 477 05:15:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:15:46 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 34771968; Write bytes: 322740736; Read time: 316; Write time: 1303 05:15:47 INFO - Running post-run listener: _upload_blobber_files 05:15:47 INFO - Blob upload gear active. 05:15:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:15:47 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 05:15:47 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'] 05:15:47 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 05:15:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:15:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:15:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:15:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:48 INFO - (blobuploader) - INFO - Done attempting. 05:15:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:15:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:15:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:49 INFO - (blobuploader) - INFO - Done attempting. 05:15:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:15:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:15:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:50 INFO - (blobuploader) - INFO - Done attempting. 05:15:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:15:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:15:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:52 INFO - (blobuploader) - INFO - Done attempting. 05:15:52 INFO - (blobuploader) - INFO - Iteration through files over. 05:15:52 INFO - Return code: 0 05:15:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:15:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:15:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91ce88a213dd71080a8bafd71a61911beb4b177651765063e768b459947a27dbf15cd3d16086c31f1182620a6513faec047a56fc965124616c8565e9916c608e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3cec6afb7fd93d2424a9b0467ffba226dd195f79fe48b18c0af0e5c07389d3643ad4533e0813c6ef89060412e2b88ac59f2b8e52c723471ef97cc5c6e1231e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/403c7b7d5a7fd68f9110691c45ae0ffe70fa45179232a95760ef5ba939ca8e8e5d8e23b6b74044bbf814625841d425bfca999163545bce5f6e37d4376a0e2f2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca95219cc8607a7becd4de6f8643b2e90c94c08929b5d9ab7cf70e71118ec3ce717d862af7f6e9dca41d2c998d50fd1b209fb6cf97f14e3d3411ac4c7afbe444"} 05:15:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:15:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:15:52 INFO - Contents: 05:15:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91ce88a213dd71080a8bafd71a61911beb4b177651765063e768b459947a27dbf15cd3d16086c31f1182620a6513faec047a56fc965124616c8565e9916c608e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3cec6afb7fd93d2424a9b0467ffba226dd195f79fe48b18c0af0e5c07389d3643ad4533e0813c6ef89060412e2b88ac59f2b8e52c723471ef97cc5c6e1231e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/403c7b7d5a7fd68f9110691c45ae0ffe70fa45179232a95760ef5ba939ca8e8e5d8e23b6b74044bbf814625841d425bfca999163545bce5f6e37d4376a0e2f2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca95219cc8607a7becd4de6f8643b2e90c94c08929b5d9ab7cf70e71118ec3ce717d862af7f6e9dca41d2c998d50fd1b209fb6cf97f14e3d3411ac4c7afbe444"} 05:15:52 INFO - Running post-run listener: copy_logs_to_upload_dir 05:15:52 INFO - Copying logs to upload dir... 05:15:52 INFO - mkdir: /builds/slave/test/build/upload/logs 05:15:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.225319 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-05 05:15:52.724641) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 05:15:52.728639) ========= 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.HGDVt12tvo/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.FeiAbD2sN5/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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/91ce88a213dd71080a8bafd71a61911beb4b177651765063e768b459947a27dbf15cd3d16086c31f1182620a6513faec047a56fc965124616c8565e9916c608e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3cec6afb7fd93d2424a9b0467ffba226dd195f79fe48b18c0af0e5c07389d3643ad4533e0813c6ef89060412e2b88ac59f2b8e52c723471ef97cc5c6e1231e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/403c7b7d5a7fd68f9110691c45ae0ffe70fa45179232a95760ef5ba939ca8e8e5d8e23b6b74044bbf814625841d425bfca999163545bce5f6e37d4376a0e2f2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca95219cc8607a7becd4de6f8643b2e90c94c08929b5d9ab7cf70e71118ec3ce717d862af7f6e9dca41d2c998d50fd1b209fb6cf97f14e3d3411ac4c7afbe444"} build_url:https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/dMhod4R_QDyxL0Ri2vzEAw/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/91ce88a213dd71080a8bafd71a61911beb4b177651765063e768b459947a27dbf15cd3d16086c31f1182620a6513faec047a56fc965124616c8565e9916c608e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3cec6afb7fd93d2424a9b0467ffba226dd195f79fe48b18c0af0e5c07389d3643ad4533e0813c6ef89060412e2b88ac59f2b8e52c723471ef97cc5c6e1231e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/403c7b7d5a7fd68f9110691c45ae0ffe70fa45179232a95760ef5ba939ca8e8e5d8e23b6b74044bbf814625841d425bfca999163545bce5f6e37d4376a0e2f2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca95219cc8607a7becd4de6f8643b2e90c94c08929b5d9ab7cf70e71118ec3ce717d862af7f6e9dca41d2c998d50fd1b209fb6cf97f14e3d3411ac4c7afbe444"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 05:15:52.761525) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 05:15:52.761853) ========= 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.HGDVt12tvo/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.FeiAbD2sN5/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.005362 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 05:15:52.810322) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 05:15:52.810675) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 05:15:52.811023) ========= ========= Total master_lag: 0.17 =========