builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0013 starttime: 1497318215.32 results: success (0) revision: 2a3a253806d129c0bb6f2b76bf75630457a24492 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:35.320054) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:35.320498) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:35.320801) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:35.346106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:35.346486) ========= 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.y8isfbyl5V/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.eAhW13aaz2/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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-12 18:43:35-- 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% 17.8M=0.001s 2017-06-12 18:43:35 (17.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.484858 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:35.858113) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:35.858423) ========= 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.y8isfbyl5V/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.eAhW13aaz2/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.036119 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:35.920573) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-12 18:43:35.920915) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2a3a253806d129c0bb6f2b76bf75630457a24492 --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 2a3a253806d129c0bb6f2b76bf75630457a24492 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y8isfbyl5V/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.eAhW13aaz2/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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-12 18:43:36,070 truncating revision to first 12 chars 2017-06-12 18:43:36,070 Setting DEBUG logging. 2017-06-12 18:43:36,070 attempt 1/10 2017-06-12 18:43:36,070 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2a3a253806d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 18:43:38,883 unpacking tar archive at: mozilla-central-2a3a253806d1/testing/mozharness/ program finished with exit code 0 elapsedTime=3.119444 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-12 18:43:39.061707) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:39.062080) ========= script_repo_revision: 2a3a253806d129c0bb6f2b76bf75630457a24492 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:39.062572) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:39.062864) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 18:43:39.081391) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 32 secs) (at 2017-06-12 18:43:39.081787) ========= /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.y8isfbyl5V/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.eAhW13aaz2/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:43:39 INFO - MultiFileLogger online at 20170612 18:43:39 in /builds/slave/test 18:43:39 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 18:43:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:43:39 INFO - {'append_to_log': False, 18:43:39 INFO - 'base_work_dir': '/builds/slave/test', 18:43:39 INFO - 'blob_upload_branch': 'Firefox', 18:43:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:43:39 INFO - 'branch': 'Firefox', 18:43:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:43:39 INFO - 'config_files': ('talos/mac_config.py',), 18:43:39 INFO - 'default_actions': ('clobber', 18:43:39 INFO - 'read-buildbot-config', 18:43:39 INFO - 'download-and-extract', 18:43:39 INFO - 'populate-webroot', 18:43:39 INFO - 'create-virtualenv', 18:43:39 INFO - 'install', 18:43:39 INFO - 'setup-mitmproxy', 18:43:39 INFO - 'run-tests'), 18:43:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:43:39 INFO - 'download_minidump_stackwalk': True, 18:43:39 INFO - 'download_symbols': 'ondemand', 18:43:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:43:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:43:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:43:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:43:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:43:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:43:39 INFO - 'gecko_profile': False, 18:43:39 INFO - 'gecko_profile_interval': 0, 18:43:39 INFO - 'installer_path': 'installer.exe', 18:43:39 INFO - 'log_level': 'info', 18:43:39 INFO - 'log_name': 'talos', 18:43:39 INFO - 'log_to_console': True, 18:43:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:43:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:43:39 INFO - 'opt_config_files': (), 18:43:39 INFO - 'pip_index': False, 18:43:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:43:39 INFO - 'cmd': ('bash', 18:43:39 INFO - '-c', 18:43:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:43:39 INFO - 'enabled': True, 18:43:39 INFO - 'halt_on_failure': False, 18:43:39 INFO - 'name': 'check_screen_resolution'},), 18:43:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:43:39 INFO - 'cmd': ('bash', 18:43:39 INFO - '-c', 18:43:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:43:39 INFO - 'enabled': True, 18:43:39 INFO - 'halt_on_failure': False, 18:43:39 INFO - 'name': 'check_screen_resolution'},), 18:43:39 INFO - 'run_cmd_checks_enabled': True, 18:43:39 INFO - 'suite': 'dromaeojs-e10s', 18:43:39 INFO - 'system_bits': '32', 18:43:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:43:39 INFO - 'title': 't-yosemite-r7-0013', 18:43:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:43:39 INFO - 'use_talos_json': True, 18:43:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:43:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:43:39 INFO - 'work_dir': 'build'} 18:43:39 INFO - [mozharness: 2017-06-13 01:43:39.347245Z] Running clobber step. 18:43:39 INFO - Running pre-action listener: _resource_record_pre_action 18:43:39 INFO - Running main action method: clobber 18:43:39 INFO - rmtree: /builds/slave/test/build 18:43:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:43:41 INFO - Running post-action listener: _resource_record_post_action 18:43:41 INFO - [mozharness: 2017-06-13 01:43:41.926387Z] Finished clobber step (success) 18:43:41 INFO - [mozharness: 2017-06-13 01:43:41.926549Z] Running read-buildbot-config step. 18:43:41 INFO - Running pre-action listener: _resource_record_pre_action 18:43:41 INFO - Running main action method: read_buildbot_config 18:43:41 INFO - Using buildbot properties: 18:43:41 INFO - { 18:43:41 INFO - "project": "", 18:43:41 INFO - "product": "firefox", 18:43:41 INFO - "who": "kwierso@gmail.com", 18:43:41 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 18:43:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:43:41 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:43:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:43:41 INFO - "stage_platform": "macosx64", 18:43:41 INFO - "basedir": "/builds/slave/test", 18:43:41 INFO - "buildnumber": 87, 18:43:41 INFO - "platform": "yosemite_r7", 18:43:41 INFO - "parent_task_id": "dL6RO1qjQ1WBPJeXt95HYQ", 18:43:41 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:43:41 INFO - "slavebuilddir": "test", 18:43:41 INFO - "taskId": "Ca5OB1jaS2edDDS7pKKv2A", 18:43:41 INFO - "branch": "mozilla-central", 18:43:41 INFO - "script_repo_revision": "production", 18:43:41 INFO - "revision": "2a3a253806d129c0bb6f2b76bf75630457a24492", 18:43:41 INFO - "slavename": "t-yosemite-r7-0013", 18:43:41 INFO - "repo_path": "mozilla-central" 18:43:41 INFO - } 18:43:41 INFO - Finding installer, test and symbols from parent task. 18:43:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ca5OB1jaS2edDDS7pKKv2A'}, attempt #1 18:43:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ca5OB1jaS2edDDS7pKKv2A'}, attempt #1 18:43:42 INFO - Task dependencies: Rk1kkchoTV-hXywhyI_y1w dL6RO1qjQ1WBPJeXt95HYQ 18:43:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ'}, attempt #1 18:43:43 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 18:43:43 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/test_packages.json 18:43:43 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/target.crashreporter-symbols.zip 18:43:43 INFO - Running post-action listener: _resource_record_post_action 18:43:43 INFO - [mozharness: 2017-06-13 01:43:43.328526Z] Finished read-buildbot-config step (success) 18:43:43 INFO - [mozharness: 2017-06-13 01:43:43.328646Z] Running download-and-extract step. 18:43:43 INFO - Running pre-action listener: _resource_record_pre_action 18:43:43 INFO - Running main action method: download_and_extract 18:43:43 INFO - mkdir: /builds/slave/test/build/tests 18:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:43:43 INFO - trying https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 18:43:43 INFO - Downloading https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 18:43:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 18:43:44 INFO - Downloaded 1328 bytes. 18:43:44 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 18:43:44 INFO - Using the following test package requirements: 18:43:44 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 18:43:44 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 18:43:44 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 18:43:44 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 18:43:44 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'jsshell-mac.zip'], 18:43:44 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 18:43:44 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 18:43:44 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 18:43:44 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 18:43:44 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 18:43:44 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 18:43:44 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 18:43:44 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:43:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 18:43:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:43:44 INFO - Fetch https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 18:43:45 INFO - Content-Length response header: 59543920 18:43:45 INFO - Bytes received: 59543920 18:44:04 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:44:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 18:44:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:44:04 INFO - Fetch https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 18:44:06 INFO - Content-Length response header: 59543920 18:44:06 INFO - Bytes received: 59543920 18:44:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 18:44:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:44:23 INFO - Fetch https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 18:44:30 INFO - Content-Length response header: 14450010 18:44:30 INFO - Bytes received: 14450010 18:44:30 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')]} 18:44:30 INFO - trying https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 18:44:30 INFO - Downloading https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 18:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 18:44:39 INFO - Downloaded 69337455 bytes. 18:44:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 18:44:39 INFO - mkdir: /builds/slave/test/properties 18:44:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:44:39 INFO - Writing to file /builds/slave/test/properties/build_url 18:44:39 INFO - Contents: 18:44:39 INFO - build_url:https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 18:44:39 INFO - Running post-action listener: _resource_record_post_action 18:44:39 INFO - Running post-action listener: set_extra_try_arguments 18:44:39 INFO - [mozharness: 2017-06-13 01:44:39.761531Z] Finished download-and-extract step (success) 18:44:39 INFO - [mozharness: 2017-06-13 01:44:39.761671Z] Running populate-webroot step. 18:44:39 INFO - Running pre-action listener: _resource_record_pre_action 18:44:39 INFO - Running main action method: populate_webroot 18:44:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:44:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:44:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:44:39 INFO - '--tppagecycles', 18:44:39 INFO - '7'], 18:44:39 INFO - 'tests': ['tsvgm']}}, 18:44:39 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:44:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:44:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:44:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:44:39 INFO - 'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:44:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:44:39 INFO - 'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['damp', 'tps']}, 18:44:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:44:39 INFO - 'tests': ['damp', 'tps']}, 18:44:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['dromaeo_dom']}, 18:44:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:44:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:44:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:44:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:44:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:44:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['a11yr', 18:44:39 INFO - 'ts_paint', 18:44:39 INFO - 'tpaint', 18:44:39 INFO - 'sessionrestore', 18:44:39 INFO - 'sessionrestore_no_auto_restore', 18:44:39 INFO - 'tabpaint']}, 18:44:39 INFO - 'other-e10s': {'tests': ['a11yr', 18:44:39 INFO - 'ts_paint', 18:44:39 INFO - 'tpaint', 18:44:39 INFO - 'sessionrestore', 18:44:39 INFO - 'sessionrestore_no_auto_restore', 18:44:39 INFO - 'tabpaint']}, 18:44:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:44:39 INFO - 'ts_paint', 18:44:39 INFO - 'tpaint', 18:44:39 INFO - 'sessionrestore', 18:44:39 INFO - 'sessionrestore_no_auto_restore', 18:44:39 INFO - 'tabpaint']}, 18:44:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:44:39 INFO - 'ts_paint', 18:44:39 INFO - 'tpaint', 18:44:39 INFO - 'sessionrestore', 18:44:39 INFO - 'sessionrestore_no_auto_restore', 18:44:39 INFO - 'tabpaint']}, 18:44:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['a11yr', 18:44:39 INFO - 'ts_paint', 18:44:39 INFO - 'tpaint', 18:44:39 INFO - 'sessionrestore', 18:44:39 INFO - 'sessionrestore_no_auto_restore', 18:44:39 INFO - 'tabpaint']}, 18:44:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['a11yr', 18:44:39 INFO - 'ts_paint', 18:44:39 INFO - 'tpaint', 18:44:39 INFO - 'sessionrestore', 18:44:39 INFO - 'sessionrestore_no_auto_restore', 18:44:39 INFO - 'tabpaint']}, 18:44:39 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:44:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:44:39 INFO - 'bloom_basic_ref']}, 18:44:39 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:44:39 INFO - 'talos_options': ['--mitmproxy', 18:44:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:44:39 INFO - '--firstNonBlankPaint'], 18:44:39 INFO - 'tests': ['quantum_pageload_google', 18:44:39 INFO - 'quantum_pageload_youtube', 18:44:39 INFO - 'quantum_pageload_amazon', 18:44:39 INFO - 'quantum_pageload_facebook']}, 18:44:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['tsvgx', 18:44:39 INFO - 'tsvgr_opacity', 18:44:39 INFO - 'tart', 18:44:39 INFO - 'tscrollx', 18:44:39 INFO - 'cart', 18:44:39 INFO - 'tsvg_static']}, 18:44:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:44:39 INFO - 'tsvgr_opacity', 18:44:39 INFO - 'tart', 18:44:39 INFO - 'tscrollx', 18:44:39 INFO - 'cart', 18:44:39 INFO - 'tsvg_static']}, 18:44:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:44:39 INFO - 'talos_options': ['--disable-e10s'], 18:44:39 INFO - 'tests': ['tp5o']}, 18:44:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:44:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:44:39 INFO - 'talos_options': ['--disable-e10s', 18:44:39 INFO - '--xperf_path', 18:44:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:39 INFO - 'tests': ['tp5n']}, 18:44:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:44:39 INFO - 'talos_options': ['--xperf_path', 18:44:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:39 INFO - 'tests': ['tp5n']}}, 18:44:39 INFO - 'talos.zip': {'path': '', 18:44:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:44:39 INFO - Running post-action listener: _resource_record_post_action 18:44:39 INFO - [mozharness: 2017-06-13 01:44:39.784714Z] Finished populate-webroot step (success) 18:44:39 INFO - [mozharness: 2017-06-13 01:44:39.784857Z] Running create-virtualenv step. 18:44:39 INFO - Running pre-action listener: _resource_record_pre_action 18:44:39 INFO - Running main action method: create_virtualenv 18:44:39 INFO - Creating virtualenv /builds/slave/test/build/venv 18:44:39 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 18:44:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:44:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:44:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:44:39 INFO - Using real prefix '/tools/python27' 18:44:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:44:40 INFO - Installing distribute.............................................................................................................................................................................................done. 18:44:43 INFO - Installing pip.................done. 18:44:43 INFO - Return code: 0 18:44:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:44:43 INFO - Reading from file tmpfile_stdout 18:44:43 INFO - Output received: 18:44:43 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) 18:44:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:44:43 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 18:44:43 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 18:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:43 INFO - 'HOME': '/Users/cltbld', 18:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:43 INFO - 'LOGNAME': 'cltbld', 18:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:43 INFO - 'MOZ_NO_REMOTE': '1', 18:44:43 INFO - 'NO_EM_RESTART': '1', 18:44:43 INFO - 'PAGER': '/bin/cat', 18:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:43 INFO - 'PWD': '/builds/slave/test', 18:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:43 INFO - 'SHELL': '/bin/bash', 18:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:44:43 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:43 INFO - 'USER': 'cltbld', 18:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:43 INFO - 'XPC_FLAGS': '0x0', 18:44:43 INFO - 'XPC_SERVICE_NAME': '0', 18:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:44 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 18:44:44 INFO - Cleaning up... 18:44:44 INFO - Return code: 0 18:44:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:44:44 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')]} 18:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:44 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')]} 18:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:44 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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:44:44 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 18:44:44 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 18:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:44 INFO - 'HOME': '/Users/cltbld', 18:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:44 INFO - 'LOGNAME': 'cltbld', 18:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:44 INFO - 'MOZ_NO_REMOTE': '1', 18:44:44 INFO - 'NO_EM_RESTART': '1', 18:44:44 INFO - 'PAGER': '/bin/cat', 18:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:44 INFO - 'PWD': '/builds/slave/test', 18:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:44 INFO - 'SHELL': '/bin/bash', 18:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:44:44 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:44 INFO - 'USER': 'cltbld', 18:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:44 INFO - 'XPC_FLAGS': '0x0', 18:44:44 INFO - 'XPC_SERVICE_NAME': '0', 18:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:44 INFO - Downloading/unpacking psutil>=3.1.1 18:44: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 18:44: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 18:44: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 18:44: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 18:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:44:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:44:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:44:46 INFO - Installing collected packages: psutil 18:44:46 INFO - Running setup.py install for psutil 18:44:46 INFO - building 'psutil._psutil_osx' extension 18:44:46 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 18:44:47 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 18:44:47 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 18:44:47 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 18:44:47 INFO - building 'psutil._psutil_posix' extension 18:44:47 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 18:44:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:44:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:44:47 INFO - ^ 18:44:47 INFO - 1 warning generated. 18:44:47 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 18:44:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:44:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:44:47 INFO - Successfully installed psutil 18:44:47 INFO - Cleaning up... 18:44:47 INFO - Return code: 0 18:44:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:44:47 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')]} 18:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:47 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')]} 18:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:47 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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:44:47 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 18:44:47 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 18:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:47 INFO - 'HOME': '/Users/cltbld', 18:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:47 INFO - 'LOGNAME': 'cltbld', 18:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:47 INFO - 'MOZ_NO_REMOTE': '1', 18:44:47 INFO - 'NO_EM_RESTART': '1', 18:44:47 INFO - 'PAGER': '/bin/cat', 18:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:47 INFO - 'PWD': '/builds/slave/test', 18:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:47 INFO - 'SHELL': '/bin/bash', 18:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:44:47 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:47 INFO - 'USER': 'cltbld', 18:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:47 INFO - 'XPC_FLAGS': '0x0', 18:44:47 INFO - 'XPC_SERVICE_NAME': '0', 18:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:44:48 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 18:44:48 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 18:44:48 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 18:44:48 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 18:44:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:44:49 INFO - Installing collected packages: mozsystemmonitor 18:44:49 INFO - Running setup.py install for mozsystemmonitor 18:44:49 INFO - Successfully installed mozsystemmonitor 18:44:49 INFO - Cleaning up... 18:44:50 INFO - Return code: 0 18:44:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:44:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:50 INFO - 'HOME': '/Users/cltbld', 18:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:50 INFO - 'LOGNAME': 'cltbld', 18:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:50 INFO - 'MOZ_NO_REMOTE': '1', 18:44:50 INFO - 'NO_EM_RESTART': '1', 18:44:50 INFO - 'PAGER': '/bin/cat', 18:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:50 INFO - 'PWD': '/builds/slave/test', 18:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:50 INFO - 'SHELL': '/bin/bash', 18:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:44:50 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:50 INFO - 'USER': 'cltbld', 18:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:50 INFO - 'XPC_FLAGS': '0x0', 18:44:50 INFO - 'XPC_SERVICE_NAME': '0', 18:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:50 INFO - Downloading/unpacking jsonschema==2.5.1 18:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:44:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:44:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:44:51 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 18:44:51 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 18:44:51 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 18:44:51 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 18:44:51 INFO - Downloading functools32-3.2.3-2.tar.gz 18:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:44:51 INFO - warning: no files found matching '*.txt' 18:44:51 INFO - no previously-included directories found matching 'build' 18:44:51 INFO - no previously-included directories found matching 'dist' 18:44:51 INFO - no previously-included directories found matching '.git*' 18:44:52 INFO - Installing collected packages: jsonschema, functools32 18:44:52 INFO - Running setup.py install for functools32 18:44:52 INFO - warning: no files found matching '*.txt' 18:44:52 INFO - no previously-included directories found matching 'build' 18:44:52 INFO - no previously-included directories found matching 'dist' 18:44:52 INFO - no previously-included directories found matching '.git*' 18:44:52 INFO - Successfully installed jsonschema functools32 18:44:52 INFO - Cleaning up... 18:44:52 INFO - Return code: 0 18:44:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:44:52 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')]} 18:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:52 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')]} 18:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:52 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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:44:52 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 18:44:52 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 18:44:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:44:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:52 INFO - 'HOME': '/Users/cltbld', 18:44:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:52 INFO - 'LOGNAME': 'cltbld', 18:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:52 INFO - 'MOZ_NO_REMOTE': '1', 18:44:52 INFO - 'NO_EM_RESTART': '1', 18:44:52 INFO - 'PAGER': '/bin/cat', 18:44:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:52 INFO - 'PWD': '/builds/slave/test', 18:44:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:52 INFO - 'SHELL': '/bin/bash', 18:44:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:44:52 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:44:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:52 INFO - 'USER': 'cltbld', 18:44:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:52 INFO - 'XPC_FLAGS': '0x0', 18:44:52 INFO - 'XPC_SERVICE_NAME': '0', 18:44:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:44:52 INFO - Cleaning up... 18:44:52 INFO - Return code: 0 18:44:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:44:52 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')]} 18:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:52 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')]} 18:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:52 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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:44:52 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 18:44:52 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 18:44:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:44:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:52 INFO - 'HOME': '/Users/cltbld', 18:44:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:52 INFO - 'LOGNAME': 'cltbld', 18:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:52 INFO - 'MOZ_NO_REMOTE': '1', 18:44:52 INFO - 'NO_EM_RESTART': '1', 18:44:52 INFO - 'PAGER': '/bin/cat', 18:44:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:52 INFO - 'PWD': '/builds/slave/test', 18:44:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:52 INFO - 'SHELL': '/bin/bash', 18:44:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:44:52 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:44:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:52 INFO - 'USER': 'cltbld', 18:44:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:52 INFO - 'XPC_FLAGS': '0x0', 18:44:52 INFO - 'XPC_SERVICE_NAME': '0', 18:44:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:52 INFO - Downloading/unpacking blobuploader==1.2.4 18:44:52 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 18:44:52 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 18:44:52 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 18:44:52 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 18:44:54 INFO - Downloading blobuploader-1.2.4.tar.gz 18:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:44:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:44:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:44:55 INFO - Downloading docopt-0.6.1.tar.gz 18:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:44:55 INFO - Installing collected packages: blobuploader, requests, docopt 18:44:55 INFO - Running setup.py install for blobuploader 18:44:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:44:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:44:55 INFO - Running setup.py install for requests 18:44:55 INFO - Running setup.py install for docopt 18:44:55 INFO - Successfully installed blobuploader requests docopt 18:44:55 INFO - Cleaning up... 18:44:56 INFO - Return code: 0 18:44:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:56 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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:44:56 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 18:44:56 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 18:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:56 INFO - 'HOME': '/Users/cltbld', 18:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:56 INFO - 'LOGNAME': 'cltbld', 18:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:56 INFO - 'MOZ_NO_REMOTE': '1', 18:44:56 INFO - 'NO_EM_RESTART': '1', 18:44:56 INFO - 'PAGER': '/bin/cat', 18:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:56 INFO - 'PWD': '/builds/slave/test', 18:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:56 INFO - 'SHELL': '/bin/bash', 18:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:44:56 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:56 INFO - 'USER': 'cltbld', 18:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:56 INFO - 'XPC_FLAGS': '0x0', 18:44:56 INFO - 'XPC_SERVICE_NAME': '0', 18:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:44:56 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-hm7KHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:44:56 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Wik2Jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:44:56 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-gYiY74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:44:56 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-XHuYdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:44:56 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-3592pJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:44:56 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-2v07S2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:44:56 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-R5OY33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-N9iqyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-yE9GSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-zKetXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-THp5f0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-fyJ1Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-37mRgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-XAs9CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-hOjHiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-NyqauL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:44:57 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-78CuOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:44:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:44:58 INFO - Running setup.py install for manifestparser 18:44:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:44:58 INFO - Running setup.py install for mozcrash 18:44:58 INFO - Running setup.py install for mozdebug 18:44:58 INFO - Running setup.py install for mozdevice 18:44:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:44:58 INFO - Running setup.py install for mozfile 18:44:58 INFO - Running setup.py install for mozhttpd 18:44:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:44:58 INFO - Running setup.py install for mozinfo 18:44:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:44:59 INFO - Running setup.py install for mozInstall 18:44:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:44:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:44:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:44:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:44:59 INFO - Running setup.py install for mozleak 18:44:59 INFO - Running setup.py install for mozlog 18:44:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:44:59 INFO - Running setup.py install for moznetwork 18:44:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:44:59 INFO - Running setup.py install for mozprocess 18:44:59 INFO - Running setup.py install for mozprofile 18:45:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:45:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:45:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:45:00 INFO - Running setup.py install for mozrunner 18:45:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:45:00 INFO - Running setup.py install for mozscreenshot 18:45:00 INFO - Running setup.py install for moztest 18:45:00 INFO - Running setup.py install for mozversion 18:45:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:45:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:45:00 INFO - Cleaning up... 18:45:00 INFO - Return code: 0 18:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:00 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')]} 18:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:00 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')]} 18:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:00 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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:45:00 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 18:45:00 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 18:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:00 INFO - 'HOME': '/Users/cltbld', 18:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:00 INFO - 'LOGNAME': 'cltbld', 18:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:00 INFO - 'MOZ_NO_REMOTE': '1', 18:45:00 INFO - 'NO_EM_RESTART': '1', 18:45:00 INFO - 'PAGER': '/bin/cat', 18:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:00 INFO - 'PWD': '/builds/slave/test', 18:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:00 INFO - 'SHELL': '/bin/bash', 18:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:45:00 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:00 INFO - 'USER': 'cltbld', 18:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:00 INFO - 'XPC_FLAGS': '0x0', 18:45:00 INFO - 'XPC_SERVICE_NAME': '0', 18:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:45:00 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-NmSAF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:45:01 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-gGIklz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:45:01 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-cBKIGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:45:01 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-aQMEIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:45:01 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-XtbnFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:45:01 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-GH2LYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:45:01 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-AE1qJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:45:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-0gMgm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:45:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-QqecSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:45:01 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)) 18:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:45:01 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-gaUMRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:45:02 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)) 18:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:45:02 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-NYaRXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:45:02 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)) 18:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:45:02 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-kpX2s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:45:02 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)) 18:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:45:02 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-OmTnT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:45:02 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)) 18:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:45:02 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-TiHXwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:45:02 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)) 18:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:02 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-4_T6y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:02 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)) 18:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:45:02 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-UIMsHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:45:02 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)) 18:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:45:02 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-e6S0GP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:45:02 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)) 18:45:02 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)) 18:45:02 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)) 18:45:02 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)) 18:45:02 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)) 18:45:02 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)) 18:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:45:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:45:02 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 18:45:02 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 18:45:02 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 18:45:02 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 18:45:04 INFO - Downloading blessings-1.6.tar.gz 18:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:45:04 INFO - Installing collected packages: blessings 18:45:04 INFO - Running setup.py install for blessings 18:45:04 INFO - Successfully installed blessings 18:45:04 INFO - Cleaning up... 18:45:04 INFO - Return code: 0 18:45:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:45:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:45:04 INFO - Reading from file tmpfile_stdout 18:45:04 INFO - Current package versions: 18:45:04 INFO - blessings == 1.6 18:45:04 INFO - blobuploader == 1.2.4 18:45:04 INFO - docopt == 0.6.1 18:45:04 INFO - functools32 == 3.2.3-2 18:45:04 INFO - jsonschema == 2.5.1 18:45:04 INFO - manifestparser == 1.1 18:45:04 INFO - mozInstall == 1.13 18:45:04 INFO - mozcrash == 1.0 18:45:04 INFO - mozdebug == 0.1 18:45:04 INFO - mozdevice == 0.51 18:45:04 INFO - mozfile == 1.2 18:45:04 INFO - mozhttpd == 0.7 18:45:04 INFO - mozinfo == 0.10 18:45:04 INFO - mozleak == 0.1 18:45:04 INFO - mozlog == 3.4 18:45:04 INFO - moznetwork == 0.27 18:45:04 INFO - mozprocess == 0.25 18:45:04 INFO - mozprofile == 0.28 18:45:04 INFO - mozrunner == 6.13 18:45:04 INFO - mozscreenshot == 0.1 18:45:04 INFO - mozsystemmonitor == 0.3 18:45:04 INFO - moztest == 0.8 18:45:04 INFO - mozversion == 1.4 18:45:04 INFO - psutil == 3.1.1 18:45:04 INFO - requests == 1.2.3 18:45:04 INFO - wsgiref == 0.1.2 18:45:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:04 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')]} 18:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:04 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')]} 18:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:04 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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:45:04 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 18:45:04 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 18:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:04 INFO - 'HOME': '/Users/cltbld', 18:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:04 INFO - 'LOGNAME': 'cltbld', 18:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:04 INFO - 'MOZ_NO_REMOTE': '1', 18:45:04 INFO - 'NO_EM_RESTART': '1', 18:45:04 INFO - 'PAGER': '/bin/cat', 18:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:04 INFO - 'PWD': '/builds/slave/test', 18:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:04 INFO - 'SHELL': '/bin/bash', 18:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:45:04 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:04 INFO - 'USER': 'cltbld', 18:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:04 INFO - 'XPC_FLAGS': '0x0', 18:45:04 INFO - 'XPC_SERVICE_NAME': '0', 18:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:05 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)) 18:45:05 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)) 18:45:05 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)) 18:45:05 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)) 18:45:05 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)) 18:45:05 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)) 18:45:05 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)) 18:45:05 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)) 18:45:05 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)) 18:45:05 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:45:05 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 18:45:05 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 18:45:05 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 18:45:05 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 18:45:06 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:45:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:45:06 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)) 18:45:06 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)) 18:45:06 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)) 18:45:06 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)) 18:45:06 INFO - Installing collected packages: marionette-driver 18:45:06 INFO - Running setup.py install for marionette-driver 18:45:06 INFO - Successfully installed marionette-driver 18:45:06 INFO - Cleaning up... 18:45:06 INFO - Return code: 0 18:45:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:45:06 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')]} 18:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:06 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')]} 18:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:06 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 0x10cb17c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb1b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6695558b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, '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.y8isfbyl5V/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.eAhW13aaz2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 18:45:06 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 18:45:06 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 18:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:06 INFO - 'HOME': '/Users/cltbld', 18:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:06 INFO - 'LOGNAME': 'cltbld', 18:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:06 INFO - 'MOZ_NO_REMOTE': '1', 18:45:06 INFO - 'NO_EM_RESTART': '1', 18:45:06 INFO - 'PAGER': '/bin/cat', 18:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:06 INFO - 'PWD': '/builds/slave/test', 18:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:06 INFO - 'SHELL': '/bin/bash', 18:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:45:06 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:06 INFO - 'USER': 'cltbld', 18:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:06 INFO - 'XPC_FLAGS': '0x0', 18:45:06 INFO - 'XPC_SERVICE_NAME': '0', 18:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:45:07 INFO - Cleaning up... 18:45:07 INFO - Return code: 0 18:45:07 INFO - Running post-action listener: _resource_record_post_action 18:45:07 INFO - Running post-action listener: _start_resource_monitoring 18:45:07 INFO - Starting resource monitoring. 18:45:07 INFO - [mozharness: 2017-06-13 01:45:07.136436Z] Finished create-virtualenv step (success) 18:45:07 INFO - [mozharness: 2017-06-13 01:45:07.137353Z] Running install step. 18:45:07 INFO - Running pre-action listener: _resource_record_pre_action 18:45:07 INFO - Running main action method: install 18:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:45:07 INFO - Reading from file tmpfile_stdout 18:45:07 INFO - Detecting whether we're running mozinstall >=1.0... 18:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:45:07 INFO - Reading from file tmpfile_stdout 18:45:07 INFO - Output received: 18:45:07 INFO - Usage: mozinstall [options] installer 18:45:07 INFO - Options: 18:45:07 INFO - -h, --help show this help message and exit 18:45:07 INFO - -d DEST, --destination=DEST 18:45:07 INFO - Directory to install application into. [default: 18:45:07 INFO - "/builds/slave/test"] 18:45:07 INFO - --app=APP Application being installed. [default: firefox] 18:45:07 INFO - mkdir: /builds/slave/test/build/application 18:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:45:29 INFO - Reading from file tmpfile_stdout 18:45:29 INFO - Output received: 18:45:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:45:29 INFO - Running post-action listener: _resource_record_post_action 18:45:29 INFO - [mozharness: 2017-06-13 01:45:29.795006Z] Finished install step (success) 18:45:29 INFO - [mozharness: 2017-06-13 01:45:29.795123Z] Running setup-mitmproxy step. 18:45:29 INFO - Running pre-action listener: _resource_record_pre_action 18:45:29 INFO - Running main action method: setup_mitmproxy 18:45:29 INFO - Skipping: mitmproxy is not required 18:45:29 INFO - Running post-action listener: _resource_record_post_action 18:45:29 INFO - [mozharness: 2017-06-13 01:45:29.795736Z] Finished setup-mitmproxy step (success) 18:45:29 INFO - [mozharness: 2017-06-13 01:45:29.795823Z] Running run-tests step. 18:45:29 INFO - Running pre-action listener: _resource_record_pre_action 18:45:29 INFO - Running main action method: run_tests 18:45:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:45:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:45:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:45:29 INFO - 2017-06-12 18:45:29.808 screenresolution[1632:7810] starting screenresolution argv=screenresolution get 18:45:29 INFO - 2017-06-12 18:45:29.811 screenresolution[1632:7810] Display 0: 1600x1200x32@60 18:45:29 INFO - 2017-06-12 18:45:29.816 screenresolution[1633:7812] starting screenresolution argv=screenresolution list 18:45:29 INFO - Available Modes on Display 0 18:45:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:45:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:45:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:45:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:45:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:45:30 INFO - Intel Iris: 18:45:30 INFO - Chipset Model: Intel Iris 18:45:30 INFO - Type: GPU 18:45:30 INFO - Bus: Built-In 18:45:30 INFO - VRAM (Dynamic, Max): 1536 MB 18:45:30 INFO - Vendor: Intel (0x8086) 18:45:30 INFO - Device ID: 0x0a2e 18:45:30 INFO - Revision ID: 0x0009 18:45:30 INFO - Displays: 18:45:30 INFO - Display: 18:45:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:45:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:45:30 INFO - Display Serial Number: 1600x1200 60 18:45:30 INFO - Main Display: Yes 18:45:30 INFO - Mirror: Off 18:45:30 INFO - Online: Yes 18:45:30 INFO - Rotation: Supported 18:45:30 INFO - Return code: 0 18:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:45:30 INFO - Python 2.7.3 18:45:30 INFO - Return code: 0 18:45:30 INFO - grabbing minidump binary from tooltool 18:45:30 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')]} 18:45:30 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 0x10c86dd30>, '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 0x10c837cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c85ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:45:30 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 18:45:30 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 18:45:30 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 18:45:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:45:30 INFO - Return code: 0 18:45:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:45:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:45:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:45:30 INFO - ENV: RUST_BACKTRACE is now 1 18:45:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:45:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:45:30 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/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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 18:45:30 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/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0013 --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 18:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y8isfbyl5V/Render', 18:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:30 INFO - 'HOME': '/Users/cltbld', 18:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:30 INFO - 'LOGNAME': 'cltbld', 18:45:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:45:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:30 INFO - 'MOZ_NO_REMOTE': '1', 18:45:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:45:30 INFO - 'NO_EM_RESTART': '1', 18:45:30 INFO - 'PAGER': '/bin/cat', 18:45:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:30 INFO - 'PWD': '/builds/slave/test', 18:45:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:30 INFO - 'RUST_BACKTRACE': '1', 18:45:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:45:30 INFO - 'SHELL': '/bin/bash', 18:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eAhW13aaz2/Listeners', 18:45:30 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 18:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:30 INFO - 'USER': 'cltbld', 18:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:30 INFO - 'XPC_FLAGS': '0x0', 18:45:30 INFO - 'XPC_SERVICE_NAME': '0', 18:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:30 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/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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 18:45:30 INFO - mozversion application_buildid: 20170612170603 18:45:30 INFO - mozversion application_changeset: 2a3a253806d129c0bb6f2b76bf75630457a24492 18:45:30 INFO - mozversion application_display_name: Nightly 18:45:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:45:30 INFO - mozversion application_name: Firefox 18:45:30 INFO - mozversion application_remotingname: firefox 18:45:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:45:30 INFO - mozversion application_vendor: Mozilla 18:45:30 INFO - mozversion application_version: 56.0a1 18:45:30 INFO - mozversion platform_buildid: 20170612170603 18:45:30 INFO - mozversion platform_changeset: 2a3a253806d129c0bb6f2b76bf75630457a24492 18:45:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:45:30 INFO - mozversion platform_version: 56.0a1 18:45:30 INFO - using testdate: 1497318330 18:45:30 INFO - actual date: 1497318330 18:45:30 INFO - starting webserver on 'localhost:49228' 18:45:30 INFO - SUITE-START | Running 2 tests 18:45:30 INFO - TEST-START | dromaeo_css 18:45:30 INFO - operating with platform_type : mac_ 18:45:30 INFO - Initialising browser for dromaeo_css test... 18:45:30 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpRYO8uv/profile 18:45:30 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 18:46:04 INFO - TEST-INFO | 1643: killed by SIGTERM 18:46:04 INFO - Browser initialized. 18:46:04 INFO - Running cycle 1/1 for dromaeo_css test... 18:46:04 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpRYO8uv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:46:06 INFO - PID 1651 | 2017-06-12 18:46:06.009 plugin-container[1652:8316] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 18:46:06 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:46:06 INFO - PID 1651 | 2017-06-12 18:46:06.010 plugin-container[1652:8316] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 18:46:06 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:46:06 INFO - PID 1651 | 2017-06-12 18:46:06.010 plugin-container[1652:8316] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:46:06 INFO - PID 1651 | 2017-06-12 18:46:06.747 plugin-container[1653:8364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 18:46:06 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:46:06 INFO - PID 1651 | 2017-06-12 18:46:06.748 plugin-container[1653:8364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 18:46:06 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:46:06 INFO - PID 1651 | 2017-06-12 18:46:06.748 plugin-container[1653:8364] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:46:16 INFO - PID 1651 | RSS: Main: 252936192 18:46:16 INFO - PID 1651 | 18:49:18 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 18:49:21 INFO - PID 1651 | RSS: Main: 272871424 18:49:21 INFO - PID 1651 | 18:52:22 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 18:52:23 INFO - PID 1651 | RSS: Main: 281022464 18:52:23 INFO - PID 1651 | 18:55:25 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 18:55:25 INFO - PID 1651 | RSS: Main: 285667328 18:55:25 INFO - PID 1651 | 18:58:27 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 18:58:28 INFO - PID 1651 | RSS: Main: 285978624 18:58:28 INFO - PID 1651 | 19:01:31 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 19:01:31 INFO - PID 1651 | RSS: Main: 288043008 19:01:31 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 19:04:33 INFO - PID 1651 | RSS: Main: 285466624 19:04:33 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | __start_tp_report 19:04:33 INFO - PID 1651 | _x_x_mozilla_page_load 19:04:33 INFO - PID 1651 | _x_x_mozilla_page_load_details 19:04:33 INFO - PID 1651 | |i|pagename|runs| 19:04:33 INFO - PID 1651 | |0;dojo.html;2731.4946734854047;3470.4100302948495;3540.769849959753;3600.2259514204443;3607.891763247102;4902.7303498307665;6174.567780255378;6186.56694031418;6187.257529096515;6240.0327949167895;6181.567290289681;6183.721732522036;6271.122042913984;6282.183316168903;6298.149749783778;5471.616986810925;5482.616216864821;5489.451054894498;5511.559075273968;5594.24477695512;5496.230527726121;5524.652401881791;5563.248961390201;5573.108302671573;5589.161625756149;5343.198520221998;5430.375506816687;5480.862978425552;5505.3393592768925;5529.723513824333;5416.458354164592;5434.483724046209;5435.755391007375;5436.184572314145;5488.710153114015;5357.357117145967;5423.457654234585;5501.779928802843;5509.917351239735;5522.447755224445;6066.817995460143;6276.278228003793;6282.277538083134;6293.590916590453;6301.180846489973;6740.29226931175;6764.763233286812;6765.2219994700745;6766.864662706767;6769.323067693241;6648.135742353463;6694.765683201064;6735.090762747015;6880.415164711006;6897.689603967783;6224.315325314212;6270.028145637433;6349.6507692077375;6353.174087368612;6367.745290188387;6954.79135625932;6971.895421568681;6976.790696279119;6983.511154219257;6989.3360130788515;5686.772529098873;6363.300036995926;6464.22429308486;6487.7080531376205;6644.368784965421;6645.803755323991;6649.401553860176;6664.600123992576;6715.395614394728;6806.727730890709;6737.52837301394;6737.6968036438475;6746.954222095583;6750.696218670171;6758.324167583202;6723.899141512881;6752.932470675329;6762.560433571802;6772.69522871472;6785.558938668971;5281.102212623863;5316.494932981438;5346;5365.812196573102;5415.079436495806;4773;4820.4456487503685;4906.288588154769;4912.606991440676;4935.185694360463;6244.2819075805955;6282.591631543935;6314.873702526015;6370.872582548416;6391.328910464427;6837.62393068386;6843.486738494533;6862.4853136015;6874.621895795779;6884.896726549106;4947.851564453073;6287.931051721218;6332.986722124438;6351.2060992375955;6353.396427339396;5716.085426331767;5809.448102430262;5843.766249350064;5894.67579283135;6015.428534289235;6768.9661551691925;6913.3777959983845;6938.5143039986715;7029.9648501757165;7035.683394247373;6812.795616131524;6886.48351373659;6891.827704307432;6898.517103802686;6910.585364878123;7005.159380874124;7027.05134806815;7029.683664235019;7043.2604576518725;7047.612381319077;6712.93287067123;6736.831579210559;6737.461003119641;6742.561733487308;6774.966125169342;6587.209534855848;6693.062971183941;6694.196696396463;6862.828429289108;6865.897011544931;6476.838079048061;6492.415682588401;6558.8360290993105;6650;6670;6597.670116494252;6630.569013014138;6636.303188165366;6678.3989440950545;6680.164979377578;6313;6374.235091789015;6462.838429039311;6540.247871494839;6548.672566371758;6462.483001359974;6466.838329041623;6484.157059582413;6550.63971481554;6557.721244357304;5414.458554144554;6460.418562329225;6463.709133089116;6463.967680161569;6469.126667899774 19:04:33 INFO - PID 1651 | |1;ext.html;677.5697432130597;795.280271354424;812.2973627811945;815.710422799906;851.4380508864147;36766.26467470652;37678.811605942;37733.622663773356;37762.24475510495;37780.62219377806;28809.855950720215;37920.67277645283;38173.23653526928;38725.80637096811;38968.610313896854;6404.711787969529;8280.420370574062;8324.958375208116;8335.583220838964;8351.08138104811;8132.75601731949;8232.876506852403;8298.543580103093;8301.958490207571;8303.916960830406;8274.2139496748;8305.584720763967;8334.70828521002;8349.499030058216;8351.54066526344;8276.337892968579;8297.917020829775;8316.085230624627;8321.542315172663;8331.166883311651;8133.10535841057;8168.4282100253;8169.060558035842;8193.754187374388;8230.958845205736;37048.81475592605;37234;37292.813535932415;37309.44035839465;37325.81337093324;3494.4583589543663;3510.262844802569;3532.682058614737;3533.169705119295;3570.321638888603;30007;30219.79120835164;30236.546451803242;30371.848140759375;30524.931627392936;2963.7036296370406;3047.740942019931;3050.877964881402;3088.969110308891;3092.8453577321043;243768;247842;251668.48331516635;251856;294501;38144.237115258096;43027.784861075495;46333.76833115813;46545.83635409142;46599.53400465952;50961.745191274546;51002.724931877;51053;51128.74435627798;51247;12757.42591583381;13026.80459793104;13048.086633935533;13164.539241126515;13178.011649126354;10494.370337779757;10613.787724245472;10671;10683.358998460117;10706.94646526778;13670.906327493776;13676.042677012712;13706.794398084037;13746.93126534361;13774.793378099337;6043.637381757108;6062.151298818169;6114.388561143939;6132.356102609251;6137;882.2500874256958;959.52023988006;975.380633297861;977.8093271811929;1044.237706474263;23103.19138830133;23170.7682923172;23271.185508507122;23361.649575256386;24294.87852560726;1048.3028785857318;1058.402002868377;1117.8938000889902;1119.1829964126234;1135.1146106037138;9374.625014999323;11769.823452648217;12052.33712145823;12534.24794512314;12558.686032849251;331884;334472;335236.64763352054;335260;336968;54113.72943135259;54395;54441.45558544443;54457.72771136198;54782;1243.595831354795;1520.7414739494313;1523.474401331539;1524.580740296405;1524.702682976831;19848.60302793965;22721.772782272627;22792.544149117257;22947.65578516357;23733.525329492968;28000.579991300554;35763.82118089393;36296.637033629326;36321.27357452889;36568;240464;241863.5813641841;242765;250344;274601;34108.82945585256;35168.64831351756;35282.647173527934;35631;35659.643403565635;42123;42530.936726582084;42641.57358426376;42790.786046069574;43490.782546087066;28020.43959120847;29381;29676;29970.850145749133;31020.68979310178;29426.67579958864;30291.69708302977;30926.38147237088;31114.68885311118;31203;35791.46312805258;36031.63968360283;36393.45409818802;36575.817120914224;36918.630813692595;29106.563401549418;30531.542026870058;31021.844890775403;31736.682633174296;32225.87209447658;20450.488737781674;22657.093716251165;23782.52434951326;24773;24860.12989545357 19:04:33 INFO - PID 1651 | |2;jquery.html;432.5595766098891;446.7074066486451;477.36748807829633;495.5366732105484;525.7686617888131;14338.56984290473;21683.241086561975;22230.221942232027;22493.437664058387;22579;16889.071101089434;17137.31450741972;17218.225179866906;17326.133693315314;18674.719879201788;3726.47829303897;6370.8088757337355;6410.455111315544;6431;6437.935620643781;6197.659128747917;6240.531960102988;6307.463865571432;6324.652144132071;6330.968345158268;6273.404026617464;6291.150694656231;6295.653739044351;6296.37036296369;6300.9369906301035;4917.4344950330815;5493.697846618435;6285.245770507523;6309.337519560426;6310.085037669535;4754.548317909793;5628.380878103405;5646.717664116819;5660.773569057233;5687.772489100431;28612.998545051032;28853;29377.265568360748;29452.55821162684;29507.70492295071;2213.701150344715;2230.453538882984;2230.654248591471;2250.1674380479326;2253.312739614417;17694.292228310853;18662.720059199124;19177.61644767111;19210.423687289403;19454;2081.4172031831113;2117.671760877051;2119.3747844385944;2119.4489432747596;2120.978790212109;181136;181943;187652;187854;214440;14180.622038458325;14575.565637961825;14708.970372074065;14716.411343546353;14745.894057945698;19162.80837191638;19434.319798806973;19504.414867553995;19549.413517594494;19559.31542396038;9091;9164.633414663338;9177.816443671223;9281.535923203814;9287.44275343468;6818.249992500821;7562.2437756223935;7594.974678418343;8184;8294.755786632053;7284;7461.477696561297;8867.955660221658;8892.688755893512;8934.240589549961;2175.5540114276537;2575.7939364850768;2585.870706464669;2587.676540432446;2732.767714744269;926.1479438916078;943.5140902435252;944.8299306124826;985.6550207427343;1006.5872992073215;19265;19535;19925.302614408716;20568.794312056987;26678.53268070236;1009.5204777730667;1037.1754455208127;1040.287403128859;1042.781015986636;1046.5029111172132;10848.969347911936;11135.05352045069;11224.719382015515;11242;11353.489092990836;556741;567134;582964;593563;598983;144852;148800.2559987193;148982;150152;162215;1067.80779459698;1213.1326101837228;1241.3669028795198;1243.502598960423;1245.4271035323852;18518.44444666662;18641.347552835585;19314.32399865998;19359.8064019358;19758.209671612974;49861;64127.03809442769;66948;67499.32500675127;69894.65052674703;84919;85093.14906850852;87409.56295218738;87556;90006.54996725236;11620;11633.59282425111;11742.88257117418;11757;11766.235194712317;17970.5507362317;18287.908560457643;18471.815281847546;18666.440006799818;18710.625787484445;14772.33524491422;14895.180765058027;14900.18049007315;14967.326470308644;15002.94979351435;10878.292910960763;11514.675812281695;11690.298582085103;12353.135280530276;13581;14107.294635268401;15206.315715793036;15216.391344346102;15335.616609584404;15383.30775115145;11378.943105284752;11412.201145919706;11424.200305978502;11522.711932201762;11653.708657283636;14143.151410914967;14860.925695371454;14943.551693449213;14966.176860272353;14966.551003469913 19:04:33 INFO - PID 1651 | |3;mootools.html;1128.3568366031363;1402.1236727045596;1412.975592695296;1414.186842565525;1433.4911106557174;12715.237085774837;12915.870841291597;13492.527761528348;13500.662483437918;13525.729485410298;13145.079844410895;13357.465701371933;13375.59873203803;13386.263755493444;13400.731985360311;2658.9069382571565;2715.0090217070824;2718.972810271892;2720.6735191776916;2720.782337413012;2652.4827658606578;2691.3675286307703;2691.528982428067;2692.3000019994747;2701.18964310707;2627.3300308421326;2655.468906218764;2686.4224191798758;2686.9731302687014;2705.5941608758744;2666.413389054405;2669.399385138348;2681;2682.1283082997907;2684.704682484925;2280.6122959096992;2647.351398907261;2654.1639383594106;2663.920082397531;2707.6073969274544;13006.739865202642;13152.802707959292;13158.407871645702;13171.407286672124;13187.868121318856;7165.713371465135;7386.778396648109;7450.701971921117;7457.776266712007;7461.477696561188;3851.595582463829;4578.702384345041;4593.379893714339;4613.261878099522;4618;1399.930003499821;1402.263811498971;1404.4101477379525;1430.6637940084072;1435.3253970633784;15853.92073039639;16725.414610488573;17847.46457606274;18161.636767264714;19591.31430399929;5423.593230507728;5743.023685973395;6374.936250637435;6493.22081350241;6587.110740050128;13644.65888352799;13732.244726539937;13777.655558610915;13779.724405511834;13801.378937947817;7059.047028651169;7259.709611615582;7282.781516554512;7315.341619254293;7332;6544.214694236722;6663.566868153554;6673.833154171185;6734.494912881553;6747.831304217336;4535.818567257273;4639.814407423665;4973.850784476472;5039.647224694236;5080.237964305384;1215.2708374974945;1234.5802427174804;1430.8569143085608;1454.7454195515743;1474.4249742600393;808.2039191396469;835.9456635318807;837.2548431895618;837.3426859914938;868.2142660891903;6865.210500792373;7007.509474336747;7142.428605711634;7210.774168391386;7262.782116536512;653.2389765922742;678.5962352400314;705.8658854817671;714.139461948351;715.7566427414704;4484.887877803081;4924.429295420883;4935.90128197434;4999.850004499798;5199.896002079937;17475.82524174742;17887.74785764984;18022.00878951644;18210.18054187565;18221.63556728858;15306.846931530765;16376.426825061066;16691.749623755655;16955.38923802237;17655.38206162778;9168.679096231599;9173.633054677872;9292.488913109708;9437.575309111107;9469.526523673927;6599.373059559334;7432.442566807619;7479.252074792477;7537.773866784005;7890.289873911375;14944.925275373553;15523.146226957626;16017.038977661377;16031;16214.10822404779;15476.458323958608;16202.784791140948;16377.263023164225;16630.417935372203;16632.918860274043;9646.372985755903;9756.21950243993;9767.267454940766;9778.511074446391;9785.755356116155;11817.704557386134;12225.266484010988;12238.959688426583;12400.875991240333;12403.069769767124;9825.65610203668;9851.458169800442;10043.648472303727;10089.344192627455;10139.290249682746;9640.084192001748;9847.45838978863;9937.701868943654;10120;10126.24053196028;8779.824403512022;8786.077461866413;8872.378933474853;8886;8955.910440895508;6549.279579246374;6610.10763546915;6616.933830661632;6923.169219693669;6985.860282794214;6859.5541289818;6876.828079298058;6944.305569443015;7106.360427561544;7131.896090653166 19:04:33 INFO - PID 1651 | |4;prototype.html;424.34438792066254;445.6212219613328;449.0816280705956;462.70849364900107;468.6789549158826;5259.342582177228;6456;6484.416402523778;6506.577072490297;6518.445932095771;4743.861473246422;6156.969215153918;6230.875382492359;6385;6393.4565561927175;4322.308430651096;4970.527799859007;4979.427365852937;4993.6005119590345;5041.369828771403;4934.901301973959;4936.963237720083;4952.925706114411;5022.217112924895;5044.470330615288;4364.629006534448;4444.466664000324;4580.7402964184785;4642.9553350496035;4953.207486802113;4135.379693046037;4505.256632655608;4535.841245556389;4705.788239529229;4784.832530861438;3240.4653232216656;3289.4572395554706;3897.9475541603892;4154.439150714676;4195.350045460469;4807.975960120212;5955.702214889281;6107.4808641265545;6169.814905552796;6197.442230199259;5590.329160500725;5612.747426365823;5617.80337688183;5642.125470552071;5688.829335119953;5740.540756739492;6732.81183615327;6770.765647336367;6802.65986700663;6818.352256535622;4351.804168812411;4397.648188144941;4399.670024748157;4794.208955522335;4794.808207671689;6392.648404337713;7141.928580714179;7183.676734546958;7219.855602887913;7238.2761723827725;4822.565969062801;4826.903461930741;4863.784053986504;4876.804927802919;4877.219644856806;4521.400194887887;4941.382327209099;4951.455339912607;4951.653384263067;4972.353594032753;4496.820127194876;5649.593317212581;5653.152027195872;5657.264555607827;5687.232223649837;5604.327480702342;6341.397567231105;6817.227520844517;6898.517103802686;6902.689378977958;1536.377767004365;1685.890417122883;1703.6081701208543;1704.8295170482852;1709.991450042742;1130.2653275371074;1135.233717240859;1139.361957303896;1141.7944769941491;1142.4858813533942;5117.795288188505;6281.095769976299;6286.968565157145;6340;6342.39747224013;6037.939620603738;6066;6869.725210991604;7045.401140903081;7075.469339799535;5254.632175747661;5635.661860288314;5645.5483561314995;5743.629706224693;6120.14317995481;3150.558921750957;3532.717382609385;3639.70882329413;3652.945205821915;3698.334299826003;5753.367129615739;6059.4546490815155;6438.678066096676;7008.404285635676;7221.8555628887125;6082.726277317532;6639.43564796988;7283.2352602975925;7291.37510496221;7302.48882578225;6447.903856344433;7175.31834475724;7178.030965819652;7217.67520461591;7282.198958114497;6972.965135174291;6994.160700715946;7076.32774886385;7140;7176.21061683204;5429.049916264559;6422.807315780534;6823.965880170567;6835.350641689031;6915.446764258948;6510.576812507172;6785.491088168308;7209.026781384656;7211.2428195038765;7253.347198752138;4236.851710190129;4819.2530157825595;4826.3243145958895;4826.6621336506105;4850.369451971221;6639.800805975829;6668.166479190101;7057.2237053923;7059.752908648173;7114.537555058904;5732.541396688338;5885.410939046519;6001.909971350346;6430.813523402066;6708.094407254958;5708.429157084258;6339.225016995249;6502.414782669583;6845.589264644137;6895.448364130757;5586.692731899784;5617.634853734494;5907.261592300963;5992.254840724547;6375.203099612549;5657.943420565742;5778.959787238337;6586.275509693834;6802.741492823843;6835.829104272433;5490.752916118864;6580.934190658032;6587.209534855848;6614.239362473377;6633.2040155181685 19:04:33 INFO - PID 1651 | |5;yui.html;710.7441321124397;766.6894907562437;825.273759091999;854.7991222062814;855.8758979947908;16801.747973780402;17303.87524810888;17305.653886922253;17316.480505584823;17373.391931282407;17959.73060404095;18789.906050469763;18871.905640471814;18992.905035474803;19004.714929276037;962.1100482054089;976.2873102635081;981.3228872078244;986.1371300112402;986.758244230161;970.8592254123148;975.6146322202726;978.6427953796884;979.3193730357405;988.8615593816871;859.669027424443;1023.5905637744887;1026.0201507560262;1028.4292217819145;1031.046282188976;972.4700038479045;994.4033579852068;1021.1371391174446;1023.6059117239841;1030.7114008077751;749.4454103963083;750.5684231566871;751.6692655231735;751.8984937033485;759.6391713935891;17579.56051098733;17643.382481613084;17653.7351939721;17686.469405917844;17705.20326585294;17491;17734.55663608407;17943.371981980566;18040.90979545107;18082.276708931626;14486.203258820866;14883.10701357922;15015.774763378558;15046.699066018618;15276.465323713617;3678.8712395066045;3680.429533422324;3702.4446333049987;3712;3712.535933008374;91216.54391728;140101;142992;151574;155136.44863551334;11367.545298188144;11453;11631.953124218691;14226.502072427415;14244.715105697827;26164.822582983812;26368.73631263701;26567.33581660474;26601;26633.467330653664;6936.445084393236;6968.547044442095;6991.056207412036;7009.9649501752165;7014.088168538057;3897.590752970954;3919;4011.4183443400543;4084.1423301106506;4091.365838295069;4571.634269258451;4651.09303685778;4683.157031734252;4692.1554120258;4719.905601887943;983.9163671088038;1005.5927349423495;1016.6390931219458;1026.6714651311659;1027.8612387327764;652.2368828470701;659.9010148477767;662.1458318768831;680.119245576979;683.62400679627;9607.423554586607;11154.888451115547;11561.479733412016;12086.214396064228;12248.571300004458;608.3217212807688;619.8791235709084;654.6399480285824;670.7618795327686;679.4937771360368;5498.945010549924;5569.665820050729;5659.54943005597;5899.4985426239255;6024.909626355608;119388;120283;125825;127264;129713.05430418551;109356;109840;111758.76482470467;116627.8337216617;116686;75134;93348.06651933529;97046.99999999859;99028.51457228148;100588.98822023519;11754.177207595387;12021.939890300493;12176.75646487083;12182.634520964386;12313.859965708687;42469.362959555016;43611;44850;44971;45664.771676141405;57717.71141144267;60440.697796510736;61286.38713612807;81400.18599813926;88550;15035.398584056953;16021.198940053184;16349.34602615882;16609.584760380603;16971.91514042422;14242.643933901736;18241.908790455964;18561.35035273759;18808.811911881254;18897.43307700771;16149;16191.352345906504;16486.587835304214;16686.66626667484;16687.249073791467;16541.917290413472;16617.833821662112;16726.247318870435;16855.325786968384;16949.32202711878;11381.719703361216;16347.427840025543;16630.916845415697;16815.915920420808;17065.91467042657;11711.355875426932;11747;11875.228110172811;12102.394880255775;12103.81844272319;11592.652220433063;12552.497900083894;12584.433700483318;12644.810327845275;12730.872691272969 19:04:33 INFO - PID 1651 | __end_tp_report 19:04:33 INFO - PID 1651 | __start_cc_report 19:04:33 INFO - PID 1651 | _x_x_mozilla_cycle_collect,476 19:04:33 INFO - PID 1651 | __end_cc_report 19:04:33 INFO - PID 1651 | __startTimestamp1497319473659__endTimestamp 19:04:33 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | ------- Summary: start ------- 19:04:33 INFO - PID 1651 | Number of tests: 6 19:04:33 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6201.42 Median:6462.84 stddev:773.07 (12.0%) stddev-sans-first:725.93 19:04:33 INFO - PID 1651 | Values: 2731.5 3470.4 3540.8 3600.2 3607.9 4902.7 6174.6 6186.6 6187.3 6240.0 6181.6 6183.7 6271.1 6282.2 6298.1 5471.6 5482.6 5489.5 5511.6 5594.2 5496.2 5524.7 5563.2 5573.1 5589.2 5343.2 5430.4 5480.9 5505.3 5529.7 5416.5 5434.5 5435.8 5436.2 5488.7 5357.4 5423.5 5501.8 5509.9 5522.4 6066.8 6276.3 6282.3 6293.6 6301.2 6740.3 6764.8 6765.2 6766.9 6769.3 6648.1 6694.8 6735.1 6880.4 6897.7 6224.3 6270.0 6349.7 6353.2 6367.7 6954.8 6971.9 6976.8 6983.5 6989.3 5686.8 6363.3 6464.2 6487.7 6644.4 6645.8 6649.4 6664.6 6715.4 6806.7 6737.5 6737.7 6747.0 6750.7 6758.3 6723.9 6752.9 6762.6 6772.7 6785.6 5281.1 5316.5 5346.0 5365.8 5415.1 4773.0 4820.4 4906.3 4912.6 4935.2 6244.3 6282.6 6314.9 6370.9 6391.3 6837.6 6843.5 6862.5 6874.6 6884.9 4947.9 6287.9 6333.0 6351.2 6353.4 5716.1 5809.4 5843.8 5894.7 6015.4 6769.0 6913.4 6938.5 7030.0 7035.7 6812.8 6886.5 6891.8 6898.5 6910.6 7005.2 7027.1 7029.7 7043.3 7047.6 6712.9 6736.8 6737.5 6742.6 6775.0 6587.2 6693.1 6694.2 6862.8 6865.9 6476.8 6492.4 6558.8 6650.0 6670.0 6597.7 6630.6 6636.3 6678.4 6680.2 6313.0 6374.2 6462.8 6540.2 6548.7 6462.5 6466.8 6484.2 6550.6 6557.7 5414.5 6460.4 6463.7 6464.0 6469.1 19:04:33 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:43057.46 Median:35225.65 stddev:74449.07 (211.3%) stddev-sans-first:74589.92 19:04:33 INFO - PID 1651 | Values: 677.6 795.3 812.3 815.7 851.4 36766.3 37678.8 37733.6 37762.2 37780.6 28809.9 37920.7 38173.2 38725.8 38968.6 6404.7 8280.4 8325.0 8335.6 8351.1 8132.8 8232.9 8298.5 8302.0 8303.9 8274.2 8305.6 8334.7 8349.5 8351.5 8276.3 8297.9 8316.1 8321.5 8331.2 8133.1 8168.4 8169.1 8193.8 8231.0 37048.8 37234.0 37292.8 37309.4 37325.8 3494.5 3510.3 3532.7 3533.2 3570.3 30007.0 30219.8 30236.5 30371.8 30524.9 2963.7 3047.7 3050.9 3089.0 3092.8 243768.0 247842.0 251668.5 251856.0 294501.0 38144.2 43027.8 46333.8 46545.8 46599.5 50961.7 51002.7 51053.0 51128.7 51247.0 12757.4 13026.8 13048.1 13164.5 13178.0 10494.4 10613.8 10671.0 10683.4 10706.9 13670.9 13676.0 13706.8 13746.9 13774.8 6043.6 6062.2 6114.4 6132.4 6137.0 882.3 959.5 975.4 977.8 1044.2 23103.2 23170.8 23271.2 23361.6 24294.9 1048.3 1058.4 1117.9 1119.2 1135.1 9374.6 11769.8 12052.3 12534.2 12558.7 331884.0 334472.0 335236.6 335260.0 336968.0 54113.7 54395.0 54441.5 54457.7 54782.0 1243.6 1520.7 1523.5 1524.6 1524.7 19848.6 22721.8 22792.5 22947.7 23733.5 28000.6 35763.8 36296.6 36321.3 36568.0 240464.0 241863.6 242765.0 250344.0 274601.0 34108.8 35168.6 35282.6 35631.0 35659.6 42123.0 42530.9 42641.6 42790.8 43490.8 28020.4 29381.0 29676.0 29970.9 31020.7 29426.7 30291.7 30926.4 31114.7 31203.0 35791.5 36031.6 36393.5 36575.8 36918.6 29106.6 30531.5 31021.8 31736.7 32225.9 20450.5 22657.1 23782.5 24773.0 24860.1 19:04:33 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:39100.57 Median:2099.54 stddev:99969.46 (4761.5%) stddev-sans-first:100207.74 19:04:33 INFO - PID 1651 | Values: 432.6 446.7 477.4 495.5 525.8 14338.6 21683.2 22230.2 22493.4 22579.0 16889.1 17137.3 17218.2 17326.1 18674.7 3726.5 6370.8 6410.5 6431.0 6437.9 6197.7 6240.5 6307.5 6324.7 6331.0 6273.4 6291.2 6295.7 6296.4 6300.9 4917.4 5493.7 6285.2 6309.3 6310.1 4754.5 5628.4 5646.7 5660.8 5687.8 28613.0 28853.0 29377.3 29452.6 29507.7 2213.7 2230.5 2230.7 2250.2 2253.3 17694.3 18662.7 19177.6 19210.4 19454.0 2081.4 2117.7 2119.4 2119.4 2121.0 181136.0 181943.0 187652.0 187854.0 214440.0 14180.6 14575.6 14709.0 14716.4 14745.9 19162.8 19434.3 19504.4 19549.4 19559.3 9091.0 9164.6 9177.8 9281.5 9287.4 6818.2 7562.2 7595.0 8184.0 8294.8 7284.0 7461.5 8868.0 8892.7 8934.2 2175.6 2575.8 2585.9 2587.7 2732.8 926.1 943.5 944.8 985.7 1006.6 19265.0 19535.0 19925.3 20568.8 26678.5 1009.5 1037.2 1040.3 1042.8 1046.5 10849.0 11135.1 11224.7 11242.0 11353.5 556741.0 567134.0 582964.0 593563.0 598983.0 144852.0 148800.3 148982.0 150152.0 162215.0 1067.8 1213.1 1241.4 1243.5 1245.4 18518.4 18641.3 19314.3 19359.8 19758.2 49861.0 64127.0 66948.0 67499.3 69894.7 84919.0 85093.1 87409.6 87556.0 90006.5 11620.0 11633.6 11742.9 11757.0 11766.2 17970.6 18287.9 18471.8 18666.4 18710.6 14772.3 14895.2 14900.2 14967.3 15002.9 10878.3 11514.7 11690.3 12353.1 13581.0 14107.3 15206.3 15216.4 15335.6 15383.3 11378.9 11412.2 11424.2 11522.7 11653.7 14143.2 14860.9 14943.6 14966.2 14966.6 19:04:33 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:7959.85 Median:2711.31 stddev:5191.00 (191.5%) stddev-sans-first:5180.18 19:04:33 INFO - PID 1651 | Values: 1128.4 1402.1 1413.0 1414.2 1433.5 12715.2 12915.9 13492.5 13500.7 13525.7 13145.1 13357.5 13375.6 13386.3 13400.7 2658.9 2715.0 2719.0 2720.7 2720.8 2652.5 2691.4 2691.5 2692.3 2701.2 2627.3 2655.5 2686.4 2687.0 2705.6 2666.4 2669.4 2681.0 2682.1 2684.7 2280.6 2647.4 2654.2 2663.9 2707.6 13006.7 13152.8 13158.4 13171.4 13187.9 7165.7 7386.8 7450.7 7457.8 7461.5 3851.6 4578.7 4593.4 4613.3 4618.0 1399.9 1402.3 1404.4 1430.7 1435.3 15853.9 16725.4 17847.5 18161.6 19591.3 5423.6 5743.0 6374.9 6493.2 6587.1 13644.7 13732.2 13777.7 13779.7 13801.4 7059.0 7259.7 7282.8 7315.3 7332.0 6544.2 6663.6 6673.8 6734.5 6747.8 4535.8 4639.8 4973.9 5039.6 5080.2 1215.3 1234.6 1430.9 1454.7 1474.4 808.2 835.9 837.3 837.3 868.2 6865.2 7007.5 7142.4 7210.8 7262.8 653.2 678.6 705.9 714.1 715.8 4484.9 4924.4 4935.9 4999.9 5199.9 17475.8 17887.7 18022.0 18210.2 18221.6 15306.8 16376.4 16691.7 16955.4 17655.4 9168.7 9173.6 9292.5 9437.6 9469.5 6599.4 7432.4 7479.3 7537.8 7890.3 14944.9 15523.1 16017.0 16031.0 16214.1 15476.5 16202.8 16377.3 16630.4 16632.9 9646.4 9756.2 9767.3 9778.5 9785.8 11817.7 12225.3 12239.0 12400.9 12403.1 9825.7 9851.5 10043.6 10089.3 10139.3 9640.1 9847.5 9937.7 10120.0 10126.2 8779.8 8786.1 8872.4 8886.0 8955.9 6549.3 6610.1 6616.9 6923.2 6985.9 6859.6 6876.8 6944.3 7106.4 7131.9 19:04:33 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5424.44 Median:5742.09 stddev:1658.49 (28.9%) stddev-sans-first:1620.13 19:04:33 INFO - PID 1651 | Values: 424.3 445.6 449.1 462.7 468.7 5259.3 6456.0 6484.4 6506.6 6518.4 4743.9 6157.0 6230.9 6385.0 6393.5 4322.3 4970.5 4979.4 4993.6 5041.4 4934.9 4937.0 4952.9 5022.2 5044.5 4364.6 4444.5 4580.7 4643.0 4953.2 4135.4 4505.3 4535.8 4705.8 4784.8 3240.5 3289.5 3897.9 4154.4 4195.4 4808.0 5955.7 6107.5 6169.8 6197.4 5590.3 5612.7 5617.8 5642.1 5688.8 5740.5 6732.8 6770.8 6802.7 6818.4 4351.8 4397.6 4399.7 4794.2 4794.8 6392.6 7141.9 7183.7 7219.9 7238.3 4822.6 4826.9 4863.8 4876.8 4877.2 4521.4 4941.4 4951.5 4951.7 4972.4 4496.8 5649.6 5653.2 5657.3 5687.2 5604.3 6341.4 6817.2 6898.5 6902.7 1536.4 1685.9 1703.6 1704.8 1710.0 1130.3 1135.2 1139.4 1141.8 1142.5 5117.8 6281.1 6287.0 6340.0 6342.4 6037.9 6066.0 6869.7 7045.4 7075.5 5254.6 5635.7 5645.5 5743.6 6120.1 3150.6 3532.7 3639.7 3652.9 3698.3 5753.4 6059.5 6438.7 7008.4 7221.9 6082.7 6639.4 7283.2 7291.4 7302.5 6447.9 7175.3 7178.0 7217.7 7282.2 6973.0 6994.2 7076.3 7140.0 7176.2 5429.0 6422.8 6824.0 6835.4 6915.4 6510.6 6785.5 7209.0 7211.2 7253.3 4236.9 4819.3 4826.3 4826.7 4850.4 6639.8 6668.2 7057.2 7059.8 7114.5 5732.5 5885.4 6001.9 6430.8 6708.1 5708.4 6339.2 6502.4 6845.6 6895.4 5586.7 5617.6 5907.3 5992.3 6375.2 5657.9 5779.0 6586.3 6802.7 6835.8 5490.8 6580.9 6587.2 6614.2 6633.2 19:04:33 INFO - PID 1651 | 19:04:33 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:24157.38 Median:18061.59 stddev:36056.14 (199.6%) stddev-sans-first:36114.31 19:04:33 INFO - PID 1651 | Values: 710.7 766.7 825.3 854.8 855.9 16801.7 17303.9 17305.7 17316.5 17373.4 17959.7 18789.9 18871.9 18992.9 19004.7 962.1 976.3 981.3 986.1 986.8 970.9 975.6 978.6 979.3 988.9 859.7 1023.6 1026.0 1028.4 1031.0 972.5 994.4 1021.1 1023.6 1030.7 749.4 750.6 751.7 751.9 759.6 17579.6 17643.4 17653.7 17686.5 17705.2 17491.0 17734.6 17943.4 18040.9 18082.3 14486.2 14883.1 15015.8 15046.7 15276.5 3678.9 3680.4 3702.4 3712.0 3712.5 91216.5 140101.0 142992.0 151574.0 155136.4 11367.5 11453.0 11632.0 14226.5 14244.7 26164.8 26368.7 26567.3 26601.0 26633.5 6936.4 6968.5 6991.1 7010.0 7014.1 3897.6 3919.0 4011.4 4084.1 4091.4 4571.6 4651.1 4683.2 4692.2 4719.9 983.9 1005.6 1016.6 1026.7 1027.9 652.2 659.9 662.1 680.1 683.6 9607.4 11154.9 11561.5 12086.2 12248.6 608.3 619.9 654.6 670.8 679.5 5498.9 5569.7 5659.5 5899.5 6024.9 119388.0 120283.0 125825.0 127264.0 129713.1 109356.0 109840.0 111758.8 116627.8 116686.0 75134.0 93348.1 97047.0 99028.5 100589.0 11754.2 12021.9 12176.8 12182.6 12313.9 42469.4 43611.0 44850.0 44971.0 45664.8 57717.7 60440.7 61286.4 81400.2 88550.0 15035.4 16021.2 16349.3 16609.6 16971.9 14242.6 18241.9 18561.4 18808.8 18897.4 16149.0 16191.4 16486.6 16686.7 16687.2 16541.9 16617.8 16726.2 16855.3 16949.3 11381.7 16347.4 16630.9 16815.9 17065.9 11711.4 11747.0 11875.2 12102.4 12103.8 11592.7 12552.5 12584.4 12644.8 12730.9 19:04:33 INFO - PID 1651 | -------- Summary: end -------- 19:04:33 INFO - PID 1651 | 19:04:34 INFO - TEST-INFO | 1651: exit 0 19:04:34 INFO - TEST-OK | dromaeo_css | took 1143839ms 19:04:34 INFO - TEST-START | kraken 19:04:34 INFO - operating with platform_type : mac_ 19:04:34 INFO - Initialising browser for kraken test... 19:04:34 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpMpD1Lp/profile 19:04:34 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 19:05:08 INFO - TEST-INFO | 1661: killed by SIGTERM 19:05:08 INFO - Browser initialized. 19:05:08 INFO - Running cycle 1/1 for kraken test... 19:05:08 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpMpD1Lp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:05:10 INFO - PID 1665 | 2017-06-12 19:05:10.003 plugin-container[1666:10998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 19:05:10 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:10 INFO - PID 1665 | 2017-06-12 19:05:10.004 plugin-container[1666:10998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 19:05:10 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:10 INFO - PID 1665 | 2017-06-12 19:05:10.005 plugin-container[1666:10998] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:05:10 INFO - PID 1665 | 2017-06-12 19:05:10.776 plugin-container[1667:11049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 19:05:10 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:10 INFO - PID 1665 | 2017-06-12 19:05:10.777 plugin-container[1667:11049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9723, name = 'com.apple.CFPasteboardClient' 19:05:10 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:10 INFO - PID 1665 | 2017-06-12 19:05:10.777 plugin-container[1667:11049] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:05:20 INFO - PID 1665 | RSS: Main: 253120512 19:05:20 INFO - PID 1665 | 19:05:23 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 19:05:24 INFO - PID 1665 | RSS: Main: 265129984 19:05:24 INFO - PID 1665 | 19:05:26 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 19:05:27 INFO - PID 1665 | RSS: Main: 274640896 19:05:27 INFO - PID 1665 | 19:05:30 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 19:05:31 INFO - PID 1665 | RSS: Main: 278142976 19:05:31 INFO - PID 1665 | 19:05:33 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 19:05:33 INFO - PID 1665 | RSS: Main: 279642112 19:05:33 INFO - PID 1665 | 19:05:35 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:05:36 INFO - PID 1665 | RSS: Main: 280403968 19:05:36 INFO - PID 1665 | 19:05:39 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 19:05:40 INFO - PID 1665 | RSS: Main: 287805440 19:05:40 INFO - PID 1665 | 19:05:43 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 19:05:43 INFO - PID 1665 | RSS: Main: 286502912 19:05:43 INFO - PID 1665 | 19:05:47 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 19:05:47 INFO - PID 1665 | RSS: Main: 285110272 19:05:47 INFO - PID 1665 | 19:05:49 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:05:49 INFO - PID 1665 | RSS: Main: 285286400 19:05:49 INFO - PID 1665 | 19:05:51 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:05:52 INFO - PID 1665 | RSS: Main: 285437952 19:05:52 INFO - PID 1665 | 19:05:54 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:05:55 INFO - PID 1665 | RSS: Main: 284852224 19:05:55 INFO - PID 1665 | 19:05:57 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:05:58 INFO - PID 1665 | RSS: Main: 285212672 19:05:58 INFO - PID 1665 | 19:06:01 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:06:02 INFO - PID 1665 | RSS: Main: 284930048 19:06:02 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 19:06:04 INFO - PID 1665 | RSS: Main: 286806016 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | __start_tp_report 19:06:04 INFO - PID 1665 | _x_x_mozilla_page_load 19:06:04 INFO - PID 1665 | _x_x_mozilla_page_load_details 19:06:04 INFO - PID 1665 | |i|pagename|runs| 19:06:04 INFO - PID 1665 | |0;ai-astar;106;113;113;131;148;160;170;109;172;178 19:06:04 INFO - PID 1665 | |1;audio-beat-detection;136;115;149;145;112;130;108;146;153;123 19:06:04 INFO - PID 1665 | |2;audio-dft;203;202;210;226;225;250;287;206;201;204 19:06:04 INFO - PID 1665 | |3;audio-fft;71;75;88;131;61;60;73;67;102;61 19:06:04 INFO - PID 1665 | |4;audio-oscillator;71;69;110;90;71;77;113;71;72;112 19:06:04 INFO - PID 1665 | |5;imaging-gaussian-blur;135;90;90;90;89;90;136;135;91;89 19:06:04 INFO - PID 1665 | |6;imaging-darkroom;96;98;98;122;96;97;98;98;100;98 19:06:04 INFO - PID 1665 | |7;imaging-desaturate;105;95;92;92;89;92;90;144;144;142 19:06:04 INFO - PID 1665 | |8;json-parse-financial;51;51;51;53;55;75;81;73;57;52 19:06:04 INFO - PID 1665 | |9;json-stringify-tinderbox;56;54;57;65;66;67;58;56;57;58 19:06:04 INFO - PID 1665 | |10;stanford-crypto-aes;83;90;109;105;79;83;78;80;82;78 19:06:04 INFO - PID 1665 | |11;stanford-crypto-ccm;146;153;128;143;133;153;131;146;161;176 19:06:04 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;194;186;181;178;185;238;205;175;179;177 19:06:04 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;57;93;97;67;68;67;67;68;64;68 19:06:04 INFO - PID 1665 | __end_tp_report 19:06:04 INFO - PID 1665 | __start_cc_report 19:06:04 INFO - PID 1665 | _x_x_mozilla_cycle_collect,1194 19:06:04 INFO - PID 1665 | __end_cc_report 19:06:04 INFO - PID 1665 | __startTimestamp1497319564539__endTimestamp 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | ------- Summary: start ------- 19:06:04 INFO - PID 1665 | Number of tests: 14 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:140.00 Median:154.00 stddev:28.84 (18.7%) stddev-sans-first:27.85 19:06:04 INFO - PID 1665 | Values: 106.0 113.0 113.0 131.0 148.0 160.0 170.0 109.0 172.0 178.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:140.50 stddev:16.53 (11.8%) stddev-sans-first:17.46 19:06:04 INFO - PID 1665 | Values: 136.0 115.0 149.0 145.0 112.0 130.0 108.0 146.0 153.0 123.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:221.40 Median:217.50 stddev:27.80 (12.8%) stddev-sans-first:28.68 19:06:04 INFO - PID 1665 | Values: 203.0 202.0 210.0 226.0 225.0 250.0 287.0 206.0 201.0 204.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:78.90 Median:69.00 stddev:22.57 (32.7%) stddev-sans-first:23.75 19:06:04 INFO - PID 1665 | Values: 71.0 75.0 88.0 131.0 61.0 60.0 73.0 67.0 102.0 61.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:85.60 Median:71.00 stddev:18.96 (26.7%) stddev-sans-first:19.36 19:06:04 INFO - PID 1665 | Values: 71.0 69.0 110.0 90.0 71.0 77.0 113.0 71.0 72.0 112.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:103.50 Median:90.00 stddev:21.98 (24.4%) stddev-sans-first:20.14 19:06:04 INFO - PID 1665 | Values: 135.0 90.0 90.0 90.0 89.0 90.0 136.0 135.0 91.0 89.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:98.00 stddev:7.78 (7.9%) stddev-sans-first:8.11 19:06:04 INFO - PID 1665 | Values: 96.0 98.0 98.0 122.0 96.0 97.0 98.0 98.0 100.0 98.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:91.00 stddev:24.44 (26.9%) stddev-sans-first:25.89 19:06:04 INFO - PID 1665 | Values: 105.0 95.0 92.0 92.0 89.0 92.0 90.0 144.0 144.0 142.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:56.00 stddev:11.67 (20.8%) stddev-sans-first:11.92 19:06:04 INFO - PID 1665 | Values: 51.0 51.0 51.0 53.0 55.0 75.0 81.0 73.0 57.0 52.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:58.00 stddev:4.72 (8.1%) stddev-sans-first:4.84 19:06:04 INFO - PID 1665 | Values: 56.0 54.0 57.0 65.0 66.0 67.0 58.0 56.0 57.0 58.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:81.00 stddev:11.29 (13.9%) stddev-sans-first:11.90 19:06:04 INFO - PID 1665 | Values: 83.0 90.0 109.0 105.0 79.0 83.0 78.0 80.0 82.0 78.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:149.50 stddev:14.68 (9.8%) stddev-sans-first:15.57 19:06:04 INFO - PID 1665 | Values: 146.0 153.0 128.0 143.0 133.0 153.0 131.0 146.0 161.0 176.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.80 Median:185.50 stddev:19.22 (10.4%) stddev-sans-first:20.33 19:06:04 INFO - PID 1665 | Values: 194.0 186.0 181.0 178.0 185.0 238.0 205.0 175.0 179.0 177.0 19:06:04 INFO - PID 1665 | 19:06:04 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:68.00 stddev:12.81 (18.8%) stddev-sans-first:12.45 19:06:04 INFO - PID 1665 | Values: 57.0 93.0 97.0 67.0 68.0 67.0 67.0 68.0 64.0 68.0 19:06:04 INFO - PID 1665 | -------- Summary: end -------- 19:06:04 INFO - PID 1665 | 19:06:05 INFO - TEST-INFO | 1665: exit 0 19:06:05 INFO - TEST-OK | kraken | took 91141ms 19:06:05 INFO - SUITE-END | took 1234s 19:06:05 INFO - Completed test suite (00:20:35) 19:06:05 INFO - javascript benchmark 19:06:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8150.115710252549, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2731.4946734854047, 3470.4100302948495, 3540.769849959753, 3600.2259514204443, 3607.891763247102, 4902.7303498307665, 6174.567780255378, 6186.56694031418, 6187.257529096515, 6240.0327949167895, 6181.567290289681, 6183.721732522036, 6271.122042913984, 6282.183316168903, 6298.149749783778, 5471.616986810925, 5482.616216864821, 5489.451054894498, 5511.559075273968, 5594.24477695512, 5496.230527726121, 5524.652401881791, 5563.248961390201, 5573.108302671573, 5589.161625756149, 5343.198520221998, 5430.375506816687, 5480.862978425552, 5505.3393592768925, 5529.723513824333, 5416.458354164592, 5434.483724046209, 5435.755391007375, 5436.184572314145, 5488.710153114015, 5357.357117145967, 5423.457654234585, 5501.779928802843, 5509.917351239735, 5522.447755224445, 6066.817995460143, 6276.278228003793, 6282.277538083134, 6293.590916590453, 6301.180846489973, 6740.29226931175, 6764.763233286812, 6765.2219994700745, 6766.864662706767, 6769.323067693241, 6648.135742353463, 6694.765683201064, 6735.090762747015, 6880.415164711006, 6897.689603967783, 6224.315325314212, 6270.028145637433, 6349.6507692077375, 6353.174087368612, 6367.745290188387, 6954.79135625932, 6971.895421568681, 6976.790696279119, 6983.511154219257, 6989.3360130788515, 5686.772529098873, 6363.300036995926, 6464.22429308486, 6487.7080531376205, 6644.368784965421, 6645.803755323991, 6649.401553860176, 6664.600123992576, 6715.395614394728, 6806.727730890709, 6737.52837301394, 6737.6968036438475, 6746.954222095583, 6750.696218670171, 6758.324167583202, 6723.899141512881, 6752.932470675329, 6762.560433571802, 6772.69522871472, 6785.558938668971, 5281.102212623863, 5316.494932981438, 5346.0, 5365.812196573102, 5415.079436495806, 4773.0, 4820.4456487503685, 4906.288588154769, 4912.606991440676, 4935.185694360463, 6244.2819075805955, 6282.591631543935, 6314.873702526015, 6370.872582548416, 6391.328910464427, 6837.62393068386, 6843.486738494533, 6862.4853136015, 6874.621895795779, 6884.896726549106, 4947.851564453073, 6287.931051721218, 6332.986722124438, 6351.2060992375955, 6353.396427339396, 5716.085426331767, 5809.448102430262, 5843.766249350064, 5894.67579283135, 6015.428534289235, 6768.9661551691925, 6913.3777959983845, 6938.5143039986715, 7029.9648501757165, 7035.683394247373, 6812.795616131524, 6886.48351373659, 6891.827704307432, 6898.517103802686, 6910.585364878123, 7005.159380874124, 7027.05134806815, 7029.683664235019, 7043.2604576518725, 7047.612381319077, 6712.93287067123, 6736.831579210559, 6737.461003119641, 6742.561733487308, 6774.966125169342, 6587.209534855848, 6693.062971183941, 6694.196696396463, 6862.828429289108, 6865.897011544931, 6476.838079048061, 6492.415682588401, 6558.8360290993105, 6650.0, 6670.0, 6597.670116494252, 6630.569013014138, 6636.303188165366, 6678.3989440950545, 6680.164979377578, 6313.0, 6374.235091789015, 6462.838429039311, 6540.247871494839, 6548.672566371758, 6462.483001359974, 6466.838329041623, 6484.157059582413, 6550.63971481554, 6557.721244357304, 5414.458554144554, 6460.418562329225, 6463.709133089116, 6463.967680161569, 6469.126667899774], "value": 6146.7547044228795, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.5697432130597, 795.280271354424, 812.2973627811945, 815.710422799906, 851.4380508864147, 36766.26467470652, 37678.811605942, 37733.622663773356, 37762.24475510495, 37780.62219377806, 28809.855950720215, 37920.67277645283, 38173.23653526928, 38725.80637096811, 38968.610313896854, 6404.711787969529, 8280.420370574062, 8324.958375208116, 8335.583220838964, 8351.08138104811, 8132.75601731949, 8232.876506852403, 8298.543580103093, 8301.958490207571, 8303.916960830406, 8274.2139496748, 8305.584720763967, 8334.70828521002, 8349.499030058216, 8351.54066526344, 8276.337892968579, 8297.917020829775, 8316.085230624627, 8321.542315172663, 8331.166883311651, 8133.10535841057, 8168.4282100253, 8169.060558035842, 8193.754187374388, 8230.958845205736, 37048.81475592605, 37234.0, 37292.813535932415, 37309.44035839465, 37325.81337093324, 3494.4583589543663, 3510.262844802569, 3532.682058614737, 3533.169705119295, 3570.321638888603, 30007.0, 30219.79120835164, 30236.546451803242, 30371.848140759375, 30524.931627392936, 2963.7036296370406, 3047.740942019931, 3050.877964881402, 3088.969110308891, 3092.8453577321043, 243768.0, 247842.0, 251668.48331516635, 251856.0, 294501.0, 38144.237115258096, 43027.784861075495, 46333.76833115813, 46545.83635409142, 46599.53400465952, 50961.745191274546, 51002.724931877, 51053.0, 51128.74435627798, 51247.0, 12757.42591583381, 13026.80459793104, 13048.086633935533, 13164.539241126515, 13178.011649126354, 10494.370337779757, 10613.787724245472, 10671.0, 10683.358998460117, 10706.94646526778, 13670.906327493776, 13676.042677012712, 13706.794398084037, 13746.93126534361, 13774.793378099337, 6043.637381757108, 6062.151298818169, 6114.388561143939, 6132.356102609251, 6137.0, 882.2500874256958, 959.52023988006, 975.380633297861, 977.8093271811929, 1044.237706474263, 23103.19138830133, 23170.7682923172, 23271.185508507122, 23361.649575256386, 24294.87852560726, 1048.3028785857318, 1058.402002868377, 1117.8938000889902, 1119.1829964126234, 1135.1146106037138, 9374.625014999323, 11769.823452648217, 12052.33712145823, 12534.24794512314, 12558.686032849251, 331884.0, 334472.0, 335236.64763352054, 335260.0, 336968.0, 54113.72943135259, 54395.0, 54441.45558544443, 54457.72771136198, 54782.0, 1243.595831354795, 1520.7414739494313, 1523.474401331539, 1524.580740296405, 1524.702682976831, 19848.60302793965, 22721.772782272627, 22792.544149117257, 22947.65578516357, 23733.525329492968, 28000.579991300554, 35763.82118089393, 36296.637033629326, 36321.27357452889, 36568.0, 240464.0, 241863.5813641841, 242765.0, 250344.0, 274601.0, 34108.82945585256, 35168.64831351756, 35282.647173527934, 35631.0, 35659.643403565635, 42123.0, 42530.936726582084, 42641.57358426376, 42790.786046069574, 43490.782546087066, 28020.43959120847, 29381.0, 29676.0, 29970.850145749133, 31020.68979310178, 29426.67579958864, 30291.69708302977, 30926.38147237088, 31114.68885311118, 31203.0, 35791.46312805258, 36031.63968360283, 36393.45409818802, 36575.817120914224, 36918.630813692595, 29106.563401549418, 30531.542026870058, 31021.844890775403, 31736.682633174296, 32225.87209447658, 20450.488737781674, 22657.093716251165, 23782.52434951326, 24773.0, 24860.12989545357], "value": 16946.331959551084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.5595766098891, 446.7074066486451, 477.36748807829633, 495.5366732105484, 525.7686617888131, 14338.56984290473, 21683.241086561975, 22230.221942232027, 22493.437664058387, 22579.0, 16889.071101089434, 17137.31450741972, 17218.225179866906, 17326.133693315314, 18674.719879201788, 3726.47829303897, 6370.8088757337355, 6410.455111315544, 6431.0, 6437.935620643781, 6197.659128747917, 6240.531960102988, 6307.463865571432, 6324.652144132071, 6330.968345158268, 6273.404026617464, 6291.150694656231, 6295.653739044351, 6296.37036296369, 6300.9369906301035, 4917.4344950330815, 5493.697846618435, 6285.245770507523, 6309.337519560426, 6310.085037669535, 4754.548317909793, 5628.380878103405, 5646.717664116819, 5660.773569057233, 5687.772489100431, 28612.998545051032, 28853.0, 29377.265568360748, 29452.55821162684, 29507.70492295071, 2213.701150344715, 2230.453538882984, 2230.654248591471, 2250.1674380479326, 2253.312739614417, 17694.292228310853, 18662.720059199124, 19177.61644767111, 19210.423687289403, 19454.0, 2081.4172031831113, 2117.671760877051, 2119.3747844385944, 2119.4489432747596, 2120.978790212109, 181136.0, 181943.0, 187652.0, 187854.0, 214440.0, 14180.622038458325, 14575.565637961825, 14708.970372074065, 14716.411343546353, 14745.894057945698, 19162.80837191638, 19434.319798806973, 19504.414867553995, 19549.413517594494, 19559.31542396038, 9091.0, 9164.633414663338, 9177.816443671223, 9281.535923203814, 9287.44275343468, 6818.249992500821, 7562.2437756223935, 7594.974678418343, 8184.0, 8294.755786632053, 7284.0, 7461.477696561297, 8867.955660221658, 8892.688755893512, 8934.240589549961, 2175.5540114276537, 2575.7939364850768, 2585.870706464669, 2587.676540432446, 2732.767714744269, 926.1479438916078, 943.5140902435252, 944.8299306124826, 985.6550207427343, 1006.5872992073215, 19265.0, 19535.0, 19925.302614408716, 20568.794312056987, 26678.53268070236, 1009.5204777730667, 1037.1754455208127, 1040.287403128859, 1042.781015986636, 1046.5029111172132, 10848.969347911936, 11135.05352045069, 11224.719382015515, 11242.0, 11353.489092990836, 556741.0, 567134.0, 582964.0, 593563.0, 598983.0, 144852.0, 148800.2559987193, 148982.0, 150152.0, 162215.0, 1067.80779459698, 1213.1326101837228, 1241.3669028795198, 1243.502598960423, 1245.4271035323852, 18518.44444666662, 18641.347552835585, 19314.32399865998, 19359.8064019358, 19758.209671612974, 49861.0, 64127.03809442769, 66948.0, 67499.32500675127, 69894.65052674703, 84919.0, 85093.14906850852, 87409.56295218738, 87556.0, 90006.54996725236, 11620.0, 11633.59282425111, 11742.88257117418, 11757.0, 11766.235194712317, 17970.5507362317, 18287.908560457643, 18471.815281847546, 18666.440006799818, 18710.625787484445, 14772.33524491422, 14895.180765058027, 14900.18049007315, 14967.326470308644, 15002.94979351435, 10878.292910960763, 11514.675812281695, 11690.298582085103, 12353.135280530276, 13581.0, 14107.294635268401, 15206.315715793036, 15216.391344346102, 15335.616609584404, 15383.30775115145, 11378.943105284752, 11412.201145919706, 11424.200305978502, 11522.711932201762, 11653.708657283636, 14143.151410914967, 14860.925695371454, 14943.551693449213, 14966.176860272353, 14966.551003469913], "value": 11248.624248886892, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1128.3568366031363, 1402.1236727045596, 1412.975592695296, 1414.186842565525, 1433.4911106557174, 12715.237085774837, 12915.870841291597, 13492.527761528348, 13500.662483437918, 13525.729485410298, 13145.079844410895, 13357.465701371933, 13375.59873203803, 13386.263755493444, 13400.731985360311, 2658.9069382571565, 2715.0090217070824, 2718.972810271892, 2720.6735191776916, 2720.782337413012, 2652.4827658606578, 2691.3675286307703, 2691.528982428067, 2692.3000019994747, 2701.18964310707, 2627.3300308421326, 2655.468906218764, 2686.4224191798758, 2686.9731302687014, 2705.5941608758744, 2666.413389054405, 2669.399385138348, 2681.0, 2682.1283082997907, 2684.704682484925, 2280.6122959096992, 2647.351398907261, 2654.1639383594106, 2663.920082397531, 2707.6073969274544, 13006.739865202642, 13152.802707959292, 13158.407871645702, 13171.407286672124, 13187.868121318856, 7165.713371465135, 7386.778396648109, 7450.701971921117, 7457.776266712007, 7461.477696561188, 3851.595582463829, 4578.702384345041, 4593.379893714339, 4613.261878099522, 4618.0, 1399.930003499821, 1402.263811498971, 1404.4101477379525, 1430.6637940084072, 1435.3253970633784, 15853.92073039639, 16725.414610488573, 17847.46457606274, 18161.636767264714, 19591.31430399929, 5423.593230507728, 5743.023685973395, 6374.936250637435, 6493.22081350241, 6587.110740050128, 13644.65888352799, 13732.244726539937, 13777.655558610915, 13779.724405511834, 13801.378937947817, 7059.047028651169, 7259.709611615582, 7282.781516554512, 7315.341619254293, 7332.0, 6544.214694236722, 6663.566868153554, 6673.833154171185, 6734.494912881553, 6747.831304217336, 4535.818567257273, 4639.814407423665, 4973.850784476472, 5039.647224694236, 5080.237964305384, 1215.2708374974945, 1234.5802427174804, 1430.8569143085608, 1454.7454195515743, 1474.4249742600393, 808.2039191396469, 835.9456635318807, 837.2548431895618, 837.3426859914938, 868.2142660891903, 6865.210500792373, 7007.509474336747, 7142.428605711634, 7210.774168391386, 7262.782116536512, 653.2389765922742, 678.5962352400314, 705.8658854817671, 714.139461948351, 715.7566427414704, 4484.887877803081, 4924.429295420883, 4935.90128197434, 4999.850004499798, 5199.896002079937, 17475.82524174742, 17887.74785764984, 18022.00878951644, 18210.18054187565, 18221.63556728858, 15306.846931530765, 16376.426825061066, 16691.749623755655, 16955.38923802237, 17655.38206162778, 9168.679096231599, 9173.633054677872, 9292.488913109708, 9437.575309111107, 9469.526523673927, 6599.373059559334, 7432.442566807619, 7479.252074792477, 7537.773866784005, 7890.289873911375, 14944.925275373553, 15523.146226957626, 16017.038977661377, 16031.0, 16214.10822404779, 15476.458323958608, 16202.784791140948, 16377.263023164225, 16630.417935372203, 16632.918860274043, 9646.372985755903, 9756.21950243993, 9767.267454940766, 9778.511074446391, 9785.755356116155, 11817.704557386134, 12225.266484010988, 12238.959688426583, 12400.875991240333, 12403.069769767124, 9825.65610203668, 9851.458169800442, 10043.648472303727, 10089.344192627455, 10139.290249682746, 9640.084192001748, 9847.45838978863, 9937.701868943654, 10120.0, 10126.24053196028, 8779.824403512022, 8786.077461866413, 8872.378933474853, 8886.0, 8955.910440895508, 6549.279579246374, 6610.10763546915, 6616.933830661632, 6923.169219693669, 6985.860282794214, 6859.5541289818, 6876.828079298058, 6944.305569443015, 7106.360427561544, 7131.896090653166], "value": 5877.712941311265, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.34438792066254, 445.6212219613328, 449.0816280705956, 462.70849364900107, 468.6789549158826, 5259.342582177228, 6456.0, 6484.416402523778, 6506.577072490297, 6518.445932095771, 4743.861473246422, 6156.969215153918, 6230.875382492359, 6385.0, 6393.4565561927175, 4322.308430651096, 4970.527799859007, 4979.427365852937, 4993.6005119590345, 5041.369828771403, 4934.901301973959, 4936.963237720083, 4952.925706114411, 5022.217112924895, 5044.470330615288, 4364.629006534448, 4444.466664000324, 4580.7402964184785, 4642.9553350496035, 4953.207486802113, 4135.379693046037, 4505.256632655608, 4535.841245556389, 4705.788239529229, 4784.832530861438, 3240.4653232216656, 3289.4572395554706, 3897.9475541603892, 4154.439150714676, 4195.350045460469, 4807.975960120212, 5955.702214889281, 6107.4808641265545, 6169.814905552796, 6197.442230199259, 5590.329160500725, 5612.747426365823, 5617.80337688183, 5642.125470552071, 5688.829335119953, 5740.540756739492, 6732.81183615327, 6770.765647336367, 6802.65986700663, 6818.352256535622, 4351.804168812411, 4397.648188144941, 4399.670024748157, 4794.208955522335, 4794.808207671689, 6392.648404337713, 7141.928580714179, 7183.676734546958, 7219.855602887913, 7238.2761723827725, 4822.565969062801, 4826.903461930741, 4863.784053986504, 4876.804927802919, 4877.219644856806, 4521.400194887887, 4941.382327209099, 4951.455339912607, 4951.653384263067, 4972.353594032753, 4496.820127194876, 5649.593317212581, 5653.152027195872, 5657.264555607827, 5687.232223649837, 5604.327480702342, 6341.397567231105, 6817.227520844517, 6898.517103802686, 6902.689378977958, 1536.377767004365, 1685.890417122883, 1703.6081701208543, 1704.8295170482852, 1709.991450042742, 1130.2653275371074, 1135.233717240859, 1139.361957303896, 1141.7944769941491, 1142.4858813533942, 5117.795288188505, 6281.095769976299, 6286.968565157145, 6340.0, 6342.39747224013, 6037.939620603738, 6066.0, 6869.725210991604, 7045.401140903081, 7075.469339799535, 5254.632175747661, 5635.661860288314, 5645.5483561314995, 5743.629706224693, 6120.14317995481, 3150.558921750957, 3532.717382609385, 3639.70882329413, 3652.945205821915, 3698.334299826003, 5753.367129615739, 6059.4546490815155, 6438.678066096676, 7008.404285635676, 7221.8555628887125, 6082.726277317532, 6639.43564796988, 7283.2352602975925, 7291.37510496221, 7302.48882578225, 6447.903856344433, 7175.31834475724, 7178.030965819652, 7217.67520461591, 7282.198958114497, 6972.965135174291, 6994.160700715946, 7076.32774886385, 7140.0, 7176.21061683204, 5429.049916264559, 6422.807315780534, 6823.965880170567, 6835.350641689031, 6915.446764258948, 6510.576812507172, 6785.491088168308, 7209.026781384656, 7211.2428195038765, 7253.347198752138, 4236.851710190129, 4819.2530157825595, 4826.3243145958895, 4826.6621336506105, 4850.369451971221, 6639.800805975829, 6668.166479190101, 7057.2237053923, 7059.752908648173, 7114.537555058904, 5732.541396688338, 5885.410939046519, 6001.909971350346, 6430.813523402066, 6708.094407254958, 5708.429157084258, 6339.225016995249, 6502.414782669583, 6845.589264644137, 6895.448364130757, 5586.692731899784, 5617.634853734494, 5907.261592300963, 5992.254840724547, 6375.203099612549, 5657.943420565742, 5778.959787238337, 6586.275509693834, 6802.741492823843, 6835.829104272433, 5490.752916118864, 6580.934190658032, 6587.209534855848, 6614.239362473377, 6633.2040155181685], "value": 4931.736110830394, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.7441321124397, 766.6894907562437, 825.273759091999, 854.7991222062814, 855.8758979947908, 16801.747973780402, 17303.87524810888, 17305.653886922253, 17316.480505584823, 17373.391931282407, 17959.73060404095, 18789.906050469763, 18871.905640471814, 18992.905035474803, 19004.714929276037, 962.1100482054089, 976.2873102635081, 981.3228872078244, 986.1371300112402, 986.758244230161, 970.8592254123148, 975.6146322202726, 978.6427953796884, 979.3193730357405, 988.8615593816871, 859.669027424443, 1023.5905637744887, 1026.0201507560262, 1028.4292217819145, 1031.046282188976, 972.4700038479045, 994.4033579852068, 1021.1371391174446, 1023.6059117239841, 1030.7114008077751, 749.4454103963083, 750.5684231566871, 751.6692655231735, 751.8984937033485, 759.6391713935891, 17579.56051098733, 17643.382481613084, 17653.7351939721, 17686.469405917844, 17705.20326585294, 17491.0, 17734.55663608407, 17943.371981980566, 18040.90979545107, 18082.276708931626, 14486.203258820866, 14883.10701357922, 15015.774763378558, 15046.699066018618, 15276.465323713617, 3678.8712395066045, 3680.429533422324, 3702.4446333049987, 3712.0, 3712.535933008374, 91216.54391728, 140101.0, 142992.0, 151574.0, 155136.44863551334, 11367.545298188144, 11453.0, 11631.953124218691, 14226.502072427415, 14244.715105697827, 26164.822582983812, 26368.73631263701, 26567.33581660474, 26601.0, 26633.467330653664, 6936.445084393236, 6968.547044442095, 6991.056207412036, 7009.9649501752165, 7014.088168538057, 3897.590752970954, 3919.0, 4011.4183443400543, 4084.1423301106506, 4091.365838295069, 4571.634269258451, 4651.09303685778, 4683.157031734252, 4692.1554120258, 4719.905601887943, 983.9163671088038, 1005.5927349423495, 1016.6390931219458, 1026.6714651311659, 1027.8612387327764, 652.2368828470701, 659.9010148477767, 662.1458318768831, 680.119245576979, 683.62400679627, 9607.423554586607, 11154.888451115547, 11561.479733412016, 12086.214396064228, 12248.571300004458, 608.3217212807688, 619.8791235709084, 654.6399480285824, 670.7618795327686, 679.4937771360368, 5498.945010549924, 5569.665820050729, 5659.54943005597, 5899.4985426239255, 6024.909626355608, 119388.0, 120283.0, 125825.0, 127264.0, 129713.05430418551, 109356.0, 109840.0, 111758.76482470467, 116627.8337216617, 116686.0, 75134.0, 93348.06651933529, 97046.99999999859, 99028.51457228148, 100588.98822023519, 11754.177207595387, 12021.939890300493, 12176.75646487083, 12182.634520964386, 12313.859965708687, 42469.362959555016, 43611.0, 44850.0, 44971.0, 45664.771676141405, 57717.71141144267, 60440.697796510736, 61286.38713612807, 81400.18599813926, 88550.0, 15035.398584056953, 16021.198940053184, 16349.34602615882, 16609.584760380603, 16971.91514042422, 14242.643933901736, 18241.908790455964, 18561.35035273759, 18808.811911881254, 18897.43307700771, 16149.0, 16191.352345906504, 16486.587835304214, 16686.66626667484, 16687.249073791467, 16541.917290413472, 16617.833821662112, 16726.247318870435, 16855.325786968384, 16949.32202711878, 11381.719703361216, 16347.427840025543, 16630.916845415697, 16815.915920420808, 17065.91467042657, 11711.355875426932, 11747.0, 11875.228110172811, 12102.394880255775, 12103.81844272319, 11592.652220433063, 12552.497900083894, 12584.433700483318, 12644.810327845275, 12730.872691272969], "value": 8628.315133529486, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1584.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 113.0, 131.0, 148.0, 160.0, 170.0, 109.0, 172.0, 178.0], "value": 140.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 115.0, 149.0, 145.0, 112.0, 130.0, 108.0, 146.0, 153.0, 123.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 202.0, 210.0, 226.0, 225.0, 250.0, 287.0, 206.0, 201.0, 204.0], "value": 221.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 88.0, 131.0, 61.0, 60.0, 73.0, 67.0, 102.0, 61.0], "value": 78.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 110.0, 90.0, 71.0, 77.0, 113.0, 71.0, 72.0, 112.0], "value": 85.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 90.0, 90.0, 90.0, 89.0, 90.0, 136.0, 135.0, 91.0, 89.0], "value": 103.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 98.0, 122.0, 96.0, 97.0, 98.0, 98.0, 100.0, 98.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 95.0, 92.0, 92.0, 89.0, 92.0, 90.0, 144.0, 144.0, 142.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 53.0, 55.0, 75.0, 81.0, 73.0, 57.0, 52.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 57.0, 65.0, 66.0, 67.0, 58.0, 56.0, 57.0, 58.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 90.0, 109.0, 105.0, 79.0, 83.0, 78.0, 80.0, 82.0, 78.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 153.0, 128.0, 143.0, 133.0, 153.0, 131.0, 146.0, 161.0, 176.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 186.0, 181.0, 178.0, 185.0, 238.0, 205.0, 175.0, 179.0, 177.0], "value": 189.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 93.0, 97.0, 67.0, 68.0, 67.0, 67.0, 68.0, 64.0, 68.0], "value": 71.6, "unit": "score"}]}]} 19:06:05 INFO - Return code: 0 19:06:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:06:05 INFO - # TBPL SUCCESS # 19:06:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:06:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:06:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:06:05 INFO - 2017-06-12 19:06:05.930 screenresolution[1673:11438] starting screenresolution argv=screenresolution get 19:06:05 INFO - 2017-06-12 19:06:05.932 screenresolution[1673:11438] Display 0: 1600x1200x32@60 19:06:05 INFO - 2017-06-12 19:06:05.938 screenresolution[1674:11441] starting screenresolution argv=screenresolution list 19:06:05 INFO - Available Modes on Display 0 19:06:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:06:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:06:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:06:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:06:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:06:06 INFO - Intel Iris: 19:06:06 INFO - Chipset Model: Intel Iris 19:06:06 INFO - Type: GPU 19:06:06 INFO - Bus: Built-In 19:06:06 INFO - VRAM (Dynamic, Max): 1536 MB 19:06:06 INFO - Vendor: Intel (0x8086) 19:06:06 INFO - Device ID: 0x0a2e 19:06:06 INFO - Revision ID: 0x0009 19:06:06 INFO - Displays: 19:06:06 INFO - Display: 19:06:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:06:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:06:06 INFO - Display Serial Number: 1600x1200 60 19:06:06 INFO - Main Display: Yes 19:06:06 INFO - Mirror: Off 19:06:06 INFO - Online: Yes 19:06:06 INFO - Rotation: Supported 19:06:06 INFO - Return code: 0 19:06:06 INFO - Running post-action listener: _resource_record_post_action 19:06:06 INFO - [mozharness: 2017-06-13 02:06:06.175068Z] Finished run-tests step (success) 19:06:06 INFO - Running post-run listener: _resource_record_post_run 19:06:06 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 44524544; Write bytes: 577037312; Read time: 486; Write time: 1812 19:06:06 INFO - TinderboxPrint: CPU usage
53.2% 19:06:06 INFO - TinderboxPrint: I/O read bytes / time
44,524,544 / 486 19:06:06 INFO - TinderboxPrint: I/O write bytes / time
577,037,312 / 1,812 19:06:06 INFO - TinderboxPrint: CPU idle
2,353.6 (46.8%) 19:06:06 INFO - TinderboxPrint: CPU system
1,017.6 (20.2%) 19:06:06 INFO - TinderboxPrint: CPU user
1,662.6 (33.0%) 19:06:06 INFO - TinderboxPrint: Swap in / out
283,770,880 / 0 19:06:06 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 246084096; Write bytes: 236701696; Read time: 17470; Write time: 475 19:06:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:06:06 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 34071552; Write bytes: 321022976; Read time: 311; Write time: 1296 19:06:06 INFO - Running post-run listener: _upload_blobber_files 19:06:06 INFO - Blob upload gear active. 19:06:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:06:06 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 19:06:06 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'] 19:06:06 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 19:06:07 INFO - (blobuploader) - INFO - Open directory for files ... 19:06:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:06:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:06:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:06:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:06:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:06:07 INFO - (blobuploader) - INFO - Done attempting. 19:06:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:06:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:06:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:06:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:06:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:06:08 INFO - (blobuploader) - INFO - Done attempting. 19:06:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:06:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:06:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:06:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:06:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:06:09 INFO - (blobuploader) - INFO - Done attempting. 19:06:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:06:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:06:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:06:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:06:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:06:11 INFO - (blobuploader) - INFO - Done attempting. 19:06:11 INFO - (blobuploader) - INFO - Iteration through files over. 19:06:11 INFO - Return code: 0 19:06:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:06:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:06:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d140b3e9c159de5919ca85b220ba22e62e159036cd29b66db72cc908ff7d3fab6fff65465b7a30477a71e2b1cf0b44e45aca448c2a67816659afd786df637286", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76736ae50423c9ac3eeffea8b28ce5a9b310d4f5391ab843bd43256d5fefa94e57d5191464072aefd13f0303ea384e63fa39f5db0751424f11bf5b8e7cff77c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ef441fd984125b7fcd963c95730224bb0eba3ff72a5453ddbb367f1861911fc06589cbc9b6c63106c510ac4bb672100e5223a8b99ca1107f28ae5b0c10a1e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a54fe02ce2eb730a797c6ed052987394e341f5d84d02cda894f7227b8ba3e7769caa8880ddce4ea0eb3d1715a9ac8f409155c96f9ba1712ee883463063ae848"} 19:06:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:06:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:06:11 INFO - Contents: 19:06:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d140b3e9c159de5919ca85b220ba22e62e159036cd29b66db72cc908ff7d3fab6fff65465b7a30477a71e2b1cf0b44e45aca448c2a67816659afd786df637286", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76736ae50423c9ac3eeffea8b28ce5a9b310d4f5391ab843bd43256d5fefa94e57d5191464072aefd13f0303ea384e63fa39f5db0751424f11bf5b8e7cff77c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ef441fd984125b7fcd963c95730224bb0eba3ff72a5453ddbb367f1861911fc06589cbc9b6c63106c510ac4bb672100e5223a8b99ca1107f28ae5b0c10a1e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a54fe02ce2eb730a797c6ed052987394e341f5d84d02cda894f7227b8ba3e7769caa8880ddce4ea0eb3d1715a9ac8f409155c96f9ba1712ee883463063ae848"} 19:06:11 INFO - Running post-run listener: copy_logs_to_upload_dir 19:06:11 INFO - Copying logs to upload dir... 19:06:11 INFO - mkdir: /builds/slave/test/build/upload/logs 19:06:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1352.844314 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 32 secs) (at 2017-06-12 19:06:11.970213) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 19:06:11.976294) ========= 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.y8isfbyl5V/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.eAhW13aaz2/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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/d140b3e9c159de5919ca85b220ba22e62e159036cd29b66db72cc908ff7d3fab6fff65465b7a30477a71e2b1cf0b44e45aca448c2a67816659afd786df637286", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76736ae50423c9ac3eeffea8b28ce5a9b310d4f5391ab843bd43256d5fefa94e57d5191464072aefd13f0303ea384e63fa39f5db0751424f11bf5b8e7cff77c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ef441fd984125b7fcd963c95730224bb0eba3ff72a5453ddbb367f1861911fc06589cbc9b6c63106c510ac4bb672100e5223a8b99ca1107f28ae5b0c10a1e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a54fe02ce2eb730a797c6ed052987394e341f5d84d02cda894f7227b8ba3e7769caa8880ddce4ea0eb3d1715a9ac8f409155c96f9ba1712ee883463063ae848"} build_url:https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011445 build_url: 'https://queue.taskcluster.net/v1/task/dL6RO1qjQ1WBPJeXt95HYQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d140b3e9c159de5919ca85b220ba22e62e159036cd29b66db72cc908ff7d3fab6fff65465b7a30477a71e2b1cf0b44e45aca448c2a67816659afd786df637286", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76736ae50423c9ac3eeffea8b28ce5a9b310d4f5391ab843bd43256d5fefa94e57d5191464072aefd13f0303ea384e63fa39f5db0751424f11bf5b8e7cff77c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ef441fd984125b7fcd963c95730224bb0eba3ff72a5453ddbb367f1861911fc06589cbc9b6c63106c510ac4bb672100e5223a8b99ca1107f28ae5b0c10a1e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a54fe02ce2eb730a797c6ed052987394e341f5d84d02cda894f7227b8ba3e7769caa8880ddce4ea0eb3d1715a9ac8f409155c96f9ba1712ee883463063ae848"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 19:06:12.011481) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:06:12.011947) ========= 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.y8isfbyl5V/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.eAhW13aaz2/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.005070 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:06:12.061898) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 19:06:12.062219) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 19:06:12.062667) ========= ========= Total master_lag: 0.19 =========