builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0308 starttime: 1496195685.46 results: success (0) revision: 9a5c710587f9e64bf044602d5e988b152ef4f40c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:45.456700) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:45.457141) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:45.457416) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:45.482510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:45.482872) ========= 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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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-05-30 18:54:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2017-05-30 18:54:45 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:45.603333) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:45.603642) ========= 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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.027093 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:45.656471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-30 18:54:45.656825) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9a5c710587f9e64bf044602d5e988b152ef4f40c --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 9a5c710587f9e64bf044602d5e988b152ef4f40c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7WlqEv3S8X/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.zOjk9qGMxM/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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-05-30 18:54:45,753 truncating revision to first 12 chars 2017-05-30 18:54:45,753 Setting DEBUG logging. 2017-05-30 18:54:45,753 attempt 1/10 2017-05-30 18:54:45,753 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9a5c710587f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 18:54:47,633 unpacking tar archive at: mozilla-central-9a5c710587f9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.163323 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-30 18:54:47.842162) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:47.842474) ========= script_repo_revision: 9a5c710587f9e64bf044602d5e988b152ef4f40c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:47.842870) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:47.843146) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 18:54:47.861831) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-05-30 18:54:47.862214) ========= /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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:54:48 INFO - MultiFileLogger online at 20170530 18:54:48 in /builds/slave/test 18:54:48 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:54:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:54:48 INFO - {'append_to_log': False, 18:54:48 INFO - 'base_work_dir': '/builds/slave/test', 18:54:48 INFO - 'blob_upload_branch': 'Firefox', 18:54:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:54:48 INFO - 'branch': 'Firefox', 18:54:48 INFO - 'buildbot_json_path': 'buildprops.json', 18:54:48 INFO - 'config_files': ('talos/mac_config.py',), 18:54:48 INFO - 'default_actions': ('clobber', 18:54:48 INFO - 'read-buildbot-config', 18:54:48 INFO - 'download-and-extract', 18:54:48 INFO - 'populate-webroot', 18:54:48 INFO - 'create-virtualenv', 18:54:48 INFO - 'install', 18:54:48 INFO - 'setup-mitmproxy', 18:54:48 INFO - 'run-tests'), 18:54:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:54:48 INFO - 'download_minidump_stackwalk': True, 18:54:48 INFO - 'download_symbols': 'ondemand', 18:54:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:54:48 INFO - 'tooltool.py': '/tools/tooltool.py', 18:54:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:54:48 INFO - '/tools/misc-python/virtualenv.py')}, 18:54:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:54:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:54:48 INFO - 'gecko_profile': False, 18:54:48 INFO - 'gecko_profile_interval': 0, 18:54:48 INFO - 'installer_path': 'installer.exe', 18:54:48 INFO - 'log_level': 'info', 18:54:48 INFO - 'log_name': 'talos', 18:54:48 INFO - 'log_to_console': True, 18:54:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:54:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:54:48 INFO - 'opt_config_files': (), 18:54:48 INFO - 'pip_index': False, 18:54:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:54:48 INFO - 'cmd': ('bash', 18:54:48 INFO - '-c', 18:54:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:54:48 INFO - 'enabled': True, 18:54:48 INFO - 'halt_on_failure': False, 18:54:48 INFO - 'name': 'check_screen_resolution'},), 18:54:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:54:48 INFO - 'cmd': ('bash', 18:54:48 INFO - '-c', 18:54:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:54:48 INFO - 'enabled': True, 18:54:48 INFO - 'halt_on_failure': False, 18:54:48 INFO - 'name': 'check_screen_resolution'},), 18:54:48 INFO - 'run_cmd_checks_enabled': True, 18:54:48 INFO - 'suite': 'dromaeojs-e10s', 18:54:48 INFO - 'system_bits': '32', 18:54:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:54:48 INFO - 'title': 't-yosemite-r7-0308', 18:54:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:54:48 INFO - 'use_talos_json': True, 18:54:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:54:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:54:48 INFO - 'work_dir': 'build'} 18:54:48 INFO - [mozharness: 2017-05-31 01:54:48.069430Z] Running clobber step. 18:54:48 INFO - Running pre-action listener: _resource_record_pre_action 18:54:48 INFO - Running main action method: clobber 18:54:48 INFO - rmtree: /builds/slave/test/build 18:54:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:54:50 INFO - Running post-action listener: _resource_record_post_action 18:54:50 INFO - [mozharness: 2017-05-31 01:54:50.532960Z] Finished clobber step (success) 18:54:50 INFO - [mozharness: 2017-05-31 01:54:50.533153Z] Running read-buildbot-config step. 18:54:50 INFO - Running pre-action listener: _resource_record_pre_action 18:54:50 INFO - Running main action method: read_buildbot_config 18:54:50 INFO - Using buildbot properties: 18:54:50 INFO - { 18:54:50 INFO - "project": "", 18:54:50 INFO - "product": "firefox", 18:54:50 INFO - "who": "ryanvm@gmail.com", 18:54:50 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:54:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:54:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:54:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:54:50 INFO - "stage_platform": "macosx64", 18:54:50 INFO - "basedir": "/builds/slave/test", 18:54:50 INFO - "buildnumber": 38, 18:54:50 INFO - "platform": "yosemite_r7", 18:54:50 INFO - "parent_task_id": "AweIWHGYREGposN6LvIM7g", 18:54:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:54:50 INFO - "slavebuilddir": "test", 18:54:50 INFO - "taskId": "VeNQjIXLT52FNgn4Oeq0Iw", 18:54:50 INFO - "branch": "mozilla-central", 18:54:50 INFO - "script_repo_revision": "production", 18:54:50 INFO - "revision": "9a5c710587f9e64bf044602d5e988b152ef4f40c", 18:54:50 INFO - "slavename": "t-yosemite-r7-0308", 18:54:50 INFO - "repo_path": "mozilla-central" 18:54:50 INFO - } 18:54:50 INFO - Finding installer, test and symbols from parent task. 18:54:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VeNQjIXLT52FNgn4Oeq0Iw'}, attempt #1 18:54:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VeNQjIXLT52FNgn4Oeq0Iw'}, attempt #1 18:54:51 INFO - Task dependencies: FBaBIXx4TpKnuxsEi8RNwg AweIWHGYREGposN6LvIM7g 18:54:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g'}, attempt #1 18:54:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:54:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/test_packages.json 18:54:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/target.crashreporter-symbols.zip 18:54:52 INFO - Running post-action listener: _resource_record_post_action 18:54:52 INFO - [mozharness: 2017-05-31 01:54:52.295490Z] Finished read-buildbot-config step (success) 18:54:52 INFO - [mozharness: 2017-05-31 01:54:52.295616Z] Running download-and-extract step. 18:54:52 INFO - Running pre-action listener: _resource_record_pre_action 18:54:52 INFO - Running main action method: download_and_extract 18:54:52 INFO - mkdir: /builds/slave/test/build/tests 18:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:52 INFO - trying https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:54:52 INFO - Downloading https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/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:54:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/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:54:53 INFO - Downloaded 1325 bytes. 18:54:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:54:53 INFO - Using the following test package requirements: 18:54:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:54:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:54:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:54:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:54:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'jsshell-mac.zip'], 18:54:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:54:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:54:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:54:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:54:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:54:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:54:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:54:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:54:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:54:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:54:53 INFO - Fetch https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:54:54 INFO - Content-Length response header: 57633406 18:54:54 INFO - Bytes received: 57633406 18:55:12 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:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:55:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:12 INFO - Fetch https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:55:15 INFO - Content-Length response header: 57633406 18:55:15 INFO - Bytes received: 57633406 18:55:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:55:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:55:32 INFO - Fetch https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:55:33 INFO - Content-Length response header: 14447425 18:55:33 INFO - Bytes received: 14447425 18:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:33 INFO - trying https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:33 INFO - Downloading https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:55:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/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:35 INFO - Downloaded 67880633 bytes. 18:55:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:35 INFO - mkdir: /builds/slave/test/properties 18:55:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:55:35 INFO - Writing to file /builds/slave/test/properties/build_url 18:55:35 INFO - Contents: 18:55:35 INFO - build_url:https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:35 INFO - Running post-action listener: _resource_record_post_action 18:55:35 INFO - Running post-action listener: set_extra_try_arguments 18:55:35 INFO - [mozharness: 2017-05-31 01:55:35.638943Z] Finished download-and-extract step (success) 18:55:35 INFO - [mozharness: 2017-05-31 01:55:35.639062Z] Running populate-webroot step. 18:55:35 INFO - Running pre-action listener: _resource_record_pre_action 18:55:35 INFO - Running main action method: populate_webroot 18:55:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:55:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:55:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:55:35 INFO - '--tppagecycles', 18:55:35 INFO - '7'], 18:55:35 INFO - 'tests': ['tsvgm']}}, 18:55:35 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:55:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:55:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:55:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:55:35 INFO - 'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:55:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:55:35 INFO - 'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['damp', 'tps']}, 18:55:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:55:35 INFO - 'tests': ['damp', 'tps']}, 18:55:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['dromaeo_dom']}, 18:55:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:55:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:55:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:55:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:55:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:55:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['a11yr', 18:55:35 INFO - 'ts_paint', 18:55:35 INFO - 'tpaint', 18:55:35 INFO - 'sessionrestore', 18:55:35 INFO - 'sessionrestore_no_auto_restore', 18:55:35 INFO - 'tabpaint']}, 18:55:35 INFO - 'other-e10s': {'tests': ['a11yr', 18:55:35 INFO - 'ts_paint', 18:55:35 INFO - 'tpaint', 18:55:35 INFO - 'sessionrestore', 18:55:35 INFO - 'sessionrestore_no_auto_restore', 18:55:35 INFO - 'tabpaint']}, 18:55:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:55:35 INFO - 'ts_paint', 18:55:35 INFO - 'tpaint', 18:55:35 INFO - 'sessionrestore', 18:55:35 INFO - 'sessionrestore_no_auto_restore', 18:55:35 INFO - 'tabpaint']}, 18:55:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:55:35 INFO - 'ts_paint', 18:55:35 INFO - 'tpaint', 18:55:35 INFO - 'sessionrestore', 18:55:35 INFO - 'sessionrestore_no_auto_restore', 18:55:35 INFO - 'tabpaint']}, 18:55:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['a11yr', 18:55:35 INFO - 'ts_paint', 18:55:35 INFO - 'tpaint', 18:55:35 INFO - 'sessionrestore', 18:55:35 INFO - 'sessionrestore_no_auto_restore', 18:55:35 INFO - 'tabpaint']}, 18:55:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['a11yr', 18:55:35 INFO - 'ts_paint', 18:55:35 INFO - 'tpaint', 18:55:35 INFO - 'sessionrestore', 18:55:35 INFO - 'sessionrestore_no_auto_restore', 18:55:35 INFO - 'tabpaint']}, 18:55:35 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:55:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:55:35 INFO - 'bloom_basic_ref']}, 18:55:35 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:55:35 INFO - 'talos_options': ['--mitmproxy', 18:55:35 INFO - 'mitmproxy-recording-1.mp', 18:55:35 INFO - '--firstNonBlankPaint'], 18:55:35 INFO - 'tests': ['Quantum_1']}, 18:55:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['tsvgx', 18:55:35 INFO - 'tsvgr_opacity', 18:55:35 INFO - 'tart', 18:55:35 INFO - 'tscrollx', 18:55:35 INFO - 'cart', 18:55:35 INFO - 'tsvg_static']}, 18:55:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:55:35 INFO - 'tsvgr_opacity', 18:55:35 INFO - 'tart', 18:55:35 INFO - 'tscrollx', 18:55:35 INFO - 'cart', 18:55:35 INFO - 'tsvg_static']}, 18:55:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:55:35 INFO - 'talos_options': ['--disable-e10s'], 18:55:35 INFO - 'tests': ['tp5o']}, 18:55:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:55:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:55:35 INFO - 'talos_options': ['--disable-e10s', 18:55:35 INFO - '--xperf_path', 18:55:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:35 INFO - 'tests': ['tp5n']}, 18:55:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:55:35 INFO - 'talos_options': ['--xperf_path', 18:55:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:35 INFO - 'tests': ['tp5n']}}, 18:55:35 INFO - 'talos.zip': {'path': '', 18:55:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:55:35 INFO - Running post-action listener: _resource_record_post_action 18:55:35 INFO - [mozharness: 2017-05-31 01:55:35.661173Z] Finished populate-webroot step (success) 18:55:35 INFO - [mozharness: 2017-05-31 01:55:35.661287Z] Running create-virtualenv step. 18:55:35 INFO - Running pre-action listener: _resource_record_pre_action 18:55:35 INFO - Running main action method: create_virtualenv 18:55:35 INFO - Creating virtualenv /builds/slave/test/build/venv 18:55:35 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:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:55:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:55:35 INFO - Using real prefix '/tools/python27' 18:55:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:55:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:55:39 INFO - Installing pip.................done. 18:55:39 INFO - Return code: 0 18:55:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:55:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:55:39 INFO - Reading from file tmpfile_stdout 18:55:39 INFO - Output received: 18:55:39 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:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:55:39 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:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:40 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:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:40 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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:40 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:40 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:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:55:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:40 INFO - 'HOME': '/Users/cltbld', 18:55:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:40 INFO - 'LOGNAME': 'cltbld', 18:55:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:40 INFO - 'MOZ_NO_REMOTE': '1', 18:55:40 INFO - 'NO_EM_RESTART': '1', 18:55:40 INFO - 'PAGER': '/bin/cat', 18:55:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:40 INFO - 'PWD': '/builds/slave/test', 18:55:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:40 INFO - 'SHELL': '/bin/bash', 18:55:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:55:40 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:55:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:40 INFO - 'USER': 'cltbld', 18:55:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:40 INFO - 'XPC_FLAGS': '0x0', 18:55:40 INFO - 'XPC_SERVICE_NAME': '0', 18:55:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:40 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:40 INFO - Cleaning up... 18:55:40 INFO - Return code: 0 18:55:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:55:40 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:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:40 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:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:41 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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:41 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:41 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:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:55:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:41 INFO - 'HOME': '/Users/cltbld', 18:55:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:41 INFO - 'LOGNAME': 'cltbld', 18:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:41 INFO - 'MOZ_NO_REMOTE': '1', 18:55:41 INFO - 'NO_EM_RESTART': '1', 18:55:41 INFO - 'PAGER': '/bin/cat', 18:55:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:41 INFO - 'PWD': '/builds/slave/test', 18:55:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:41 INFO - 'SHELL': '/bin/bash', 18:55:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:55:41 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:55:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:41 INFO - 'USER': 'cltbld', 18:55:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:41 INFO - 'XPC_FLAGS': '0x0', 18:55:41 INFO - 'XPC_SERVICE_NAME': '0', 18:55:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:41 INFO - Downloading/unpacking psutil>=3.1.1 18:55:41 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:41 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:41 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:41 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:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:55:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:43 INFO - Installing collected packages: psutil 18:55:43 INFO - Running setup.py install for psutil 18:55:43 INFO - building 'psutil._psutil_osx' extension 18:55:43 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:44 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:44 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:44 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:44 INFO - building 'psutil._psutil_posix' extension 18:55:44 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:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:55:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:55:44 INFO - ^ 18:55:44 INFO - 1 warning generated. 18:55:44 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:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:44 INFO - Successfully installed psutil 18:55:44 INFO - Cleaning up... 18:55:44 INFO - Return code: 0 18:55:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:55:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:55:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:55:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:44 INFO - 'HOME': '/Users/cltbld', 18:55:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:44 INFO - 'LOGNAME': 'cltbld', 18:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:44 INFO - 'MOZ_NO_REMOTE': '1', 18:55:44 INFO - 'NO_EM_RESTART': '1', 18:55:44 INFO - 'PAGER': '/bin/cat', 18:55:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:44 INFO - 'PWD': '/builds/slave/test', 18:55:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:44 INFO - 'SHELL': '/bin/bash', 18:55:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:55:44 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:55:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:44 INFO - 'USER': 'cltbld', 18:55:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:44 INFO - 'XPC_FLAGS': '0x0', 18:55:44 INFO - 'XPC_SERVICE_NAME': '0', 18:55:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:55:45 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:45 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:45 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:45 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:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:55:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:55:46 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:46 INFO - Installing collected packages: mozsystemmonitor 18:55:46 INFO - Running setup.py install for mozsystemmonitor 18:55:46 INFO - Successfully installed mozsystemmonitor 18:55:46 INFO - Cleaning up... 18:55:46 INFO - Return code: 0 18:55:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:46 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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:46 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:46 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:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:46 INFO - 'HOME': '/Users/cltbld', 18:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:46 INFO - 'LOGNAME': 'cltbld', 18:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:46 INFO - 'MOZ_NO_REMOTE': '1', 18:55:46 INFO - 'NO_EM_RESTART': '1', 18:55:46 INFO - 'PAGER': '/bin/cat', 18:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:46 INFO - 'PWD': '/builds/slave/test', 18:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:46 INFO - 'SHELL': '/bin/bash', 18:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:55:46 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:46 INFO - 'USER': 'cltbld', 18:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:46 INFO - 'XPC_FLAGS': '0x0', 18:55:46 INFO - 'XPC_SERVICE_NAME': '0', 18:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:47 INFO - Downloading/unpacking jsonschema==2.5.1 18:55:47 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:47 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:47 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:47 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:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:55:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:48 INFO - Downloading functools32-3.2.3-2.tar.gz 18:55:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:55:48 INFO - warning: no files found matching '*.txt' 18:55:48 INFO - no previously-included directories found matching 'build' 18:55:48 INFO - no previously-included directories found matching 'dist' 18:55:48 INFO - no previously-included directories found matching '.git*' 18:55:48 INFO - Installing collected packages: jsonschema, functools32 18:55:48 INFO - Running setup.py install for functools32 18:55:49 INFO - warning: no files found matching '*.txt' 18:55:49 INFO - no previously-included directories found matching 'build' 18:55:49 INFO - no previously-included directories found matching 'dist' 18:55:49 INFO - no previously-included directories found matching '.git*' 18:55:49 INFO - Successfully installed jsonschema functools32 18:55:49 INFO - Cleaning up... 18:55:49 INFO - Return code: 0 18:55:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:55:49 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:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:49 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:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:49 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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:49 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:55:49 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:55:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:55:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:49 INFO - 'HOME': '/Users/cltbld', 18:55:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:49 INFO - 'LOGNAME': 'cltbld', 18:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:49 INFO - 'MOZ_NO_REMOTE': '1', 18:55:49 INFO - 'NO_EM_RESTART': '1', 18:55:49 INFO - 'PAGER': '/bin/cat', 18:55:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:49 INFO - 'PWD': '/builds/slave/test', 18:55:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:49 INFO - 'SHELL': '/bin/bash', 18:55:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:55:49 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:55:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:49 INFO - 'USER': 'cltbld', 18:55:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:49 INFO - 'XPC_FLAGS': '0x0', 18:55:49 INFO - 'XPC_SERVICE_NAME': '0', 18:55:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:55:49 INFO - Cleaning up... 18:55:49 INFO - Return code: 0 18:55:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:55:49 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:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:49 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:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:49 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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:49 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:55:49 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:55:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:55:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:49 INFO - 'HOME': '/Users/cltbld', 18:55:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:49 INFO - 'LOGNAME': 'cltbld', 18:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:49 INFO - 'MOZ_NO_REMOTE': '1', 18:55:49 INFO - 'NO_EM_RESTART': '1', 18:55:49 INFO - 'PAGER': '/bin/cat', 18:55:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:49 INFO - 'PWD': '/builds/slave/test', 18:55:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:49 INFO - 'SHELL': '/bin/bash', 18:55:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:55:49 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:55:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:49 INFO - 'USER': 'cltbld', 18:55:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:49 INFO - 'XPC_FLAGS': '0x0', 18:55:49 INFO - 'XPC_SERVICE_NAME': '0', 18:55:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:49 INFO - Downloading/unpacking blobuploader==1.2.4 18:55:49 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:49 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:49 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:49 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:50 INFO - Downloading blobuploader-1.2.4.tar.gz 18:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:55:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:55:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:51 INFO - Downloading docopt-0.6.1.tar.gz 18:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:55:51 INFO - Installing collected packages: blobuploader, requests, docopt 18:55:51 INFO - Running setup.py install for blobuploader 18:55:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:55:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:55:52 INFO - Running setup.py install for requests 18:55:52 INFO - Running setup.py install for docopt 18:55:52 INFO - Successfully installed blobuploader requests docopt 18:55:52 INFO - Cleaning up... 18:55:52 INFO - Return code: 0 18:55:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:52 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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:52 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:55:52 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:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:52 INFO - 'HOME': '/Users/cltbld', 18:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:52 INFO - 'LOGNAME': 'cltbld', 18:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:52 INFO - 'MOZ_NO_REMOTE': '1', 18:55:52 INFO - 'NO_EM_RESTART': '1', 18:55:52 INFO - 'PAGER': '/bin/cat', 18:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:52 INFO - 'PWD': '/builds/slave/test', 18:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:52 INFO - 'SHELL': '/bin/bash', 18:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:55:52 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:52 INFO - 'USER': 'cltbld', 18:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:52 INFO - 'XPC_FLAGS': '0x0', 18:55:52 INFO - 'XPC_SERVICE_NAME': '0', 18:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-NYof9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-_CouN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-7Xw7Oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-psRHKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-U57b8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-QcGWlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-3vAe7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-M0bezc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-zowZzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:55:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:55:53 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-LmbF9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:55:54 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-41xhBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:55:54 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-1ub9UU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:55:54 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-CQotDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:55:54 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-UXyRlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:55:54 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-CUotTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:55:54 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-EnHHAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:55:54 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-UoAgEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:55:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:55:54 INFO - Running setup.py install for manifestparser 18:55:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:55:54 INFO - Running setup.py install for mozcrash 18:55:55 INFO - Running setup.py install for mozdebug 18:55:55 INFO - Running setup.py install for mozdevice 18:55:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:55:55 INFO - Running setup.py install for mozfile 18:55:55 INFO - Running setup.py install for mozhttpd 18:55:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:55:55 INFO - Running setup.py install for mozinfo 18:55:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:55:55 INFO - Running setup.py install for mozInstall 18:55:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:55:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:55:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:55:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:55:55 INFO - Running setup.py install for mozleak 18:55:56 INFO - Running setup.py install for mozlog 18:55:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:55:56 INFO - Running setup.py install for moznetwork 18:55:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:55:56 INFO - Running setup.py install for mozprocess 18:55:56 INFO - Running setup.py install for mozprofile 18:55:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:55:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:55:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:55:56 INFO - Running setup.py install for mozrunner 18:55:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:55:56 INFO - Running setup.py install for mozscreenshot 18:55:57 INFO - Running setup.py install for moztest 18:55:57 INFO - Running setup.py install for mozversion 18:55:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:55:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:55:57 INFO - Cleaning up... 18:55:57 INFO - Return code: 0 18:55:57 INFO - Installing None 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', '-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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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', '-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:55:57 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:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 18:55:57 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:55:57 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-dOeguQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:55:57 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:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:55:57 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-9YL9vK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:55:57 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:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:55:57 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ABSQv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:55:57 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:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:55:57 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-NXAXB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-YmkJLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-UtdgmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-mthUuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-m8mfSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-UHpwTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-zM0Epw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-C7VCeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-vHq3Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:55:58 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:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:55:58 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-8PaiW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:55:59 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:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:55:59 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-dOn1em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:55:59 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:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:55:59 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-l72fk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:55:59 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:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:55:59 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-TfhhXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:55:59 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:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:55:59 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-cGaihl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:55:59 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:55:59 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:55:59 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:55:59 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:55:59 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:55:59 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:55:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:55:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:00 INFO - Downloading blessings-1.6.tar.gz 18:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:56:01 INFO - Installing collected packages: blessings 18:56:01 INFO - Running setup.py install for blessings 18:56:01 INFO - Successfully installed blessings 18:56:01 INFO - Cleaning up... 18:56:01 INFO - Return code: 0 18:56:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:56:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:01 INFO - Reading from file tmpfile_stdout 18:56:01 INFO - Current package versions: 18:56:01 INFO - blessings == 1.6 18:56:01 INFO - blobuploader == 1.2.4 18:56:01 INFO - docopt == 0.6.1 18:56:01 INFO - functools32 == 3.2.3-2 18:56:01 INFO - jsonschema == 2.5.1 18:56:01 INFO - manifestparser == 1.1 18:56:01 INFO - mozInstall == 1.12 18:56:01 INFO - mozcrash == 1.0 18:56:01 INFO - mozdebug == 0.1 18:56:01 INFO - mozdevice == 0.51 18:56:01 INFO - mozfile == 1.2 18:56:01 INFO - mozhttpd == 0.7 18:56:01 INFO - mozinfo == 0.9 18:56:01 INFO - mozleak == 0.1 18:56:01 INFO - mozlog == 3.4 18:56:01 INFO - moznetwork == 0.27 18:56:01 INFO - mozprocess == 0.25 18:56:01 INFO - mozprofile == 0.28 18:56:01 INFO - mozrunner == 6.13 18:56:01 INFO - mozscreenshot == 0.1 18:56:01 INFO - mozsystemmonitor == 0.3 18:56:01 INFO - moztest == 0.8 18:56:01 INFO - mozversion == 1.4 18:56:01 INFO - psutil == 3.1.1 18:56:01 INFO - requests == 1.2.3 18:56:01 INFO - wsgiref == 0.1.2 18:56:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:01 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:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:01 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:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:01 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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:01 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:01 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:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:56:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:01 INFO - 'HOME': '/Users/cltbld', 18:56:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:01 INFO - 'LOGNAME': 'cltbld', 18:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:01 INFO - 'MOZ_NO_REMOTE': '1', 18:56:01 INFO - 'NO_EM_RESTART': '1', 18:56:01 INFO - 'PAGER': '/bin/cat', 18:56:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:01 INFO - 'PWD': '/builds/slave/test', 18:56:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:01 INFO - 'SHELL': '/bin/bash', 18:56:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:56:01 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:56:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:01 INFO - 'USER': 'cltbld', 18:56:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:01 INFO - 'XPC_FLAGS': '0x0', 18:56:01 INFO - 'XPC_SERVICE_NAME': '0', 18:56:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:01 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:01 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:01 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:01 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:01 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:01 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:01 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:01 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:01 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:01 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 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:03 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:56:03 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:03 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:03 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:03 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:03 INFO - Installing collected packages: marionette-driver 18:56:03 INFO - Running setup.py install for marionette-driver 18:56:03 INFO - Successfully installed marionette-driver 18:56:03 INFO - Cleaning up... 18:56:03 INFO - Return code: 0 18:56:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:56:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:03 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 0x109d29c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd500d5f970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, '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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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:03 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:03 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:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:03 INFO - 'HOME': '/Users/cltbld', 18:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:03 INFO - 'LOGNAME': 'cltbld', 18:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:03 INFO - 'MOZ_NO_REMOTE': '1', 18:56:03 INFO - 'NO_EM_RESTART': '1', 18:56:03 INFO - 'PAGER': '/bin/cat', 18:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:03 INFO - 'PWD': '/builds/slave/test', 18:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:03 INFO - 'SHELL': '/bin/bash', 18:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:56:03 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:03 INFO - 'USER': 'cltbld', 18:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:03 INFO - 'XPC_FLAGS': '0x0', 18:56:03 INFO - 'XPC_SERVICE_NAME': '0', 18:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:56:03 INFO - Cleaning up... 18:56:03 INFO - Return code: 0 18:56:03 INFO - Running post-action listener: _resource_record_post_action 18:56:03 INFO - Running post-action listener: _start_resource_monitoring 18:56:03 INFO - Starting resource monitoring. 18:56:03 INFO - [mozharness: 2017-05-31 01:56:03.806125Z] Finished create-virtualenv step (success) 18:56:03 INFO - [mozharness: 2017-05-31 01:56:03.807097Z] Running install step. 18:56:03 INFO - Running pre-action listener: _resource_record_pre_action 18:56:03 INFO - Running main action method: install 18:56:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:04 INFO - Reading from file tmpfile_stdout 18:56:04 INFO - Detecting whether we're running mozinstall >=1.0... 18:56:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:56:04 INFO - Reading from file tmpfile_stdout 18:56:04 INFO - Output received: 18:56:04 INFO - Usage: mozinstall [options] installer 18:56:04 INFO - Options: 18:56:04 INFO - -h, --help show this help message and exit 18:56:04 INFO - -d DEST, --destination=DEST 18:56:04 INFO - Directory to install application into. [default: 18:56:04 INFO - "/builds/slave/test"] 18:56:04 INFO - --app=APP Application being installed. [default: firefox] 18:56:04 INFO - mkdir: /builds/slave/test/build/application 18:56:04 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:04 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:26 INFO - Reading from file tmpfile_stdout 18:56:26 INFO - Output received: 18:56:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:56:26 INFO - Running post-action listener: _resource_record_post_action 18:56:26 INFO - [mozharness: 2017-05-31 01:56:26.008046Z] Finished install step (success) 18:56:26 INFO - [mozharness: 2017-05-31 01:56:26.008181Z] Running setup-mitmproxy step. 18:56:26 INFO - Running pre-action listener: _resource_record_pre_action 18:56:26 INFO - Running main action method: setup_mitmproxy 18:56:26 INFO - Skipping: mitmproxy is not required 18:56:26 INFO - Running post-action listener: _resource_record_post_action 18:56:26 INFO - [mozharness: 2017-05-31 01:56:26.008841Z] Finished setup-mitmproxy step (success) 18:56:26 INFO - [mozharness: 2017-05-31 01:56:26.008927Z] Running run-tests step. 18:56:26 INFO - Running pre-action listener: _resource_record_pre_action 18:56:26 INFO - Running main action method: run_tests 18:56:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:56:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:56:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:56:26 INFO - 2017-05-30 18:56:26.023 screenresolution[1629:7475] starting screenresolution argv=screenresolution get 18:56:26 INFO - 2017-05-30 18:56:26.026 screenresolution[1629:7475] Display 0: 1600x1200x32@60 18:56:26 INFO - 2017-05-30 18:56:26.032 screenresolution[1630:7478] starting screenresolution argv=screenresolution list 18:56:26 INFO - Available Modes on Display 0 18:56:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:56:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:56:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:56:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:56:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:56:26 INFO - Intel Iris: 18:56:26 INFO - Chipset Model: Intel Iris 18:56:26 INFO - Type: GPU 18:56:26 INFO - Bus: Built-In 18:56:26 INFO - VRAM (Dynamic, Max): 1536 MB 18:56:26 INFO - Vendor: Intel (0x8086) 18:56:26 INFO - Device ID: 0x0a2e 18:56:26 INFO - Revision ID: 0x0009 18:56:26 INFO - Displays: 18:56:26 INFO - Display: 18:56:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:56:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:56:26 INFO - Display Serial Number: 1600x1200 60 18:56:26 INFO - Main Display: Yes 18:56:26 INFO - Mirror: Off 18:56:26 INFO - Online: Yes 18:56:26 INFO - Rotation: Supported 18:56:26 INFO - Return code: 0 18:56:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:56:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:56:26 INFO - Python 2.7.3 18:56:26 INFO - Return code: 0 18:56:26 INFO - grabbing minidump binary from tooltool 18:56:26 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:26 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 0x109cb2c60>, '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 0x109a47cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a70be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:56:26 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:26 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:26 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:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:56:26 INFO - Return code: 0 18:56:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:56:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:56:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:56:26 INFO - ENV: RUST_BACKTRACE is now 1 18:56:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:56:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:56:26 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/AweIWHGYREGposN6LvIM7g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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:26 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/AweIWHGYREGposN6LvIM7g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0308 --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:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7WlqEv3S8X/Render', 18:56:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:26 INFO - 'HOME': '/Users/cltbld', 18:56:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:26 INFO - 'LOGNAME': 'cltbld', 18:56:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:56:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:26 INFO - 'MOZ_NO_REMOTE': '1', 18:56:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:56:26 INFO - 'NO_EM_RESTART': '1', 18:56:26 INFO - 'PAGER': '/bin/cat', 18:56:26 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:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:26 INFO - 'PWD': '/builds/slave/test', 18:56:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:56:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:26 INFO - 'RUST_BACKTRACE': '1', 18:56:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:56:26 INFO - 'SHELL': '/bin/bash', 18:56:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zOjk9qGMxM/Listeners', 18:56:26 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 18:56:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:26 INFO - 'USER': 'cltbld', 18:56:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:26 INFO - 'XPC_FLAGS': '0x0', 18:56:26 INFO - 'XPC_SERVICE_NAME': '0', 18:56:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:26 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/AweIWHGYREGposN6LvIM7g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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:26 INFO - mozversion application_buildid: 20170530174056 18:56:26 INFO - mozversion application_changeset: 9a5c710587f9e64bf044602d5e988b152ef4f40c 18:56:26 INFO - mozversion application_display_name: Nightly 18:56:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:56:26 INFO - mozversion application_name: Firefox 18:56:26 INFO - mozversion application_remotingname: firefox 18:56:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:56:26 INFO - mozversion application_vendor: Mozilla 18:56:26 INFO - mozversion application_version: 55.0a1 18:56:26 INFO - mozversion platform_buildid: 20170530174056 18:56:26 INFO - mozversion platform_changeset: 9a5c710587f9e64bf044602d5e988b152ef4f40c 18:56:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:56:26 INFO - mozversion platform_version: 55.0a1 18:56:26 INFO - using testdate: 1496195786 18:56:26 INFO - actual date: 1496195786 18:56:26 INFO - starting webserver on 'localhost:49229' 18:56:26 INFO - SUITE-START | Running 2 tests 18:56:26 INFO - TEST-START | dromaeo_css 18:56:26 INFO - operating with platform_type : mac_ 18:56:26 INFO - Initialising browser for dromaeo_css test... 18:56:26 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpgNQxhA/profile 18:56:26 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 18:57:00 INFO - TEST-INFO | 1640: killed by SIGTERM 18:57:00 INFO - Browser initialized. 18:57:00 INFO - Running cycle 1/1 for dromaeo_css test... 18:57:00 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpgNQxhA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:02 INFO - PID 1650 | 2017-05-30 18:57:02.954 plugin-container[1651:8025] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 18:57:02 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:02 INFO - PID 1650 | 2017-05-30 18:57:02.954 plugin-container[1651:8025] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994f, name = 'com.apple.CFPasteboardClient' 18:57:02 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:02 INFO - PID 1650 | 2017-05-30 18:57:02.954 plugin-container[1651:8025] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:57:03 INFO - PID 1650 | 2017-05-30 18:57:03.459 plugin-container[1652:8070] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 18:57:03 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:03 INFO - PID 1650 | 2017-05-30 18:57:03.460 plugin-container[1652:8070] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 18:57:03 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:03 INFO - PID 1650 | 2017-05-30 18:57:03.460 plugin-container[1652:8070] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:57:12 INFO - PID 1650 | RSS: Main: 269500416 18:57:12 INFO - PID 1650 | 19:00:15 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 19:00:16 INFO - PID 1650 | RSS: Main: 273219584 19:00:16 INFO - PID 1650 | 19:03:18 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 19:03:19 INFO - PID 1650 | RSS: Main: 282787840 19:03:19 INFO - PID 1650 | 19:06:20 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 19:06:21 INFO - PID 1650 | RSS: Main: 283750400 19:06:21 INFO - PID 1650 | 19:09:23 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 19:09:23 INFO - PID 1650 | RSS: Main: 287506432 19:09:23 INFO - PID 1650 | 19:12:26 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 19:12:27 INFO - PID 1650 | RSS: Main: 287531008 19:12:27 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 19:15:29 INFO - PID 1650 | RSS: Main: 287498240 19:15:29 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | __start_tp_report 19:15:29 INFO - PID 1650 | _x_x_mozilla_page_load 19:15:29 INFO - PID 1650 | _x_x_mozilla_page_load_details 19:15:29 INFO - PID 1650 | |i|pagename|runs| 19:15:29 INFO - PID 1650 | |0;dojo.html;3420.2988387380587;3593.993681769104;3604.4232922732367;3620.094976255936;3639.3994990826513;6083.482903953158;6096.969515152424;6148.723307451164;6160.414760597736;6171.50627949764;6118.101457354467;6126.509879209663;6151.692415379235;6177.413145751158;6183.567150299481;5416.945830541684;5448.073827449343;5459.099248623972;5507.834764957058;5572.86067848303;4532.682712210147;5341.545968592674;5487;5555.055640541097;5614;5447.210154527571;5478.91781623276;5547.44525547444;5551.3893471718075;5567.526760225385;4903.19097348937;4930.186519224324;5428.941356435496;5481.890362192734;5484.643498172606;5402.540784033362;5414.539764120054;5442.12925931849;5448.318960129984;5453.072977593819;6035.7887473938645;6129.38706129388;6153.569250152491;6158.815235542941;6170.321264660884;6435.131257280254;6547.705353259114;6552.901706474407;6566.606003639749;6681.465482761368;6803.523753337268;6808.455323574102;6816.863662726767;6818.011388348711;6824.931750682479;5153.922691159673;6224.719887605069;6241.875162496724;6244.219472565929;6262;5788.18965344852;6707.6981535831;6769.729210831511;6822.079019332326;6928.099347084847;6342.112104305401;6509.9023514647315;6542.869142617121;6582;6593.703283352164;6017.585867321176;6348.8412789679705;6528.477721782246;6549.803505894735;6596.703148358335;5961.688428545713;6178.66017369049;6629.370209830058;6645.767398141139;6647.434968027772;5672.546196304285;6668.799936001928;6686.197656281277;6712.261651218362;6736.831579210559;5223.660462069954;5301.787928482818;5302.522772950376;5326;5365.678059316453;4709.693869898446;4786.976065119722;4792.400949881265;4827.662063655581;4850.902981940412;6127.4485296322655;6223.813285601439;6247.344028876993;6250.218722659668;6259.155014073133;6580.670966451754;6668.33316668339;6763.222229443579;6801.693923773442;6830.658467076726;6110.663913484712;6123.25211838015;6152.261728592597;6155.199824022849;6176.042713379434;5960.195373624592;5997.940020599738;5998.850028749316;6017.368176341509;6036.577439579188;6871.896921546181;6878.243393226641;6921.757738479129;6927.7228910843005;6928.1339832520935;6573.375529324707;6579.671016449254;6642.933570664231;6712.160979877515;6785.389314961677;6806.93193068063;6849.863002739818;6856.965715171392;6872.278410766798;6894.069300644349;6539.149910511603;6617.702203400762;6644.33556644351;6665.0668906352175;6676.799696009128;5509.752061157337;6662.700178492077;6683.097781799394;6723.562968407038;6799.762008329685;6476.676166191766;6523.869522609616;6534.150560427114;6534.281229064894;6560.671966401756;6304.968475157595;6312.4634406076;6527.11883895668;6529.347065293433;6539.6403197824575;6241.21984751906;6316.494680425647;6366.363363663597;6419.2938776735455;6429.58207716497;6372.330905254881;6463.289038205699;6566.11357466755;6600.636964966973;6602.735890564516;6311.084892690443;6326.810195694136;6336.619802811846;6405.551611387158;6425.293217746138 19:15:29 INFO - PID 1650 | |1;ext.html;724.5362967700672;738.774673724514;742.3726950726638;1151.8732939376666;1332.5202926946292;33096.83451582742;39623.603763962354;39737.205255894936;39795.602043979554;39807.805765827;40330.79834600823;40448.19103617926;40461;40507;40542.99999999993;7757.806054848632;7940.563269020202;8201.58992050398;8444.366672499556;8445.957770211171;8345.165483451667;8453.619587118563;8459.788505287355;8472.74581762548;8479.236868681817;6671.866562668743;8417.747467575982;8453.408261421702;8473.915260847405;8478.406511544194;8407.789805254886;8456.78858028551;8457.873131903027;8467.745967620982;8471.788205294855;8143.144969778147;8192.508449493049;8205.179482051806;8215.876761848576;8219.794505137359;37459.625403745886;37614;37635.43546846799;37648.05879852998;37724.62275377238;3593.083763640255;3614.132608174046;3627.6553727396117;3632.400653892105;3633.1098880774116;30309;30720.924767633027;30755.538666920012;30807.845960770275;30877.845610772027;3093.535969604555;3117.7817552771317;3118.7037231462973;3126.187191330248;3143.6227652681596;234850;239069;270001.999999998;296749;298654;47974.520254797004;48241.517584823705;48276.5172348272;48337.7915552114;48462.84991475613;50481.24278135758;50511.24233136506;50705.23942140871;53461;54261.72869135629;14533.709325813425;14757.188354640382;14829.480968166063;14834.554963351116;14856.851431485547;11841.230320029343;11941.522339106512;11963.282203067844;11965.820512692317;11975.520979160736;13700.246486443142;13777.724445511034;13883.097598656055;13914.026018178833;14004.789928151085;6064.4541991221;6106.6336019838955;6107.358727333655;6112.388761123941;6115.785947491905;869.826034793044;1004.6282875336173;1015.5683834370363;1019.6125472320471;1065.1106326217696;24437.877810610833;25067.74932250654;30720;31389.843050785057;31487.21281967969;1089.5968491658136;1093.4805967165692;1124.8650161980452;1125.5311818077323;1132.116948779953;11074.446277686084;11215.66353009411;12103.515859365703;12674.176178548365;12689.111762176559;330981;331632;332257;332688;333356;53879.46120538823;54137.72931135398;54218;54237.72881135648;54473.45526544763;1467.7578199596946;1489.6052546075298;1529.2812378182236;1534.6930613877269;1542.2365928865283;20441.79558204399;20669.689954650392;23268.650970235798;24285.878570607034;24372.390690232176;38454.80772596119;38695.6130438692;38700;38735.22529549337;38795.41806872956;283690.5815470992;285136;285627;285727.57136214187;286749;33967.66032339645;34254;34264.828675856465;34272;34326.48510272298;33080.83459582687;42583.50957716465;43853;43867;44367.55632443763;24479;29125.41749165047;30465.086047418612;30534.389312214076;30992.845035774677;29719.702802971693;29760.089585218197;30821.691783081882;30827.383452331276;30950.53574196344;36504.81747591245;36521.634783651825;36567.45148822823;36622.63377366299;36626;25975.870120649277;27508.899644014014;28254.293642659097;30607.234819128807;31182.688173118884;20132.39602811918;22830;23661.81690915482;24275.028998840557;24300.635490468012 19:15:29 INFO - PID 1650 | |2;jquery.html;424.69634211538744;425.0145789884653;433.30022014446666;437.85988483685213;445.236419540488;13589.38847751851;14198.92900535496;21450.141994320227;22184.223552175677;22446.88776556117;22559.210427635036;23388.298351049452;23504.29487115385;23527.882360588173;23553.29340119795;5917.40825917407;6011.098335249726;6019.939800601982;6038;6053.757849686007;3538.7522873398743;3745.007572993159;5681.602287839853;5927.081302398135;6040.456358927696;6017.518598512109;6045.093236014589;6052.213212282397;6058.030715085587;6061;6005.318510816935;6042.365551617083;6050.606710563821;6053.606515576495;6054.636721796706;5363.544098751572;5402.648827826179;5404.729763511808;5416.133418653036;5441.863953401157;22092.55814883693;28133.57799633007;28184.436311273865;28440.004599838907;28525.57211641827;2189.9452513687283;2244.16965722682;2253.0649780341137;2256.548690261954;2259.3560835162066;18036;18598.44204673862;18600.255989760528;18621.627567448577;18683.439496815117;1896.544829240986;2081.3131666550003;2107.3256557901477;2135.092585651092;2162.8702277863376;198258;204057;204536;205224;206790;12769.872301277053;12837.743245135045;13044.608661739972;13073.673158170932;13142.277174755289;16706.58233544171;16849.49451516432;16897.324107035827;16937.491875243762;16971.660566788596;7816.843663126706;8923.196912277795;9126;9170.128837760401;9191.264698824078;8810.955945220232;8811.691590794291;8817.118288171132;8897.822043559092;8919.687810926587;6931;7274.254930064788;7899.486533375312;8191;8305.543195124204;2031.502281940934;2224.477247846755;2269.4326418395403;2286.8856557172076;2315.455867871049;863.3481721300503;865.2558799432483;992.9404235745878;1004.6182450668699;1028.708133971295;21218.24480369489;21273.04271307795;21320.573588528143;21362.893185533972;21581;1024.9487525623838;1036.5801850250662;1049.3703777733451;1068.433730123036;1074.8172810622214;9288.396254243453;9735.41587504752;10697.144228461704;11003.944980275048;11130.998210160962;512057;513208;515423;519343;525277;140647;145273;145805.54194458132;145852;146433;1182.6806762174165;1193.361551569906;1222.1567118688076;1224.4489979509078;1226.7853125702788;18167.36414225496;18646;18650.81349186545;19079.236830526625;19138.52153696113;67265.9910101339;67604.66197668981;67662.66168669322;68327.65836170787;68858;91226.17547649142;91497;92024.53987730018;92141;92756.53621731848;8540.786480337792;10126.038026387481;10145.89854101479;10246;10325.141474534355;13454.798178027533;16755.832441675426;17649.38227162043;18028.909855450638;18070.81929180744;14760.409583616534;14761.852381476478;14890.478833240784;14895.329710163285;14913.105213687213;13057.477700891857;13305.866941330463;13431.731365372443;13507.932460337635;13516.324183790968;13850.376733046836;14812.185329806964;15041.774373384627;15042.623934401727;15190.620234494227;9770.218382529523;10139.64511242103;10196.541155647865;10206.489675516343;10216;14523.983321167418;14718.63203419923;14817.481388151364;14849.109053456827;14862.479813206486 19:15:29 INFO - PID 1650 | |3;mootools.html;1363.5772910397777;1426.4650755966513;1436.0019786248554;1437.1233547536;1446.3202294921389;11564.306141631487;11631.360275184861;12102.455389507471;12102.576409825659;12163.270203787768;10864.78270434591;11931.940340298308;12106.818397724019;12142.81785773212;12147.453364598592;2522.646829443879;2572.0354866924904;2574.600936854781;2582.3156863431204;2588.029488941647;2517.748225177486;2537.746225377466;2552.8001839135577;2554.910578129766;2556.7315431879665;2500.562401579716;2535.9746402536016;2537.6954765428172;2539.1113110411393;2542.0467324753217;2479.925602231936;2501.6747822783104;2507.4608959073794;2507.749225077496;2517.282574466268;2453.472503411775;2491.4145175883655;2501.962470562943;2559.69283685957;2575.22743177048;10172.44051577163;11826.881731182664;11890.881091189063;11910.106741994385;11936.582219622358;6386.968065159691;6567.967160164217;6637.46900247979;6651.368120028589;6656.633885136315;3976;4116.670666346685;4198.286291330481;4199.202151591188;4293.248681480728;1185.0934035462878;1206.4631239098605;1225.4669218889812;1231.8644949055597;1234.6542967968983;14185.858141418557;15511.457099001593;16285.755713664304;16292.185390730534;16421.932574382627;5517.475839795213;5523.806666766724;5648.039833228361;5710.486056254957;5726.570507211893;12047.518099276105;12072.396380180955;12075.818862717066;12093.45579448927;12124.939375303067;6350.0792385103905;6369.872602547923;6407.519436042316;6439.420452159328;6456.612603243727;5973.223480947449;6030.638161710312;6085.6348619083;6090.177825993523;6121.296050954108;4298.699091063596;4362.672799540047;4403.9559604404185;4438.556144385536;4449.199144154083;1105.7125147461718;1253.1916913590865;1275.8851703346743;1279.8464184297943;1283.0633637444719;757.5946868425366;759.5974133709143;784.1687810920442;790.7390561114819;791.2483141016057;6013.609115407485;6616.768413105518;6759.763408280686;6941;7051.576905385693;895.9596818143199;923.3167456082585;928.9256859451227;934.9065093490733;961.8268711631973;3881.922361552753;3882.165334453106;4072.2873497138476;4164.9583504165175;4240.554741752133;14402.567922962327;15187.088774673333;15192;15208.935374523677;15258.847411525965;14990.47533336328;15138.621534461727;15140.848591514165;15142.091474511564;15167.848321516864;8141.592920354077;8253.876191857127;8656.17766312212;8699.347548933729;8716.520591367409;5977.5217982560425;6652.2349929756765;6769.984502324691;6918.5502942308585;6996.545224560591;12527.93736031314;12741.80887286673;12917.966562674776;12946.805797913226;12976.480940762263;12598.622041338775;13105.868941310466;13133.802992955305;13135.802962955373;13195.802062969255;7972.720954766555;8831.249343805592;9269.582868770787;9601.47191904452;9678.17735492491;10750.946245269037;10856.945715271373;10867.402292873969;10874.782504350027;10889.727756805827;8078.19218078211;8157.918420815716;8799.856018717526;8863.892013498313;9307.581158848003;7660.233976602295;8905.154010369004;8912.33157513176;9028.954855225682;9202.079792020744;8282.751517454486;8299.336053115858;8349.415540912078;8387.035490918623;8425.241728244488;6100.816975490742;6103.42017508336;6103.908441373294;6146.754129834757;6232.906506402317;6055.1522786810765;6176.567640265138;6206.968965155326;6216.471599914058;6266.874662506633 19:15:29 INFO - PID 1650 | |4;prototype.html;332.4049950588447;384.40033547665644;389.67656844818805;392.4740847264666;402.883163882474;6360.522960777937;6439.517036222284;6464.159659244304;6465.741370345187;6512.413882750551;6237.844053898655;6395.456386207178;6405.231372235338;6409.903851442232;6428.3250258722865;4277.25148099083;4377.496587892401;4555.5172431025;4682.882927926795;4703.294505824137;3929.744566603176;4650.697904537259;4673.439187297529;4679.672422930396;4685.8594242172785;4601.171789077965;4605.217113090783;4614.930776038362;4638.884027899313;4640.559146881058;4631.490536041033;4662.883427914296;4666.300054991744;4670.743109128996;4674.742889141079;3389.898283058016;3960.2079584083012;3968.6626636735914;3969.642732154091;3982.143839074613;5794.50746686528;6362.681865906686;6374.107624932513;6413.551051426402;6425.45383642391;4683.882902927454;5493.176023596492;5501.697406642673;5519.448055194489;5540.418256083093;6896.27589103147;6903.6548172591165;6909.516333856632;6910.965445172792;6935.167779866398;3847.0190101524213;4153.356229784358;4486.349479325512;4501.7974191161475;4507.639388848885;6931.376176144171;7229.530080544748;7230.710771569131;7321;7366.889496657554;3621.764585301947;4383.868483945486;4506.2564676828615;4508.549145085465;4516.345129956138;4616.561426664462;4616.676832621752;4623.722576645412;4625.699329543569;4671.182543054952;5265.209828658275;5421.945780542144;5445.564354851603;5446.373667028263;5448.727563621803;5774.3070831499645;6080.57435979486;6867.416269617039;6876.696306737299;6918.654067296615;1569.6782159657243;1571.3793051744726;1573.3313341829676;1583.1292788966252;1591.077175238382;987.5259875259916;1005.5324274212526;1024.2318261304022;1024.472396715692;1031.7781676939344;6374.904376434357;6384.648844313608;6418.518611104186;6423.839404014843;6463.935360646334;7060.435165186773;7103.076600041962;7107.964460177666;7118.430525557943;7170.3905168060255;5919.556033297434;6413.0380442933;6443.29123796382;6455.223694138981;6477.287498375175;3149.732272756841;3324.7007769300394;3710.165212827146;3730.7948062856804;3751.3611796557298;6759.290274521203;6771.492138089663;7290.125184977836;7321.890171647429;7360.742374016883;7363.263673632594;7364.153122390887;7408.481406301615;7436.739714110088;7443.330100291;6891.827704307432;7027.3780975219615;7226.7109315626785;7333.4866559340335;7351.632418379167;7032.331928466788;7156.342713296931;7162.570245785061;7175.174854891546;7189.7483588074165;6188.040853667689;6386.361363863576;6446.419822215836;6467.380710812128;7018.140192849001;6388.872222555616;7033.296670332926;7138.250483699137;7139.428845692436;7231.409090000191;5131.281620573197;5139.948600513947;5162.535371816404;5163.225516172604;5185.948140518546;6574.178227721535;7144.357007869109;7149.640618595955;7185.353318201387;7196.532225405332;5726.570507211893;6624.33756624353;6759.695813688494;6876.553024053421;6892.517523773488;6057.848553786191;6198.938010619836;6451.3548645135115;6914.407097338498;6929.757458488929;6777.661116944035;6814.488913331619;6829.52193346461;6831.795046148623;6832.248452670302;5614.410486898817;5924.170616113834;6820.147481564804;6909;6932.272111428228;5227.529522342856;6664.667666166916;6700.966495167493;6702.664866756545;6726.8720045179225 19:15:29 INFO - PID 1650 | |5;yui.html;688.9448844092473;805.0902480197376;822.2435359469291;844.7877296080126;853.6713365354339;14854.554363369116;15474.845251547482;17323.39368122116;17815.91092044541;17971.73042404365;16207.432739854108;17566.385176518823;18022.369217077405;18090.276388944425;18127.81872181281;701.9017337572757;909.4815954905706;918.7978644698189;920.2040235196574;921.0283151275402;957.1816097236866;970.277143528069;972.8103019911085;974.2449601558777;974.5663179884954;688.1432616392573;691.0325544238057;997.2548040928375;1012.9493525323741;1013.9138173255283;1004.8542961270612;1005.8290090684601;1005.9849102263508;1006.1849901579744;1007.5214273220198;727.3526561360394;738.0995185873226;742.1873049011324;743.4609907816844;744.0178963767829;17155.656886862318;17190.312387504484;17301.7404738929;17460.563485912953;17621.7356739649;17288.56778580533;17320.393786217548;17355.826441735546;17756.289748410047;17775.111244437725;14350.569482915529;14757.631059223499;14758.114513129247;14775.778363324558;14825.258737063104;3464.9480257796154;3543.911402214965;3550.822458877046;3571.160777217352;3585.3008663310666;149360;152148.71776923354;153226;153616;153790.23104884516;13664.726705465835;13812.033157678867;13832.585022449342;13859.722805544034;13944.302784860716;26569;26682.866585666947;27208.727912721017;27214.45571088567;27431.86284068567;6849.863002739918;7081.964590177016;7131.750388736473;7436.73971410998;7903.446758726834;3911.530616326059;3954.9011274718364;3989.740666856645;3994.08136128692;3996.2407142642805;4216.241076606179;4240.194363071007;4298.527162012176;4299.376590394375;4417.094495628388;877.6708734224666;908.7410088124852;922.280621115531;922.8523436250168;923.3213588012806;611.7858749437751;622.3931666625004;646.4828137489989;653.5327241022596;674.6694119881223;11619.6514104577;12241.755164896653;12431.067669924792;12523.499060037679;12586.307753073486;578.1587789765964;589.9882002359928;590.1796502860941;591.7011908985935;619.7180282971237;5571.220029195916;5578.776848925998;5750.856228594319;5765.135229715576;5870.031444811643;130352;136756;143394.84907726393;145271;151562;121151;122394;125234.37382813028;127377.3631131857;130482;112804;112817;114288.42855785834;116619;116966;11259.268147570381;11476.426178690865;11572.479238434458;11574.594889178838;11776.76446471083;37515.62484375121;47003.52996469991;47413.525864740914;47620.7618961903;47891.52108478871;70871.64564177147;78543.82184267126;81098;85035;85228;17391.739123913405;17588.912055440152;17738.5565360867;17789.46631601054;17846.19692113832;17029.89305695126;18127.72808407901;18449.63100737951;18978;19174.424767257005;17601.82398176002;17854.285828566713;18200.635987279915;18277.634447311244;18471.90764046171;17778;18181.181846817188;18232.088395579904;18246.996415197293;18399.172037258624;15444.150571718214;17807.643847122727;18014.279428823225;18168;18382.540436489195;11754.177207595387;11892.643220703392;12002.459889304828;12028.09789265791;12141.635750927486;11438.542458301576;13195.934020330222;13751.381187846771;13849.723005540034;14108.2945852709 19:15:29 INFO - PID 1650 | __end_tp_report 19:15:29 INFO - PID 1650 | __start_cc_report 19:15:29 INFO - PID 1650 | _x_x_mozilla_cycle_collect,448 19:15:29 INFO - PID 1650 | __end_cc_report 19:15:29 INFO - PID 1650 | __startTimestamp1496196929637__endTimestamp 19:15:29 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | ------- Summary: start ------- 19:15:29 INFO - PID 1650 | Number of tests: 6 19:15:29 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6112.91 Median:6304.97 stddev:718.49 (11.4%) stddev-sans-first:688.94 19:15:29 INFO - PID 1650 | Values: 3420.3 3594.0 3604.4 3620.1 3639.4 6083.5 6097.0 6148.7 6160.4 6171.5 6118.1 6126.5 6151.7 6177.4 6183.6 5416.9 5448.1 5459.1 5507.8 5572.9 4532.7 5341.5 5487.0 5555.1 5614.0 5447.2 5478.9 5547.4 5551.4 5567.5 4903.2 4930.2 5428.9 5481.9 5484.6 5402.5 5414.5 5442.1 5448.3 5453.1 6035.8 6129.4 6153.6 6158.8 6170.3 6435.1 6547.7 6552.9 6566.6 6681.5 6803.5 6808.5 6816.9 6818.0 6824.9 5153.9 6224.7 6241.9 6244.2 6262.0 5788.2 6707.7 6769.7 6822.1 6928.1 6342.1 6509.9 6542.9 6582.0 6593.7 6017.6 6348.8 6528.5 6549.8 6596.7 5961.7 6178.7 6629.4 6645.8 6647.4 5672.5 6668.8 6686.2 6712.3 6736.8 5223.7 5301.8 5302.5 5326.0 5365.7 4709.7 4787.0 4792.4 4827.7 4850.9 6127.4 6223.8 6247.3 6250.2 6259.2 6580.7 6668.3 6763.2 6801.7 6830.7 6110.7 6123.3 6152.3 6155.2 6176.0 5960.2 5997.9 5998.9 6017.4 6036.6 6871.9 6878.2 6921.8 6927.7 6928.1 6573.4 6579.7 6642.9 6712.2 6785.4 6806.9 6849.9 6857.0 6872.3 6894.1 6539.1 6617.7 6644.3 6665.1 6676.8 5509.8 6662.7 6683.1 6723.6 6799.8 6476.7 6523.9 6534.2 6534.3 6560.7 6305.0 6312.5 6527.1 6529.3 6539.6 6241.2 6316.5 6366.4 6419.3 6429.6 6372.3 6463.3 6566.1 6600.6 6602.7 6311.1 6326.8 6336.6 6405.6 6425.3 19:15:29 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:44770.06 Median:332472.50 stddev:77837.54 (23.4%) stddev-sans-first:77985.64 19:15:29 INFO - PID 1650 | Values: 724.5 738.8 742.4 1151.9 1332.5 33096.8 39623.6 39737.2 39795.6 39807.8 40330.8 40448.2 40461.0 40507.0 40543.0 7757.8 7940.6 8201.6 8444.4 8446.0 8345.2 8453.6 8459.8 8472.7 8479.2 6671.9 8417.7 8453.4 8473.9 8478.4 8407.8 8456.8 8457.9 8467.7 8471.8 8143.1 8192.5 8205.2 8215.9 8219.8 37459.6 37614.0 37635.4 37648.1 37724.6 3593.1 3614.1 3627.7 3632.4 3633.1 30309.0 30720.9 30755.5 30807.8 30877.8 3093.5 3117.8 3118.7 3126.2 3143.6 234850.0 239069.0 270002.0 296749.0 298654.0 47974.5 48241.5 48276.5 48337.8 48462.8 50481.2 50511.2 50705.2 53461.0 54261.7 14533.7 14757.2 14829.5 14834.6 14856.9 11841.2 11941.5 11963.3 11965.8 11975.5 13700.2 13777.7 13883.1 13914.0 14004.8 6064.5 6106.6 6107.4 6112.4 6115.8 869.8 1004.6 1015.6 1019.6 1065.1 24437.9 25067.7 30720.0 31389.8 31487.2 1089.6 1093.5 1124.9 1125.5 1132.1 11074.4 11215.7 12103.5 12674.2 12689.1 330981.0 331632.0 332257.0 332688.0 333356.0 53879.5 54137.7 54218.0 54237.7 54473.5 1467.8 1489.6 1529.3 1534.7 1542.2 20441.8 20669.7 23268.7 24285.9 24372.4 38454.8 38695.6 38700.0 38735.2 38795.4 283690.6 285136.0 285627.0 285727.6 286749.0 33967.7 34254.0 34264.8 34272.0 34326.5 33080.8 42583.5 43853.0 43867.0 44367.6 24479.0 29125.4 30465.1 30534.4 30992.8 29719.7 29760.1 30821.7 30827.4 30950.5 36504.8 36521.6 36567.5 36622.6 36626.0 25975.9 27508.9 28254.3 30607.2 31182.7 20132.4 22830.0 23661.8 24275.0 24300.6 19:15:29 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:37558.22 Median:11871.94 stddev:91120.76 (767.5%) stddev-sans-first:91333.73 19:15:29 INFO - PID 1650 | Values: 424.7 425.0 433.3 437.9 445.2 13589.4 14198.9 21450.1 22184.2 22446.9 22559.2 23388.3 23504.3 23527.9 23553.3 5917.4 6011.1 6019.9 6038.0 6053.8 3538.8 3745.0 5681.6 5927.1 6040.5 6017.5 6045.1 6052.2 6058.0 6061.0 6005.3 6042.4 6050.6 6053.6 6054.6 5363.5 5402.6 5404.7 5416.1 5441.9 22092.6 28133.6 28184.4 28440.0 28525.6 2189.9 2244.2 2253.1 2256.5 2259.4 18036.0 18598.4 18600.3 18621.6 18683.4 1896.5 2081.3 2107.3 2135.1 2162.9 198258.0 204057.0 204536.0 205224.0 206790.0 12769.9 12837.7 13044.6 13073.7 13142.3 16706.6 16849.5 16897.3 16937.5 16971.7 7816.8 8923.2 9126.0 9170.1 9191.3 8811.0 8811.7 8817.1 8897.8 8919.7 6931.0 7274.3 7899.5 8191.0 8305.5 2031.5 2224.5 2269.4 2286.9 2315.5 863.3 865.3 992.9 1004.6 1028.7 21218.2 21273.0 21320.6 21362.9 21581.0 1024.9 1036.6 1049.4 1068.4 1074.8 9288.4 9735.4 10697.1 11003.9 11131.0 512057.0 513208.0 515423.0 519343.0 525277.0 140647.0 145273.0 145805.5 145852.0 146433.0 1182.7 1193.4 1222.2 1224.4 1226.8 18167.4 18646.0 18650.8 19079.2 19138.5 67266.0 67604.7 67662.7 68327.7 68858.0 91226.2 91497.0 92024.5 92141.0 92756.5 8540.8 10126.0 10145.9 10246.0 10325.1 13454.8 16755.8 17649.4 18028.9 18070.8 14760.4 14761.9 14890.5 14895.3 14913.1 13057.5 13305.9 13431.7 13507.9 13516.3 13850.4 14812.2 15041.8 15042.6 15190.6 9770.2 10139.6 10196.5 10206.5 10216.0 14524.0 14718.6 14817.5 14849.1 14862.5 19:15:29 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7068.35 Median:3882.04 stddev:4436.95 (114.3%) stddev-sans-first:4428.69 19:15:29 INFO - PID 1650 | Values: 1363.6 1426.5 1436.0 1437.1 1446.3 11564.3 11631.4 12102.5 12102.6 12163.3 10864.8 11931.9 12106.8 12142.8 12147.5 2522.6 2572.0 2574.6 2582.3 2588.0 2517.7 2537.7 2552.8 2554.9 2556.7 2500.6 2536.0 2537.7 2539.1 2542.0 2479.9 2501.7 2507.5 2507.7 2517.3 2453.5 2491.4 2502.0 2559.7 2575.2 10172.4 11826.9 11890.9 11910.1 11936.6 6387.0 6568.0 6637.5 6651.4 6656.6 3976.0 4116.7 4198.3 4199.2 4293.2 1185.1 1206.5 1225.5 1231.9 1234.7 14185.9 15511.5 16285.8 16292.2 16421.9 5517.5 5523.8 5648.0 5710.5 5726.6 12047.5 12072.4 12075.8 12093.5 12124.9 6350.1 6369.9 6407.5 6439.4 6456.6 5973.2 6030.6 6085.6 6090.2 6121.3 4298.7 4362.7 4404.0 4438.6 4449.2 1105.7 1253.2 1275.9 1279.8 1283.1 757.6 759.6 784.2 790.7 791.2 6013.6 6616.8 6759.8 6941.0 7051.6 896.0 923.3 928.9 934.9 961.8 3881.9 3882.2 4072.3 4165.0 4240.6 14402.6 15187.1 15192.0 15208.9 15258.8 14990.5 15138.6 15140.8 15142.1 15167.8 8141.6 8253.9 8656.2 8699.3 8716.5 5977.5 6652.2 6770.0 6918.6 6996.5 12527.9 12741.8 12918.0 12946.8 12976.5 12598.6 13105.9 13133.8 13135.8 13195.8 7972.7 8831.2 9269.6 9601.5 9678.2 10750.9 10856.9 10867.4 10874.8 10889.7 8078.2 8157.9 8799.9 8863.9 9307.6 7660.2 8905.2 8912.3 9029.0 9202.1 8282.8 8299.3 8349.4 8387.0 8425.2 6100.8 6103.4 6103.9 6146.8 6232.9 6055.2 6176.6 6207.0 6216.5 6266.9 19:15:29 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5542.40 Median:6385.51 stddev:1775.43 (27.8%) stddev-sans-first:1736.81 19:15:29 INFO - PID 1650 | Values: 332.4 384.4 389.7 392.5 402.9 6360.5 6439.5 6464.2 6465.7 6512.4 6237.8 6395.5 6405.2 6409.9 6428.3 4277.3 4377.5 4555.5 4682.9 4703.3 3929.7 4650.7 4673.4 4679.7 4685.9 4601.2 4605.2 4614.9 4638.9 4640.6 4631.5 4662.9 4666.3 4670.7 4674.7 3389.9 3960.2 3968.7 3969.6 3982.1 5794.5 6362.7 6374.1 6413.6 6425.5 4683.9 5493.2 5501.7 5519.4 5540.4 6896.3 6903.7 6909.5 6911.0 6935.2 3847.0 4153.4 4486.3 4501.8 4507.6 6931.4 7229.5 7230.7 7321.0 7366.9 3621.8 4383.9 4506.3 4508.5 4516.3 4616.6 4616.7 4623.7 4625.7 4671.2 5265.2 5421.9 5445.6 5446.4 5448.7 5774.3 6080.6 6867.4 6876.7 6918.7 1569.7 1571.4 1573.3 1583.1 1591.1 987.5 1005.5 1024.2 1024.5 1031.8 6374.9 6384.6 6418.5 6423.8 6463.9 7060.4 7103.1 7108.0 7118.4 7170.4 5919.6 6413.0 6443.3 6455.2 6477.3 3149.7 3324.7 3710.2 3730.8 3751.4 6759.3 6771.5 7290.1 7321.9 7360.7 7363.3 7364.2 7408.5 7436.7 7443.3 6891.8 7027.4 7226.7 7333.5 7351.6 7032.3 7156.3 7162.6 7175.2 7189.7 6188.0 6386.4 6446.4 6467.4 7018.1 6388.9 7033.3 7138.3 7139.4 7231.4 5131.3 5139.9 5162.5 5163.2 5185.9 6574.2 7144.4 7149.6 7185.4 7196.5 5726.6 6624.3 6759.7 6876.6 6892.5 6057.8 6198.9 6451.4 6914.4 6929.8 6777.7 6814.5 6829.5 6831.8 6832.2 5614.4 5924.2 6820.1 6909.0 6932.3 5227.5 6664.7 6701.0 6702.7 6726.9 19:15:29 INFO - PID 1650 | 19:15:29 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:26537.06 Median:18174.59 stddev:40959.54 (225.4%) stddev-sans-first:41028.46 19:15:29 INFO - PID 1650 | Values: 688.9 805.1 822.2 844.8 853.7 14854.6 15474.8 17323.4 17815.9 17971.7 16207.4 17566.4 18022.4 18090.3 18127.8 701.9 909.5 918.8 920.2 921.0 957.2 970.3 972.8 974.2 974.6 688.1 691.0 997.3 1012.9 1013.9 1004.9 1005.8 1006.0 1006.2 1007.5 727.4 738.1 742.2 743.5 744.0 17155.7 17190.3 17301.7 17460.6 17621.7 17288.6 17320.4 17355.8 17756.3 17775.1 14350.6 14757.6 14758.1 14775.8 14825.3 3464.9 3543.9 3550.8 3571.2 3585.3 149360.0 152148.7 153226.0 153616.0 153790.2 13664.7 13812.0 13832.6 13859.7 13944.3 26569.0 26682.9 27208.7 27214.5 27431.9 6849.9 7082.0 7131.8 7436.7 7903.4 3911.5 3954.9 3989.7 3994.1 3996.2 4216.2 4240.2 4298.5 4299.4 4417.1 877.7 908.7 922.3 922.9 923.3 611.8 622.4 646.5 653.5 674.7 11619.7 12241.8 12431.1 12523.5 12586.3 578.2 590.0 590.2 591.7 619.7 5571.2 5578.8 5750.9 5765.1 5870.0 130352.0 136756.0 143394.8 145271.0 151562.0 121151.0 122394.0 125234.4 127377.4 130482.0 112804.0 112817.0 114288.4 116619.0 116966.0 11259.3 11476.4 11572.5 11574.6 11776.8 37515.6 47003.5 47413.5 47620.8 47891.5 70871.6 78543.8 81098.0 85035.0 85228.0 17391.7 17588.9 17738.6 17789.5 17846.2 17029.9 18127.7 18449.6 18978.0 19174.4 17601.8 17854.3 18200.6 18277.6 18471.9 17778.0 18181.2 18232.1 18247.0 18399.2 15444.2 17807.6 18014.3 18168.0 18382.5 11754.2 11892.6 12002.5 12028.1 12141.6 11438.5 13195.9 13751.4 13849.7 14108.3 19:15:29 INFO - PID 1650 | -------- Summary: end -------- 19:15:29 INFO - PID 1650 | 19:15:30 INFO - TEST-INFO | 1650: exit 0 19:15:30 INFO - TEST-OK | dromaeo_css | took 1143518ms 19:15:30 INFO - TEST-START | kraken 19:15:30 INFO - operating with platform_type : mac_ 19:15:30 INFO - Initialising browser for kraken test... 19:15:30 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpuZMHkI/profile 19:15:30 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 19:16:03 INFO - TEST-INFO | 1660: killed by SIGTERM 19:16:03 INFO - Browser initialized. 19:16:03 INFO - Running cycle 1/1 for kraken test... 19:16:03 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmpuZMHkI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:05 INFO - PID 1664 | 2017-05-30 19:16:05.914 plugin-container[1665:10663] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 19:16:05 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:05 INFO - PID 1664 | 2017-05-30 19:16:05.915 plugin-container[1665:10663] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 19:16:05 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:05 INFO - PID 1664 | 2017-05-30 19:16:05.915 plugin-container[1665:10663] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:16:06 INFO - PID 1664 | 2017-05-30 19:16:06.460 plugin-container[1666:10712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 19:16:06 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:06 INFO - PID 1664 | 2017-05-30 19:16:06.461 plugin-container[1666:10712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 19:16:06 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:06 INFO - PID 1664 | 2017-05-30 19:16:06.461 plugin-container[1666:10712] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:16:15 INFO - PID 1664 | RSS: Main: 269078528 19:16:15 INFO - PID 1664 | 19:16:18 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 19:16:19 INFO - PID 1664 | RSS: Main: 276226048 19:16:19 INFO - PID 1664 | 19:16:21 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 19:16:22 INFO - PID 1664 | RSS: Main: 284160000 19:16:22 INFO - PID 1664 | 19:16:26 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 19:16:27 INFO - PID 1664 | RSS: Main: 282013696 19:16:27 INFO - PID 1664 | 19:16:28 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 19:16:29 INFO - PID 1664 | RSS: Main: 282554368 19:16:29 INFO - PID 1664 | 19:16:31 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:16:32 INFO - PID 1664 | RSS: Main: 284647424 19:16:32 INFO - PID 1664 | 19:16:35 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 19:16:35 INFO - PID 1664 | RSS: Main: 289861632 19:16:35 INFO - PID 1664 | 19:16:39 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 19:16:39 INFO - PID 1664 | RSS: Main: 292294656 19:16:39 INFO - PID 1664 | 19:16:42 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 19:16:43 INFO - PID 1664 | RSS: Main: 290537472 19:16:43 INFO - PID 1664 | 19:16:44 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:16:45 INFO - PID 1664 | RSS: Main: 291143680 19:16:45 INFO - PID 1664 | 19:16:47 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:16:48 INFO - PID 1664 | RSS: Main: 290459648 19:16:48 INFO - PID 1664 | 19:16:50 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:16:51 INFO - PID 1664 | RSS: Main: 290205696 19:16:51 INFO - PID 1664 | 19:16:53 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:16:54 INFO - PID 1664 | RSS: Main: 290459648 19:16:54 INFO - PID 1664 | 19:16:57 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:16:58 INFO - PID 1664 | RSS: Main: 290336768 19:16:58 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 19:17:00 INFO - PID 1664 | RSS: Main: 292061184 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | __start_tp_report 19:17:00 INFO - PID 1664 | _x_x_mozilla_page_load 19:17:00 INFO - PID 1664 | _x_x_mozilla_page_load_details 19:17:00 INFO - PID 1664 | |i|pagename|runs| 19:17:00 INFO - PID 1664 | |0;ai-astar;102;117;117;115;116;116;117;117;139;121 19:17:00 INFO - PID 1664 | |1;audio-beat-detection;147;112;112;174;134;113;109;115;142;130 19:17:00 INFO - PID 1664 | |2;audio-dft;222;207;282;226;227;264;255;278;236;199 19:17:00 INFO - PID 1664 | |3;audio-fft;74;55;56;90;61;78;50;71;89;80 19:17:00 INFO - PID 1664 | |4;audio-oscillator;86;70;88;72;70;70;88;71;72;89 19:17:00 INFO - PID 1664 | |5;imaging-gaussian-blur;90;93;97;108;90;89;99;89;94;91 19:17:00 INFO - PID 1664 | |6;imaging-darkroom;98;96;100;114;148;124;92;95;108;105 19:17:00 INFO - PID 1664 | |7;imaging-desaturate;86;122;135;132;96;78;78;135;87;143 19:17:00 INFO - PID 1664 | |8;json-parse-financial;52;53;53;53;51;53;55;54;51;53 19:17:00 INFO - PID 1664 | |9;json-stringify-tinderbox;55;60;68;59;65;63;57;72;76;76 19:17:00 INFO - PID 1664 | |10;stanford-crypto-aes;83;88;86;85;92;109;111;107;128;90 19:17:00 INFO - PID 1664 | |11;stanford-crypto-ccm;132;136;135;139;137;137;145;156;158;156 19:17:00 INFO - PID 1664 | |12;stanford-crypto-pbkdf2;181;185;185;189;190;194;193;191;189;193 19:17:00 INFO - PID 1664 | |13;stanford-crypto-sha256-iterative;72;71;70;66;72;69;67;65;70;75 19:17:00 INFO - PID 1664 | __end_tp_report 19:17:00 INFO - PID 1664 | __start_cc_report 19:17:00 INFO - PID 1664 | _x_x_mozilla_cycle_collect,1181 19:17:00 INFO - PID 1664 | __end_cc_report 19:17:00 INFO - PID 1664 | __startTimestamp1496197020323__endTimestamp 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | ------- Summary: start ------- 19:17:00 INFO - PID 1664 | Number of tests: 14 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#0] ai-astar Cycles:10 Average:117.70 Median:117.00 stddev:8.98 (7.7%) stddev-sans-first:7.52 19:17:00 INFO - PID 1664 | Values: 102.0 117.0 117.0 115.0 116.0 116.0 117.0 117.0 139.0 121.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:132.00 stddev:21.01 (15.9%) stddev-sans-first:21.23 19:17:00 INFO - PID 1664 | Values: 147.0 112.0 112.0 174.0 134.0 113.0 109.0 115.0 142.0 130.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#2] audio-dft Cycles:10 Average:239.60 Median:245.50 stddev:28.83 (11.7%) stddev-sans-first:29.87 19:17:00 INFO - PID 1664 | Values: 222.0 207.0 282.0 226.0 227.0 264.0 255.0 278.0 236.0 199.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#3] audio-fft Cycles:10 Average:70.40 Median:76.00 stddev:14.31 (18.8%) stddev-sans-first:15.12 19:17:00 INFO - PID 1664 | Values: 74.0 55.0 56.0 90.0 61.0 78.0 50.0 71.0 89.0 80.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.00 stddev:8.80 (11.1%) stddev-sans-first:8.79 19:17:00 INFO - PID 1664 | Values: 86.0 70.0 88.0 72.0 70.0 70.0 88.0 71.0 72.0 89.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:94.00 Median:92.00 stddev:5.98 (6.5%) stddev-sans-first:6.17 19:17:00 INFO - PID 1664 | Values: 90.0 93.0 97.0 108.0 90.0 89.0 99.0 89.0 94.0 91.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#6] imaging-darkroom Cycles:10 Average:108.00 Median:120.00 stddev:17.11 (14.3%) stddev-sans-first:17.76 19:17:00 INFO - PID 1664 | Values: 98.0 96.0 100.0 114.0 148.0 124.0 92.0 95.0 108.0 105.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#7] imaging-desaturate Cycles:10 Average:109.20 Median:78.00 stddev:26.48 (33.9%) stddev-sans-first:26.72 19:17:00 INFO - PID 1664 | Values: 86.0 122.0 135.0 132.0 96.0 78.0 78.0 135.0 87.0 143.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.23 (2.3%) stddev-sans-first:1.27 19:17:00 INFO - PID 1664 | Values: 52.0 53.0 53.0 53.0 51.0 53.0 55.0 54.0 51.0 53.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:66.50 stddev:7.67 (11.5%) stddev-sans-first:7.21 19:17:00 INFO - PID 1664 | Values: 55.0 60.0 68.0 59.0 65.0 63.0 57.0 72.0 76.0 76.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#10] stanford-crypto-aes Cycles:10 Average:97.90 Median:85.50 stddev:14.94 (17.5%) stddev-sans-first:14.84 19:17:00 INFO - PID 1664 | Values: 83.0 88.0 86.0 85.0 92.0 109.0 111.0 107.0 128.0 90.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:142.00 stddev:9.94 (7.0%) stddev-sans-first:9.70 19:17:00 INFO - PID 1664 | Values: 132.0 136.0 135.0 139.0 137.0 137.0 145.0 156.0 158.0 156.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:190.50 stddev:4.19 (2.2%) stddev-sans-first:3.30 19:17:00 INFO - PID 1664 | Values: 181.0 185.0 185.0 189.0 190.0 194.0 193.0 191.0 189.0 193.0 19:17:00 INFO - PID 1664 | 19:17:00 INFO - PID 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.50 stddev:3.06 (4.3%) stddev-sans-first:3.13 19:17:00 INFO - PID 1664 | Values: 72.0 71.0 70.0 66.0 72.0 69.0 67.0 65.0 70.0 75.0 19:17:00 INFO - PID 1664 | -------- Summary: end -------- 19:17:00 INFO - PID 1664 | 19:17:01 INFO - TEST-INFO | 1664: exit 0 19:17:01 INFO - TEST-OK | kraken | took 91018ms 19:17:01 INFO - SUITE-END | took 1234s 19:17:01 INFO - Completed test suite (00:20:34) 19:17:01 INFO - javascript benchmark 19:17:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8050.94326359929, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3420.2988387380587, 3593.993681769104, 3604.4232922732367, 3620.094976255936, 3639.3994990826513, 6083.482903953158, 6096.969515152424, 6148.723307451164, 6160.414760597736, 6171.50627949764, 6118.101457354467, 6126.509879209663, 6151.692415379235, 6177.413145751158, 6183.567150299481, 5416.945830541684, 5448.073827449343, 5459.099248623972, 5507.834764957058, 5572.86067848303, 4532.682712210147, 5341.545968592674, 5487.0, 5555.055640541097, 5614.0, 5447.210154527571, 5478.91781623276, 5547.44525547444, 5551.3893471718075, 5567.526760225385, 4903.19097348937, 4930.186519224324, 5428.941356435496, 5481.890362192734, 5484.643498172606, 5402.540784033362, 5414.539764120054, 5442.12925931849, 5448.318960129984, 5453.072977593819, 6035.7887473938645, 6129.38706129388, 6153.569250152491, 6158.815235542941, 6170.321264660884, 6435.131257280254, 6547.705353259114, 6552.901706474407, 6566.606003639749, 6681.465482761368, 6803.523753337268, 6808.455323574102, 6816.863662726767, 6818.011388348711, 6824.931750682479, 5153.922691159673, 6224.719887605069, 6241.875162496724, 6244.219472565929, 6262.0, 5788.18965344852, 6707.6981535831, 6769.729210831511, 6822.079019332326, 6928.099347084847, 6342.112104305401, 6509.9023514647315, 6542.869142617121, 6582.0, 6593.703283352164, 6017.585867321176, 6348.8412789679705, 6528.477721782246, 6549.803505894735, 6596.703148358335, 5961.688428545713, 6178.66017369049, 6629.370209830058, 6645.767398141139, 6647.434968027772, 5672.546196304285, 6668.799936001928, 6686.197656281277, 6712.261651218362, 6736.831579210559, 5223.660462069954, 5301.787928482818, 5302.522772950376, 5326.0, 5365.678059316453, 4709.693869898446, 4786.976065119722, 4792.400949881265, 4827.662063655581, 4850.902981940412, 6127.4485296322655, 6223.813285601439, 6247.344028876993, 6250.218722659668, 6259.155014073133, 6580.670966451754, 6668.33316668339, 6763.222229443579, 6801.693923773442, 6830.658467076726, 6110.663913484712, 6123.25211838015, 6152.261728592597, 6155.199824022849, 6176.042713379434, 5960.195373624592, 5997.940020599738, 5998.850028749316, 6017.368176341509, 6036.577439579188, 6871.896921546181, 6878.243393226641, 6921.757738479129, 6927.7228910843005, 6928.1339832520935, 6573.375529324707, 6579.671016449254, 6642.933570664231, 6712.160979877515, 6785.389314961677, 6806.93193068063, 6849.863002739818, 6856.965715171392, 6872.278410766798, 6894.069300644349, 6539.149910511603, 6617.702203400762, 6644.33556644351, 6665.0668906352175, 6676.799696009128, 5509.752061157337, 6662.700178492077, 6683.097781799394, 6723.562968407038, 6799.762008329685, 6476.676166191766, 6523.869522609616, 6534.150560427114, 6534.281229064894, 6560.671966401756, 6304.968475157595, 6312.4634406076, 6527.11883895668, 6529.347065293433, 6539.6403197824575, 6241.21984751906, 6316.494680425647, 6366.363363663597, 6419.2938776735455, 6429.58207716497, 6372.330905254881, 6463.289038205699, 6566.11357466755, 6600.636964966973, 6602.735890564516, 6311.084892690443, 6326.810195694136, 6336.619802811846, 6405.551611387158, 6425.293217746138], "value": 6066.750675430491, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.5362967700672, 738.774673724514, 742.3726950726638, 1151.8732939376666, 1332.5202926946292, 33096.83451582742, 39623.603763962354, 39737.205255894936, 39795.602043979554, 39807.805765827, 40330.79834600823, 40448.19103617926, 40461.0, 40507.0, 40542.99999999993, 7757.806054848632, 7940.563269020202, 8201.58992050398, 8444.366672499556, 8445.957770211171, 8345.165483451667, 8453.619587118563, 8459.788505287355, 8472.74581762548, 8479.236868681817, 6671.866562668743, 8417.747467575982, 8453.408261421702, 8473.915260847405, 8478.406511544194, 8407.789805254886, 8456.78858028551, 8457.873131903027, 8467.745967620982, 8471.788205294855, 8143.144969778147, 8192.508449493049, 8205.179482051806, 8215.876761848576, 8219.794505137359, 37459.625403745886, 37614.0, 37635.43546846799, 37648.05879852998, 37724.62275377238, 3593.083763640255, 3614.132608174046, 3627.6553727396117, 3632.400653892105, 3633.1098880774116, 30309.0, 30720.924767633027, 30755.538666920012, 30807.845960770275, 30877.845610772027, 3093.535969604555, 3117.7817552771317, 3118.7037231462973, 3126.187191330248, 3143.6227652681596, 234850.0, 239069.0, 270001.999999998, 296749.0, 298654.0, 47974.520254797004, 48241.517584823705, 48276.5172348272, 48337.7915552114, 48462.84991475613, 50481.24278135758, 50511.24233136506, 50705.23942140871, 53461.0, 54261.72869135629, 14533.709325813425, 14757.188354640382, 14829.480968166063, 14834.554963351116, 14856.851431485547, 11841.230320029343, 11941.522339106512, 11963.282203067844, 11965.820512692317, 11975.520979160736, 13700.246486443142, 13777.724445511034, 13883.097598656055, 13914.026018178833, 14004.789928151085, 6064.4541991221, 6106.6336019838955, 6107.358727333655, 6112.388761123941, 6115.785947491905, 869.826034793044, 1004.6282875336173, 1015.5683834370363, 1019.6125472320471, 1065.1106326217696, 24437.877810610833, 25067.74932250654, 30720.0, 31389.843050785057, 31487.21281967969, 1089.5968491658136, 1093.4805967165692, 1124.8650161980452, 1125.5311818077323, 1132.116948779953, 11074.446277686084, 11215.66353009411, 12103.515859365703, 12674.176178548365, 12689.111762176559, 330981.0, 331632.0, 332257.0, 332688.0, 333356.0, 53879.46120538823, 54137.72931135398, 54218.0, 54237.72881135648, 54473.45526544763, 1467.7578199596946, 1489.6052546075298, 1529.2812378182236, 1534.6930613877269, 1542.2365928865283, 20441.79558204399, 20669.689954650392, 23268.650970235798, 24285.878570607034, 24372.390690232176, 38454.80772596119, 38695.6130438692, 38700.0, 38735.22529549337, 38795.41806872956, 283690.5815470992, 285136.0, 285627.0, 285727.57136214187, 286749.0, 33967.66032339645, 34254.0, 34264.828675856465, 34272.0, 34326.48510272298, 33080.83459582687, 42583.50957716465, 43853.0, 43867.0, 44367.55632443763, 24479.0, 29125.41749165047, 30465.086047418612, 30534.389312214076, 30992.845035774677, 29719.702802971693, 29760.089585218197, 30821.691783081882, 30827.383452331276, 30950.53574196344, 36504.81747591245, 36521.634783651825, 36567.45148822823, 36622.63377366299, 36626.0, 25975.870120649277, 27508.899644014014, 28254.293642659097, 30607.234819128807, 31182.688173118884, 20132.39602811918, 22830.0, 23661.81690915482, 24275.028998840557, 24300.635490468012], "value": 17513.654435351582, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.69634211538744, 425.0145789884653, 433.30022014446666, 437.85988483685213, 445.236419540488, 13589.38847751851, 14198.92900535496, 21450.141994320227, 22184.223552175677, 22446.88776556117, 22559.210427635036, 23388.298351049452, 23504.29487115385, 23527.882360588173, 23553.29340119795, 5917.40825917407, 6011.098335249726, 6019.939800601982, 6038.0, 6053.757849686007, 3538.7522873398743, 3745.007572993159, 5681.602287839853, 5927.081302398135, 6040.456358927696, 6017.518598512109, 6045.093236014589, 6052.213212282397, 6058.030715085587, 6061.0, 6005.318510816935, 6042.365551617083, 6050.606710563821, 6053.606515576495, 6054.636721796706, 5363.544098751572, 5402.648827826179, 5404.729763511808, 5416.133418653036, 5441.863953401157, 22092.55814883693, 28133.57799633007, 28184.436311273865, 28440.004599838907, 28525.57211641827, 2189.9452513687283, 2244.16965722682, 2253.0649780341137, 2256.548690261954, 2259.3560835162066, 18036.0, 18598.44204673862, 18600.255989760528, 18621.627567448577, 18683.439496815117, 1896.544829240986, 2081.3131666550003, 2107.3256557901477, 2135.092585651092, 2162.8702277863376, 198258.0, 204057.0, 204536.0, 205224.0, 206790.0, 12769.872301277053, 12837.743245135045, 13044.608661739972, 13073.673158170932, 13142.277174755289, 16706.58233544171, 16849.49451516432, 16897.324107035827, 16937.491875243762, 16971.660566788596, 7816.843663126706, 8923.196912277795, 9126.0, 9170.128837760401, 9191.264698824078, 8810.955945220232, 8811.691590794291, 8817.118288171132, 8897.822043559092, 8919.687810926587, 6931.0, 7274.254930064788, 7899.486533375312, 8191.0, 8305.543195124204, 2031.502281940934, 2224.477247846755, 2269.4326418395403, 2286.8856557172076, 2315.455867871049, 863.3481721300503, 865.2558799432483, 992.9404235745878, 1004.6182450668699, 1028.708133971295, 21218.24480369489, 21273.04271307795, 21320.573588528143, 21362.893185533972, 21581.0, 1024.9487525623838, 1036.5801850250662, 1049.3703777733451, 1068.433730123036, 1074.8172810622214, 9288.396254243453, 9735.41587504752, 10697.144228461704, 11003.944980275048, 11130.998210160962, 512057.0, 513208.0, 515423.0, 519343.0, 525277.0, 140647.0, 145273.0, 145805.54194458132, 145852.0, 146433.0, 1182.6806762174165, 1193.361551569906, 1222.1567118688076, 1224.4489979509078, 1226.7853125702788, 18167.36414225496, 18646.0, 18650.81349186545, 19079.236830526625, 19138.52153696113, 67265.9910101339, 67604.66197668981, 67662.66168669322, 68327.65836170787, 68858.0, 91226.17547649142, 91497.0, 92024.53987730018, 92141.0, 92756.53621731848, 8540.786480337792, 10126.038026387481, 10145.89854101479, 10246.0, 10325.141474534355, 13454.798178027533, 16755.832441675426, 17649.38227162043, 18028.909855450638, 18070.81929180744, 14760.409583616534, 14761.852381476478, 14890.478833240784, 14895.329710163285, 14913.105213687213, 13057.477700891857, 13305.866941330463, 13431.731365372443, 13507.932460337635, 13516.324183790968, 13850.376733046836, 14812.185329806964, 15041.774373384627, 15042.623934401727, 15190.620234494227, 9770.218382529523, 10139.64511242103, 10196.541155647865, 10206.489675516343, 10216.0, 14523.983321167418, 14718.63203419923, 14817.481388151364, 14849.109053456827, 14862.479813206486], "value": 10957.035140772796, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.5772910397777, 1426.4650755966513, 1436.0019786248554, 1437.1233547536, 1446.3202294921389, 11564.306141631487, 11631.360275184861, 12102.455389507471, 12102.576409825659, 12163.270203787768, 10864.78270434591, 11931.940340298308, 12106.818397724019, 12142.81785773212, 12147.453364598592, 2522.646829443879, 2572.0354866924904, 2574.600936854781, 2582.3156863431204, 2588.029488941647, 2517.748225177486, 2537.746225377466, 2552.8001839135577, 2554.910578129766, 2556.7315431879665, 2500.562401579716, 2535.9746402536016, 2537.6954765428172, 2539.1113110411393, 2542.0467324753217, 2479.925602231936, 2501.6747822783104, 2507.4608959073794, 2507.749225077496, 2517.282574466268, 2453.472503411775, 2491.4145175883655, 2501.962470562943, 2559.69283685957, 2575.22743177048, 10172.44051577163, 11826.881731182664, 11890.881091189063, 11910.106741994385, 11936.582219622358, 6386.968065159691, 6567.967160164217, 6637.46900247979, 6651.368120028589, 6656.633885136315, 3976.0, 4116.670666346685, 4198.286291330481, 4199.202151591188, 4293.248681480728, 1185.0934035462878, 1206.4631239098605, 1225.4669218889812, 1231.8644949055597, 1234.6542967968983, 14185.858141418557, 15511.457099001593, 16285.755713664304, 16292.185390730534, 16421.932574382627, 5517.475839795213, 5523.806666766724, 5648.039833228361, 5710.486056254957, 5726.570507211893, 12047.518099276105, 12072.396380180955, 12075.818862717066, 12093.45579448927, 12124.939375303067, 6350.0792385103905, 6369.872602547923, 6407.519436042316, 6439.420452159328, 6456.612603243727, 5973.223480947449, 6030.638161710312, 6085.6348619083, 6090.177825993523, 6121.296050954108, 4298.699091063596, 4362.672799540047, 4403.9559604404185, 4438.556144385536, 4449.199144154083, 1105.7125147461718, 1253.1916913590865, 1275.8851703346743, 1279.8464184297943, 1283.0633637444719, 757.5946868425366, 759.5974133709143, 784.1687810920442, 790.7390561114819, 791.2483141016057, 6013.609115407485, 6616.768413105518, 6759.763408280686, 6941.0, 7051.576905385693, 895.9596818143199, 923.3167456082585, 928.9256859451227, 934.9065093490733, 961.8268711631973, 3881.922361552753, 3882.165334453106, 4072.2873497138476, 4164.9583504165175, 4240.554741752133, 14402.567922962327, 15187.088774673333, 15192.0, 15208.935374523677, 15258.847411525965, 14990.47533336328, 15138.621534461727, 15140.848591514165, 15142.091474511564, 15167.848321516864, 8141.592920354077, 8253.876191857127, 8656.17766312212, 8699.347548933729, 8716.520591367409, 5977.5217982560425, 6652.2349929756765, 6769.984502324691, 6918.5502942308585, 6996.545224560591, 12527.93736031314, 12741.80887286673, 12917.966562674776, 12946.805797913226, 12976.480940762263, 12598.622041338775, 13105.868941310466, 13133.802992955305, 13135.802962955373, 13195.802062969255, 7972.720954766555, 8831.249343805592, 9269.582868770787, 9601.47191904452, 9678.17735492491, 10750.946245269037, 10856.945715271373, 10867.402292873969, 10874.782504350027, 10889.727756805827, 8078.19218078211, 8157.918420815716, 8799.856018717526, 8863.892013498313, 9307.581158848003, 7660.233976602295, 8905.154010369004, 8912.33157513176, 9028.954855225682, 9202.079792020744, 8282.751517454486, 8299.336053115858, 8349.415540912078, 8387.035490918623, 8425.241728244488, 6100.816975490742, 6103.42017508336, 6103.908441373294, 6146.754129834757, 6232.906506402317, 6055.1522786810765, 6176.567640265138, 6206.968965155326, 6216.471599914058, 6266.874662506633], "value": 5361.69601772612, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.4049950588447, 384.40033547665644, 389.67656844818805, 392.4740847264666, 402.883163882474, 6360.522960777937, 6439.517036222284, 6464.159659244304, 6465.741370345187, 6512.413882750551, 6237.844053898655, 6395.456386207178, 6405.231372235338, 6409.903851442232, 6428.3250258722865, 4277.25148099083, 4377.496587892401, 4555.5172431025, 4682.882927926795, 4703.294505824137, 3929.744566603176, 4650.697904537259, 4673.439187297529, 4679.672422930396, 4685.8594242172785, 4601.171789077965, 4605.217113090783, 4614.930776038362, 4638.884027899313, 4640.559146881058, 4631.490536041033, 4662.883427914296, 4666.300054991744, 4670.743109128996, 4674.742889141079, 3389.898283058016, 3960.2079584083012, 3968.6626636735914, 3969.642732154091, 3982.143839074613, 5794.50746686528, 6362.681865906686, 6374.107624932513, 6413.551051426402, 6425.45383642391, 4683.882902927454, 5493.176023596492, 5501.697406642673, 5519.448055194489, 5540.418256083093, 6896.27589103147, 6903.6548172591165, 6909.516333856632, 6910.965445172792, 6935.167779866398, 3847.0190101524213, 4153.356229784358, 4486.349479325512, 4501.7974191161475, 4507.639388848885, 6931.376176144171, 7229.530080544748, 7230.710771569131, 7321.0, 7366.889496657554, 3621.764585301947, 4383.868483945486, 4506.2564676828615, 4508.549145085465, 4516.345129956138, 4616.561426664462, 4616.676832621752, 4623.722576645412, 4625.699329543569, 4671.182543054952, 5265.209828658275, 5421.945780542144, 5445.564354851603, 5446.373667028263, 5448.727563621803, 5774.3070831499645, 6080.57435979486, 6867.416269617039, 6876.696306737299, 6918.654067296615, 1569.6782159657243, 1571.3793051744726, 1573.3313341829676, 1583.1292788966252, 1591.077175238382, 987.5259875259916, 1005.5324274212526, 1024.2318261304022, 1024.472396715692, 1031.7781676939344, 6374.904376434357, 6384.648844313608, 6418.518611104186, 6423.839404014843, 6463.935360646334, 7060.435165186773, 7103.076600041962, 7107.964460177666, 7118.430525557943, 7170.3905168060255, 5919.556033297434, 6413.0380442933, 6443.29123796382, 6455.223694138981, 6477.287498375175, 3149.732272756841, 3324.7007769300394, 3710.165212827146, 3730.7948062856804, 3751.3611796557298, 6759.290274521203, 6771.492138089663, 7290.125184977836, 7321.890171647429, 7360.742374016883, 7363.263673632594, 7364.153122390887, 7408.481406301615, 7436.739714110088, 7443.330100291, 6891.827704307432, 7027.3780975219615, 7226.7109315626785, 7333.4866559340335, 7351.632418379167, 7032.331928466788, 7156.342713296931, 7162.570245785061, 7175.174854891546, 7189.7483588074165, 6188.040853667689, 6386.361363863576, 6446.419822215836, 6467.380710812128, 7018.140192849001, 6388.872222555616, 7033.296670332926, 7138.250483699137, 7139.428845692436, 7231.409090000191, 5131.281620573197, 5139.948600513947, 5162.535371816404, 5163.225516172604, 5185.948140518546, 6574.178227721535, 7144.357007869109, 7149.640618595955, 7185.353318201387, 7196.532225405332, 5726.570507211893, 6624.33756624353, 6759.695813688494, 6876.553024053421, 6892.517523773488, 6057.848553786191, 6198.938010619836, 6451.3548645135115, 6914.407097338498, 6929.757458488929, 6777.661116944035, 6814.488913331619, 6829.52193346461, 6831.795046148623, 6832.248452670302, 5614.410486898817, 5924.170616113834, 6820.147481564804, 6909.0, 6932.272111428228, 5227.529522342856, 6664.667666166916, 6700.966495167493, 6702.664866756545, 6726.8720045179225], "value": 4970.344338170214, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.9448844092473, 805.0902480197376, 822.2435359469291, 844.7877296080126, 853.6713365354339, 14854.554363369116, 15474.845251547482, 17323.39368122116, 17815.91092044541, 17971.73042404365, 16207.432739854108, 17566.385176518823, 18022.369217077405, 18090.276388944425, 18127.81872181281, 701.9017337572757, 909.4815954905706, 918.7978644698189, 920.2040235196574, 921.0283151275402, 957.1816097236866, 970.277143528069, 972.8103019911085, 974.2449601558777, 974.5663179884954, 688.1432616392573, 691.0325544238057, 997.2548040928375, 1012.9493525323741, 1013.9138173255283, 1004.8542961270612, 1005.8290090684601, 1005.9849102263508, 1006.1849901579744, 1007.5214273220198, 727.3526561360394, 738.0995185873226, 742.1873049011324, 743.4609907816844, 744.0178963767829, 17155.656886862318, 17190.312387504484, 17301.7404738929, 17460.563485912953, 17621.7356739649, 17288.56778580533, 17320.393786217548, 17355.826441735546, 17756.289748410047, 17775.111244437725, 14350.569482915529, 14757.631059223499, 14758.114513129247, 14775.778363324558, 14825.258737063104, 3464.9480257796154, 3543.911402214965, 3550.822458877046, 3571.160777217352, 3585.3008663310666, 149360.0, 152148.71776923354, 153226.0, 153616.0, 153790.23104884516, 13664.726705465835, 13812.033157678867, 13832.585022449342, 13859.722805544034, 13944.302784860716, 26569.0, 26682.866585666947, 27208.727912721017, 27214.45571088567, 27431.86284068567, 6849.863002739918, 7081.964590177016, 7131.750388736473, 7436.73971410998, 7903.446758726834, 3911.530616326059, 3954.9011274718364, 3989.740666856645, 3994.08136128692, 3996.2407142642805, 4216.241076606179, 4240.194363071007, 4298.527162012176, 4299.376590394375, 4417.094495628388, 877.6708734224666, 908.7410088124852, 922.280621115531, 922.8523436250168, 923.3213588012806, 611.7858749437751, 622.3931666625004, 646.4828137489989, 653.5327241022596, 674.6694119881223, 11619.6514104577, 12241.755164896653, 12431.067669924792, 12523.499060037679, 12586.307753073486, 578.1587789765964, 589.9882002359928, 590.1796502860941, 591.7011908985935, 619.7180282971237, 5571.220029195916, 5578.776848925998, 5750.856228594319, 5765.135229715576, 5870.031444811643, 130352.0, 136756.0, 143394.84907726393, 145271.0, 151562.0, 121151.0, 122394.0, 125234.37382813028, 127377.3631131857, 130482.0, 112804.0, 112817.0, 114288.42855785834, 116619.0, 116966.0, 11259.268147570381, 11476.426178690865, 11572.479238434458, 11574.594889178838, 11776.76446471083, 37515.62484375121, 47003.52996469991, 47413.525864740914, 47620.7618961903, 47891.52108478871, 70871.64564177147, 78543.82184267126, 81098.0, 85035.0, 85228.0, 17391.739123913405, 17588.912055440152, 17738.5565360867, 17789.46631601054, 17846.19692113832, 17029.89305695126, 18127.72808407901, 18449.63100737951, 18978.0, 19174.424767257005, 17601.82398176002, 17854.285828566713, 18200.635987279915, 18277.634447311244, 18471.90764046171, 17778.0, 18181.181846817188, 18232.088395579904, 18246.996415197293, 18399.172037258624, 15444.150571718214, 17807.643847122727, 18014.279428823225, 18168.0, 18382.540436489195, 11754.177207595387, 11892.643220703392, 12002.459889304828, 12028.09789265791, 12141.635750927486, 11438.542458301576, 13195.934020330222, 13751.381187846771, 13849.723005540034, 14108.2945852709], "value": 8776.790714200986, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 117.0, 115.0, 116.0, 116.0, 117.0, 117.0, 139.0, 121.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 112.0, 174.0, 134.0, 113.0, 109.0, 115.0, 142.0, 130.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 207.0, 282.0, 226.0, 227.0, 264.0, 255.0, 278.0, 236.0, 199.0], "value": 239.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 55.0, 56.0, 90.0, 61.0, 78.0, 50.0, 71.0, 89.0, 80.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 88.0, 72.0, 70.0, 70.0, 88.0, 71.0, 72.0, 89.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 97.0, 108.0, 90.0, 89.0, 99.0, 89.0, 94.0, 91.0], "value": 94.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 100.0, 114.0, 148.0, 124.0, 92.0, 95.0, 108.0, 105.0], "value": 108.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 122.0, 135.0, 132.0, 96.0, 78.0, 78.0, 135.0, 87.0, 143.0], "value": 109.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 53.0, 51.0, 53.0, 55.0, 54.0, 51.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 60.0, 68.0, 59.0, 65.0, 63.0, 57.0, 72.0, 76.0, 76.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 86.0, 85.0, 92.0, 109.0, 111.0, 107.0, 128.0, 90.0], "value": 97.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 136.0, 135.0, 139.0, 137.0, 137.0, 145.0, 156.0, 158.0, 156.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 185.0, 189.0, 190.0, 194.0, 193.0, 191.0, 189.0, 193.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 70.0, 66.0, 72.0, 69.0, 67.0, 65.0, 70.0, 75.0], "value": 69.7, "unit": "score"}]}]} 19:17:01 INFO - Return code: 0 19:17:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:17:01 INFO - # TBPL SUCCESS # 19:17:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:17:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:17:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:17:01 INFO - 2017-05-30 19:17:01.653 screenresolution[1672:11097] starting screenresolution argv=screenresolution get 19:17:01 INFO - 2017-05-30 19:17:01.655 screenresolution[1672:11097] Display 0: 1600x1200x32@60 19:17:01 INFO - 2017-05-30 19:17:01.662 screenresolution[1673:11100] starting screenresolution argv=screenresolution list 19:17:01 INFO - Available Modes on Display 0 19:17:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:17:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:17:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:17:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:17:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:17:01 INFO - Intel Iris: 19:17:01 INFO - Chipset Model: Intel Iris 19:17:01 INFO - Type: GPU 19:17:01 INFO - Bus: Built-In 19:17:01 INFO - VRAM (Dynamic, Max): 1536 MB 19:17:01 INFO - Vendor: Intel (0x8086) 19:17:01 INFO - Device ID: 0x0a2e 19:17:01 INFO - Revision ID: 0x0009 19:17:01 INFO - Displays: 19:17:01 INFO - Display: 19:17:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:17:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:17:01 INFO - Display Serial Number: 1600x1200 60 19:17:01 INFO - Main Display: Yes 19:17:01 INFO - Mirror: Off 19:17:01 INFO - Online: Yes 19:17:01 INFO - Rotation: Supported 19:17:01 INFO - Return code: 0 19:17:01 INFO - Running post-action listener: _resource_record_post_action 19:17:01 INFO - [mozharness: 2017-05-31 02:17:01.897626Z] Finished run-tests step (success) 19:17:01 INFO - Running post-run listener: _resource_record_post_run 19:17:01 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 38706176; Write bytes: 571104256; Read time: 271; Write time: 1781 19:17:01 INFO - TinderboxPrint: CPU usage
53.4% 19:17:01 INFO - TinderboxPrint: I/O read bytes / time
38,706,176 / 271 19:17:01 INFO - TinderboxPrint: I/O write bytes / time
571,104,256 / 1,781 19:17:01 INFO - TinderboxPrint: CPU idle
2,344.1 (46.6%) 19:17:01 INFO - TinderboxPrint: CPU system
1,013.8 (20.2%) 19:17:01 INFO - TinderboxPrint: CPU user
1,671.9 (33.2%) 19:17:01 INFO - TinderboxPrint: Swap in / out
284,844,032 / 0 19:17:01 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 245881344; Write bytes: 239396864; Read time: 18118; Write time: 521 19:17:01 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:01 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 36137984; Write bytes: 320308224; Read time: 244; Write time: 1220 19:17:02 INFO - Running post-run listener: _upload_blobber_files 19:17:02 INFO - Blob upload gear active. 19:17:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:17:02 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:02 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:02 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:02 INFO - (blobuploader) - INFO - Open directory for files ... 19:17:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:17:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:17:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:03 INFO - (blobuploader) - INFO - Done attempting. 19:17:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:17:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:17:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:05 INFO - (blobuploader) - INFO - Done attempting. 19:17:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:17:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:17:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:06 INFO - (blobuploader) - INFO - Done attempting. 19:17:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:17:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:17:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:08 INFO - (blobuploader) - INFO - Done attempting. 19:17:08 INFO - (blobuploader) - INFO - Iteration through files over. 19:17:08 INFO - Return code: 0 19:17:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:17:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:17:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a0de5e65e9cb3b2421e698a00d465d1fdf9a693f2bfbdc3720fb675d0b17f78ff107230106dfd4dbcb6f766f49f14af8f91ea96cb557ead04f9571c297a4610", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f7cb0a58408fdf85f5935ac29706a687467da0c3814d8eb57e2793038afe25c647107a624bc3396a73b889805e315c7807b45049d738c0d3a093ddff169c13b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c360207160fb240b7fb5edf79bb206e1b2bb6d57fa7c5699275eaaa3f38a6dea2629cd1293f3dc6715994062911bd18f780d8d4c7c80ceca4152782751ff9e8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e393a493de672755a11aca8878b792eef3cb21a0db14a540bd70aa6d6132e35aeaa91384abea914b62cafa2ffda73549aa75a083c05418dc6cc822b717191a2"} 19:17:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:17:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:17:08 INFO - Contents: 19:17:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a0de5e65e9cb3b2421e698a00d465d1fdf9a693f2bfbdc3720fb675d0b17f78ff107230106dfd4dbcb6f766f49f14af8f91ea96cb557ead04f9571c297a4610", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f7cb0a58408fdf85f5935ac29706a687467da0c3814d8eb57e2793038afe25c647107a624bc3396a73b889805e315c7807b45049d738c0d3a093ddff169c13b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c360207160fb240b7fb5edf79bb206e1b2bb6d57fa7c5699275eaaa3f38a6dea2629cd1293f3dc6715994062911bd18f780d8d4c7c80ceca4152782751ff9e8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e393a493de672755a11aca8878b792eef3cb21a0db14a540bd70aa6d6132e35aeaa91384abea914b62cafa2ffda73549aa75a083c05418dc6cc822b717191a2"} 19:17:08 INFO - Running post-run listener: copy_logs_to_upload_dir 19:17:08 INFO - Copying logs to upload dir... 19:17:08 INFO - mkdir: /builds/slave/test/build/upload/logs 19:17:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.967768 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-05-30 19:17:08.866733) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 19:17:08.870364) ========= 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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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/4a0de5e65e9cb3b2421e698a00d465d1fdf9a693f2bfbdc3720fb675d0b17f78ff107230106dfd4dbcb6f766f49f14af8f91ea96cb557ead04f9571c297a4610", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f7cb0a58408fdf85f5935ac29706a687467da0c3814d8eb57e2793038afe25c647107a624bc3396a73b889805e315c7807b45049d738c0d3a093ddff169c13b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c360207160fb240b7fb5edf79bb206e1b2bb6d57fa7c5699275eaaa3f38a6dea2629cd1293f3dc6715994062911bd18f780d8d4c7c80ceca4152782751ff9e8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e393a493de672755a11aca8878b792eef3cb21a0db14a540bd70aa6d6132e35aeaa91384abea914b62cafa2ffda73549aa75a083c05418dc6cc822b717191a2"} build_url:https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010857 build_url: 'https://queue.taskcluster.net/v1/task/AweIWHGYREGposN6LvIM7g/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/4a0de5e65e9cb3b2421e698a00d465d1fdf9a693f2bfbdc3720fb675d0b17f78ff107230106dfd4dbcb6f766f49f14af8f91ea96cb557ead04f9571c297a4610", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f7cb0a58408fdf85f5935ac29706a687467da0c3814d8eb57e2793038afe25c647107a624bc3396a73b889805e315c7807b45049d738c0d3a093ddff169c13b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c360207160fb240b7fb5edf79bb206e1b2bb6d57fa7c5699275eaaa3f38a6dea2629cd1293f3dc6715994062911bd18f780d8d4c7c80ceca4152782751ff9e8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e393a493de672755a11aca8878b792eef3cb21a0db14a540bd70aa6d6132e35aeaa91384abea914b62cafa2ffda73549aa75a083c05418dc6cc822b717191a2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 19:17:08.903328) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 19:17:08.903626) ========= 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.7WlqEv3S8X/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.zOjk9qGMxM/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.005062 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 19:17:08.951800) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 19:17:08.952116) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 19:17:08.952422) ========= ========= Total master_lag: 0.17 =========