builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0360 starttime: 1496368498.87 results: success (0) revision: fec3a4b50acd88df3b4af2dfe645a919713852a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.874498) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.874942) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.875218) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.942963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.943240) ========= 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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 18:54:58-- 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% 29.6M=0s 2017-06-01 18:54:59 (29.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.573934 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:59.540149) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:59.540466) ========= 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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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.027477 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:59.589709) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:59.590039) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fec3a4b50acd88df3b4af2dfe645a919713852a9 --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 fec3a4b50acd88df3b4af2dfe645a919713852a9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 18:54:59,685 truncating revision to first 12 chars 2017-06-01 18:54:59,685 Setting DEBUG logging. 2017-06-01 18:54:59,686 attempt 1/10 2017-06-01 18:54:59,686 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fec3a4b50acd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 18:55:00,332 unpacking tar archive at: mozilla-central-fec3a4b50acd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.926768 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:00.540072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:00.540386) ========= script_repo_revision: fec3a4b50acd88df3b4af2dfe645a919713852a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:00.540775) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:00.541040) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:00.559888) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2017-06-01 18:55:00.560301) ========= /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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:55:00 INFO - MultiFileLogger online at 20170601 18:55:00 in /builds/slave/test 18:55:00 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:55:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:55:00 INFO - {'append_to_log': False, 18:55:00 INFO - 'base_work_dir': '/builds/slave/test', 18:55:00 INFO - 'blob_upload_branch': 'Firefox', 18:55:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:55:00 INFO - 'branch': 'Firefox', 18:55:00 INFO - 'buildbot_json_path': 'buildprops.json', 18:55:00 INFO - 'config_files': ('talos/mac_config.py',), 18:55:00 INFO - 'default_actions': ('clobber', 18:55:00 INFO - 'read-buildbot-config', 18:55:00 INFO - 'download-and-extract', 18:55:00 INFO - 'populate-webroot', 18:55:00 INFO - 'create-virtualenv', 18:55:00 INFO - 'install', 18:55:00 INFO - 'setup-mitmproxy', 18:55:00 INFO - 'run-tests'), 18:55:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:55:00 INFO - 'download_minidump_stackwalk': True, 18:55:00 INFO - 'download_symbols': 'ondemand', 18:55:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:55:00 INFO - 'tooltool.py': '/tools/tooltool.py', 18:55:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:55:00 INFO - '/tools/misc-python/virtualenv.py')}, 18:55:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:55:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:55:00 INFO - 'gecko_profile': False, 18:55:00 INFO - 'gecko_profile_interval': 0, 18:55:00 INFO - 'installer_path': 'installer.exe', 18:55:00 INFO - 'log_level': 'info', 18:55:00 INFO - 'log_name': 'talos', 18:55:00 INFO - 'log_to_console': True, 18:55:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:55:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:55:00 INFO - 'opt_config_files': (), 18:55:00 INFO - 'pip_index': False, 18:55:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:00 INFO - 'cmd': ('bash', 18:55:00 INFO - '-c', 18:55:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:00 INFO - 'enabled': True, 18:55:00 INFO - 'halt_on_failure': False, 18:55:00 INFO - 'name': 'check_screen_resolution'},), 18:55:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:00 INFO - 'cmd': ('bash', 18:55:00 INFO - '-c', 18:55:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:00 INFO - 'enabled': True, 18:55:00 INFO - 'halt_on_failure': False, 18:55:00 INFO - 'name': 'check_screen_resolution'},), 18:55:00 INFO - 'run_cmd_checks_enabled': True, 18:55:00 INFO - 'suite': 'dromaeojs-e10s', 18:55:00 INFO - 'system_bits': '32', 18:55:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:55:00 INFO - 'title': 't-yosemite-r7-0360', 18:55:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:55:00 INFO - 'use_talos_json': True, 18:55:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:55:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:55:00 INFO - 'work_dir': 'build'} 18:55:00 INFO - [mozharness: 2017-06-02 01:55:00.765972Z] Running clobber step. 18:55:00 INFO - Running pre-action listener: _resource_record_pre_action 18:55:00 INFO - Running main action method: clobber 18:55:00 INFO - rmtree: /builds/slave/test/build 18:55:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:55:03 INFO - Running post-action listener: _resource_record_post_action 18:55:03 INFO - [mozharness: 2017-06-02 01:55:03.234428Z] Finished clobber step (success) 18:55:03 INFO - [mozharness: 2017-06-02 01:55:03.234558Z] Running read-buildbot-config step. 18:55:03 INFO - Running pre-action listener: _resource_record_pre_action 18:55:03 INFO - Running main action method: read_buildbot_config 18:55:03 INFO - Using buildbot properties: 18:55:03 INFO - { 18:55:03 INFO - "project": "", 18:55:03 INFO - "product": "firefox", 18:55:03 INFO - "who": "kwierso@gmail.com", 18:55:03 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:55:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:55:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:55:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:55:03 INFO - "stage_platform": "macosx64", 18:55:03 INFO - "basedir": "/builds/slave/test", 18:55:03 INFO - "buildnumber": 174, 18:55:03 INFO - "platform": "yosemite_r7", 18:55:03 INFO - "parent_task_id": "c8gvq0tTTpO-1Y1va4HvZg", 18:55:03 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:55:03 INFO - "slavebuilddir": "test", 18:55:03 INFO - "taskId": "NCKkvoEtQKOIKue3__jhYw", 18:55:03 INFO - "branch": "mozilla-central", 18:55:03 INFO - "script_repo_revision": "production", 18:55:03 INFO - "revision": "fec3a4b50acd88df3b4af2dfe645a919713852a9", 18:55:03 INFO - "slavename": "t-yosemite-r7-0360", 18:55:03 INFO - "repo_path": "mozilla-central" 18:55:03 INFO - } 18:55:03 INFO - Finding installer, test and symbols from parent task. 18:55:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NCKkvoEtQKOIKue3__jhYw'}, attempt #1 18:55:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NCKkvoEtQKOIKue3__jhYw'}, attempt #1 18:55:04 INFO - Task dependencies: D5GAXFJCScqjCKt3DqQwHg c8gvq0tTTpO-1Y1va4HvZg 18:55:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg'}, attempt #1 18:55:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:04 INFO - Set test_url: https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/test_packages.json 18:55:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/target.crashreporter-symbols.zip 18:55:04 INFO - Running post-action listener: _resource_record_post_action 18:55:04 INFO - [mozharness: 2017-06-02 01:55:04.931616Z] Finished read-buildbot-config step (success) 18:55:04 INFO - [mozharness: 2017-06-02 01:55:04.931735Z] Running download-and-extract step. 18:55:04 INFO - Running pre-action listener: _resource_record_pre_action 18:55:04 INFO - Running main action method: download_and_extract 18:55:04 INFO - mkdir: /builds/slave/test/build/tests 18:55: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:55:04 INFO - trying https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:55:04 INFO - Downloading https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:55:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:55:05 INFO - Downloaded 1325 bytes. 18:55:05 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:55:05 INFO - Using the following test package requirements: 18:55:05 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:55:05 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:55:05 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:55:05 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:55:05 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'jsshell-mac.zip'], 18:55:05 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:55:05 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:55:05 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:55:05 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:55:05 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:55:05 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:05 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:55:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:55:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:55:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:05 INFO - Fetch https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:55:08 INFO - Content-Length response header: 57625485 18:55:08 INFO - Bytes received: 57625485 18:55:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:55:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:55:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:26 INFO - Fetch https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:55:29 INFO - Content-Length response header: 57625485 18:55:29 INFO - Bytes received: 57625485 18:55:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:55:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:55:46 INFO - Fetch https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:55:47 INFO - Content-Length response header: 14447425 18:55:47 INFO - Bytes received: 14447425 18:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:48 INFO - trying https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:48 INFO - Downloading https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:55:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:55:49 INFO - Downloaded 67922091 bytes. 18:55:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:49 INFO - mkdir: /builds/slave/test/properties 18:55:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:55:49 INFO - Writing to file /builds/slave/test/properties/build_url 18:55:49 INFO - Contents: 18:55:49 INFO - build_url:https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:49 INFO - Running post-action listener: _resource_record_post_action 18:55:49 INFO - Running post-action listener: set_extra_try_arguments 18:55:49 INFO - [mozharness: 2017-06-02 01:55:49.929334Z] Finished download-and-extract step (success) 18:55:49 INFO - [mozharness: 2017-06-02 01:55:49.929480Z] Running populate-webroot step. 18:55:49 INFO - Running pre-action listener: _resource_record_pre_action 18:55:49 INFO - Running main action method: populate_webroot 18:55:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:55:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:55:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:55:49 INFO - '--tppagecycles', 18:55:49 INFO - '7'], 18:55:49 INFO - 'tests': ['tsvgm']}}, 18:55:49 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:55:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:55:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:55:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:55:49 INFO - 'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:55:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:55:49 INFO - 'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['damp', 'tps']}, 18:55:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:55:49 INFO - 'tests': ['damp', 'tps']}, 18:55:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['dromaeo_dom']}, 18:55:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:55:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:55:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:55:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:55:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:55:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other-e10s': {'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:55:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:55:49 INFO - 'bloom_basic_ref']}, 18:55:49 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:55:49 INFO - 'talos_options': ['--mitmproxy', 18:55:49 INFO - 'mitmproxy-recording-1.mp', 18:55:49 INFO - '--firstNonBlankPaint'], 18:55:49 INFO - 'tests': ['Quantum_1']}, 18:55:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['tsvgx', 18:55:49 INFO - 'tsvgr_opacity', 18:55:49 INFO - 'tart', 18:55:49 INFO - 'tscrollx', 18:55:49 INFO - 'cart', 18:55:49 INFO - 'tsvg_static']}, 18:55:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:55:49 INFO - 'tsvgr_opacity', 18:55:49 INFO - 'tart', 18:55:49 INFO - 'tscrollx', 18:55:49 INFO - 'cart', 18:55:49 INFO - 'tsvg_static']}, 18:55:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:55:49 INFO - 'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['tp5o']}, 18:55:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:55:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:55:49 INFO - 'talos_options': ['--disable-e10s', 18:55:49 INFO - '--xperf_path', 18:55:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:49 INFO - 'tests': ['tp5n']}, 18:55:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:55:49 INFO - 'talos_options': ['--xperf_path', 18:55:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:49 INFO - 'tests': ['tp5n']}}, 18:55:49 INFO - 'talos.zip': {'path': '', 18:55:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:55:49 INFO - Running post-action listener: _resource_record_post_action 18:55:49 INFO - [mozharness: 2017-06-02 01:55:49.951131Z] Finished populate-webroot step (success) 18:55:49 INFO - [mozharness: 2017-06-02 01:55:49.951249Z] Running create-virtualenv step. 18:55:49 INFO - Running pre-action listener: _resource_record_pre_action 18:55:49 INFO - Running main action method: create_virtualenv 18:55:49 INFO - Creating virtualenv /builds/slave/test/build/venv 18:55:49 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:55:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:55:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:55:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:55:50 INFO - Using real prefix '/tools/python27' 18:55:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:55:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:55:53 INFO - Installing pip.................done. 18:55:53 INFO - Return code: 0 18:55:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:55:53 INFO - Reading from file tmpfile_stdout 18:55:53 INFO - Output received: 18:55:53 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:55:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:53 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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:55:53 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:55:53 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:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:53 INFO - 'HOME': '/Users/cltbld', 18:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:53 INFO - 'LOGNAME': 'cltbld', 18:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:53 INFO - 'MOZ_NO_REMOTE': '1', 18:55:53 INFO - 'NO_EM_RESTART': '1', 18:55:53 INFO - 'PAGER': '/bin/cat', 18:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:53 INFO - 'PWD': '/builds/slave/test', 18:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:53 INFO - 'SHELL': '/bin/bash', 18:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:55:53 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:53 INFO - 'USER': 'cltbld', 18:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:53 INFO - 'XPC_FLAGS': '0x0', 18:55:53 INFO - 'XPC_SERVICE_NAME': '0', 18:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:54 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:55:54 INFO - Cleaning up... 18:55:54 INFO - Return code: 0 18:55:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:55:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:55:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:55:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:55:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:54 INFO - 'HOME': '/Users/cltbld', 18:55:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:54 INFO - 'LOGNAME': 'cltbld', 18:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:54 INFO - 'MOZ_NO_REMOTE': '1', 18:55:54 INFO - 'NO_EM_RESTART': '1', 18:55:54 INFO - 'PAGER': '/bin/cat', 18:55:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:54 INFO - 'PWD': '/builds/slave/test', 18:55:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:54 INFO - 'SHELL': '/bin/bash', 18:55:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:55:54 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:55:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:54 INFO - 'USER': 'cltbld', 18:55:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:54 INFO - 'XPC_FLAGS': '0x0', 18:55:54 INFO - 'XPC_SERVICE_NAME': '0', 18:55:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:54 INFO - Downloading/unpacking psutil>=3.1.1 18:55: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:55: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:55: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:55: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:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:55:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:56 INFO - Installing collected packages: psutil 18:55:56 INFO - Running setup.py install for psutil 18:55:56 INFO - building 'psutil._psutil_osx' extension 18:55:56 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:55:57 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:55:57 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:55:57 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:55:57 INFO - building 'psutil._psutil_posix' extension 18:55:57 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:55:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:55:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:55:57 INFO - ^ 18:55:57 INFO - 1 warning generated. 18:55:57 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:55:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:57 INFO - Successfully installed psutil 18:55:57 INFO - Cleaning up... 18:55:57 INFO - Return code: 0 18:55:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:55:57 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:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:57 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:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:57 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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:55:57 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:55:57 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:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:57 INFO - 'HOME': '/Users/cltbld', 18:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:57 INFO - 'LOGNAME': 'cltbld', 18:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:57 INFO - 'MOZ_NO_REMOTE': '1', 18:55:57 INFO - 'NO_EM_RESTART': '1', 18:55:57 INFO - 'PAGER': '/bin/cat', 18:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:57 INFO - 'PWD': '/builds/slave/test', 18:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:57 INFO - 'SHELL': '/bin/bash', 18:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:55:57 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:57 INFO - 'USER': 'cltbld', 18:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:57 INFO - 'XPC_FLAGS': '0x0', 18:55:57 INFO - 'XPC_SERVICE_NAME': '0', 18:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:55:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:55:59 INFO - Installing collected packages: mozsystemmonitor 18:55:59 INFO - Running setup.py install for mozsystemmonitor 18:55:59 INFO - Successfully installed mozsystemmonitor 18:55:59 INFO - Cleaning up... 18:55:59 INFO - Return code: 0 18:55:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:55:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:55:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:55:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:55:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:59 INFO - 'HOME': '/Users/cltbld', 18:55:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:59 INFO - 'LOGNAME': 'cltbld', 18:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:59 INFO - 'MOZ_NO_REMOTE': '1', 18:55:59 INFO - 'NO_EM_RESTART': '1', 18:55:59 INFO - 'PAGER': '/bin/cat', 18:55:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:59 INFO - 'PWD': '/builds/slave/test', 18:55:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:59 INFO - 'SHELL': '/bin/bash', 18:55:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:55:59 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:55:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:59 INFO - 'USER': 'cltbld', 18:55:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:59 INFO - 'XPC_FLAGS': '0x0', 18:55:59 INFO - 'XPC_SERVICE_NAME': '0', 18:55:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:00 INFO - Downloading/unpacking jsonschema==2.5.1 18:56:00 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:56:00 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:56:00 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:56:00 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:56:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:56:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:01 INFO - Downloading functools32-3.2.3-2.tar.gz 18:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:56:01 INFO - warning: no files found matching '*.txt' 18:56:01 INFO - no previously-included directories found matching 'build' 18:56:01 INFO - no previously-included directories found matching 'dist' 18:56:01 INFO - no previously-included directories found matching '.git*' 18:56:01 INFO - Installing collected packages: jsonschema, functools32 18:56:01 INFO - Running setup.py install for functools32 18:56:02 INFO - warning: no files found matching '*.txt' 18:56:02 INFO - no previously-included directories found matching 'build' 18:56:02 INFO - no previously-included directories found matching 'dist' 18:56:02 INFO - no previously-included directories found matching '.git*' 18:56:02 INFO - Successfully installed jsonschema functools32 18:56:02 INFO - Cleaning up... 18:56:02 INFO - Return code: 0 18:56:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:02 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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:56:02 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:56:02 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:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:02 INFO - 'HOME': '/Users/cltbld', 18:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:02 INFO - 'LOGNAME': 'cltbld', 18:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:02 INFO - 'MOZ_NO_REMOTE': '1', 18:56:02 INFO - 'NO_EM_RESTART': '1', 18:56:02 INFO - 'PAGER': '/bin/cat', 18:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:02 INFO - 'PWD': '/builds/slave/test', 18:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:02 INFO - 'SHELL': '/bin/bash', 18:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:56:02 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:02 INFO - 'USER': 'cltbld', 18:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:02 INFO - 'XPC_FLAGS': '0x0', 18:56:02 INFO - 'XPC_SERVICE_NAME': '0', 18:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:56:02 INFO - Cleaning up... 18:56:02 INFO - Return code: 0 18:56:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:02 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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:56:02 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:56:02 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:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:02 INFO - 'HOME': '/Users/cltbld', 18:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:02 INFO - 'LOGNAME': 'cltbld', 18:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:02 INFO - 'MOZ_NO_REMOTE': '1', 18:56:02 INFO - 'NO_EM_RESTART': '1', 18:56:02 INFO - 'PAGER': '/bin/cat', 18:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:02 INFO - 'PWD': '/builds/slave/test', 18:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:02 INFO - 'SHELL': '/bin/bash', 18:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:56:02 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:02 INFO - 'USER': 'cltbld', 18:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:02 INFO - 'XPC_FLAGS': '0x0', 18:56:02 INFO - 'XPC_SERVICE_NAME': '0', 18:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:02 INFO - Downloading/unpacking blobuploader==1.2.4 18:56: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:56: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:56: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:56: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:56:04 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:56:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56:04 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:56:04 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:56:04 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:56:04 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:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:56:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:04 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:56:04 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:56:04 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:56:04 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:56:05 INFO - Downloading docopt-0.6.1.tar.gz 18:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:56:05 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:05 INFO - Running setup.py install for blobuploader 18:56:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:56:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:56:05 INFO - Running setup.py install for requests 18:56:05 INFO - Running setup.py install for docopt 18:56:05 INFO - Successfully installed blobuploader requests docopt 18:56:05 INFO - Cleaning up... 18:56:05 INFO - Return code: 0 18:56:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:05 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:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:05 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:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:05 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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:56:05 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:56:05 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:56:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:56:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:05 INFO - 'HOME': '/Users/cltbld', 18:56:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:05 INFO - 'LOGNAME': 'cltbld', 18:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:05 INFO - 'MOZ_NO_REMOTE': '1', 18:56:05 INFO - 'NO_EM_RESTART': '1', 18:56:05 INFO - 'PAGER': '/bin/cat', 18:56:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:05 INFO - 'PWD': '/builds/slave/test', 18:56:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:05 INFO - 'SHELL': '/bin/bash', 18:56:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:56:05 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:56:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:05 INFO - 'USER': 'cltbld', 18:56:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:05 INFO - 'XPC_FLAGS': '0x0', 18:56:05 INFO - 'XPC_SERVICE_NAME': '0', 18:56:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:06 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-hXIp4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:06 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-4BYhg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:06 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-8ClwKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:06 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-JErC_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:06 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-ErJjVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:06 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-rufoiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:06 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-vJYgOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:06 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-L0jwSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-Uw3DZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-rjw4qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-yycwZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-S3NAV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-b9Pa2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-39ql9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-r31LhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-4ugOs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:07 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-o4PRi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:07 INFO - Running setup.py install for manifestparser 18:56:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:56:08 INFO - Running setup.py install for mozcrash 18:56:08 INFO - Running setup.py install for mozdebug 18:56:08 INFO - Running setup.py install for mozdevice 18:56:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:56:08 INFO - Running setup.py install for mozfile 18:56:08 INFO - Running setup.py install for mozhttpd 18:56:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:56:08 INFO - Running setup.py install for mozinfo 18:56:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Running setup.py install for mozInstall 18:56:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Running setup.py install for mozleak 18:56:09 INFO - Running setup.py install for mozlog 18:56:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Running setup.py install for moznetwork 18:56:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Running setup.py install for mozprocess 18:56:09 INFO - Running setup.py install for mozprofile 18:56:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:56:09 INFO - Running setup.py install for mozrunner 18:56:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:56:10 INFO - Running setup.py install for mozscreenshot 18:56:10 INFO - Running setup.py install for moztest 18:56:10 INFO - Running setup.py install for mozversion 18:56:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:56:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:10 INFO - Cleaning up... 18:56:10 INFO - Return code: 0 18:56:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:10 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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:56:10 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:56:10 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:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:10 INFO - 'HOME': '/Users/cltbld', 18:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:10 INFO - 'LOGNAME': 'cltbld', 18:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:10 INFO - 'MOZ_NO_REMOTE': '1', 18:56:10 INFO - 'NO_EM_RESTART': '1', 18:56:10 INFO - 'PAGER': '/bin/cat', 18:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:10 INFO - 'PWD': '/builds/slave/test', 18:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:10 INFO - 'SHELL': '/bin/bash', 18:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:56:10 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:10 INFO - 'USER': 'cltbld', 18:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:10 INFO - 'XPC_FLAGS': '0x0', 18:56:10 INFO - 'XPC_SERVICE_NAME': '0', 18:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:10 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-C3WA2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:10 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:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:10 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-wE1iil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:11 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:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-j4hbGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:11 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:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-fe5qT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:11 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:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-GqG38e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:11 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:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-b9hnh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:11 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:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-yiWhBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-dabCxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-5xB2gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:11 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:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-M3gpUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:11 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:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:11 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-I30t1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:12 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:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:12 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-I9RKei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:12 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:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:12 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-jtgUTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:12 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:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:12 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-y6bwBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:12 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:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:12 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-zgUcIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:12 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:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:12 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-4mzncT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:12 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:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:12 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-CsADfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:12 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:56:12 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:56:12 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:56:12 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:56:12 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:56:12 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:56:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:56:12 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:56:12 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:56:12 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:56:12 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:56:14 INFO - Downloading blessings-1.6.tar.gz 18:56:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:56:14 INFO - Installing collected packages: blessings 18:56:14 INFO - Running setup.py install for blessings 18:56:14 INFO - Successfully installed blessings 18:56:14 INFO - Cleaning up... 18:56:14 INFO - Return code: 0 18:56:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:14 INFO - Reading from file tmpfile_stdout 18:56:14 INFO - Current package versions: 18:56:14 INFO - blessings == 1.6 18:56:14 INFO - blobuploader == 1.2.4 18:56:14 INFO - docopt == 0.6.1 18:56:14 INFO - functools32 == 3.2.3-2 18:56:14 INFO - jsonschema == 2.5.1 18:56:14 INFO - manifestparser == 1.1 18:56:14 INFO - mozInstall == 1.12 18:56:14 INFO - mozcrash == 1.0 18:56:14 INFO - mozdebug == 0.1 18:56:14 INFO - mozdevice == 0.51 18:56:14 INFO - mozfile == 1.2 18:56:14 INFO - mozhttpd == 0.7 18:56:14 INFO - mozinfo == 0.9 18:56:14 INFO - mozleak == 0.1 18:56:14 INFO - mozlog == 3.4 18:56:14 INFO - moznetwork == 0.27 18:56:14 INFO - mozprocess == 0.25 18:56:14 INFO - mozprofile == 0.28 18:56:14 INFO - mozrunner == 6.13 18:56:14 INFO - mozscreenshot == 0.1 18:56:14 INFO - mozsystemmonitor == 0.3 18:56:14 INFO - moztest == 0.8 18:56:14 INFO - mozversion == 1.4 18:56:14 INFO - psutil == 3.1.1 18:56:14 INFO - requests == 1.2.3 18:56:14 INFO - wsgiref == 0.1.2 18:56:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:14 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:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:14 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:56:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:14 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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:56:14 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:56:14 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:56:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:56:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:14 INFO - 'HOME': '/Users/cltbld', 18:56:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:14 INFO - 'LOGNAME': 'cltbld', 18:56:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:14 INFO - 'MOZ_NO_REMOTE': '1', 18:56:14 INFO - 'NO_EM_RESTART': '1', 18:56:14 INFO - 'PAGER': '/bin/cat', 18:56:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:14 INFO - 'PWD': '/builds/slave/test', 18:56:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:14 INFO - 'SHELL': '/bin/bash', 18:56:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:56:14 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:56:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:14 INFO - 'USER': 'cltbld', 18:56:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:14 INFO - 'XPC_FLAGS': '0x0', 18:56:14 INFO - 'XPC_SERVICE_NAME': '0', 18:56:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:14 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:56:14 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:56:14 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:56:14 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:56:14 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:56:14 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:56:14 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:56:14 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:56:14 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:56:14 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:16 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:56:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:56:16 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:56:16 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:56:16 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:56:16 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:56:16 INFO - Installing collected packages: marionette-driver 18:56:16 INFO - Running setup.py install for marionette-driver 18:56:16 INFO - Successfully installed marionette-driver 18:56:16 INFO - Cleaning up... 18:56:16 INFO - Return code: 0 18:56:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:56:16 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:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:16 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:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10bbcac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9db256b0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, '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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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:56:16 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:56:16 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:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:16 INFO - 'HOME': '/Users/cltbld', 18:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:16 INFO - 'LOGNAME': 'cltbld', 18:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:16 INFO - 'MOZ_NO_REMOTE': '1', 18:56:16 INFO - 'NO_EM_RESTART': '1', 18:56:16 INFO - 'PAGER': '/bin/cat', 18:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:16 INFO - 'PWD': '/builds/slave/test', 18:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:16 INFO - 'SHELL': '/bin/bash', 18:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:56:16 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:16 INFO - 'USER': 'cltbld', 18:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:16 INFO - 'XPC_FLAGS': '0x0', 18:56:16 INFO - 'XPC_SERVICE_NAME': '0', 18:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:56:16 INFO - Cleaning up... 18:56:16 INFO - Return code: 0 18:56:16 INFO - Running post-action listener: _resource_record_post_action 18:56:16 INFO - Running post-action listener: _start_resource_monitoring 18:56:16 INFO - Starting resource monitoring. 18:56:16 INFO - [mozharness: 2017-06-02 01:56:16.989498Z] Finished create-virtualenv step (success) 18:56:16 INFO - [mozharness: 2017-06-02 01:56:16.990479Z] Running install step. 18:56:16 INFO - Running pre-action listener: _resource_record_pre_action 18:56:16 INFO - Running main action method: install 18:56:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:17 INFO - Reading from file tmpfile_stdout 18:56:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:56:17 INFO - Reading from file tmpfile_stdout 18:56:17 INFO - Output received: 18:56:17 INFO - Usage: mozinstall [options] installer 18:56:17 INFO - Options: 18:56:17 INFO - -h, --help show this help message and exit 18:56:17 INFO - -d DEST, --destination=DEST 18:56:17 INFO - Directory to install application into. [default: 18:56:17 INFO - "/builds/slave/test"] 18:56:17 INFO - --app=APP Application being installed. [default: firefox] 18:56:17 INFO - mkdir: /builds/slave/test/build/application 18:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:56:38 INFO - Reading from file tmpfile_stdout 18:56:38 INFO - Output received: 18:56:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:56:38 INFO - Running post-action listener: _resource_record_post_action 18:56:38 INFO - [mozharness: 2017-06-02 01:56:38.456200Z] Finished install step (success) 18:56:38 INFO - [mozharness: 2017-06-02 01:56:38.456315Z] Running setup-mitmproxy step. 18:56:38 INFO - Running pre-action listener: _resource_record_pre_action 18:56:38 INFO - Running main action method: setup_mitmproxy 18:56:38 INFO - Skipping: mitmproxy is not required 18:56:38 INFO - Running post-action listener: _resource_record_post_action 18:56:38 INFO - [mozharness: 2017-06-02 01:56:38.456783Z] Finished setup-mitmproxy step (success) 18:56:38 INFO - [mozharness: 2017-06-02 01:56:38.456889Z] Running run-tests step. 18:56:38 INFO - Running pre-action listener: _resource_record_pre_action 18:56:38 INFO - Running main action method: run_tests 18:56:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:56:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:56:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:56:38 INFO - 2017-06-01 18:56:38.469 screenresolution[1631:8059] starting screenresolution argv=screenresolution get 18:56:38 INFO - 2017-06-01 18:56:38.471 screenresolution[1631:8059] Display 0: 1600x1200x32@60 18:56:38 INFO - 2017-06-01 18:56:38.476 screenresolution[1632:8061] starting screenresolution argv=screenresolution list 18:56:38 INFO - Available Modes on Display 0 18:56:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:56:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:56:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:56:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:56:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:56:38 INFO - Intel Iris: 18:56:38 INFO - Chipset Model: Intel Iris 18:56:38 INFO - Type: GPU 18:56:38 INFO - Bus: Built-In 18:56:38 INFO - VRAM (Dynamic, Max): 1536 MB 18:56:38 INFO - Vendor: Intel (0x8086) 18:56:38 INFO - Device ID: 0x0a2e 18:56:38 INFO - Revision ID: 0x0009 18:56:38 INFO - Displays: 18:56:38 INFO - Display: 18:56:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:56:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:56:38 INFO - Display Serial Number: 1600x1200 60 18:56:38 INFO - Main Display: Yes 18:56:38 INFO - Mirror: Off 18:56:38 INFO - Online: Yes 18:56:38 INFO - Rotation: Supported 18:56:38 INFO - Return code: 0 18:56:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:56:38 INFO - Python 2.7.3 18:56:38 INFO - Return code: 0 18:56:38 INFO - grabbing minidump binary from tooltool 18:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:38 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 0x10bb53c60>, '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 0x10b8e8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b911be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:56:38 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:56:38 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:56:38 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:56:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:56:38 INFO - Return code: 0 18:56:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:56:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:56:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:56:38 INFO - ENV: RUST_BACKTRACE is now 1 18:56:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:56:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:56:38 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/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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:56:38 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/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0360 --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:56:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZyvxGEHy2k/Render', 18:56:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:38 INFO - 'HOME': '/Users/cltbld', 18:56:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:38 INFO - 'LOGNAME': 'cltbld', 18:56:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:56:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:38 INFO - 'MOZ_NO_REMOTE': '1', 18:56:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:56:38 INFO - 'NO_EM_RESTART': '1', 18:56:38 INFO - 'PAGER': '/bin/cat', 18:56:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:38 INFO - 'PWD': '/builds/slave/test', 18:56:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:56:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:38 INFO - 'RUST_BACKTRACE': '1', 18:56:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:56:38 INFO - 'SHELL': '/bin/bash', 18:56:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8RZsW6wIR/Listeners', 18:56:38 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 18:56:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:38 INFO - 'USER': 'cltbld', 18:56:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:38 INFO - 'XPC_FLAGS': '0x0', 18:56:38 INFO - 'XPC_SERVICE_NAME': '0', 18:56:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:38 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/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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:56:39 INFO - mozversion application_buildid: 20170601171623 18:56:39 INFO - mozversion application_changeset: fec3a4b50acd88df3b4af2dfe645a919713852a9 18:56:39 INFO - mozversion application_display_name: Nightly 18:56:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:56:39 INFO - mozversion application_name: Firefox 18:56:39 INFO - mozversion application_remotingname: firefox 18:56:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:56:39 INFO - mozversion application_vendor: Mozilla 18:56:39 INFO - mozversion application_version: 55.0a1 18:56:39 INFO - mozversion platform_buildid: 20170601171623 18:56:39 INFO - mozversion platform_changeset: fec3a4b50acd88df3b4af2dfe645a919713852a9 18:56:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:56:39 INFO - mozversion platform_version: 55.0a1 18:56:39 INFO - using testdate: 1496368599 18:56:39 INFO - actual date: 1496368599 18:56:39 INFO - starting webserver on 'localhost:49230' 18:56:39 INFO - SUITE-START | Running 2 tests 18:56:39 INFO - TEST-START | dromaeo_css 18:56:39 INFO - operating with platform_type : mac_ 18:56:39 INFO - Initialising browser for dromaeo_css test... 18:56:39 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpMCi8Mn/profile 18:56:39 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 18:57:13 INFO - TEST-INFO | 1642: killed by SIGTERM 18:57:13 INFO - Browser initialized. 18:57:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:57:13 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpMCi8Mn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:15 INFO - PID 1650 | 2017-06-01 18:57:15.193 plugin-container[1651:8557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 18:57:15 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:15 INFO - PID 1650 | 2017-06-01 18:57:15.193 plugin-container[1651:8557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c23, name = 'com.apple.CFPasteboardClient' 18:57:15 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:15 INFO - PID 1650 | 2017-06-01 18:57:15.193 plugin-container[1651:8557] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:57:15 INFO - PID 1650 | 2017-06-01 18:57:15.657 plugin-container[1652:8603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 18:57:15 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:15 INFO - PID 1650 | 2017-06-01 18:57:15.658 plugin-container[1652:8603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c17, name = 'com.apple.CFPasteboardClient' 18:57:15 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:15 INFO - PID 1650 | 2017-06-01 18:57:15.658 plugin-container[1652:8603] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:57:25 INFO - PID 1650 | RSS: Main: 270061568 18:57:25 INFO - PID 1650 | 19:00:27 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 19:00:28 INFO - PID 1650 | RSS: Main: 268427264 19:00:28 INFO - PID 1650 | 19:03:30 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 19:03:31 INFO - PID 1650 | RSS: Main: 283635712 19:03:31 INFO - PID 1650 | 19:06:33 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 19:06:33 INFO - PID 1650 | RSS: Main: 282673152 19:06:33 INFO - PID 1650 | 19:09:35 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 19:09:36 INFO - PID 1650 | RSS: Main: 287494144 19:09:36 INFO - PID 1650 | 19:12:39 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 19:12:39 INFO - PID 1650 | RSS: Main: 289718272 19:12:39 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 19:15:41 INFO - PID 1650 | RSS: Main: 289714176 19:15:41 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | __start_tp_report 19:15:41 INFO - PID 1650 | _x_x_mozilla_page_load 19:15:41 INFO - PID 1650 | _x_x_mozilla_page_load_details 19:15:41 INFO - PID 1650 | |i|pagename|runs| 19:15:41 INFO - PID 1650 | |0;dojo.html;3590.389633762261;3610.729195310352;3634.509341238935;3641.563012438506;3650.689691376233;5064.670796398232;6120.751898419022;6135.447809697126;6195.256569211695;6202.689865506729;6176.907346389797;6180.351063138373;6199.659018753967;6219.720112594933;6274.341194174614;5390.6226564140525;5391.406945236021;5425.538829199503;5430.348358197012;5443.727813609324;4542.41015644525;4675.322078298645;5440.863978400532;5566.610337276392;5593.04918163911;5361.142217245222;5458.181272809071;5474.09677403228;5476.3702174250075;5606.915896261559;5446.673199607996;5454;5486.588505862076;5491.862703432406;5501.477359650867;5415.296011518478;5433.510984011418;5451.591130665176;5463;5475.890482190373;6161.98327275999;6195.132681424604;6240.563160578761;6243.344448832898;6251.937480625181;6476.417122459001;6566.606003639797;6583.341665833378;6608.9339106608795;6685.665716714194;6424.421802037839;6795.048693182958;6807.5575087619645;6814.454843612499;6822.215445223813;6347.016212487072;6361.904571431386;6363.013732871366;6372;6373.776917807854;6708.83227919306;6823.55646882961;6869.175698916062;6913.51605387618;6950.7567235147535;6323.715432805535;6467.450266727286;6572.737090516422;6595.934040659532;6645.933540664628;6662.900056499059;6788.93211067883;6794.592324460548;6833.897491537631;6835.7949261522235;5897.76408943646;6679.833004174838;6691.26396096429;6718.731250750013;6721.395074443323;6467.547271691031;6522.739090436329;6606.942889137715;6723.428508576826;6743.2919543448215;5357.249985002103;5400.83797486076;5418.647787893774;5420.674759514362;5426.9185962210595;4813.15769740301;4820.421549414023;4871.488493708181;4876.366072410564;4887.706737595756;6060.909036373407;6212.627242365473;6326.715297811609;6327.462165715966;6327.778527751599;5718;6699.631520266432;6702.932970670231;6812.863742725217;6831.795046148524;6304.306526282106;6328.145700330489;6344.365563443711;6345.524085693592;6361.745530178741;5990.760369585168;6001.12983617373;6025.457708806229;6026.306974697878;6045.244344456943;6634;6837.692303846438;6888.207856096613;6935.202451718117;6945.062416573799;6771.593704377753;6837.760678376233;6852.828679283058;6863.519553631198;6901.827454313682;6904.206016308189;6957.930420695729;6980;6981.790546283416;7022.894656580258;6468.514861385321;6562.310957349409;6595.076689263411;6739.66301684904;6741.292164322872;6773.491988100814;6811.863762724817;6816.420604248694;6830.248672646105;6838.384545390939;6407.775727849503;6604.14146160996;6605.042268871083;6616.933830661632;6652.135222421054;6473.643949582818;6505.674716264262;6584.76953306632;6607.240167380813;6641.03704962768;6427.260865000585;6454.709538070704;6514;6515;6525.510586705921;5452.918206226989;6631.20425548918;6635.568688035262;6638.867222655616;6660.6336651484635;6257.342978987323;6267.2165979252595;6328.398802113792;6338.112664227104;6412.198475190601 19:15:41 INFO - PID 1650 | |1;ext.html;720.5352547606793;789.2699253190797;846.3779122345076;1250.7310928150446;1298.883100520953;38121.618783812155;38662.613373866254;38685.03287417816;38781.03047423812;38865.61134388655;31081;39092;39156.41265381014;39215.60784392155;39240.80379598098;7795.766127016199;7992.3606111510935;8227.54748488833;8362.289205417546;8430.367722420813;8240.50556966581;8306.169383061706;8315.459495132827;8317.209865062809;8368.414211005233;8267;8277.917220827805;8297.170282971685;8344.457610255344;8383.077861435237;8203.753887383387;8244.505329680209;8271.503709777433;8288.751337459886;8325.375596830201;8141.307988820958;8189.50862948225;8208.83582328356;8250.092489826115;8275.586220688941;36774.26451470982;36902.523899044005;36932.07669808249;37007.62992370069;37045.70340038086;3577.338192434382;3578.35589593873;3619.402798538238;3629.4192929131477;3632.146445585285;30016;30190.54714179289;30225.546616800544;30245.092647220397;30283.545746813816;3040.13356193486;3095.829729364884;3101.596792416994;3105.192649911017;3110.0358888744563;239459;243001;258760;259740;268586.97119543224;47833;47875;48036.519634803204;48077.27884081742;48113.037739245716;40496.39255411171;40990.59009409927;47536;50084;55419;14552.636184095481;14664.413423462942;14683.412663493555;14687.41250349974;14733.484328048467;11871.46578403974;11900.166988310735;11945.641630751092;11963.940180299043;11997.460114294878;14131.222782747047;14152.29238538069;14165.858341416453;14209.857901420854;14278.643033924027;5112.258722485219;6214;6220.942439785247;6241.25384830673;6268.843278917972;1006.1950439648252;1029.4698230411343;1037.5382954585136;1057.9523921423554;1064.1752641702587;23542.17602383908;24809.87595062013;24821.379465513506;24838.751612484004;24910.875445622656;1114.9331040137617;1121.6466812953893;1137.1698659978097;1158.4207896051973;1170.0931777872047;11778.88221117795;12189.512419503299;12621.684457888627;12628.242305461701;12732.490700372067;318965;319626;320342;321499;322388;54357.45642543524;54513.72743136259;54896.902061958535;55013.449865501636;55988.720056399456;1503.639126609606;1513.2585033333808;1548.9380424783108;1549.5583758628736;1558.1040901481692;20881.477963050438;24306.149284774947;24380.51238975175;24380.51238975246;24501;36074.819625901706;37820.24359512849;37963.24073518459;38049.239015218984;38054.23891522099;240856;273763;274141;274555;275452.24547754263;34125;35761.46357804583;35768.463473047406;35962.820185898905;36721.08197295089;44726.74618220938;45990.310145348514;46017.619471415914;46143.76928115338;46409;28490.287742806595;29287;31420.842895785376;31422.528662070545;31518.527222091227;28130.71869281281;28824.567631485963;30627;31480.527792082677;31631;36265.818670906476;37030.8148459256;37675.62324376721;37778.055548610406;37810.43284350792;31176.68823311738;31616.841915790275;32043;32098.83950580232;32404.351912961138;22413;22822.087116515155;24516;24529.01883924623;24662.50674986526 19:15:41 INFO - PID 1650 | |2;jquery.html;395.5075930466569;404.3974478027738;429.40312964978705;441.7680717623247;461.66990601719766;11870.406479676025;15337.92331038346;21998.45003874902;22507.662385064217;22800.54398912023;22728.204512842054;23529.647055294183;23566.646500302508;23611.40971475714;23704.64443033356;5975.731092100866;6074.42292982166;6152.477039451652;6152.5693201475915;6161.045038019114;6031.758729650809;6144.44699977002;6153.230846144232;6153.815385538419;6156;6106.023036314191;6118.785842495514;6342.429181373675;6674.966625166868;6753.797386078401;6697.397234248917;6757.121574195372;6777.728890844361;6786.423154031913;6796.524243302946;6613.900791488131;6624.635645039521;6627.304133066007;6628.767993120266;6637.103990961206;21940;29472.26319342012;29734.70265297341;29872.10383688493;29872.253193670116;2291.3126062181277;2332.696749422564;2337.3922780077132;2356.22244659262;2358.469344397514;19042.904785476123;19325.613487730167;19591.314303999436;19600.50998725029;19640.821550707;2267.3651377614297;2286.737025242102;2327.837051406402;2351.0360752091733;2351.2005917987967;142701.2864935679;157311.2134439332;181268;181432;181659;18818.221292779494;25041.74958250394;25227.58517259472;26469.941202352074;26510.602340964902;32632.510512342335;33999.13004784711;38299;39732.40401393981;40972;9730.610775568897;10810.729731756768;11005.600194789422;11010.229283950206;11113.611023614134;8761.09667708239;8792.560371981375;8837.469751814911;8875.068117847663;8879.422837515542;7806.414518911104;11689.006434453167;11745.88254117465;11747.063234191452;11809.527618895148;1945.8130540370362;2205.106931692667;2278.4873403484085;2279.50990537032;2478.91323803666;834.2783492279256;959.467495539982;970.1075010989906;989.475577943691;1008.5965613754557;20696.58606827855;21126.47183820417;21135.471613209484;21399.39912918699;21462;997.2072202598952;1003.8795344558554;1021.0606242257142;1026.3072426112344;1029.1818004686293;10737.83893241602;10824.13406927444;10860.565577376816;10879.891201088047;11071.557137714562;553705;558537.4146258567;562193.1890340522;564674;565813;117515;149204;150066;150824;151396;1157.455995682028;1158.3744777820052;1201.195199216527;1209.1596340543415;1212.4180393411211;21251.256206032715;21880.405114926045;21963.23128690488;22628.886855566274;22734;67244.66377668276;69927;69945.65027174831;70594;70603;93986.53006734923;94657.3027827465;94783;94958.52520737352;97402.5129874346;16249.756253655969;16573.337066517204;16647.833521665114;16664;16689.49931502057;13335.86664133385;17820.46538603844;18319.35882244115;18381.264749409875;18462.692163479565;14846.257687115385;14939.77590336124;15059.247037647854;15081.24593770329;15086.773698394314;12615.558455454382;13151.145175563941;13152.276624785345;13169.868301317247;13303;14071.718565628835;14871.405143794127;15100.773488397903;15118.09291442517;15216.543503694907;12989.350532473149;15766.211689415713;16098.758518622464;16295.59261018484;16311.26599303058;14403.279836007949;14472.27638618086;14492.927535362678;14713.55859324222;14732.116073035651 19:15:41 INFO - PID 1650 | |3;mootools.html;1230.8276841242227;1455.272363818091;1462.7001464699738;1466.9193194374304;1478.4086365453813;11697.473613687383;11888.821667674993;12176.96495797856;12205.633830985062;12272.693182670439;12018;12206;12211.938940305308;12225;12269.447874845631;2516.5847635140226;2526.431552900601;2553.4382435864172;2554.1571281477068;2556.4248044190094;2496.613024981131;2539.1240022192405;2541.59334506479;2543.0717788007337;2553.69355677319;2508.4356019895467;2523.7602427769425;2531.0255551612577;2532.8860201290895;2532.9240122796346;2469.296250568588;2488.726240113586;2501.6747822782922;2503.2615378463397;2506.373406648338;2426.8543887366814;2474.208253358926;2476.6656501372445;2484.3416494628937;2526.12848567244;11431.771364572662;11589.884101158965;11599.710007249801;11618.767624647544;11627.883721162765;6442.4201821835795;6596.208454985384;6683.93316066838;6691.933080669229;6710.194776626788;4085.7344272622186;4266.616004559573;4334.393184954109;4336.284513055342;4353.151135528573;1252.6993521554853;1256.9811452828214;1258.3519487463964;1278.086168389606;1280.7054377493137;15286.388544458208;15707.764383534142;16040;16066.919665401716;16358.345666173338;5974.880502390015;5991.400859913973;6112.358202388774;6167.748771698298;6188.845278868064;13851.51519696826;13868.93065534666;13880.375383107785;13895.930520347334;13900.096493727875;6593.439557637558;6672.666366681647;6690.464762818963;6695.230048544479;6712.932870671329;6050.183225264533;6142.723577439026;6153.815385538441;6171.506279497629;6219.906701399483;4720.787564559603;4767.308740232646;4814.133455977958;4853.417589889236;4860.708357498562;1445.9566213013627;1482.0959214878915;1485.3984136424765;1495.611141103321;1500.8499150085122;741.1995045351008;749.8162950077266;756.8788993760971;757.4849102610278;765.9888946590559;5871.9706401468575;6048.304444988795;6100.603460775035;6994.720211191496;7055.2591977841585;823.3783493462518;857.725527831088;878.9648414063494;886.4149661223562;929.1730359979623;4032.354823228269;4296.097819457948;4388.188185185751;4411.360352748833;4494.70784399013;14502.85497145036;14870.479533216285;15000.099994000395;15144.469943552143;15181.696366072838;14886.032407893668;15079.623009424633;15094.924525377523;15105.47130850437;15178.392864285304;7491.138519070268;8145.837083258301;8434;8583.871241931376;8764.517951512727;5133.79464821403;6090.116933044772;6179.980303249913;6733.6296503692765;6744.123263975652;11415.491178263015;13310.600681979557;13331;13487.25820079905;13665;11309.830352544554;13423.395947182205;13685.836703879884;13892.930535347663;14086.506972256331;9422.670206542738;9596.616135354509;9609.567569459252;9612.855807162758;9721.805563888824;10329.431881246604;10519;10631.468426578795;10713.839292410465;10754.83867742;8951;9166;9193.402428842104;9210.355275130676;9261.398009129385;7518.436117291334;8763.956180219058;8888.688895888612;8991.505467199368;9027;6948.270431604811;7861.960690196513;8048.798780030546;8051.879221811795;8053.718119865776;5896.882062358814;5972.850678733066;6023.096535519707;6087.57386982907;6117.571769976059;5228.895422091461;5578.30271216098;6003.309619393825;6179.62922224668;6216.564840461125 19:15:41 INFO - PID 1650 | |4;prototype.html;380.4597471590341;388.6931064134362;396.79168436570797;404.06459047305503;408.7261534771704;5593.52455041321;5725.396888871122;5837.357890632027;6369.363063693634;6405.19935008124;5366.302380690505;5570.303712035996;5890.381509941459;6259.874802503959;6280.937190628092;4501.189785838539;4582.739746569687;4644.60520855726;4664.463586687541;4669.369635099269;3918.686505079587;3919.451276821247;4567.155076310876;4606.239970404879;4618.983823558811;4624.3988281523525;4629.166749985001;4641.164590373732;4641.559051890065;4646.72119672819;4597.908041839178;4625.421822272216;4629.3518907353;4636.632193502925;4649.511801260886;3555.3600351936643;3802.6387493188104;3982.522097348308;3994.3409337459284;3998.3602623580086;5623.818998010423;6208.534359923024;6234.283057448406;6241.095041219043;6262.655553944532;5441.401445840955;5514.007478653841;5514.6139770216105;5519.69641669708;5550.5282051025715;5324.41431442541;6791.083203767527;6801.761938332184;6805.693743781541;6823.317668233187;4509.210888094603;4519.457665080211;4527.592516673482;4528.547145285478;4539.455265368177;6686.933130668728;6814.216365118025;6967.338102880219;7091.397231235351;7249.223940134648;4081.5714349992822;4183.748975061445;4500.189965806187;4575.794089265991;4576.4508259009135;4242.830286788556;4296.677749168763;4670.696404733681;4671.369365135731;4671.813127474931;5316.3354580677415;5418.972905135449;5438.102713052302;5444.428335024765;5460.235567020621;6669.666516674244;6709;6713.832154196184;6728.865422691617;6747.908148473989;1317.4005827348533;1575.141547856421;1578.486991727693;1587.4126573168003;1589.6805651309385;1024.6874703215442;1045.9215558833118;1061.9044286014296;1067.295584913953;1069.1874175626424;5700.201971723962;6233.657148856856;6280.842978925563;6295.60766908581;6322.873542529123;5731.799387021434;6999.510034297652;7028.332308430691;7032.769265378538;7032.964835175791;6220.813375598739;6276.560640755103;6291.1192433059405;6292.835825372321;6310.62136271816;3528.558930133733;3613.6567026132475;3634.236810269873;3638.308721342936;3664.8167591620313;6981.2669669683955;7166.15957994233;7171.49799514029;7203.279672032755;7223.205447400782;6318.304986451487;6946.131733533309;7199.820004499825;7212;7218.350348468558;5936.9406305937255;6957.582545047313;7172.49792514519;7188.964055179691;7189.584947150166;6285.277193122849;6962.686679099352;7004.824879378057;7011.964940175267;7043.225245223124;6667.533272670867;6782.93217067823;6826.795196144124;6853.211880633591;6858.5199036067;6050.364711705208;6604.273529911802;6981.301869813181;7227.891581626174;7302.96348518254;3809.771413715186;4114.897127571715;4582.633389328779;4598.275646632513;4599.770011499345;6645.9003114954285;6846.383825455733;7020.297970202939;7026.824329391601;7054.682539285847;5711.685857277889;6362.522810789302;6736.5958042517605;6744.7976560703255;6750.932490675031;6482.319356467691;6483.994980777987;6688.464922806261;6728.697208625722;6742.022406751092;5783.248177736868;5827.766889324549;6281.748730050929;6568.638724870178;6670.731951136637;5472.91790623133;5655.773769049192;6764;6772.98405239218;6815.659217039029;6232.376762323732;6599.637019963948;6612.272650008592;6614.4943180114615;6621.1392518972225 19:15:41 INFO - PID 1650 | |5;yui.html;822.9876551851722;823.3413269384491;843.4896887383129;854.069064719456;868.3921255121414;14346.210958397283;14882.627934301649;16857.90423622465;16992.320307187714;17223.39718109868;16064;17693.1422200669;17782.377616783415;17794.377196798116;17833;946.8106378724249;988.2538683294139;1010.0909181736423;1013.3565186106802;1032.8244198486275;1018.5925629748086;1024.1141412678016;1024.4007255755355;1024.8001639680265;1026.7997740440617;929.5305870535404;1051.2168434516295;1069.593554449312;1076.042322333124;1079.1205167788266;1053.810314143454;1069.7004838645191;1070.143884892087;1071.6410002649122;1078.4553800330782;759.8442319324525;773.9303462688329;782.8786538086606;783.956882371475;784.4116912315764;16750.497485075466;16974.745378819327;17055.232514536874;17082.572935676584;17249.741253881202;17049.91475042629;17316.826831731647;17573.56066098345;17589.824101758946;17606.295748170185;14689.92655036718;14749.483768068067;14861;14969.176695281754;14971.251437428085;3628.727845411578;3651.835667394974;3654.6162652921325;3660.633936606318;3851.691864650821;134869.3256533721;136504;137546.312268439;138101;138970.3051484746;11765.705857353463;13067;13420.932895335462;13574.864251357558;13683.179009259477;25093.498130037297;26056;26071.087511936992;26083;26094.9562017521;7310.853782924311;7463.290987356192;7719.884201736979;7800.375969922393;7819.843603127905;3728.7203459740626;3757.248550289953;3887.980560097238;3986.0034991252187;3994.1013272014147;4372.4315838941375;4395.296752519645;4396.428464299621;4401.361802538678;4457.9331310030375;783.2206954080726;857.5583574459158;896.7668406214431;902.5442151713474;902.5712786426526;600.0639003155092;626.3423405424286;648.303074195242;652.4160876016034;662.7050962321817;11230.94384528072;12038.036957043416;12146.574869879689;12289.938550307192;12384.504619815287;643.793985924509;644.7936660268668;649.0523835200565;659.3604203922188;673.8820706376365;5528.364238112588;5537.861553461195;5656.727236371866;5667.291588551431;5703.515201207943;128530;138022;142355;143632.28183859013;144906;118754.40622796831;120136.39931800285;122243;122331;122984.15523767151;99129;101662.96674066478;101982;102458;102841;10182.541785619516;10899.891001089887;11014.07253730578;11235.438228088726;11338.773224535627;43649.690509284774;43902;44278;44289.557104428546;44307.33538996982;61162;61575.384246156966;61884.07173892305;67751.66124169348;74614.13464663427;16995.23521441563;17103.657926841643;17308.740368894727;17678.292868285123;17929.282828687228;18776.718349225044;18893.811061889755;18913.905430472758;19084.904575477034;19127.808721912603;17211.913940430717;17380.13099345053;17704.114794260495;17862.285508579513;17900;16734.4142954996;17433.302667893142;18014.2794288227;18089.276428942696;18185.363512277007;17035;17254.741178882578;17591.120443978005;17796.288148474436;17850.01824899591;11420.771584568429;11809.350485723367;12071.215371000857;12315.876841231831;12339.383030848601;13663.658408539866;14038.087524311295;14150.575482735534;14202.218877961812;14400.20798856031 19:15:41 INFO - PID 1650 | __end_tp_report 19:15:41 INFO - PID 1650 | __start_cc_report 19:15:41 INFO - PID 1650 | _x_x_mozilla_cycle_collect,453 19:15:41 INFO - PID 1650 | __end_cc_report 19:15:41 INFO - PID 1650 | __startTimestamp1496369741713__endTimestamp 19:15:41 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | ------- Summary: start ------- 19:15:41 INFO - PID 1650 | Number of tests: 6 19:15:41 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6176.89 Median:6373.78 stddev:733.70 (11.5%) stddev-sans-first:707.34 19:15:41 INFO - PID 1650 | Values: 3590.4 3610.7 3634.5 3641.6 3650.7 5064.7 6120.8 6135.4 6195.3 6202.7 6176.9 6180.4 6199.7 6219.7 6274.3 5390.6 5391.4 5425.5 5430.3 5443.7 4542.4 4675.3 5440.9 5566.6 5593.0 5361.1 5458.2 5474.1 5476.4 5606.9 5446.7 5454.0 5486.6 5491.9 5501.5 5415.3 5433.5 5451.6 5463.0 5475.9 6162.0 6195.1 6240.6 6243.3 6251.9 6476.4 6566.6 6583.3 6608.9 6685.7 6424.4 6795.0 6807.6 6814.5 6822.2 6347.0 6361.9 6363.0 6372.0 6373.8 6708.8 6823.6 6869.2 6913.5 6950.8 6323.7 6467.5 6572.7 6595.9 6645.9 6662.9 6788.9 6794.6 6833.9 6835.8 5897.8 6679.8 6691.3 6718.7 6721.4 6467.5 6522.7 6606.9 6723.4 6743.3 5357.2 5400.8 5418.6 5420.7 5426.9 4813.2 4820.4 4871.5 4876.4 4887.7 6060.9 6212.6 6326.7 6327.5 6327.8 5718.0 6699.6 6702.9 6812.9 6831.8 6304.3 6328.1 6344.4 6345.5 6361.7 5990.8 6001.1 6025.5 6026.3 6045.2 6634.0 6837.7 6888.2 6935.2 6945.1 6771.6 6837.8 6852.8 6863.5 6901.8 6904.2 6957.9 6980.0 6981.8 7022.9 6468.5 6562.3 6595.1 6739.7 6741.3 6773.5 6811.9 6816.4 6830.2 6838.4 6407.8 6604.1 6605.0 6616.9 6652.1 6473.6 6505.7 6584.8 6607.2 6641.0 6427.3 6454.7 6514.0 6515.0 6525.5 5452.9 6631.2 6635.6 6638.9 6660.6 6257.3 6267.2 6328.4 6338.1 6412.2 19:15:41 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:43559.80 Median:321943.50 stddev:73907.22 (23.0%) stddev-sans-first:74044.55 19:15:41 INFO - PID 1650 | Values: 720.5 789.3 846.4 1250.7 1298.9 38121.6 38662.6 38685.0 38781.0 38865.6 31081.0 39092.0 39156.4 39215.6 39240.8 7795.8 7992.4 8227.5 8362.3 8430.4 8240.5 8306.2 8315.5 8317.2 8368.4 8267.0 8277.9 8297.2 8344.5 8383.1 8203.8 8244.5 8271.5 8288.8 8325.4 8141.3 8189.5 8208.8 8250.1 8275.6 36774.3 36902.5 36932.1 37007.6 37045.7 3577.3 3578.4 3619.4 3629.4 3632.1 30016.0 30190.5 30225.5 30245.1 30283.5 3040.1 3095.8 3101.6 3105.2 3110.0 239459.0 243001.0 258760.0 259740.0 268587.0 47833.0 47875.0 48036.5 48077.3 48113.0 40496.4 40990.6 47536.0 50084.0 55419.0 14552.6 14664.4 14683.4 14687.4 14733.5 11871.5 11900.2 11945.6 11963.9 11997.5 14131.2 14152.3 14165.9 14209.9 14278.6 5112.3 6214.0 6220.9 6241.3 6268.8 1006.2 1029.5 1037.5 1058.0 1064.2 23542.2 24809.9 24821.4 24838.8 24910.9 1114.9 1121.6 1137.2 1158.4 1170.1 11778.9 12189.5 12621.7 12628.2 12732.5 318965.0 319626.0 320342.0 321499.0 322388.0 54357.5 54513.7 54896.9 55013.4 55988.7 1503.6 1513.3 1548.9 1549.6 1558.1 20881.5 24306.1 24380.5 24380.5 24501.0 36074.8 37820.2 37963.2 38049.2 38054.2 240856.0 273763.0 274141.0 274555.0 275452.2 34125.0 35761.5 35768.5 35962.8 36721.1 44726.7 45990.3 46017.6 46143.8 46409.0 28490.3 29287.0 31420.8 31422.5 31518.5 28130.7 28824.6 30627.0 31480.5 31631.0 36265.8 37030.8 37675.6 37778.1 37810.4 31176.7 31616.8 32043.0 32098.8 32404.4 22413.0 22822.1 24516.0 24529.0 24662.5 19:15:41 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:39554.33 Median:12497.78 stddev:95905.57 (767.4%) stddev-sans-first:96129.54 19:15:41 INFO - PID 1650 | Values: 395.5 404.4 429.4 441.8 461.7 11870.4 15337.9 21998.5 22507.7 22800.5 22728.2 23529.6 23566.6 23611.4 23704.6 5975.7 6074.4 6152.5 6152.6 6161.0 6031.8 6144.4 6153.2 6153.8 6156.0 6106.0 6118.8 6342.4 6675.0 6753.8 6697.4 6757.1 6777.7 6786.4 6796.5 6613.9 6624.6 6627.3 6628.8 6637.1 21940.0 29472.3 29734.7 29872.1 29872.3 2291.3 2332.7 2337.4 2356.2 2358.5 19042.9 19325.6 19591.3 19600.5 19640.8 2267.4 2286.7 2327.8 2351.0 2351.2 142701.3 157311.2 181268.0 181432.0 181659.0 18818.2 25041.7 25227.6 26469.9 26510.6 32632.5 33999.1 38299.0 39732.4 40972.0 9730.6 10810.7 11005.6 11010.2 11113.6 8761.1 8792.6 8837.5 8875.1 8879.4 7806.4 11689.0 11745.9 11747.1 11809.5 1945.8 2205.1 2278.5 2279.5 2478.9 834.3 959.5 970.1 989.5 1008.6 20696.6 21126.5 21135.5 21399.4 21462.0 997.2 1003.9 1021.1 1026.3 1029.2 10737.8 10824.1 10860.6 10879.9 11071.6 553705.0 558537.4 562193.2 564674.0 565813.0 117515.0 149204.0 150066.0 150824.0 151396.0 1157.5 1158.4 1201.2 1209.2 1212.4 21251.3 21880.4 21963.2 22628.9 22734.0 67244.7 69927.0 69945.7 70594.0 70603.0 93986.5 94657.3 94783.0 94958.5 97402.5 16249.8 16573.3 16647.8 16664.0 16689.5 13335.9 17820.5 18319.4 18381.3 18462.7 14846.3 14939.8 15059.2 15081.2 15086.8 12615.6 13151.1 13152.3 13169.9 13303.0 14071.7 14871.4 15100.8 15118.1 15216.5 12989.4 15766.2 16098.8 16295.6 16311.3 14403.3 14472.3 14492.9 14713.6 14732.1 19:15:41 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7158.87 Median:4059.04 stddev:4521.09 (111.4%) stddev-sans-first:4511.83 19:15:41 INFO - PID 1650 | Values: 1230.8 1455.3 1462.7 1466.9 1478.4 11697.5 11888.8 12177.0 12205.6 12272.7 12018.0 12206.0 12211.9 12225.0 12269.4 2516.6 2526.4 2553.4 2554.2 2556.4 2496.6 2539.1 2541.6 2543.1 2553.7 2508.4 2523.8 2531.0 2532.9 2532.9 2469.3 2488.7 2501.7 2503.3 2506.4 2426.9 2474.2 2476.7 2484.3 2526.1 11431.8 11589.9 11599.7 11618.8 11627.9 6442.4 6596.2 6683.9 6691.9 6710.2 4085.7 4266.6 4334.4 4336.3 4353.2 1252.7 1257.0 1258.4 1278.1 1280.7 15286.4 15707.8 16040.0 16066.9 16358.3 5974.9 5991.4 6112.4 6167.7 6188.8 13851.5 13868.9 13880.4 13895.9 13900.1 6593.4 6672.7 6690.5 6695.2 6712.9 6050.2 6142.7 6153.8 6171.5 6219.9 4720.8 4767.3 4814.1 4853.4 4860.7 1446.0 1482.1 1485.4 1495.6 1500.8 741.2 749.8 756.9 757.5 766.0 5872.0 6048.3 6100.6 6994.7 7055.3 823.4 857.7 879.0 886.4 929.2 4032.4 4296.1 4388.2 4411.4 4494.7 14502.9 14870.5 15000.1 15144.5 15181.7 14886.0 15079.6 15094.9 15105.5 15178.4 7491.1 8145.8 8434.0 8583.9 8764.5 5133.8 6090.1 6180.0 6733.6 6744.1 11415.5 13310.6 13331.0 13487.3 13665.0 11309.8 13423.4 13685.8 13892.9 14086.5 9422.7 9596.6 9609.6 9612.9 9721.8 10329.4 10519.0 10631.5 10713.8 10754.8 8951.0 9166.0 9193.4 9210.4 9261.4 7518.4 8764.0 8888.7 8991.5 9027.0 6948.3 7862.0 8048.8 8051.9 8053.7 5896.9 5972.9 6023.1 6087.6 6117.6 5228.9 5578.3 6003.3 6179.6 6216.6 19:15:41 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5424.75 Median:5993.65 stddev:1714.01 (28.6%) stddev-sans-first:1676.48 19:15:41 INFO - PID 1650 | Values: 380.5 388.7 396.8 404.1 408.7 5593.5 5725.4 5837.4 6369.4 6405.2 5366.3 5570.3 5890.4 6259.9 6280.9 4501.2 4582.7 4644.6 4664.5 4669.4 3918.7 3919.5 4567.2 4606.2 4619.0 4624.4 4629.2 4641.2 4641.6 4646.7 4597.9 4625.4 4629.4 4636.6 4649.5 3555.4 3802.6 3982.5 3994.3 3998.4 5623.8 6208.5 6234.3 6241.1 6262.7 5441.4 5514.0 5514.6 5519.7 5550.5 5324.4 6791.1 6801.8 6805.7 6823.3 4509.2 4519.5 4527.6 4528.5 4539.5 6686.9 6814.2 6967.3 7091.4 7249.2 4081.6 4183.7 4500.2 4575.8 4576.5 4242.8 4296.7 4670.7 4671.4 4671.8 5316.3 5419.0 5438.1 5444.4 5460.2 6669.7 6709.0 6713.8 6728.9 6747.9 1317.4 1575.1 1578.5 1587.4 1589.7 1024.7 1045.9 1061.9 1067.3 1069.2 5700.2 6233.7 6280.8 6295.6 6322.9 5731.8 6999.5 7028.3 7032.8 7033.0 6220.8 6276.6 6291.1 6292.8 6310.6 3528.6 3613.7 3634.2 3638.3 3664.8 6981.3 7166.2 7171.5 7203.3 7223.2 6318.3 6946.1 7199.8 7212.0 7218.4 5936.9 6957.6 7172.5 7189.0 7189.6 6285.3 6962.7 7004.8 7012.0 7043.2 6667.5 6782.9 6826.8 6853.2 6858.5 6050.4 6604.3 6981.3 7227.9 7303.0 3809.8 4114.9 4582.6 4598.3 4599.8 6645.9 6846.4 7020.3 7026.8 7054.7 5711.7 6362.5 6736.6 6744.8 6750.9 6482.3 6484.0 6688.5 6728.7 6742.0 5783.2 5827.8 6281.7 6568.6 6670.7 5472.9 5655.8 6764.0 6773.0 6815.7 6232.4 6599.6 6612.3 6614.5 6621.1 19:15:41 INFO - PID 1650 | 19:15:41 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:25060.34 Median:17598.71 stddev:37950.95 (215.6%) stddev-sans-first:38013.77 19:15:41 INFO - PID 1650 | Values: 823.0 823.3 843.5 854.1 868.4 14346.2 14882.6 16857.9 16992.3 17223.4 16064.0 17693.1 17782.4 17794.4 17833.0 946.8 988.3 1010.1 1013.4 1032.8 1018.6 1024.1 1024.4 1024.8 1026.8 929.5 1051.2 1069.6 1076.0 1079.1 1053.8 1069.7 1070.1 1071.6 1078.5 759.8 773.9 782.9 784.0 784.4 16750.5 16974.7 17055.2 17082.6 17249.7 17049.9 17316.8 17573.6 17589.8 17606.3 14689.9 14749.5 14861.0 14969.2 14971.3 3628.7 3651.8 3654.6 3660.6 3851.7 134869.3 136504.0 137546.3 138101.0 138970.3 11765.7 13067.0 13420.9 13574.9 13683.2 25093.5 26056.0 26071.1 26083.0 26095.0 7310.9 7463.3 7719.9 7800.4 7819.8 3728.7 3757.2 3888.0 3986.0 3994.1 4372.4 4395.3 4396.4 4401.4 4457.9 783.2 857.6 896.8 902.5 902.6 600.1 626.3 648.3 652.4 662.7 11230.9 12038.0 12146.6 12289.9 12384.5 643.8 644.8 649.1 659.4 673.9 5528.4 5537.9 5656.7 5667.3 5703.5 128530.0 138022.0 142355.0 143632.3 144906.0 118754.4 120136.4 122243.0 122331.0 122984.2 99129.0 101663.0 101982.0 102458.0 102841.0 10182.5 10899.9 11014.1 11235.4 11338.8 43649.7 43902.0 44278.0 44289.6 44307.3 61162.0 61575.4 61884.1 67751.7 74614.1 16995.2 17103.7 17308.7 17678.3 17929.3 18776.7 18893.8 18913.9 19084.9 19127.8 17211.9 17380.1 17704.1 17862.3 17900.0 16734.4 17433.3 18014.3 18089.3 18185.4 17035.0 17254.7 17591.1 17796.3 17850.0 11420.8 11809.4 12071.2 12315.9 12339.4 13663.7 14038.1 14150.6 14202.2 14400.2 19:15:41 INFO - PID 1650 | -------- Summary: end -------- 19:15:41 INFO - PID 1650 | 19:15:42 INFO - TEST-INFO | 1650: exit 0 19:15:42 INFO - TEST-OK | dromaeo_css | took 1143191ms 19:15:42 INFO - TEST-START | kraken 19:15:42 INFO - operating with platform_type : mac_ 19:15:42 INFO - Initialising browser for kraken test... 19:15:42 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpAaiJrP/profile 19:15:42 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 19:16:15 INFO - TEST-INFO | 1660: killed by SIGTERM 19:16:15 INFO - Browser initialized. 19:16:15 INFO - Running cycle 1/1 for kraken test... 19:16:15 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpAaiJrP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:17 INFO - PID 1664 | 2017-06-01 19:16:17.979 plugin-container[1665:11057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 19:16:17 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:17 INFO - PID 1664 | 2017-06-01 19:16:17.980 plugin-container[1665:11057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 19:16:17 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:17 INFO - PID 1664 | 2017-06-01 19:16:17.980 plugin-container[1665:11057] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:16:18 INFO - PID 1664 | 2017-06-01 19:16:18.531 plugin-container[1666:11104] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 19:16:18 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:18 INFO - PID 1664 | 2017-06-01 19:16:18.531 plugin-container[1666:11104] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 19:16:18 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:18 INFO - PID 1664 | 2017-06-01 19:16:18.531 plugin-container[1666:11104] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:16:27 INFO - PID 1664 | RSS: Main: 268849152 19:16:27 INFO - PID 1664 | 19:16:30 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 19:16:31 INFO - PID 1664 | RSS: Main: 276418560 19:16:31 INFO - PID 1664 | 19:16:33 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 19:16:34 INFO - PID 1664 | RSS: Main: 286552064 19:16:34 INFO - PID 1664 | 19:16:38 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 19:16:38 INFO - PID 1664 | RSS: Main: 284504064 19:16:38 INFO - PID 1664 | 19:16:40 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 19:16:41 INFO - PID 1664 | RSS: Main: 285749248 19:16:41 INFO - PID 1664 | 19:16:43 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:16:43 INFO - PID 1664 | RSS: Main: 287719424 19:16:43 INFO - PID 1664 | 19:16:46 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 19:16:47 INFO - PID 1664 | RSS: Main: 292020224 19:16:47 INFO - PID 1664 | 19:16:50 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 19:16:51 INFO - PID 1664 | RSS: Main: 294936576 19:16:51 INFO - PID 1664 | 19:16:54 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 19:16:54 INFO - PID 1664 | RSS: Main: 294223872 19:16:54 INFO - PID 1664 | 19:16:56 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:16:56 INFO - PID 1664 | RSS: Main: 293765120 19:16:56 INFO - PID 1664 | 19:16:58 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:16:59 INFO - PID 1664 | RSS: Main: 293601280 19:16:59 INFO - PID 1664 | 19:17:01 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:17:02 INFO - PID 1664 | RSS: Main: 293781504 19:17:02 INFO - PID 1664 | 19:17:05 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:17:05 INFO - PID 1664 | RSS: Main: 293994496 19:17:05 INFO - PID 1664 | 19:17:08 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:17:09 INFO - PID 1664 | RSS: Main: 293908480 19:17:09 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 19:17:11 INFO - PID 1664 | RSS: Main: 296288256 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | __start_tp_report 19:17:11 INFO - PID 1664 | _x_x_mozilla_page_load 19:17:11 INFO - PID 1664 | _x_x_mozilla_page_load_details 19:17:11 INFO - PID 1664 | |i|pagename|runs| 19:17:11 INFO - PID 1664 | |0;ai-astar;103;114;114;115;114;116;115;114;116;115 19:17:11 INFO - PID 1664 | |1;audio-beat-detection;139;109;112;146;116;138;136;113;138;110 19:17:11 INFO - PID 1664 | |2;audio-dft;198;198;195;201;209;229;219;228;269;284 19:17:11 INFO - PID 1664 | |3;audio-fft;80;60;77;112;56;57;57;61;76;56 19:17:11 INFO - PID 1664 | |4;audio-oscillator;82;70;86;72;69;68;88;68;69;95 19:17:11 INFO - PID 1664 | |5;imaging-gaussian-blur;88;93;89;88;90;87;88;90;89;129 19:17:11 INFO - PID 1664 | |6;imaging-darkroom;97;97;96;95;96;109;154;95;96;122 19:17:11 INFO - PID 1664 | |7;imaging-desaturate;90;93;126;141;90;91;95;90;91;93 19:17:11 INFO - PID 1664 | |8;json-parse-financial;51;49;52;54;51;48;51;48;51;50 19:17:11 INFO - PID 1664 | |9;json-stringify-tinderbox;57;57;55;55;58;59;56;56;57;62 19:17:11 INFO - PID 1664 | |10;stanford-crypto-aes;91;76;74;79;102;111;112;89;137;90 19:17:11 INFO - PID 1664 | |11;stanford-crypto-ccm;134;138;138;133;138;150;127;143;125;152 19:17:11 INFO - PID 1664 | |12;stanford-crypto-pbkdf2;178;184;185;187;187;184;194;186;193;220 19:17:11 INFO - PID 1664 | |13;stanford-crypto-sha256-iterative;70;70;65;66;86;89;69;59;68;66 19:17:11 INFO - PID 1664 | __end_tp_report 19:17:11 INFO - PID 1664 | __start_cc_report 19:17:11 INFO - PID 1664 | _x_x_mozilla_cycle_collect,1201 19:17:11 INFO - PID 1664 | __end_cc_report 19:17:11 INFO - PID 1664 | __startTimestamp1496369831363__endTimestamp 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | ------- Summary: start ------- 19:17:11 INFO - PID 1664 | Number of tests: 14 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#0] ai-astar Cycles:10 Average:113.60 Median:115.00 stddev:3.81 (3.3%) stddev-sans-first:0.83 19:17:11 INFO - PID 1664 | Values: 103.0 114.0 114.0 115.0 114.0 116.0 115.0 114.0 116.0 115.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:137.00 stddev:14.78 (10.8%) stddev-sans-first:14.87 19:17:11 INFO - PID 1664 | Values: 139.0 109.0 112.0 146.0 116.0 138.0 136.0 113.0 138.0 110.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#2] audio-dft Cycles:10 Average:223.00 Median:223.50 stddev:30.96 (13.9%) stddev-sans-first:31.49 19:17:11 INFO - PID 1664 | Values: 198.0 198.0 195.0 201.0 209.0 229.0 219.0 228.0 269.0 284.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#3] audio-fft Cycles:10 Average:69.20 Median:60.50 stddev:17.81 (29.4%) stddev-sans-first:18.45 19:17:11 INFO - PID 1664 | Values: 80.0 60.0 77.0 112.0 56.0 57.0 57.0 61.0 76.0 56.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:77.00 stddev:10.08 (13.1%) stddev-sans-first:10.51 19:17:11 INFO - PID 1664 | Values: 82.0 70.0 86.0 72.0 69.0 68.0 88.0 68.0 69.0 95.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:93.10 Median:89.00 stddev:12.72 (14.3%) stddev-sans-first:13.36 19:17:11 INFO - PID 1664 | Values: 88.0 93.0 89.0 88.0 90.0 87.0 88.0 90.0 89.0 129.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#6] imaging-darkroom Cycles:10 Average:105.70 Median:96.00 stddev:19.07 (19.9%) stddev-sans-first:19.96 19:17:11 INFO - PID 1664 | Values: 97.0 97.0 96.0 95.0 96.0 109.0 154.0 95.0 96.0 122.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#7] imaging-desaturate Cycles:10 Average:100.00 Median:91.00 stddev:18.08 (19.9%) stddev-sans-first:18.81 19:17:11 INFO - PID 1664 | Values: 90.0 93.0 126.0 141.0 90.0 91.0 95.0 90.0 91.0 93.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:51.00 stddev:1.84 (3.6%) stddev-sans-first:1.94 19:17:11 INFO - PID 1664 | Values: 51.0 49.0 52.0 54.0 51.0 48.0 51.0 48.0 51.0 50.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:57.20 Median:57.00 stddev:2.10 (3.7%) stddev-sans-first:2.22 19:17:11 INFO - PID 1664 | Values: 57.0 57.0 55.0 55.0 58.0 59.0 56.0 56.0 57.0 62.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#10] stanford-crypto-aes Cycles:10 Average:96.10 Median:77.50 stddev:19.67 (25.4%) stddev-sans-first:20.77 19:17:11 INFO - PID 1664 | Values: 91.0 76.0 74.0 79.0 102.0 111.0 112.0 89.0 137.0 90.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:137.80 Median:138.00 stddev:8.79 (6.4%) stddev-sans-first:9.22 19:17:11 INFO - PID 1664 | Values: 134.0 138.0 138.0 133.0 138.0 150.0 127.0 143.0 125.0 152.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.80 Median:187.00 stddev:11.55 (6.2%) stddev-sans-first:11.43 19:17:11 INFO - PID 1664 | Values: 178.0 184.0 185.0 187.0 187.0 184.0 194.0 186.0 193.0 220.0 19:17:11 INFO - PID 1664 | 19:17:11 INFO - PID 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:69.50 stddev:9.39 (13.5%) stddev-sans-first:9.96 19:17:11 INFO - PID 1664 | Values: 70.0 70.0 65.0 66.0 86.0 89.0 69.0 59.0 68.0 66.0 19:17:11 INFO - PID 1664 | -------- Summary: end -------- 19:17:11 INFO - PID 1664 | 19:17:12 INFO - TEST-INFO | 1664: exit 0 19:17:12 INFO - TEST-OK | kraken | took 89935ms 19:17:12 INFO - SUITE-END | took 1233s 19:17:12 INFO - Completed test suite (00:20:33) 19:17:12 INFO - javascript benchmark 19:17:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8198.504520057457, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3590.389633762261, 3610.729195310352, 3634.509341238935, 3641.563012438506, 3650.689691376233, 5064.670796398232, 6120.751898419022, 6135.447809697126, 6195.256569211695, 6202.689865506729, 6176.907346389797, 6180.351063138373, 6199.659018753967, 6219.720112594933, 6274.341194174614, 5390.6226564140525, 5391.406945236021, 5425.538829199503, 5430.348358197012, 5443.727813609324, 4542.41015644525, 4675.322078298645, 5440.863978400532, 5566.610337276392, 5593.04918163911, 5361.142217245222, 5458.181272809071, 5474.09677403228, 5476.3702174250075, 5606.915896261559, 5446.673199607996, 5454.0, 5486.588505862076, 5491.862703432406, 5501.477359650867, 5415.296011518478, 5433.510984011418, 5451.591130665176, 5463.0, 5475.890482190373, 6161.98327275999, 6195.132681424604, 6240.563160578761, 6243.344448832898, 6251.937480625181, 6476.417122459001, 6566.606003639797, 6583.341665833378, 6608.9339106608795, 6685.665716714194, 6424.421802037839, 6795.048693182958, 6807.5575087619645, 6814.454843612499, 6822.215445223813, 6347.016212487072, 6361.904571431386, 6363.013732871366, 6372.0, 6373.776917807854, 6708.83227919306, 6823.55646882961, 6869.175698916062, 6913.51605387618, 6950.7567235147535, 6323.715432805535, 6467.450266727286, 6572.737090516422, 6595.934040659532, 6645.933540664628, 6662.900056499059, 6788.93211067883, 6794.592324460548, 6833.897491537631, 6835.7949261522235, 5897.76408943646, 6679.833004174838, 6691.26396096429, 6718.731250750013, 6721.395074443323, 6467.547271691031, 6522.739090436329, 6606.942889137715, 6723.428508576826, 6743.2919543448215, 5357.249985002103, 5400.83797486076, 5418.647787893774, 5420.674759514362, 5426.9185962210595, 4813.15769740301, 4820.421549414023, 4871.488493708181, 4876.366072410564, 4887.706737595756, 6060.909036373407, 6212.627242365473, 6326.715297811609, 6327.462165715966, 6327.778527751599, 5718.0, 6699.631520266432, 6702.932970670231, 6812.863742725217, 6831.795046148524, 6304.306526282106, 6328.145700330489, 6344.365563443711, 6345.524085693592, 6361.745530178741, 5990.760369585168, 6001.12983617373, 6025.457708806229, 6026.306974697878, 6045.244344456943, 6634.0, 6837.692303846438, 6888.207856096613, 6935.202451718117, 6945.062416573799, 6771.593704377753, 6837.760678376233, 6852.828679283058, 6863.519553631198, 6901.827454313682, 6904.206016308189, 6957.930420695729, 6980.0, 6981.790546283416, 7022.894656580258, 6468.514861385321, 6562.310957349409, 6595.076689263411, 6739.66301684904, 6741.292164322872, 6773.491988100814, 6811.863762724817, 6816.420604248694, 6830.248672646105, 6838.384545390939, 6407.775727849503, 6604.14146160996, 6605.042268871083, 6616.933830661632, 6652.135222421054, 6473.643949582818, 6505.674716264262, 6584.76953306632, 6607.240167380813, 6641.03704962768, 6427.260865000585, 6454.709538070704, 6514.0, 6515.0, 6525.510586705921, 5452.918206226989, 6631.20425548918, 6635.568688035262, 6638.867222655616, 6660.6336651484635, 6257.342978987323, 6267.2165979252595, 6328.398802113792, 6338.112664227104, 6412.198475190601], "value": 6128.901906563534, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.5352547606793, 789.2699253190797, 846.3779122345076, 1250.7310928150446, 1298.883100520953, 38121.618783812155, 38662.613373866254, 38685.03287417816, 38781.03047423812, 38865.61134388655, 31081.0, 39092.0, 39156.41265381014, 39215.60784392155, 39240.80379598098, 7795.766127016199, 7992.3606111510935, 8227.54748488833, 8362.289205417546, 8430.367722420813, 8240.50556966581, 8306.169383061706, 8315.459495132827, 8317.209865062809, 8368.414211005233, 8267.0, 8277.917220827805, 8297.170282971685, 8344.457610255344, 8383.077861435237, 8203.753887383387, 8244.505329680209, 8271.503709777433, 8288.751337459886, 8325.375596830201, 8141.307988820958, 8189.50862948225, 8208.83582328356, 8250.092489826115, 8275.586220688941, 36774.26451470982, 36902.523899044005, 36932.07669808249, 37007.62992370069, 37045.70340038086, 3577.338192434382, 3578.35589593873, 3619.402798538238, 3629.4192929131477, 3632.146445585285, 30016.0, 30190.54714179289, 30225.546616800544, 30245.092647220397, 30283.545746813816, 3040.13356193486, 3095.829729364884, 3101.596792416994, 3105.192649911017, 3110.0358888744563, 239459.0, 243001.0, 258760.0, 259740.0, 268586.97119543224, 47833.0, 47875.0, 48036.519634803204, 48077.27884081742, 48113.037739245716, 40496.39255411171, 40990.59009409927, 47536.0, 50084.0, 55419.0, 14552.636184095481, 14664.413423462942, 14683.412663493555, 14687.41250349974, 14733.484328048467, 11871.46578403974, 11900.166988310735, 11945.641630751092, 11963.940180299043, 11997.460114294878, 14131.222782747047, 14152.29238538069, 14165.858341416453, 14209.857901420854, 14278.643033924027, 5112.258722485219, 6214.0, 6220.942439785247, 6241.25384830673, 6268.843278917972, 1006.1950439648252, 1029.4698230411343, 1037.5382954585136, 1057.9523921423554, 1064.1752641702587, 23542.17602383908, 24809.87595062013, 24821.379465513506, 24838.751612484004, 24910.875445622656, 1114.9331040137617, 1121.6466812953893, 1137.1698659978097, 1158.4207896051973, 1170.0931777872047, 11778.88221117795, 12189.512419503299, 12621.684457888627, 12628.242305461701, 12732.490700372067, 318965.0, 319626.0, 320342.0, 321499.0, 322388.0, 54357.45642543524, 54513.72743136259, 54896.902061958535, 55013.449865501636, 55988.720056399456, 1503.639126609606, 1513.2585033333808, 1548.9380424783108, 1549.5583758628736, 1558.1040901481692, 20881.477963050438, 24306.149284774947, 24380.51238975175, 24380.51238975246, 24501.0, 36074.819625901706, 37820.24359512849, 37963.24073518459, 38049.239015218984, 38054.23891522099, 240856.0, 273763.0, 274141.0, 274555.0, 275452.24547754263, 34125.0, 35761.46357804583, 35768.463473047406, 35962.820185898905, 36721.08197295089, 44726.74618220938, 45990.310145348514, 46017.619471415914, 46143.76928115338, 46409.0, 28490.287742806595, 29287.0, 31420.842895785376, 31422.528662070545, 31518.527222091227, 28130.71869281281, 28824.567631485963, 30627.0, 31480.527792082677, 31631.0, 36265.818670906476, 37030.8148459256, 37675.62324376721, 37778.055548610406, 37810.43284350792, 31176.68823311738, 31616.841915790275, 32043.0, 32098.83950580232, 32404.351912961138, 22413.0, 22822.087116515155, 24516.0, 24529.01883924623, 24662.50674986526], "value": 17509.507929201314, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.5075930466569, 404.3974478027738, 429.40312964978705, 441.7680717623247, 461.66990601719766, 11870.406479676025, 15337.92331038346, 21998.45003874902, 22507.662385064217, 22800.54398912023, 22728.204512842054, 23529.647055294183, 23566.646500302508, 23611.40971475714, 23704.64443033356, 5975.731092100866, 6074.42292982166, 6152.477039451652, 6152.5693201475915, 6161.045038019114, 6031.758729650809, 6144.44699977002, 6153.230846144232, 6153.815385538419, 6156.0, 6106.023036314191, 6118.785842495514, 6342.429181373675, 6674.966625166868, 6753.797386078401, 6697.397234248917, 6757.121574195372, 6777.728890844361, 6786.423154031913, 6796.524243302946, 6613.900791488131, 6624.635645039521, 6627.304133066007, 6628.767993120266, 6637.103990961206, 21940.0, 29472.26319342012, 29734.70265297341, 29872.10383688493, 29872.253193670116, 2291.3126062181277, 2332.696749422564, 2337.3922780077132, 2356.22244659262, 2358.469344397514, 19042.904785476123, 19325.613487730167, 19591.314303999436, 19600.50998725029, 19640.821550707, 2267.3651377614297, 2286.737025242102, 2327.837051406402, 2351.0360752091733, 2351.2005917987967, 142701.2864935679, 157311.2134439332, 181268.0, 181432.0, 181659.0, 18818.221292779494, 25041.74958250394, 25227.58517259472, 26469.941202352074, 26510.602340964902, 32632.510512342335, 33999.13004784711, 38299.0, 39732.40401393981, 40972.0, 9730.610775568897, 10810.729731756768, 11005.600194789422, 11010.229283950206, 11113.611023614134, 8761.09667708239, 8792.560371981375, 8837.469751814911, 8875.068117847663, 8879.422837515542, 7806.414518911104, 11689.006434453167, 11745.88254117465, 11747.063234191452, 11809.527618895148, 1945.8130540370362, 2205.106931692667, 2278.4873403484085, 2279.50990537032, 2478.91323803666, 834.2783492279256, 959.467495539982, 970.1075010989906, 989.475577943691, 1008.5965613754557, 20696.58606827855, 21126.47183820417, 21135.471613209484, 21399.39912918699, 21462.0, 997.2072202598952, 1003.8795344558554, 1021.0606242257142, 1026.3072426112344, 1029.1818004686293, 10737.83893241602, 10824.13406927444, 10860.565577376816, 10879.891201088047, 11071.557137714562, 553705.0, 558537.4146258567, 562193.1890340522, 564674.0, 565813.0, 117515.0, 149204.0, 150066.0, 150824.0, 151396.0, 1157.455995682028, 1158.3744777820052, 1201.195199216527, 1209.1596340543415, 1212.4180393411211, 21251.256206032715, 21880.405114926045, 21963.23128690488, 22628.886855566274, 22734.0, 67244.66377668276, 69927.0, 69945.65027174831, 70594.0, 70603.0, 93986.53006734923, 94657.3027827465, 94783.0, 94958.52520737352, 97402.5129874346, 16249.756253655969, 16573.337066517204, 16647.833521665114, 16664.0, 16689.49931502057, 13335.86664133385, 17820.46538603844, 18319.35882244115, 18381.264749409875, 18462.692163479565, 14846.257687115385, 14939.77590336124, 15059.247037647854, 15081.24593770329, 15086.773698394314, 12615.558455454382, 13151.145175563941, 13152.276624785345, 13169.868301317247, 13303.0, 14071.718565628835, 14871.405143794127, 15100.773488397903, 15118.09291442517, 15216.543503694907, 12989.350532473149, 15766.211689415713, 16098.758518622464, 16295.59261018484, 16311.26599303058, 14403.279836007949, 14472.27638618086, 14492.927535362678, 14713.55859324222, 14732.116073035651], "value": 12194.233365274631, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1230.8276841242227, 1455.272363818091, 1462.7001464699738, 1466.9193194374304, 1478.4086365453813, 11697.473613687383, 11888.821667674993, 12176.96495797856, 12205.633830985062, 12272.693182670439, 12018.0, 12206.0, 12211.938940305308, 12225.0, 12269.447874845631, 2516.5847635140226, 2526.431552900601, 2553.4382435864172, 2554.1571281477068, 2556.4248044190094, 2496.613024981131, 2539.1240022192405, 2541.59334506479, 2543.0717788007337, 2553.69355677319, 2508.4356019895467, 2523.7602427769425, 2531.0255551612577, 2532.8860201290895, 2532.9240122796346, 2469.296250568588, 2488.726240113586, 2501.6747822782922, 2503.2615378463397, 2506.373406648338, 2426.8543887366814, 2474.208253358926, 2476.6656501372445, 2484.3416494628937, 2526.12848567244, 11431.771364572662, 11589.884101158965, 11599.710007249801, 11618.767624647544, 11627.883721162765, 6442.4201821835795, 6596.208454985384, 6683.93316066838, 6691.933080669229, 6710.194776626788, 4085.7344272622186, 4266.616004559573, 4334.393184954109, 4336.284513055342, 4353.151135528573, 1252.6993521554853, 1256.9811452828214, 1258.3519487463964, 1278.086168389606, 1280.7054377493137, 15286.388544458208, 15707.764383534142, 16040.0, 16066.919665401716, 16358.345666173338, 5974.880502390015, 5991.400859913973, 6112.358202388774, 6167.748771698298, 6188.845278868064, 13851.51519696826, 13868.93065534666, 13880.375383107785, 13895.930520347334, 13900.096493727875, 6593.439557637558, 6672.666366681647, 6690.464762818963, 6695.230048544479, 6712.932870671329, 6050.183225264533, 6142.723577439026, 6153.815385538441, 6171.506279497629, 6219.906701399483, 4720.787564559603, 4767.308740232646, 4814.133455977958, 4853.417589889236, 4860.708357498562, 1445.9566213013627, 1482.0959214878915, 1485.3984136424765, 1495.611141103321, 1500.8499150085122, 741.1995045351008, 749.8162950077266, 756.8788993760971, 757.4849102610278, 765.9888946590559, 5871.9706401468575, 6048.304444988795, 6100.603460775035, 6994.720211191496, 7055.2591977841585, 823.3783493462518, 857.725527831088, 878.9648414063494, 886.4149661223562, 929.1730359979623, 4032.354823228269, 4296.097819457948, 4388.188185185751, 4411.360352748833, 4494.70784399013, 14502.85497145036, 14870.479533216285, 15000.099994000395, 15144.469943552143, 15181.696366072838, 14886.032407893668, 15079.623009424633, 15094.924525377523, 15105.47130850437, 15178.392864285304, 7491.138519070268, 8145.837083258301, 8434.0, 8583.871241931376, 8764.517951512727, 5133.79464821403, 6090.116933044772, 6179.980303249913, 6733.6296503692765, 6744.123263975652, 11415.491178263015, 13310.600681979557, 13331.0, 13487.25820079905, 13665.0, 11309.830352544554, 13423.395947182205, 13685.836703879884, 13892.930535347663, 14086.506972256331, 9422.670206542738, 9596.616135354509, 9609.567569459252, 9612.855807162758, 9721.805563888824, 10329.431881246604, 10519.0, 10631.468426578795, 10713.839292410465, 10754.83867742, 8951.0, 9166.0, 9193.402428842104, 9210.355275130676, 9261.398009129385, 7518.436117291334, 8763.956180219058, 8888.688895888612, 8991.505467199368, 9027.0, 6948.270431604811, 7861.960690196513, 8048.798780030546, 8051.879221811795, 8053.718119865776, 5896.882062358814, 5972.850678733066, 6023.096535519707, 6087.57386982907, 6117.571769976059, 5228.895422091461, 5578.30271216098, 6003.309619393825, 6179.62922224668, 6216.564840461125], "value": 5425.2145811931405, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.4597471590341, 388.6931064134362, 396.79168436570797, 404.06459047305503, 408.7261534771704, 5593.52455041321, 5725.396888871122, 5837.357890632027, 6369.363063693634, 6405.19935008124, 5366.302380690505, 5570.303712035996, 5890.381509941459, 6259.874802503959, 6280.937190628092, 4501.189785838539, 4582.739746569687, 4644.60520855726, 4664.463586687541, 4669.369635099269, 3918.686505079587, 3919.451276821247, 4567.155076310876, 4606.239970404879, 4618.983823558811, 4624.3988281523525, 4629.166749985001, 4641.164590373732, 4641.559051890065, 4646.72119672819, 4597.908041839178, 4625.421822272216, 4629.3518907353, 4636.632193502925, 4649.511801260886, 3555.3600351936643, 3802.6387493188104, 3982.522097348308, 3994.3409337459284, 3998.3602623580086, 5623.818998010423, 6208.534359923024, 6234.283057448406, 6241.095041219043, 6262.655553944532, 5441.401445840955, 5514.007478653841, 5514.6139770216105, 5519.69641669708, 5550.5282051025715, 5324.41431442541, 6791.083203767527, 6801.761938332184, 6805.693743781541, 6823.317668233187, 4509.210888094603, 4519.457665080211, 4527.592516673482, 4528.547145285478, 4539.455265368177, 6686.933130668728, 6814.216365118025, 6967.338102880219, 7091.397231235351, 7249.223940134648, 4081.5714349992822, 4183.748975061445, 4500.189965806187, 4575.794089265991, 4576.4508259009135, 4242.830286788556, 4296.677749168763, 4670.696404733681, 4671.369365135731, 4671.813127474931, 5316.3354580677415, 5418.972905135449, 5438.102713052302, 5444.428335024765, 5460.235567020621, 6669.666516674244, 6709.0, 6713.832154196184, 6728.865422691617, 6747.908148473989, 1317.4005827348533, 1575.141547856421, 1578.486991727693, 1587.4126573168003, 1589.6805651309385, 1024.6874703215442, 1045.9215558833118, 1061.9044286014296, 1067.295584913953, 1069.1874175626424, 5700.201971723962, 6233.657148856856, 6280.842978925563, 6295.60766908581, 6322.873542529123, 5731.799387021434, 6999.510034297652, 7028.332308430691, 7032.769265378538, 7032.964835175791, 6220.813375598739, 6276.560640755103, 6291.1192433059405, 6292.835825372321, 6310.62136271816, 3528.558930133733, 3613.6567026132475, 3634.236810269873, 3638.308721342936, 3664.8167591620313, 6981.2669669683955, 7166.15957994233, 7171.49799514029, 7203.279672032755, 7223.205447400782, 6318.304986451487, 6946.131733533309, 7199.820004499825, 7212.0, 7218.350348468558, 5936.9406305937255, 6957.582545047313, 7172.49792514519, 7188.964055179691, 7189.584947150166, 6285.277193122849, 6962.686679099352, 7004.824879378057, 7011.964940175267, 7043.225245223124, 6667.533272670867, 6782.93217067823, 6826.795196144124, 6853.211880633591, 6858.5199036067, 6050.364711705208, 6604.273529911802, 6981.301869813181, 7227.891581626174, 7302.96348518254, 3809.771413715186, 4114.897127571715, 4582.633389328779, 4598.275646632513, 4599.770011499345, 6645.9003114954285, 6846.383825455733, 7020.297970202939, 7026.824329391601, 7054.682539285847, 5711.685857277889, 6362.522810789302, 6736.5958042517605, 6744.7976560703255, 6750.932490675031, 6482.319356467691, 6483.994980777987, 6688.464922806261, 6728.697208625722, 6742.022406751092, 5783.248177736868, 5827.766889324549, 6281.748730050929, 6568.638724870178, 6670.731951136637, 5472.91790623133, 5655.773769049192, 6764.0, 6772.98405239218, 6815.659217039029, 6232.376762323732, 6599.637019963948, 6612.272650008592, 6614.4943180114615, 6621.1392518972225], "value": 4885.449336514661, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [822.9876551851722, 823.3413269384491, 843.4896887383129, 854.069064719456, 868.3921255121414, 14346.210958397283, 14882.627934301649, 16857.90423622465, 16992.320307187714, 17223.39718109868, 16064.0, 17693.1422200669, 17782.377616783415, 17794.377196798116, 17833.0, 946.8106378724249, 988.2538683294139, 1010.0909181736423, 1013.3565186106802, 1032.8244198486275, 1018.5925629748086, 1024.1141412678016, 1024.4007255755355, 1024.8001639680265, 1026.7997740440617, 929.5305870535404, 1051.2168434516295, 1069.593554449312, 1076.042322333124, 1079.1205167788266, 1053.810314143454, 1069.7004838645191, 1070.143884892087, 1071.6410002649122, 1078.4553800330782, 759.8442319324525, 773.9303462688329, 782.8786538086606, 783.956882371475, 784.4116912315764, 16750.497485075466, 16974.745378819327, 17055.232514536874, 17082.572935676584, 17249.741253881202, 17049.91475042629, 17316.826831731647, 17573.56066098345, 17589.824101758946, 17606.295748170185, 14689.92655036718, 14749.483768068067, 14861.0, 14969.176695281754, 14971.251437428085, 3628.727845411578, 3651.835667394974, 3654.6162652921325, 3660.633936606318, 3851.691864650821, 134869.3256533721, 136504.0, 137546.312268439, 138101.0, 138970.3051484746, 11765.705857353463, 13067.0, 13420.932895335462, 13574.864251357558, 13683.179009259477, 25093.498130037297, 26056.0, 26071.087511936992, 26083.0, 26094.9562017521, 7310.853782924311, 7463.290987356192, 7719.884201736979, 7800.375969922393, 7819.843603127905, 3728.7203459740626, 3757.248550289953, 3887.980560097238, 3986.0034991252187, 3994.1013272014147, 4372.4315838941375, 4395.296752519645, 4396.428464299621, 4401.361802538678, 4457.9331310030375, 783.2206954080726, 857.5583574459158, 896.7668406214431, 902.5442151713474, 902.5712786426526, 600.0639003155092, 626.3423405424286, 648.303074195242, 652.4160876016034, 662.7050962321817, 11230.94384528072, 12038.036957043416, 12146.574869879689, 12289.938550307192, 12384.504619815287, 643.793985924509, 644.7936660268668, 649.0523835200565, 659.3604203922188, 673.8820706376365, 5528.364238112588, 5537.861553461195, 5656.727236371866, 5667.291588551431, 5703.515201207943, 128530.0, 138022.0, 142355.0, 143632.28183859013, 144906.0, 118754.40622796831, 120136.39931800285, 122243.0, 122331.0, 122984.15523767151, 99129.0, 101662.96674066478, 101982.0, 102458.0, 102841.0, 10182.541785619516, 10899.891001089887, 11014.07253730578, 11235.438228088726, 11338.773224535627, 43649.690509284774, 43902.0, 44278.0, 44289.557104428546, 44307.33538996982, 61162.0, 61575.384246156966, 61884.07173892305, 67751.66124169348, 74614.13464663427, 16995.23521441563, 17103.657926841643, 17308.740368894727, 17678.292868285123, 17929.282828687228, 18776.718349225044, 18893.811061889755, 18913.905430472758, 19084.904575477034, 19127.808721912603, 17211.913940430717, 17380.13099345053, 17704.114794260495, 17862.285508579513, 17900.0, 16734.4142954996, 17433.302667893142, 18014.2794288227, 18089.276428942696, 18185.363512277007, 17035.0, 17254.741178882578, 17591.120443978005, 17796.288148474436, 17850.01824899591, 11420.771584568429, 11809.350485723367, 12071.215371000857, 12315.876841231831, 12339.383030848601, 13663.658408539866, 14038.087524311295, 14150.575482735534, 14202.218877961812, 14400.20798856031], "value": 8754.741059406182, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1509.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 115.0, 114.0, 116.0, 115.0, 114.0, 116.0, 115.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 112.0, 146.0, 116.0, 138.0, 136.0, 113.0, 138.0, 110.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 195.0, 201.0, 209.0, 229.0, 219.0, 228.0, 269.0, 284.0], "value": 223.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 77.0, 112.0, 56.0, 57.0, 57.0, 61.0, 76.0, 56.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 86.0, 72.0, 69.0, 68.0, 88.0, 68.0, 69.0, 95.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 89.0, 88.0, 90.0, 87.0, 88.0, 90.0, 89.0, 129.0], "value": 93.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 95.0, 96.0, 109.0, 154.0, 95.0, 96.0, 122.0], "value": 105.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 126.0, 141.0, 90.0, 91.0, 95.0, 90.0, 91.0, 93.0], "value": 100.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 52.0, 54.0, 51.0, 48.0, 51.0, 48.0, 51.0, 50.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 55.0, 58.0, 59.0, 56.0, 56.0, 57.0, 62.0], "value": 57.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 76.0, 74.0, 79.0, 102.0, 111.0, 112.0, 89.0, 137.0, 90.0], "value": 96.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 138.0, 138.0, 133.0, 138.0, 150.0, 127.0, 143.0, 125.0, 152.0], "value": 137.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 184.0, 185.0, 187.0, 187.0, 184.0, 194.0, 186.0, 193.0, 220.0], "value": 189.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 65.0, 66.0, 86.0, 89.0, 69.0, 59.0, 68.0, 66.0], "value": 70.8, "unit": "score"}]}]} 19:17:12 INFO - Return code: 0 19:17:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:17:12 INFO - # TBPL SUCCESS # 19:17:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:17:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:17:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:17:12 INFO - 2017-06-01 19:17:12.741 screenresolution[1672:11488] starting screenresolution argv=screenresolution get 19:17:12 INFO - 2017-06-01 19:17:12.743 screenresolution[1672:11488] Display 0: 1600x1200x32@60 19:17:12 INFO - 2017-06-01 19:17:12.749 screenresolution[1673:11491] starting screenresolution argv=screenresolution list 19:17:12 INFO - Available Modes on Display 0 19:17:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:17:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:17:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:17:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:17:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:17:12 INFO - Intel Iris: 19:17:12 INFO - Chipset Model: Intel Iris 19:17:12 INFO - Type: GPU 19:17:12 INFO - Bus: Built-In 19:17:12 INFO - VRAM (Dynamic, Max): 1536 MB 19:17:12 INFO - Vendor: Intel (0x8086) 19:17:12 INFO - Device ID: 0x0a2e 19:17:12 INFO - Revision ID: 0x0009 19:17:12 INFO - Displays: 19:17:12 INFO - Display: 19:17:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:17:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:17:12 INFO - Display Serial Number: 1600x1200 60 19:17:12 INFO - Main Display: Yes 19:17:12 INFO - Mirror: Off 19:17:12 INFO - Online: Yes 19:17:12 INFO - Rotation: Supported 19:17:12 INFO - Return code: 0 19:17:12 INFO - Running post-action listener: _resource_record_post_action 19:17:12 INFO - [mozharness: 2017-06-02 02:17:12.985993Z] Finished run-tests step (success) 19:17:12 INFO - Running post-run listener: _resource_record_post_run 19:17:13 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 33616896; Write bytes: 570977280; Read time: 217; Write time: 1751 19:17:13 INFO - TinderboxPrint: CPU usage
53.1% 19:17:13 INFO - TinderboxPrint: I/O read bytes / time
33,616,896 / 217 19:17:13 INFO - TinderboxPrint: I/O write bytes / time
570,977,280 / 1,751 19:17:13 INFO - TinderboxPrint: CPU idle
2,353.3 (46.9%) 19:17:13 INFO - TinderboxPrint: CPU system
1,005.9 (20.0%) 19:17:13 INFO - TinderboxPrint: CPU user
1,662.4 (33.1%) 19:17:13 INFO - TinderboxPrint: Swap in / out
276,283,392 / 0 19:17:13 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 237249024; Write bytes: 229568512; Read time: 16986; Write time: 468 19:17:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:17:13 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 31650816; Write bytes: 323070976; Read time: 199; Write time: 1235 19:17:13 INFO - Running post-run listener: _upload_blobber_files 19:17:13 INFO - Blob upload gear active. 19:17:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:17:13 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:17:13 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:17:13 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:17:13 INFO - (blobuploader) - INFO - Open directory for files ... 19:17:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:17:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:17:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:20 INFO - (blobuploader) - INFO - Done attempting. 19:17:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:17:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:17:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:20 INFO - (blobuploader) - INFO - Done attempting. 19:17:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:17:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:17:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:21 INFO - (blobuploader) - INFO - Done attempting. 19:17:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:17:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:17:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:23 INFO - (blobuploader) - INFO - Done attempting. 19:17:23 INFO - (blobuploader) - INFO - Iteration through files over. 19:17:23 INFO - Return code: 0 19:17:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:17:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:17:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebcb9b767f147d1e35ae8bedd29cc38795868b09f5a600e4fc1d39f206c6348ccc0afb19edb60a157daecd63e3c195c0517386b7bc61aac5fc4636f398179de1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b001b2c75730da4a6b039a8ba7845f8c3bb7f3da964b56143ff0d3ed641007f8d9680bd5c1542f4796c102bed71ffad77e95c11e5d354589dc600f9d42f4d83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3f63d9e4c2c5e2f8373f237d850c9ddc4f3c3bf1b56c0f5c7b13bcd6145649af3b9e437688316389f85e408c1b19ca11448d5a58ecb9ee2fea3a161baf5ff38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a186ea25cf17d6ba1a6bdb4851e6a0ad6e1007b4d08b446fdc4eed933c4985cd63fdc6b5bcc95bfbce49b8d9e5fca0f348376242058b32f3d0205e8d879c4d08"} 19:17:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:17:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:17:23 INFO - Contents: 19:17:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebcb9b767f147d1e35ae8bedd29cc38795868b09f5a600e4fc1d39f206c6348ccc0afb19edb60a157daecd63e3c195c0517386b7bc61aac5fc4636f398179de1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b001b2c75730da4a6b039a8ba7845f8c3bb7f3da964b56143ff0d3ed641007f8d9680bd5c1542f4796c102bed71ffad77e95c11e5d354589dc600f9d42f4d83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3f63d9e4c2c5e2f8373f237d850c9ddc4f3c3bf1b56c0f5c7b13bcd6145649af3b9e437688316389f85e408c1b19ca11448d5a58ecb9ee2fea3a161baf5ff38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a186ea25cf17d6ba1a6bdb4851e6a0ad6e1007b4d08b446fdc4eed933c4985cd63fdc6b5bcc95bfbce49b8d9e5fca0f348376242058b32f3d0205e8d879c4d08"} 19:17:23 INFO - Running post-run listener: copy_logs_to_upload_dir 19:17:23 INFO - Copying logs to upload dir... 19:17:23 INFO - mkdir: /builds/slave/test/build/upload/logs 19:17:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1343.243074 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2017-06-01 19:17:23.856112) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 19:17:23.864051) ========= 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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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/ebcb9b767f147d1e35ae8bedd29cc38795868b09f5a600e4fc1d39f206c6348ccc0afb19edb60a157daecd63e3c195c0517386b7bc61aac5fc4636f398179de1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b001b2c75730da4a6b039a8ba7845f8c3bb7f3da964b56143ff0d3ed641007f8d9680bd5c1542f4796c102bed71ffad77e95c11e5d354589dc600f9d42f4d83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3f63d9e4c2c5e2f8373f237d850c9ddc4f3c3bf1b56c0f5c7b13bcd6145649af3b9e437688316389f85e408c1b19ca11448d5a58ecb9ee2fea3a161baf5ff38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a186ea25cf17d6ba1a6bdb4851e6a0ad6e1007b4d08b446fdc4eed933c4985cd63fdc6b5bcc95bfbce49b8d9e5fca0f348376242058b32f3d0205e8d879c4d08"} build_url:https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010770 build_url: 'https://queue.taskcluster.net/v1/task/c8gvq0tTTpO-1Y1va4HvZg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebcb9b767f147d1e35ae8bedd29cc38795868b09f5a600e4fc1d39f206c6348ccc0afb19edb60a157daecd63e3c195c0517386b7bc61aac5fc4636f398179de1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b001b2c75730da4a6b039a8ba7845f8c3bb7f3da964b56143ff0d3ed641007f8d9680bd5c1542f4796c102bed71ffad77e95c11e5d354589dc600f9d42f4d83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3f63d9e4c2c5e2f8373f237d850c9ddc4f3c3bf1b56c0f5c7b13bcd6145649af3b9e437688316389f85e408c1b19ca11448d5a58ecb9ee2fea3a161baf5ff38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a186ea25cf17d6ba1a6bdb4851e6a0ad6e1007b4d08b446fdc4eed933c4985cd63fdc6b5bcc95bfbce49b8d9e5fca0f348376242058b32f3d0205e8d879c4d08"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 19:17:23.896279) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:17:23.896564) ========= 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.ZyvxGEHy2k/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.b8RZsW6wIR/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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.004619 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:17:23.947287) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 19:17:23.947594) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 19:17:23.950928) ========= ========= Total master_lag: 0.19 =========