builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0110 starttime: 1496323348.47 results: success (0) revision: 0bcea6bac1797e14b00af45cc7c368d12460ab7f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:28.470813) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:28.471291) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:28.471570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:28.497711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:28.498129) ========= 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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 06:22:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.3M=0.001s 2017-06-01 06:22:28 (13.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108205 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:28.629725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:28.630128) ========= 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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.029544 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:28.681125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-01 06:22:28.681461) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0bcea6bac1797e14b00af45cc7c368d12460ab7f --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 0bcea6bac1797e14b00af45cc7c368d12460ab7f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3qMnheLZjD/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.6ZGSo6fyNe/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 06:22:28,776 truncating revision to first 12 chars 2017-06-01 06:22:28,776 Setting DEBUG logging. 2017-06-01 06:22:28,776 attempt 1/10 2017-06-01 06:22:28,776 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0bcea6bac179?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 06:22:30,273 unpacking tar archive at: mozilla-central-0bcea6bac179/testing/mozharness/ program finished with exit code 0 elapsedTime=1.763293 ========= master_lag: 2.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-01 06:22:32.526071) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:32.526367) ========= script_repo_revision: 0bcea6bac1797e14b00af45cc7c368d12460ab7f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:32.526732) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:32.527017) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 06:22:32.651657) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-01 06:22:32.651956) ========= /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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 06:22:32 INFO - MultiFileLogger online at 20170601 06:22:32 in /builds/slave/test 06:22:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:22:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:22:32 INFO - {'append_to_log': False, 06:22:32 INFO - 'base_work_dir': '/builds/slave/test', 06:22:32 INFO - 'blob_upload_branch': 'Firefox', 06:22:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:22:32 INFO - 'branch': 'Firefox', 06:22:32 INFO - 'buildbot_json_path': 'buildprops.json', 06:22:32 INFO - 'config_files': ('talos/mac_config.py',), 06:22:32 INFO - 'default_actions': ('clobber', 06:22:32 INFO - 'read-buildbot-config', 06:22:32 INFO - 'download-and-extract', 06:22:32 INFO - 'populate-webroot', 06:22:32 INFO - 'create-virtualenv', 06:22:32 INFO - 'install', 06:22:32 INFO - 'setup-mitmproxy', 06:22:32 INFO - 'run-tests'), 06:22:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:22:32 INFO - 'download_minidump_stackwalk': True, 06:22:32 INFO - 'download_symbols': 'ondemand', 06:22:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:22:32 INFO - 'tooltool.py': '/tools/tooltool.py', 06:22:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:22:32 INFO - '/tools/misc-python/virtualenv.py')}, 06:22:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:22:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:22:32 INFO - 'gecko_profile': False, 06:22:32 INFO - 'gecko_profile_interval': 0, 06:22:32 INFO - 'installer_path': 'installer.exe', 06:22:32 INFO - 'log_level': 'info', 06:22:32 INFO - 'log_name': 'talos', 06:22:32 INFO - 'log_to_console': True, 06:22:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:22:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:22:32 INFO - 'opt_config_files': (), 06:22:32 INFO - 'pip_index': False, 06:22:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:22:32 INFO - 'cmd': ('bash', 06:22:32 INFO - '-c', 06:22:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:22:32 INFO - 'enabled': True, 06:22:32 INFO - 'halt_on_failure': False, 06:22:32 INFO - 'name': 'check_screen_resolution'},), 06:22:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:22:32 INFO - 'cmd': ('bash', 06:22:32 INFO - '-c', 06:22:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:22:32 INFO - 'enabled': True, 06:22:32 INFO - 'halt_on_failure': False, 06:22:32 INFO - 'name': 'check_screen_resolution'},), 06:22:32 INFO - 'run_cmd_checks_enabled': True, 06:22:32 INFO - 'suite': 'dromaeojs-e10s', 06:22:32 INFO - 'system_bits': '32', 06:22:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:22:32 INFO - 'title': 't-yosemite-r7-0110', 06:22:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:22:32 INFO - 'use_talos_json': True, 06:22:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:22:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:22:32 INFO - 'work_dir': 'build'} 06:22:32 INFO - [mozharness: 2017-06-01 13:22:32.871347Z] Running clobber step. 06:22:32 INFO - Running pre-action listener: _resource_record_pre_action 06:22:32 INFO - Running main action method: clobber 06:22:32 INFO - rmtree: /builds/slave/test/build 06:22:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:22:35 INFO - Running post-action listener: _resource_record_post_action 06:22:35 INFO - [mozharness: 2017-06-01 13:22:35.306031Z] Finished clobber step (success) 06:22:35 INFO - [mozharness: 2017-06-01 13:22:35.306209Z] Running read-buildbot-config step. 06:22:35 INFO - Running pre-action listener: _resource_record_pre_action 06:22:35 INFO - Running main action method: read_buildbot_config 06:22:35 INFO - Using buildbot properties: 06:22:35 INFO - { 06:22:35 INFO - "project": "", 06:22:35 INFO - "product": "firefox", 06:22:35 INFO - "who": "cbook@mozilla.com", 06:22:35 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 06:22:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:22:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:22:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:22:35 INFO - "stage_platform": "macosx64", 06:22:35 INFO - "basedir": "/builds/slave/test", 06:22:35 INFO - "buildnumber": 70, 06:22:35 INFO - "platform": "yosemite_r7", 06:22:35 INFO - "parent_task_id": "QV5zZ7ObTCC0mimt3wLK3w", 06:22:35 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:22:35 INFO - "slavebuilddir": "test", 06:22:35 INFO - "taskId": "KsiYbHC2QIO2x866YDDywg", 06:22:35 INFO - "branch": "mozilla-central", 06:22:35 INFO - "script_repo_revision": "production", 06:22:35 INFO - "revision": "0bcea6bac1797e14b00af45cc7c368d12460ab7f", 06:22:35 INFO - "slavename": "t-yosemite-r7-0110", 06:22:35 INFO - "repo_path": "mozilla-central" 06:22:35 INFO - } 06:22:35 INFO - Finding installer, test and symbols from parent task. 06:22:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsiYbHC2QIO2x866YDDywg'}, attempt #1 06:22:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsiYbHC2QIO2x866YDDywg'}, attempt #1 06:22:36 INFO - Task dependencies: KcWFheBGRzmgQ_D9gqITgQ QV5zZ7ObTCC0mimt3wLK3w 06:22:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w'}, attempt #1 06:22:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:22:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/test_packages.json 06:22:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/target.crashreporter-symbols.zip 06:22:37 INFO - Running post-action listener: _resource_record_post_action 06:22:37 INFO - [mozharness: 2017-06-01 13:22:37.200033Z] Finished read-buildbot-config step (success) 06:22:37 INFO - [mozharness: 2017-06-01 13:22:37.200175Z] Running download-and-extract step. 06:22:37 INFO - Running pre-action listener: _resource_record_pre_action 06:22:37 INFO - Running main action method: download_and_extract 06:22:37 INFO - mkdir: /builds/slave/test/build/tests 06:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:22:37 INFO - trying https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 06:22:37 INFO - Downloading https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:22:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 06:22:38 INFO - Downloaded 1325 bytes. 06:22:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:22:38 INFO - Using the following test package requirements: 06:22:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 06:22:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:22:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 06:22:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 06:22:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'jsshell-mac.zip'], 06:22:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 06:22:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:22:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 06:22:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 06:22:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 06:22:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:22:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 06:22:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:22:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:22:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:22:38 INFO - Fetch https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:22:39 INFO - Content-Length response header: 57630373 06:22:39 INFO - Bytes received: 57630373 06:22:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:22:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:22:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:22:58 INFO - Fetch https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:23:00 INFO - Content-Length response header: 57630373 06:23:00 INFO - Bytes received: 57630373 06:23:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 06:23:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:23:17 INFO - Fetch https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 06:23:18 INFO - Content-Length response header: 14447425 06:23:18 INFO - Bytes received: 14447425 06:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:19 INFO - trying https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:23:19 INFO - Downloading https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 06:23:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 06:23:21 INFO - Downloaded 67867761 bytes. 06:23:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:23:21 INFO - mkdir: /builds/slave/test/properties 06:23:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:23:21 INFO - Writing to file /builds/slave/test/properties/build_url 06:23:21 INFO - Contents: 06:23:21 INFO - build_url:https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:23:21 INFO - Running post-action listener: _resource_record_post_action 06:23:21 INFO - Running post-action listener: set_extra_try_arguments 06:23:21 INFO - [mozharness: 2017-06-01 13:23:21.096185Z] Finished download-and-extract step (success) 06:23:21 INFO - [mozharness: 2017-06-01 13:23:21.096328Z] Running populate-webroot step. 06:23:21 INFO - Running pre-action listener: _resource_record_pre_action 06:23:21 INFO - Running main action method: populate_webroot 06:23:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:23:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:23:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:23:21 INFO - '--tppagecycles', 06:23:21 INFO - '7'], 06:23:21 INFO - 'tests': ['tsvgm']}}, 06:23:21 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:23:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:23:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:23:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:23:21 INFO - 'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:23:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:23:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:23:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:23:21 INFO - 'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['damp', 'tps']}, 06:23:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:23:21 INFO - 'tests': ['damp', 'tps']}, 06:23:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['dromaeo_dom']}, 06:23:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:23:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:23:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:23:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:23:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:23:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['a11yr', 06:23:21 INFO - 'ts_paint', 06:23:21 INFO - 'tpaint', 06:23:21 INFO - 'sessionrestore', 06:23:21 INFO - 'sessionrestore_no_auto_restore', 06:23:21 INFO - 'tabpaint']}, 06:23:21 INFO - 'other-e10s': {'tests': ['a11yr', 06:23:21 INFO - 'ts_paint', 06:23:21 INFO - 'tpaint', 06:23:21 INFO - 'sessionrestore', 06:23:21 INFO - 'sessionrestore_no_auto_restore', 06:23:21 INFO - 'tabpaint']}, 06:23:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:23:21 INFO - 'ts_paint', 06:23:21 INFO - 'tpaint', 06:23:21 INFO - 'sessionrestore', 06:23:21 INFO - 'sessionrestore_no_auto_restore', 06:23:21 INFO - 'tabpaint']}, 06:23:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:23:21 INFO - 'ts_paint', 06:23:21 INFO - 'tpaint', 06:23:21 INFO - 'sessionrestore', 06:23:21 INFO - 'sessionrestore_no_auto_restore', 06:23:21 INFO - 'tabpaint']}, 06:23:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['a11yr', 06:23:21 INFO - 'ts_paint', 06:23:21 INFO - 'tpaint', 06:23:21 INFO - 'sessionrestore', 06:23:21 INFO - 'sessionrestore_no_auto_restore', 06:23:21 INFO - 'tabpaint']}, 06:23:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['a11yr', 06:23:21 INFO - 'ts_paint', 06:23:21 INFO - 'tpaint', 06:23:21 INFO - 'sessionrestore', 06:23:21 INFO - 'sessionrestore_no_auto_restore', 06:23:21 INFO - 'tabpaint']}, 06:23:21 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:23:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:23:21 INFO - 'bloom_basic_ref']}, 06:23:21 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 06:23:21 INFO - 'talos_options': ['--mitmproxy', 06:23:21 INFO - 'mitmproxy-recording-1.mp', 06:23:21 INFO - '--firstNonBlankPaint'], 06:23:21 INFO - 'tests': ['Quantum_1']}, 06:23:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['tsvgx', 06:23:21 INFO - 'tsvgr_opacity', 06:23:21 INFO - 'tart', 06:23:21 INFO - 'tscrollx', 06:23:21 INFO - 'cart', 06:23:21 INFO - 'tsvg_static']}, 06:23:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:23:21 INFO - 'tsvgr_opacity', 06:23:21 INFO - 'tart', 06:23:21 INFO - 'tscrollx', 06:23:21 INFO - 'cart', 06:23:21 INFO - 'tsvg_static']}, 06:23:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:23:21 INFO - 'talos_options': ['--disable-e10s'], 06:23:21 INFO - 'tests': ['tp5o']}, 06:23:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:23:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:23:21 INFO - 'talos_options': ['--disable-e10s', 06:23:21 INFO - '--xperf_path', 06:23:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:23:21 INFO - 'tests': ['tp5n']}, 06:23:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:23:21 INFO - 'talos_options': ['--xperf_path', 06:23:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:23:21 INFO - 'tests': ['tp5n']}}, 06:23:21 INFO - 'talos.zip': {'path': '', 06:23:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:23:21 INFO - Running post-action listener: _resource_record_post_action 06:23:21 INFO - [mozharness: 2017-06-01 13:23:21.117935Z] Finished populate-webroot step (success) 06:23:21 INFO - [mozharness: 2017-06-01 13:23:21.118048Z] Running create-virtualenv step. 06:23:21 INFO - Running pre-action listener: _resource_record_pre_action 06:23:21 INFO - Running main action method: create_virtualenv 06:23:21 INFO - Creating virtualenv /builds/slave/test/build/venv 06:23:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:23:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:23:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:23:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:23:21 INFO - Using real prefix '/tools/python27' 06:23:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:23:21 INFO - Installing distribute.............................................................................................................................................................................................done. 06:23:24 INFO - Installing pip.................done. 06:23:24 INFO - Return code: 0 06:23:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:23:25 INFO - Reading from file tmpfile_stdout 06:23:25 INFO - Output received: 06:23:25 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:23:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:25 INFO - 'HOME': '/Users/cltbld', 06:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:25 INFO - 'LOGNAME': 'cltbld', 06:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:25 INFO - 'MOZ_NO_REMOTE': '1', 06:23:25 INFO - 'NO_EM_RESTART': '1', 06:23:25 INFO - 'PAGER': '/bin/cat', 06:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:25 INFO - 'PWD': '/builds/slave/test', 06:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:25 INFO - 'SHELL': '/bin/bash', 06:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:25 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:25 INFO - 'USER': 'cltbld', 06:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:25 INFO - 'XPC_FLAGS': '0x0', 06:23:25 INFO - 'XPC_SERVICE_NAME': '0', 06:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:23:25 INFO - Cleaning up... 06:23:25 INFO - Return code: 0 06:23:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:25 INFO - 'HOME': '/Users/cltbld', 06:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:25 INFO - 'LOGNAME': 'cltbld', 06:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:25 INFO - 'MOZ_NO_REMOTE': '1', 06:23:25 INFO - 'NO_EM_RESTART': '1', 06:23:25 INFO - 'PAGER': '/bin/cat', 06:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:25 INFO - 'PWD': '/builds/slave/test', 06:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:25 INFO - 'SHELL': '/bin/bash', 06:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:25 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:25 INFO - 'USER': 'cltbld', 06:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:25 INFO - 'XPC_FLAGS': '0x0', 06:23:25 INFO - 'XPC_SERVICE_NAME': '0', 06:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:25 INFO - Downloading/unpacking psutil>=3.1.1 06:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:23:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:23:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:23:28 INFO - Installing collected packages: psutil 06:23:28 INFO - Running setup.py install for psutil 06:23:28 INFO - building 'psutil._psutil_osx' extension 06:23:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:23:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:23:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:23:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:23:28 INFO - building 'psutil._psutil_posix' extension 06:23:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:23:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:23:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:23:29 INFO - ^ 06:23:29 INFO - 1 warning generated. 06:23:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:23:29 INFO - Successfully installed psutil 06:23:29 INFO - Cleaning up... 06:23:29 INFO - Return code: 0 06:23:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:29 INFO - 'HOME': '/Users/cltbld', 06:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:29 INFO - 'LOGNAME': 'cltbld', 06:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:29 INFO - 'MOZ_NO_REMOTE': '1', 06:23:29 INFO - 'NO_EM_RESTART': '1', 06:23:29 INFO - 'PAGER': '/bin/cat', 06:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:29 INFO - 'PWD': '/builds/slave/test', 06:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:29 INFO - 'SHELL': '/bin/bash', 06:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:29 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:29 INFO - 'USER': 'cltbld', 06:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:29 INFO - 'XPC_FLAGS': '0x0', 06:23:29 INFO - 'XPC_SERVICE_NAME': '0', 06:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:23:31 INFO - Installing collected packages: mozsystemmonitor 06:23:31 INFO - Running setup.py install for mozsystemmonitor 06:23:31 INFO - Successfully installed mozsystemmonitor 06:23:31 INFO - Cleaning up... 06:23:31 INFO - Return code: 0 06:23:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:31 INFO - 'HOME': '/Users/cltbld', 06:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:31 INFO - 'LOGNAME': 'cltbld', 06:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:31 INFO - 'MOZ_NO_REMOTE': '1', 06:23:31 INFO - 'NO_EM_RESTART': '1', 06:23:31 INFO - 'PAGER': '/bin/cat', 06:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:31 INFO - 'PWD': '/builds/slave/test', 06:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:31 INFO - 'SHELL': '/bin/bash', 06:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:31 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:31 INFO - 'USER': 'cltbld', 06:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:31 INFO - 'XPC_FLAGS': '0x0', 06:23:31 INFO - 'XPC_SERVICE_NAME': '0', 06:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:31 INFO - Downloading/unpacking jsonschema==2.5.1 06:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:23:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:33 INFO - Downloading functools32-3.2.3-2.tar.gz 06:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:23:33 INFO - warning: no files found matching '*.txt' 06:23:33 INFO - no previously-included directories found matching 'build' 06:23:33 INFO - no previously-included directories found matching 'dist' 06:23:33 INFO - no previously-included directories found matching '.git*' 06:23:33 INFO - Installing collected packages: jsonschema, functools32 06:23:33 INFO - Running setup.py install for functools32 06:23:33 INFO - warning: no files found matching '*.txt' 06:23:33 INFO - no previously-included directories found matching 'build' 06:23:33 INFO - no previously-included directories found matching 'dist' 06:23:33 INFO - no previously-included directories found matching '.git*' 06:23:33 INFO - Successfully installed jsonschema functools32 06:23:33 INFO - Cleaning up... 06:23:33 INFO - Return code: 0 06:23:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:23: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')]} 06:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23: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')]} 06:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:23:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:23:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:33 INFO - 'HOME': '/Users/cltbld', 06:23:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:33 INFO - 'LOGNAME': 'cltbld', 06:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:33 INFO - 'MOZ_NO_REMOTE': '1', 06:23:33 INFO - 'NO_EM_RESTART': '1', 06:23:33 INFO - 'PAGER': '/bin/cat', 06:23:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:33 INFO - 'PWD': '/builds/slave/test', 06:23:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:33 INFO - 'SHELL': '/bin/bash', 06:23:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:33 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:33 INFO - 'USER': 'cltbld', 06:23:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:33 INFO - 'XPC_FLAGS': '0x0', 06:23:33 INFO - 'XPC_SERVICE_NAME': '0', 06:23:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:23:33 INFO - Cleaning up... 06:23:33 INFO - Return code: 0 06:23:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:23: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')]} 06:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23: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')]} 06:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:23:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:23:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:33 INFO - 'HOME': '/Users/cltbld', 06:23:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:33 INFO - 'LOGNAME': 'cltbld', 06:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:33 INFO - 'MOZ_NO_REMOTE': '1', 06:23:33 INFO - 'NO_EM_RESTART': '1', 06:23:33 INFO - 'PAGER': '/bin/cat', 06:23:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:33 INFO - 'PWD': '/builds/slave/test', 06:23:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:33 INFO - 'SHELL': '/bin/bash', 06:23:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:33 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:33 INFO - 'USER': 'cltbld', 06:23:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:33 INFO - 'XPC_FLAGS': '0x0', 06:23:33 INFO - 'XPC_SERVICE_NAME': '0', 06:23:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:33 INFO - Downloading/unpacking blobuploader==1.2.4 06:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:35 INFO - Downloading blobuploader-1.2.4.tar.gz 06:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:23:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:23:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:36 INFO - Downloading docopt-0.6.1.tar.gz 06:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:23:36 INFO - Installing collected packages: blobuploader, requests, docopt 06:23:36 INFO - Running setup.py install for blobuploader 06:23:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:23:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:23:36 INFO - Running setup.py install for requests 06:23:36 INFO - Running setup.py install for docopt 06:23:37 INFO - Successfully installed blobuploader requests docopt 06:23:37 INFO - Cleaning up... 06:23:37 INFO - Return code: 0 06:23:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:37 INFO - 'HOME': '/Users/cltbld', 06:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:37 INFO - 'LOGNAME': 'cltbld', 06:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:37 INFO - 'MOZ_NO_REMOTE': '1', 06:23:37 INFO - 'NO_EM_RESTART': '1', 06:23:37 INFO - 'PAGER': '/bin/cat', 06:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:37 INFO - 'PWD': '/builds/slave/test', 06:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:37 INFO - 'SHELL': '/bin/bash', 06:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:37 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:37 INFO - 'USER': 'cltbld', 06:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:37 INFO - 'XPC_FLAGS': '0x0', 06:23:37 INFO - 'XPC_SERVICE_NAME': '0', 06:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:23:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_dDgd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:23:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-p8kkZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:23:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LpexEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:23:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-PX5gwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:23:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-SuUfvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:23:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-PMtaoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-mt8hvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-UVlQYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-wcBghP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-IQV4jV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-UZmzlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-30Fe94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-CruyOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_EX6AO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-IkQIUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:23:38 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-qEGEZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:23:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Lb5WIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:23:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:23:39 INFO - Running setup.py install for manifestparser 06:23:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:23:39 INFO - Running setup.py install for mozcrash 06:23:39 INFO - Running setup.py install for mozdebug 06:23:39 INFO - Running setup.py install for mozdevice 06:23:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:23:39 INFO - Running setup.py install for mozfile 06:23:39 INFO - Running setup.py install for mozhttpd 06:23:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:23:40 INFO - Running setup.py install for mozinfo 06:23:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:23:40 INFO - Running setup.py install for mozInstall 06:23:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:23:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:23:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:23:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:23:40 INFO - Running setup.py install for mozleak 06:23:40 INFO - Running setup.py install for mozlog 06:23:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:23:40 INFO - Running setup.py install for moznetwork 06:23:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:23:40 INFO - Running setup.py install for mozprocess 06:23:40 INFO - Running setup.py install for mozprofile 06:23:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:23:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:23:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:23:41 INFO - Running setup.py install for mozrunner 06:23:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:23:41 INFO - Running setup.py install for mozscreenshot 06:23:41 INFO - Running setup.py install for moztest 06:23:41 INFO - Running setup.py install for mozversion 06:23:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:23:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:23:41 INFO - Cleaning up... 06:23:41 INFO - Return code: 0 06:23:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:41 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 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:41 INFO - 'HOME': '/Users/cltbld', 06:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:41 INFO - 'LOGNAME': 'cltbld', 06:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:41 INFO - 'MOZ_NO_REMOTE': '1', 06:23:41 INFO - 'NO_EM_RESTART': '1', 06:23:41 INFO - 'PAGER': '/bin/cat', 06:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:41 INFO - 'PWD': '/builds/slave/test', 06:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:41 INFO - 'SHELL': '/bin/bash', 06:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:41 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:41 INFO - 'USER': 'cltbld', 06:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:41 INFO - 'XPC_FLAGS': '0x0', 06:23:41 INFO - 'XPC_SERVICE_NAME': '0', 06:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:23:41 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-dEz6la-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-v3Uzpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-KLr_NV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-NwO9Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-1xd6OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BmMNPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_FdFE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-FghzaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-WHGQq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:23:42 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-x_a8QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:23:43 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-bHToyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:23:43 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-CQYXfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:23:43 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-5qXKmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:23:43 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-YCcoxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:43 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-MkinGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:23:43 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Wu05lM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:23:43 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xm6Eex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:23:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:45 INFO - Downloading blessings-1.6.tar.gz 06:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:23:45 INFO - Installing collected packages: blessings 06:23:45 INFO - Running setup.py install for blessings 06:23:45 INFO - Successfully installed blessings 06:23:45 INFO - Cleaning up... 06:23:45 INFO - Return code: 0 06:23:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:23:45 INFO - Reading from file tmpfile_stdout 06:23:45 INFO - Current package versions: 06:23:45 INFO - blessings == 1.6 06:23:45 INFO - blobuploader == 1.2.4 06:23:45 INFO - docopt == 0.6.1 06:23:45 INFO - functools32 == 3.2.3-2 06:23:45 INFO - jsonschema == 2.5.1 06:23:45 INFO - manifestparser == 1.1 06:23:45 INFO - mozInstall == 1.12 06:23:45 INFO - mozcrash == 1.0 06:23:45 INFO - mozdebug == 0.1 06:23:45 INFO - mozdevice == 0.51 06:23:45 INFO - mozfile == 1.2 06:23:45 INFO - mozhttpd == 0.7 06:23:45 INFO - mozinfo == 0.9 06:23:45 INFO - mozleak == 0.1 06:23:45 INFO - mozlog == 3.4 06:23:45 INFO - moznetwork == 0.27 06:23:45 INFO - mozprocess == 0.25 06:23:45 INFO - mozprofile == 0.28 06:23:45 INFO - mozrunner == 6.13 06:23:45 INFO - mozscreenshot == 0.1 06:23:45 INFO - mozsystemmonitor == 0.3 06:23:45 INFO - moztest == 0.8 06:23:45 INFO - mozversion == 1.4 06:23:45 INFO - psutil == 3.1.1 06:23:45 INFO - requests == 1.2.3 06:23:45 INFO - wsgiref == 0.1.2 06:23:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:45 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 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:45 INFO - 'HOME': '/Users/cltbld', 06:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:45 INFO - 'LOGNAME': 'cltbld', 06:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:45 INFO - 'MOZ_NO_REMOTE': '1', 06:23:45 INFO - 'NO_EM_RESTART': '1', 06:23:45 INFO - 'PAGER': '/bin/cat', 06:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:45 INFO - 'PWD': '/builds/slave/test', 06:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:45 INFO - 'SHELL': '/bin/bash', 06:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:45 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:45 INFO - 'USER': 'cltbld', 06:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:45 INFO - 'XPC_FLAGS': '0x0', 06:23:45 INFO - 'XPC_SERVICE_NAME': '0', 06:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:23:46 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:23:47 INFO - Downloading marionette_driver-1.4.0.tar.gz 06:23:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 06:23:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:23:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:23:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:23:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:23:47 INFO - Installing collected packages: marionette-driver 06:23:47 INFO - Running setup.py install for marionette-driver 06:23:47 INFO - Successfully installed marionette-driver 06:23:47 INFO - Cleaning up... 06:23:47 INFO - Return code: 0 06:23:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:23:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f38ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc549dd1340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, '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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:23:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:23:47 INFO - 'HOME': '/Users/cltbld', 06:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:23:47 INFO - 'LOGNAME': 'cltbld', 06:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:23:47 INFO - 'MOZ_NO_REMOTE': '1', 06:23:47 INFO - 'NO_EM_RESTART': '1', 06:23:47 INFO - 'PAGER': '/bin/cat', 06:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:23:47 INFO - 'PWD': '/builds/slave/test', 06:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:23:47 INFO - 'SHELL': '/bin/bash', 06:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:23:47 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:23:47 INFO - 'USER': 'cltbld', 06:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:23:47 INFO - 'XPC_FLAGS': '0x0', 06:23:47 INFO - 'XPC_SERVICE_NAME': '0', 06:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:23:48 INFO - Cleaning up... 06:23:48 INFO - Return code: 0 06:23:48 INFO - Running post-action listener: _resource_record_post_action 06:23:48 INFO - Running post-action listener: _start_resource_monitoring 06:23:48 INFO - Starting resource monitoring. 06:23:48 INFO - [mozharness: 2017-06-01 13:23:48.097998Z] Finished create-virtualenv step (success) 06:23:48 INFO - [mozharness: 2017-06-01 13:23:48.098979Z] Running install step. 06:23:48 INFO - Running pre-action listener: _resource_record_pre_action 06:23:48 INFO - Running main action method: install 06:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:23:48 INFO - Reading from file tmpfile_stdout 06:23:48 INFO - Detecting whether we're running mozinstall >=1.0... 06:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:23:48 INFO - Reading from file tmpfile_stdout 06:23:48 INFO - Output received: 06:23:48 INFO - Usage: mozinstall [options] installer 06:23:48 INFO - Options: 06:23:48 INFO - -h, --help show this help message and exit 06:23:48 INFO - -d DEST, --destination=DEST 06:23:48 INFO - Directory to install application into. [default: 06:23:48 INFO - "/builds/slave/test"] 06:23:48 INFO - --app=APP Application being installed. [default: firefox] 06:23:48 INFO - mkdir: /builds/slave/test/build/application 06:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:24:10 INFO - Reading from file tmpfile_stdout 06:24:10 INFO - Output received: 06:24:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:24:10 INFO - Running post-action listener: _resource_record_post_action 06:24:10 INFO - [mozharness: 2017-06-01 13:24:10.021840Z] Finished install step (success) 06:24:10 INFO - [mozharness: 2017-06-01 13:24:10.021996Z] Running setup-mitmproxy step. 06:24:10 INFO - Running pre-action listener: _resource_record_pre_action 06:24:10 INFO - Running main action method: setup_mitmproxy 06:24:10 INFO - Skipping: mitmproxy is not required 06:24:10 INFO - Running post-action listener: _resource_record_post_action 06:24:10 INFO - [mozharness: 2017-06-01 13:24:10.022602Z] Finished setup-mitmproxy step (success) 06:24:10 INFO - [mozharness: 2017-06-01 13:24:10.022724Z] Running run-tests step. 06:24:10 INFO - Running pre-action listener: _resource_record_pre_action 06:24:10 INFO - Running main action method: run_tests 06:24:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:24:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:24:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:24:10 INFO - 2017-06-01 06:24:10.037 screenresolution[1631:8028] starting screenresolution argv=screenresolution get 06:24:10 INFO - 2017-06-01 06:24:10.039 screenresolution[1631:8028] Display 0: 1600x1200x32@60 06:24:10 INFO - 2017-06-01 06:24:10.045 screenresolution[1632:8030] starting screenresolution argv=screenresolution list 06:24:10 INFO - Available Modes on Display 0 06:24:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:24:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:24:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:24:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:24:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:24:10 INFO - Intel Iris: 06:24:10 INFO - Chipset Model: Intel Iris 06:24:10 INFO - Type: GPU 06:24:10 INFO - Bus: Built-In 06:24:10 INFO - VRAM (Dynamic, Max): 1536 MB 06:24:10 INFO - Vendor: Intel (0x8086) 06:24:10 INFO - Device ID: 0x0a2e 06:24:10 INFO - Revision ID: 0x0009 06:24:10 INFO - Displays: 06:24:10 INFO - Display: 06:24:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:24:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:24:10 INFO - Display Serial Number: 1600x1200 60 06:24:10 INFO - Main Display: Yes 06:24:10 INFO - Mirror: Off 06:24:10 INFO - Online: Yes 06:24:10 INFO - Rotation: Supported 06:24:10 INFO - Return code: 0 06:24:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:24:10 INFO - Python 2.7.3 06:24:10 INFO - Return code: 0 06:24:10 INFO - grabbing minidump binary from tooltool 06:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:10 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313c60>, '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 0x10f0a8cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0d1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:24:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:24:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:24:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:24:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:24:10 INFO - Return code: 0 06:24:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:24:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:24:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:24:10 INFO - ENV: RUST_BACKTRACE is now 1 06:24:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:24:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:24:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0110 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:24:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qMnheLZjD/Render', 06:24:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:10 INFO - 'HOME': '/Users/cltbld', 06:24:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:10 INFO - 'LOGNAME': 'cltbld', 06:24:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:24:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:10 INFO - 'MOZ_NO_REMOTE': '1', 06:24:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:24:10 INFO - 'NO_EM_RESTART': '1', 06:24:10 INFO - 'PAGER': '/bin/cat', 06:24:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:10 INFO - 'PWD': '/builds/slave/test', 06:24:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:24:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:10 INFO - 'RUST_BACKTRACE': '1', 06:24:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:24:10 INFO - 'SHELL': '/bin/bash', 06:24:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ZGSo6fyNe/Listeners', 06:24:10 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 06:24:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:10 INFO - 'USER': 'cltbld', 06:24:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:10 INFO - 'XPC_FLAGS': '0x0', 06:24:10 INFO - 'XPC_SERVICE_NAME': '0', 06:24:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:24:10 INFO - mozversion application_buildid: 20170601045429 06:24:10 INFO - mozversion application_changeset: 0bcea6bac1797e14b00af45cc7c368d12460ab7f 06:24:10 INFO - mozversion application_display_name: Nightly 06:24:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:24:10 INFO - mozversion application_name: Firefox 06:24:10 INFO - mozversion application_remotingname: firefox 06:24:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:24:10 INFO - mozversion application_vendor: Mozilla 06:24:10 INFO - mozversion application_version: 55.0a1 06:24:10 INFO - mozversion platform_buildid: 20170601045429 06:24:10 INFO - mozversion platform_changeset: 0bcea6bac1797e14b00af45cc7c368d12460ab7f 06:24:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:24:10 INFO - mozversion platform_version: 55.0a1 06:24:10 INFO - using testdate: 1496323450 06:24:10 INFO - actual date: 1496323450 06:24:10 INFO - starting webserver on 'localhost:49228' 06:24:10 INFO - SUITE-START | Running 2 tests 06:24:10 INFO - TEST-START | dromaeo_css 06:24:10 INFO - operating with platform_type : mac_ 06:24:10 INFO - Initialising browser for dromaeo_css test... 06:24:10 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpj7KxHf/profile 06:24:10 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 06:24:44 INFO - TEST-INFO | 1642: killed by SIGTERM 06:24:44 INFO - Browser initialized. 06:24:44 INFO - Running cycle 1/1 for dromaeo_css test... 06:24:44 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpj7KxHf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:24:46 INFO - PID 1651 | 2017-06-01 06:24:46.899 plugin-container[1652:8678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c43, name = 'com.apple.tsm.portname' 06:24:46 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:24:46 INFO - PID 1651 | 2017-06-01 06:24:46.900 plugin-container[1652:8678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 06:24:46 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:24:46 INFO - PID 1651 | 2017-06-01 06:24:46.900 plugin-container[1652:8678] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:24:47 INFO - PID 1651 | 2017-06-01 06:24:47.363 plugin-container[1653:8723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 06:24:47 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:24:47 INFO - PID 1651 | 2017-06-01 06:24:47.363 plugin-container[1653:8723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x991b, name = 'com.apple.CFPasteboardClient' 06:24:47 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:24:47 INFO - PID 1651 | 2017-06-01 06:24:47.363 plugin-container[1653:8723] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:24:56 INFO - PID 1651 | RSS: Main: 269103104 06:24:56 INFO - PID 1651 | 06:27:59 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 06:28:00 INFO - PID 1651 | RSS: Main: 269012992 06:28:00 INFO - PID 1651 | 06:31:02 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 06:31:03 INFO - PID 1651 | RSS: Main: 284467200 06:31:03 INFO - PID 1651 | 06:34:05 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 06:34:06 INFO - PID 1651 | RSS: Main: 283500544 06:34:06 INFO - PID 1651 | 06:37:08 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 06:37:08 INFO - PID 1651 | RSS: Main: 287662080 06:37:08 INFO - PID 1651 | 06:40:11 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 06:40:12 INFO - PID 1651 | RSS: Main: 287682560 06:40:12 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 06:43:14 INFO - PID 1651 | RSS: Main: 287612928 06:43:14 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | __start_tp_report 06:43:14 INFO - PID 1651 | _x_x_mozilla_page_load 06:43:14 INFO - PID 1651 | _x_x_mozilla_page_load_details 06:43:14 INFO - PID 1651 | |i|pagename|runs| 06:43:14 INFO - PID 1651 | |0;dojo.html;3509.6490350964923;3602.135487483004;3609.368360536907;3635.672789448949;3668.3396988542054;5147.665401748884;5823.806119745442;5947.256592925884;6118.192813661906;6224.346443623423;6212.316645169028;6226.910290699132;6259.186305780242;6282.623042617434;6326.873462530747;5063.569596584295;5326.094563924142;5438.619296649236;5492.313460817398;5501.064818980783;5576.9163462548095;5611.859703507404;5614.185943038257;5637.295338082739;5643.379228284885;5408.161734931092;5487.6981766002855;5507.421720719307;5518.91721624174;5527.060399732015;5022.748862556894;5254.92117618236;5524.226608274845;5524.558035357162;5540.639858409231;5438.211459338374;5452.781888724447;5482.698451585201;5490.231367608538;5531.944680553223;6126.181801870944;6167.229096362955;6269.686515674243;6280.183576135073;6295.307516173218;5526.861828454321;5598.888022239573;6559.2128944526485;6709.765158219488;6723.831904202385;6806.829829254259;6814;6815.216250131249;6816.216135144423;6824.249332573413;6266.62400255977;6320.778772742932;6320.93679063208;6341.239051313872;6376.872462550816;6693.397594216543;6777.220619628707;6882.931170688329;6914.51598388108;6939.861202775916;6374.394432528902;6386.489080873519;6471.6764161791725;6603.7358505660195;6639.900401493981;6689.866202675919;6772.627505487246;6784.253732089466;6790.626515541692;6799.626020568818;6656.966715166393;6659.101021362151;6675.46596272297;6675.9332406676285;6679.198496180489;6447.709853056624;6568.244651865001;6613.3056029117215;6667.533272670867;6694.899576506356;5272.367315922113;5328.200769884548;5372.167314066326;5382;5418.078926582491;4101.159262351211;4357.651387888961;4400.713953593006;4769.499202583748;4816;6256.562040657202;6276.6547839869245;6297.811065668037;6310.652914089769;6373.362663733682;6759.357861003196;6807.829804254933;6823.727050917908;6825.215100263434;6832.760853370109;6378.043293506012;6384.904226436607;6387.776427825004;6394.488440924715;6406.903896441557;5771.422857714195;5857.531397488191;5888.146218798251;5951.9107213391835;5975.43233392827;6834.555753875982;6941.895871561931;6963.512554121366;6967.1291088613925;6986.755463558751;6691.59850408977;6811.182658081062;6818.624975626388;6830.043793868763;6844.076049733222;5718.571107166896;5948.5538584605465;6011.489023433057;6947.166340039228;6970.686319115754;6399;6584.572002819801;6640.037194606852;6693.899591506034;6713.765018224534;6812.829679258059;6821.317868213139;6829.590224586541;6834.9316506836285;6867.3476019778045;6411.8397040072505;6470.417662410406;6537.084808126961;6581.605103693793;6598.175228096488;6500.122483464861;6528.37980391862;6545.21457425112;6582.80251592453;6587.868242635216;6359.459445947146;6455.870882582416;6461.869172894819;6462.063000864942;6469.223693156851;6578.671066446755;6593.142891424276;6596.472282217315;6632.767853125118;6634.369734875179;6267.310595834546;6341.112244285896;6355.682215889095;6441.872672282426;6442.420182183626 06:43:14 INFO - PID 1651 | |1;ext.html;663.9070530125782;699.244815599153;747.7495039161882;1297.1892567145528;1314.4545013819265;37451.81274093632;38434;39055.80472097635;39062.60937390625;39151.60848391515;40458.78623641288;40910;40921.59078409215;40995;41020.58979410205;8295.253427191567;8435.156484351546;8439.282660973824;8441.831163376728;8449.028361738387;8286.00567931849;8356.331493480508;8367.874481882776;8374.916250837474;8406;8340.249377556018;8362.456440331389;8384.664613415456;8387;8410.747677569683;7214.819629509304;8332.83334333313;8383.95808020962;8398.538080405575;8402.87395689062;6943.583384996917;6996.6851491683;8143.837123257502;8211.34309255258;8215;36876.81561592175;37096.258074838624;37109.07227319312;37116;37147.81426092879;3497.7376696747847;3505.263894582141;3523.365794157051;3531.5697142657264;3545.1846075402545;30125.69874301251;30318.84840575805;30340.393192136253;30380.54429183564;30436.847815761;2903;2954.793164478487;3076.615423072116;3082.969170308291;3092.443360195164;245555.77222113954;248686;268788;293939;294188;46452.5354746455;47149.5285047152;47316.52683473121;47372;47466;49034.264486032735;49136.26295605569;49291.50708492869;49714;50473.49526504761;14602.97779155449;14739.041962272418;14739.189344586151;14748.483803066842;14766.335514901855;11676.76646467066;11800.881991179978;11870.050395968474;11884.227525210834;11919.761604767857;14058.437662493387;14076.014678972579;14084;14090.013699040968;14221.360038798071;4977.32806071183;5277.445868183785;6249.562530622904;6275.246970363494;6281.560290779602;933.7572231219796;971.3880255439028;1003.8494225866178;1014.8071866345371;1022.9283950123569;20692.068856901475;24235.272941811774;24274.757252427604;24466.633000505008;31230;1049.2497864027257;1088.5156105532963;1114.637742733615;1119.289251325398;1120.1878637987395;12034.458449369624;12350.135490515757;12382.876171238173;12400.56598019083;12436.81344779829;285617;290122;290311.54844225646;290865;323128;55955.44044559502;56380.71809641008;56569;56637.43362566404;56646;1457.6574504991318;1481.0891301849501;1492.2315007771006;1496.738070837599;1507.9924600376928;26339.604905926044;28942.855285723435;29585.704142958293;29686.40627187401;30048.69951300459;37002.44496332611;37521;37547;37627;37738.62261377461;276597;276673.61663191556;278821;279382;279621;33287.33425331528;33573.83213083919;34739.82630086935;34827.477587835696;35018.474722879684;42699.50376037181;43889.78055109704;44065.339019914085;44178.779106104266;44179.558204417546;24755.381115472257;27705.44589108166;28601.713982859907;30421.391572168875;30495.695043050175;28354.85822570874;29287.974920877667;29810;30045;30450;31069.067927962198;35547.82226088957;36180.4572931401;36474;36626;26043;26446.41321520715;28561.428771424038;28792.856035719687;29834.850825745732;21948.34154975353;23645.29064128079;23872.880635597405;23932.28203153908;23950.88024559866 06:43:14 INFO - PID 1651 | |2;jquery.html;333.50641051244156;376.410916915028;429.13957515182057;434.2942718083116;453.62802501948414;21667.45831354217;22540.098396064157;22558.88720556397;22972.425689357773;24139.637905431387;17582;23734.525309493798;24275.27174184773;24346.513069738594;24357.512849742994;6083.665398403086;6181.690915454232;6212.316645169028;6227.190465239512;6231.501479881598;6163.352847950968;6170.259568851744;6203.720832562545;6216.720247588869;6247.125402443661;6001.339852616209;6553.901691474608;6917;6990.720371185172;7006.789796306119;6471.546991710582;6751.966240168792;6944.930550694479;6992.091028166356;6992.475564332695;6818.659067046678;6831.2144103428245;6833.4191593714595;6844.4182244509275;6849.349311815369;30777;30786.23034424135;31341.05976820699;31414;32063;2155.6227660159566;2212.5021870079104;2214.767449417804;2217.7782221777693;2221.7889300516426;19556.902215488975;19656.80343196578;19755.80244197554;19817;19844.404667859988;2305.1586171047447;2320.3271051395077;2390.151496218852;2393.6050551658955;2404.8076153907646;150170;155892;201000.99999999854;208309;212122;22442;24966.37584060413;25626.28418879252;27380.494072825786;27518.862405687843;42744.78627606842;42950;43506.69479915544;43514.47699330572;43583.56416435795;11448.484818183202;11627.244229125079;11674.182807203584;11678.94160529209;11716.355600441888;9228.26173906086;9270.814583708423;9287.442753434816;9347.813043739086;9360.672376466791;12244.938775306244;12495.187812792139;12536.498540058477;12578.748425031448;12601;4469.754163520973;4682.41469816273;4865.416150062015;4947.876303092379;5085.7202853842655;829.2868133405267;860.9741707748777;876.369014309696;949.126803340928;991.5785791038779;21160.365189044045;21531.67702484464;21572.244971425924;21812.672809907865;21838.675589996452;950.6862735297474;978.2516374973009;989.0653332600767;1000.8190335404292;1029.891861354562;9410.858837117448;10096.192304615613;11262.662120136409;11309.830352544719;11370;521858;527572;540509;546313;547955;139971;144812;146409;147197;148364.2581787106;979.020979020979;1023.1916785739182;1183.1954271095565;1207.1489599832098;1266.9556565520163;22208;23052.538949221256;23116.422089447227;23257;23303.18438854632;64136.71726565536;64151.679241603495;67944;70760.64619676869;71147;85983.57008214919;86189.56905215434;87187;87322;87823;10167.034131757471;10288.588456461657;10407.94796026015;10408;10416.166706663296;13670.453181872614;18079;18254.904705717698;18478.260869565067;18505.889646621246;15061.698766024838;15116.924415378293;15205.087694738351;15227.695446091238;15322;13169.341532923125;13762.24307663088;13855.515056972958;13924;13979.860201398262;12642.367881606067;15283.15942623122;15443.382264709286;15510.302036408162;15570.455034073753;9716.813716913637;9794.804103917739;10284.537195826355;10293.53679084428;10381.11760500366;14922;15131.940764146404;15256.618584535476;15263.3894644213;15275.312610932313 06:43:14 INFO - PID 1651 | |3;mootools.html;1417.5888992192263;1474.4249742600393;1498.7227362937858;1501.6020754500053;1508.2684897824554;11645.30128192308;12081.214721043138;12104.939475302623;12156;12571.120021598468;11975.341356225405;12018.51925922962;12164.756704865897;12177.512899484032;12192.207506512093;2545.0710490670913;2591.7019542752637;2602.323395917056;2605.6221847832053;2607.804414668898;2540.5554028045017;2551.9744802552013;2576.2915198320425;2577.9742202577922;2580.7677309042183;2547.6942766868;2557.9104731334405;2558.616207568861;2573.652556904822;2574.9227523174336;2488.825782195247;2524.192258477288;2528.329992551966;2534.9873250633814;2536.4039450729065;2348.8120950323932;2365.053978408633;2366.148186652804;2375.09746296408;2494.2018554062706;11768.529258829722;11828.408579570987;11851.459310289694;11871.169018168732;11907.16649834512;6431.389018043278;6633.9668301658185;6683.832904177386;6700.530962832604;6710;4083.1833633273463;4250.447441832542;4320.891977700551;4328.718633288826;4356.150550642595;1220.4385982448084;1235.4008305971597;1272.694553307209;1274.961751147467;1278.0925542864484;15303.617409564738;15730.764038539432;16557.58606034847;16680;16711.83288167115;5547.140193270049;5624.887502249932;5705.74324155414;5761.107028410603;5779.682117483495;10371.429571373646;12358.320292384005;12363.196392234477;12376.62870113898;12386.442610082568;6385.840353991188;6432.903506447407;6502.837429064217;6514.348565143448;6517.055027021055;6032.638041717511;6146.5390095743;6156.753729850845;6174.166487524217;6215.844603884939;4175.227582897113;4266.6160045595425;4280.871573852789;4290.742555446684;4325.329573916048;1269.5590504777144;1290.5289569307135;1292.8513220979519;1305.451710281684;1306.8366454193226;753.8492301539713;773.1302284929454;776.1423626892316;776.3556248313886;782.8199514111786;6084.178635884099;6125.84685382869;6182;6288.119663247149;6356.711583829818;568.6417556939102;578.9549862498147;593.7981086430634;599.8320470268332;892.5314210039782;4219.599138081878;4370.672199585044;4460.910781784346;4500.897280166315;4526.547345265513;14533.128012319296;15235.923820380827;15286.312115954808;15315.31081101353;15339.23303834826;14603.926980365242;14969.326380312694;15257.6185595361;15339.002964807252;15342.616434589225;8469.957650211833;8604.709293606009;8617.870731939025;8674.479531228146;8688.6524539019;5506.614536982533;6905.654717264017;6995.720171193096;7203.567785932861;7211.3870321023305;12961.351932403531;13608.251546165056;13633;13846.723065538834;13866.029377943447;13748.106373085717;13775.655608609864;14047.735703786708;14072.78890816659;14161.716765664423;8819.647214111365;8868.423552469068;8987.46075235488;9005.459672419676;9009;10798.244122911321;10867.29362591429;10959;10961.890381096406;10968.725781855517;9203.309751768511;9293.72118836436;9432.009638988082;9542.618295268112;9579.760505987406;9018.503982280776;9033.322500812334;9103.681371151977;9168.495732734764;9197.540122993958;8252.133525979925;8260.710875119583;8310.293625041939;8349.624266908126;8361.874571881304;6046.546509011754;6109;6111.54163437735;6154.446099851035;6283.8114856554375;6122.591803885042;6242.968785156045;6249.406306400884;6252.9374706254175;6263.342349053466 06:43:14 INFO - PID 1651 | |4;prototype.html;334.3647070565926;384.55199692358394;394.49504634068387;397.08868147601254;410.282006488645;4652.651051171163;4697.953020469794;5244.921326180101;6037.849053773658;6252.624842509441;4991.7504124793795;5282.5245727884485;6273;6275.46658534024;6298.7165577549;4497.617702495292;4618.4688760792515;4652.511486293941;4669.953300467003;4703.835365762199;4409.492908315537;4660.766961651921;4674.807923979398;4675.953240467603;4695.201815691341;3854.4025676020124;4648.2562789953445;4648.9767551162195;4656;4658.953410465902;4049.800657480587;4635.165670179366;4647.512011238805;4650.162970665296;4658.767061646921;3560.922743458725;3814.9890279076067;4017.4375587417785;4028.053407449247;4030.234255491447;6168.198134242564;6181.443670069671;6199.50403967686;6235.9376406235815;6246.937530624681;5539.25220095286;5544.473275038865;5551.250581171531;5554.305711786027;5560.165975103767;5688.63023903445;6750.797476075725;6803.2856550062525;6820.454363650896;6827.52207345486;3931.5282166140246;4226.5773422657485;4529.230030894756;4531.705439146445;4532.864014079583;6897.172339319263;6972.163340399185;7173.5337203081635;7285.125784905846;7293.380062694624;4260.552641972548;4543.909121817545;4553.5218802025975;4559.863204103882;4573.908521829545;3913.021744563859;4644.535546445329;4645.581897629229;4647.837325693585;4678.47949416441;4562.817487300471;5140.434552199255;5291.497307755757;5424.728763561806;5443.564514838803;6834.2482326944;6843.3840954314355;6849.794506164823;6850.451963842881;6864.553804002724;1586.3178833101647;1591.124881315272;1597.8641815445585;1605.117185547944;1608.6165897328287;1023.7798873242289;1045.9895401046044;1065.648336049102;1066.861308029951;1069.823479125951;5636.830895073154;6389.8722025560155;6403.135576697179;6448.613083215022;6456.903146452807;6134.78528251509;6990.685419156151;7021.29787021304;7066.434685225169;7089;5585.776568937197;6392.689498652765;6394.136791533177;6421.261554921244;6441.1626488556185;3210.598675165604;3321.568196134487;3362.8654853805574;3651.050726811048;3658.652428019334;6574.783665021891;6744.224414192333;7207.639618019078;7224.602646854473;7267.491275610762;6255.499560035186;6493.448056915215;7192.496525243288;7195.89206161908;7243.203247642755;6377.904331435032;6506;6512.72054780823;7107.005019297302;7193.64031798408;6154.323024467394;6962.3037696231995;6981.5112942093565;6989.5107342485535;7054.4003759681;6433.871322573429;6885.414739747178;6888.793336199922;6900.309969003059;6946.37482626566;5787.987102257004;6540.444062254762;7162.964185179041;7171;7182.820429489096;4564.634829213722;4575.450945886515;4578.359029735773;4603.723776573416;4604.792784324646;6211.875762484816;6929.7228110875;6956.721731130698;6960;6968.303169683194;5633.718314084197;5906.438888305604;6639.2696803352555;6713;6748.089007983859;6040.818775436568;6105.419985101408;6315.273743519554;6420.229572451336;6691.76578819739;5359.544438722753;6130.448259656474;6592.077109204811;6600.669966501751;6609.372109649576;5962.227564182675;6691.397774200345;6751.49363797727;6751.615918736746;6772.255051944184;5153.943441594539;5673.503658390295;6513.892638251604;6549.443297319781;6594.670266486561 06:43:14 INFO - PID 1651 | |5;yui.html;817.2930415190859;831.3307787231277;838.2832678060262;866.5225790028;879.3492815316664;16640.417585384497;16775.245113969886;16857.24142413591;17103.059331736764;17140;13079.346032698351;15386.845986550998;16145.43490977816;16190.190490475488;16382.508524744248;963.4123184857232;976.8144052629978;983.2281658897766;984.3159004491881;988.4514094677451;989.2135752077116;1005.2711783956646;1005.9446730429823;1008.3899240959224;1009.5709323537504;997.9002099790035;1035.4926086217774;1041.5052849896315;1047.8899715529874;1048.1509976918683;1026.2867307221484;1034.3690348887174;1040.2510192661273;1041.812473754724;1042.9935112616324;753.8567672142275;753.9246075392472;754.5020286610866;761.0182864105298;761.4022991951292;16930.576735581584;16955;17092;17131.828681713272;17202.827971720373;14673.413063477448;17803.554911127325;17856.28574857004;17910.462686119437;17914.28342866284;12352;12696.87303126966;12769.936150319281;12775;12793.232406055666;3571.2857428514403;3596.478510615946;3602.657747513982;3606.206634540397;3606.495090687306;152412;154586.72479651257;155503;155621;156328.21835890747;12810.9359453204;12847.614571562868;12856.935715321364;12904.935475322563;12974.286414247306;26589.734102658727;27044.05345812887;27063.1881043569;27071.729282707314;27125;7851.136374998746;7934.761957141295;7936.6031698414845;7939.166387529342;7940.563269020259;3902.1220225449615;3949.071968087497;3974.3044967130636;3980.522337319482;3981.7810020448574;3890.824912878927;4874.488178741252;4904.117258893433;4906.828261010847;4922.015596880638;941.3081385181886;955.2071780422233;962.6775030364906;978.0708327089152;978.8531720242021;589.9203607512931;631.3528633151057;632.3265722006049;655.9275584419509;668.0847239282117;11165.776684466266;11558.56224955787;12018.699532511582;12645.747085058247;12734.853863152175;638.2978723404218;644.7002144003061;648.6043513456788;659.6899457255082;670.2258890980925;5565.551157490691;6468.706258748269;6496.675166241669;6535.640539770263;6683.6658167091455;141188;142059;142099.28950355388;142348;145176;104464;123231;124055;124545;129095.35452322678;86290.13709862946;91397.54301228584;93074;95511;97949;11095.445227738743;12088.91199792023;12368.690782730504;12597.433115510008;12670.303133327756;33572.32855342928;43590.56409435992;43673.34489982589;44100.77949610232;45048.54951450443;60346;60361.69819150876;60802.695986519786;61660.38339616547;61896.69051654713;16481.258343374815;16515.504534863972;16983.773083114167;17003.979761214367;17123.058231796873;18393.356232531798;18638.627227455647;18639.90680046591;18816.435506934813;18830;16764.58088547796;17142.314307428063;17327.220275087904;17523.21145548479;17604.119794010505;16628.251728671996;16943.830561694227;17477.863938843933;17540.473785786446;17573;16422.014679119293;16452.341906323614;16463.670726585162;16667.91658542191;16729.414470493473;10005;11861.466234019663;12008.63974080744;12080.09399295074;12227.755444890874;13251.13867598603;13282.269475178959;13315.334233288491;13340.132891362031;13436.664083397602 06:43:14 INFO - PID 1651 | __end_tp_report 06:43:14 INFO - PID 1651 | __start_cc_report 06:43:14 INFO - PID 1651 | _x_x_mozilla_cycle_collect,459 06:43:14 INFO - PID 1651 | __end_cc_report 06:43:14 INFO - PID 1651 | __startTimestamp1496324594412__endTimestamp 06:43:14 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | ------- Summary: start ------- 06:43:14 INFO - PID 1651 | Number of tests: 6 06:43:14 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6158.36 Median:6386.49 stddev:745.08 (11.7%) stddev-sans-first:717.80 06:43:14 INFO - PID 1651 | Values: 3509.6 3602.1 3609.4 3635.7 3668.3 5147.7 5823.8 5947.3 6118.2 6224.3 6212.3 6226.9 6259.2 6282.6 6326.9 5063.6 5326.1 5438.6 5492.3 5501.1 5576.9 5611.9 5614.2 5637.3 5643.4 5408.2 5487.7 5507.4 5518.9 5527.1 5022.7 5254.9 5524.2 5524.6 5540.6 5438.2 5452.8 5482.7 5490.2 5531.9 6126.2 6167.2 6269.7 6280.2 6295.3 5526.9 5598.9 6559.2 6709.8 6723.8 6806.8 6814.0 6815.2 6816.2 6824.2 6266.6 6320.8 6320.9 6341.2 6376.9 6693.4 6777.2 6882.9 6914.5 6939.9 6374.4 6386.5 6471.7 6603.7 6639.9 6689.9 6772.6 6784.3 6790.6 6799.6 6657.0 6659.1 6675.5 6675.9 6679.2 6447.7 6568.2 6613.3 6667.5 6694.9 5272.4 5328.2 5372.2 5382.0 5418.1 4101.2 4357.7 4400.7 4769.5 4816.0 6256.6 6276.7 6297.8 6310.7 6373.4 6759.4 6807.8 6823.7 6825.2 6832.8 6378.0 6384.9 6387.8 6394.5 6406.9 5771.4 5857.5 5888.1 5951.9 5975.4 6834.6 6941.9 6963.5 6967.1 6986.8 6691.6 6811.2 6818.6 6830.0 6844.1 5718.6 5948.6 6011.5 6947.2 6970.7 6399.0 6584.6 6640.0 6693.9 6713.8 6812.8 6821.3 6829.6 6834.9 6867.3 6411.8 6470.4 6537.1 6581.6 6598.2 6500.1 6528.4 6545.2 6582.8 6587.9 6359.5 6455.9 6461.9 6462.1 6469.2 6578.7 6593.1 6596.5 6632.8 6634.4 6267.3 6341.1 6355.7 6441.9 6442.4 06:43:14 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:43593.25 Median:178207.67 stddev:73790.21 (41.4%) stddev-sans-first:73926.81 06:43:14 INFO - PID 1651 | Values: 663.9 699.2 747.7 1297.2 1314.5 37451.8 38434.0 39055.8 39062.6 39151.6 40458.8 40910.0 40921.6 40995.0 41020.6 8295.3 8435.2 8439.3 8441.8 8449.0 8286.0 8356.3 8367.9 8374.9 8406.0 8340.2 8362.5 8384.7 8387.0 8410.7 7214.8 8332.8 8384.0 8398.5 8402.9 6943.6 6996.7 8143.8 8211.3 8215.0 36876.8 37096.3 37109.1 37116.0 37147.8 3497.7 3505.3 3523.4 3531.6 3545.2 30125.7 30318.8 30340.4 30380.5 30436.8 2903.0 2954.8 3076.6 3083.0 3092.4 245555.8 248686.0 268788.0 293939.0 294188.0 46452.5 47149.5 47316.5 47372.0 47466.0 49034.3 49136.3 49291.5 49714.0 50473.5 14603.0 14739.0 14739.2 14748.5 14766.3 11676.8 11800.9 11870.1 11884.2 11919.8 14058.4 14076.0 14084.0 14090.0 14221.4 4977.3 5277.4 6249.6 6275.2 6281.6 933.8 971.4 1003.8 1014.8 1022.9 20692.1 24235.3 24274.8 24466.6 31230.0 1049.2 1088.5 1114.6 1119.3 1120.2 12034.5 12350.1 12382.9 12400.6 12436.8 285617.0 290122.0 290311.5 290865.0 323128.0 55955.4 56380.7 56569.0 56637.4 56646.0 1457.7 1481.1 1492.2 1496.7 1508.0 26339.6 28942.9 29585.7 29686.4 30048.7 37002.4 37521.0 37547.0 37627.0 37738.6 276597.0 276673.6 278821.0 279382.0 279621.0 33287.3 33573.8 34739.8 34827.5 35018.5 42699.5 43889.8 44065.3 44178.8 44179.6 24755.4 27705.4 28601.7 30421.4 30495.7 28354.9 29288.0 29810.0 30045.0 30450.0 31069.1 35547.8 36180.5 36474.0 36626.0 26043.0 26446.4 28561.4 28792.9 29834.9 21948.3 23645.3 23872.9 23932.3 23950.9 06:43:14 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:39409.76 Median:23518.85 stddev:92884.49 (394.9%) stddev-sans-first:93098.72 06:43:14 INFO - PID 1651 | Values: 333.5 376.4 429.1 434.3 453.6 21667.5 22540.1 22558.9 22972.4 24139.6 17582.0 23734.5 24275.3 24346.5 24357.5 6083.7 6181.7 6212.3 6227.2 6231.5 6163.4 6170.3 6203.7 6216.7 6247.1 6001.3 6553.9 6917.0 6990.7 7006.8 6471.5 6752.0 6944.9 6992.1 6992.5 6818.7 6831.2 6833.4 6844.4 6849.3 30777.0 30786.2 31341.1 31414.0 32063.0 2155.6 2212.5 2214.8 2217.8 2221.8 19556.9 19656.8 19755.8 19817.0 19844.4 2305.2 2320.3 2390.2 2393.6 2404.8 150170.0 155892.0 201001.0 208309.0 212122.0 22442.0 24966.4 25626.3 27380.5 27518.9 42744.8 42950.0 43506.7 43514.5 43583.6 11448.5 11627.2 11674.2 11678.9 11716.4 9228.3 9270.8 9287.4 9347.8 9360.7 12244.9 12495.2 12536.5 12578.7 12601.0 4469.8 4682.4 4865.4 4947.9 5085.7 829.3 861.0 876.4 949.1 991.6 21160.4 21531.7 21572.2 21812.7 21838.7 950.7 978.3 989.1 1000.8 1029.9 9410.9 10096.2 11262.7 11309.8 11370.0 521858.0 527572.0 540509.0 546313.0 547955.0 139971.0 144812.0 146409.0 147197.0 148364.3 979.0 1023.2 1183.2 1207.1 1267.0 22208.0 23052.5 23116.4 23257.0 23303.2 64136.7 64151.7 67944.0 70760.6 71147.0 85983.6 86189.6 87187.0 87322.0 87823.0 10167.0 10288.6 10407.9 10408.0 10416.2 13670.5 18079.0 18254.9 18478.3 18505.9 15061.7 15116.9 15205.1 15227.7 15322.0 13169.3 13762.2 13855.5 13924.0 13979.9 12642.4 15283.2 15443.4 15510.3 15570.5 9716.8 9794.8 10284.5 10293.5 10381.1 14922.0 15131.9 15256.6 15263.4 15275.3 06:43:14 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:7183.41 Median:4129.21 stddev:4561.16 (110.5%) stddev-sans-first:4553.38 06:43:14 INFO - PID 1651 | Values: 1417.6 1474.4 1498.7 1501.6 1508.3 11645.3 12081.2 12104.9 12156.0 12571.1 11975.3 12018.5 12164.8 12177.5 12192.2 2545.1 2591.7 2602.3 2605.6 2607.8 2540.6 2552.0 2576.3 2578.0 2580.8 2547.7 2557.9 2558.6 2573.7 2574.9 2488.8 2524.2 2528.3 2535.0 2536.4 2348.8 2365.1 2366.1 2375.1 2494.2 11768.5 11828.4 11851.5 11871.2 11907.2 6431.4 6634.0 6683.8 6700.5 6710.0 4083.2 4250.4 4320.9 4328.7 4356.2 1220.4 1235.4 1272.7 1275.0 1278.1 15303.6 15730.8 16557.6 16680.0 16711.8 5547.1 5624.9 5705.7 5761.1 5779.7 10371.4 12358.3 12363.2 12376.6 12386.4 6385.8 6432.9 6502.8 6514.3 6517.1 6032.6 6146.5 6156.8 6174.2 6215.8 4175.2 4266.6 4280.9 4290.7 4325.3 1269.6 1290.5 1292.9 1305.5 1306.8 753.8 773.1 776.1 776.4 782.8 6084.2 6125.8 6182.0 6288.1 6356.7 568.6 579.0 593.8 599.8 892.5 4219.6 4370.7 4460.9 4500.9 4526.5 14533.1 15235.9 15286.3 15315.3 15339.2 14603.9 14969.3 15257.6 15339.0 15342.6 8470.0 8604.7 8617.9 8674.5 8688.7 5506.6 6905.7 6995.7 7203.6 7211.4 12961.4 13608.3 13633.0 13846.7 13866.0 13748.1 13775.7 14047.7 14072.8 14161.7 8819.6 8868.4 8987.5 9005.5 9009.0 10798.2 10867.3 10959.0 10961.9 10968.7 9203.3 9293.7 9432.0 9542.6 9579.8 9018.5 9033.3 9103.7 9168.5 9197.5 8252.1 8260.7 8310.3 8349.6 8361.9 6046.5 6109.0 6111.5 6154.4 6283.8 6122.6 6243.0 6249.4 6252.9 6263.3 06:43:14 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5390.39 Median:6039.33 stddev:1705.94 (28.2%) stddev-sans-first:1667.98 06:43:14 INFO - PID 1651 | Values: 334.4 384.6 394.5 397.1 410.3 4652.7 4698.0 5244.9 6037.8 6252.6 4991.8 5282.5 6273.0 6275.5 6298.7 4497.6 4618.5 4652.5 4670.0 4703.8 4409.5 4660.8 4674.8 4676.0 4695.2 3854.4 4648.3 4649.0 4656.0 4659.0 4049.8 4635.2 4647.5 4650.2 4658.8 3560.9 3815.0 4017.4 4028.1 4030.2 6168.2 6181.4 6199.5 6235.9 6246.9 5539.3 5544.5 5551.3 5554.3 5560.2 5688.6 6750.8 6803.3 6820.5 6827.5 3931.5 4226.6 4529.2 4531.7 4532.9 6897.2 6972.2 7173.5 7285.1 7293.4 4260.6 4543.9 4553.5 4559.9 4573.9 3913.0 4644.5 4645.6 4647.8 4678.5 4562.8 5140.4 5291.5 5424.7 5443.6 6834.2 6843.4 6849.8 6850.5 6864.6 1586.3 1591.1 1597.9 1605.1 1608.6 1023.8 1046.0 1065.6 1066.9 1069.8 5636.8 6389.9 6403.1 6448.6 6456.9 6134.8 6990.7 7021.3 7066.4 7089.0 5585.8 6392.7 6394.1 6421.3 6441.2 3210.6 3321.6 3362.9 3651.1 3658.7 6574.8 6744.2 7207.6 7224.6 7267.5 6255.5 6493.4 7192.5 7195.9 7243.2 6377.9 6506.0 6512.7 7107.0 7193.6 6154.3 6962.3 6981.5 6989.5 7054.4 6433.9 6885.4 6888.8 6900.3 6946.4 5788.0 6540.4 7163.0 7171.0 7182.8 4564.6 4575.5 4578.4 4603.7 4604.8 6211.9 6929.7 6956.7 6960.0 6968.3 5633.7 5906.4 6639.3 6713.0 6748.1 6040.8 6105.4 6315.3 6420.2 6691.8 5359.5 6130.4 6592.1 6600.7 6609.4 5962.2 6691.4 6751.5 6751.6 6772.3 5153.9 5673.5 6513.9 6549.4 6594.7 06:43:14 INFO - PID 1651 | 06:43:14 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:25102.43 Median:17531.84 stddev:39177.85 (223.5%) stddev-sans-first:39245.32 06:43:14 INFO - PID 1651 | Values: 817.3 831.3 838.3 866.5 879.3 16640.4 16775.2 16857.2 17103.1 17140.0 13079.3 15386.8 16145.4 16190.2 16382.5 963.4 976.8 983.2 984.3 988.5 989.2 1005.3 1005.9 1008.4 1009.6 997.9 1035.5 1041.5 1047.9 1048.2 1026.3 1034.4 1040.3 1041.8 1043.0 753.9 753.9 754.5 761.0 761.4 16930.6 16955.0 17092.0 17131.8 17202.8 14673.4 17803.6 17856.3 17910.5 17914.3 12352.0 12696.9 12769.9 12775.0 12793.2 3571.3 3596.5 3602.7 3606.2 3606.5 152412.0 154586.7 155503.0 155621.0 156328.2 12810.9 12847.6 12856.9 12904.9 12974.3 26589.7 27044.1 27063.2 27071.7 27125.0 7851.1 7934.8 7936.6 7939.2 7940.6 3902.1 3949.1 3974.3 3980.5 3981.8 3890.8 4874.5 4904.1 4906.8 4922.0 941.3 955.2 962.7 978.1 978.9 589.9 631.4 632.3 655.9 668.1 11165.8 11558.6 12018.7 12645.7 12734.9 638.3 644.7 648.6 659.7 670.2 5565.6 6468.7 6496.7 6535.6 6683.7 141188.0 142059.0 142099.3 142348.0 145176.0 104464.0 123231.0 124055.0 124545.0 129095.4 86290.1 91397.5 93074.0 95511.0 97949.0 11095.4 12088.9 12368.7 12597.4 12670.3 33572.3 43590.6 43673.3 44100.8 45048.5 60346.0 60361.7 60802.7 61660.4 61896.7 16481.3 16515.5 16983.8 17004.0 17123.1 18393.4 18638.6 18639.9 18816.4 18830.0 16764.6 17142.3 17327.2 17523.2 17604.1 16628.3 16943.8 17477.9 17540.5 17573.0 16422.0 16452.3 16463.7 16667.9 16729.4 10005.0 11861.5 12008.6 12080.1 12227.8 13251.1 13282.3 13315.3 13340.1 13436.7 06:43:14 INFO - PID 1651 | -------- Summary: end -------- 06:43:14 INFO - PID 1651 | 06:43:14 INFO - TEST-INFO | 1651: exit 0 06:43:14 INFO - TEST-OK | dromaeo_css | took 1144287ms 06:43:14 INFO - TEST-START | kraken 06:43:14 INFO - operating with platform_type : mac_ 06:43:14 INFO - Initialising browser for kraken test... 06:43:14 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpml_9G2/profile 06:43:14 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 06:43:48 INFO - TEST-INFO | 1660: killed by SIGTERM 06:43:48 INFO - Browser initialized. 06:43:48 INFO - Running cycle 1/1 for kraken test... 06:43:48 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpml_9G2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:43:50 INFO - PID 1664 | 2017-06-01 06:43:50.927 plugin-container[1665:11226] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 06:43:50 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:43:50 INFO - PID 1664 | 2017-06-01 06:43:50.928 plugin-container[1665:11226] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 06:43:50 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:43:50 INFO - PID 1664 | 2017-06-01 06:43:50.928 plugin-container[1665:11226] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:43:51 INFO - PID 1664 | 2017-06-01 06:43:51.475 plugin-container[1666:11274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 06:43:51 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:43:51 INFO - PID 1664 | 2017-06-01 06:43:51.476 plugin-container[1666:11274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 06:43:51 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:43:51 INFO - PID 1664 | 2017-06-01 06:43:51.476 plugin-container[1666:11274] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:44:00 INFO - PID 1664 | RSS: Main: 268881920 06:44:00 INFO - PID 1664 | 06:44:03 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 06:44:04 INFO - PID 1664 | RSS: Main: 276082688 06:44:04 INFO - PID 1664 | 06:44:06 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 06:44:07 INFO - PID 1664 | RSS: Main: 283869184 06:44:07 INFO - PID 1664 | 06:44:10 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 06:44:11 INFO - PID 1664 | RSS: Main: 281591808 06:44:11 INFO - PID 1664 | 06:44:13 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 06:44:13 INFO - PID 1664 | RSS: Main: 282828800 06:44:13 INFO - PID 1664 | 06:44:15 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:44:16 INFO - PID 1664 | RSS: Main: 284401664 06:44:16 INFO - PID 1664 | 06:44:19 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 06:44:19 INFO - PID 1664 | RSS: Main: 288661504 06:44:19 INFO - PID 1664 | 06:44:23 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 06:44:23 INFO - PID 1664 | RSS: Main: 291958784 06:44:23 INFO - PID 1664 | 06:44:26 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 06:44:27 INFO - PID 1664 | RSS: Main: 290820096 06:44:27 INFO - PID 1664 | 06:44:28 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:44:29 INFO - PID 1664 | RSS: Main: 290390016 06:44:29 INFO - PID 1664 | 06:44:31 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:44:32 INFO - PID 1664 | RSS: Main: 290349056 06:44:32 INFO - PID 1664 | 06:44:34 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:44:34 INFO - PID 1664 | RSS: Main: 290181120 06:44:34 INFO - PID 1664 | 06:44:37 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:44:38 INFO - PID 1664 | RSS: Main: 289730560 06:44:38 INFO - PID 1664 | 06:44:41 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:44:42 INFO - PID 1664 | RSS: Main: 289914880 06:44:42 INFO - PID 1664 | 06:44:43 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 06:44:44 INFO - PID 1664 | RSS: Main: 291606528 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | __start_tp_report 06:44:44 INFO - PID 1664 | _x_x_mozilla_page_load 06:44:44 INFO - PID 1664 | _x_x_mozilla_page_load_details 06:44:44 INFO - PID 1664 | |i|pagename|runs| 06:44:44 INFO - PID 1664 | |0;ai-astar;102;115;112;114;113;115;114;112;115;113 06:44:44 INFO - PID 1664 | |1;audio-beat-detection;133;110;109;147;109;113;108;116;134;113 06:44:44 INFO - PID 1664 | |2;audio-dft;194;196;201;205;210;219;217;207;199;200 06:44:44 INFO - PID 1664 | |3;audio-fft;77;57;59;82;62;58;60;65;96;50 06:44:44 INFO - PID 1664 | |4;audio-oscillator;84;70;85;69;68;69;85;63;64;100 06:44:44 INFO - PID 1664 | |5;imaging-gaussian-blur;88;90;88;88;89;89;88;88;89;93 06:44:44 INFO - PID 1664 | |6;imaging-darkroom;108;95;149;130;96;96;96;96;123;95 06:44:44 INFO - PID 1664 | |7;imaging-desaturate;92;99;148;91;91;89;92;90;144;76 06:44:44 INFO - PID 1664 | |8;json-parse-financial;52;52;52;53;54;54;52;61;52;54 06:44:44 INFO - PID 1664 | |9;json-stringify-tinderbox;56;61;59;61;72;65;59;54;54;56 06:44:44 INFO - PID 1664 | |10;stanford-crypto-aes;88;84;84;86;88;88;87;93;122;88 06:44:44 INFO - PID 1664 | |11;stanford-crypto-ccm;138;137;136;142;144;147;131;142;144;155 06:44:44 INFO - PID 1664 | |12;stanford-crypto-pbkdf2;187;213;201;185;189;182;186;186;186;173 06:44:44 INFO - PID 1664 | |13;stanford-crypto-sha256-iterative;67;65;70;89;73;64;88;59;72;92 06:44:44 INFO - PID 1664 | __end_tp_report 06:44:44 INFO - PID 1664 | __start_cc_report 06:44:44 INFO - PID 1664 | _x_x_mozilla_cycle_collect,1220 06:44:44 INFO - PID 1664 | __end_cc_report 06:44:44 INFO - PID 1664 | __startTimestamp1496324684065__endTimestamp 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | ------- Summary: start ------- 06:44:44 INFO - PID 1664 | Number of tests: 14 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#0] ai-astar Cycles:10 Average:112.50 Median:114.00 stddev:3.87 (3.4%) stddev-sans-first:1.22 06:44:44 INFO - PID 1664 | Values: 102.0 115.0 112.0 114.0 113.0 115.0 114.0 112.0 115.0 113.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#1] audio-beat-detection Cycles:10 Average:119.20 Median:114.50 stddev:13.69 (12.0%) stddev-sans-first:13.58 06:44:44 INFO - PID 1664 | Values: 133.0 110.0 109.0 147.0 109.0 113.0 108.0 116.0 134.0 113.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#2] audio-dft Cycles:10 Average:204.80 Median:206.00 stddev:8.48 (4.1%) stddev-sans-first:8.05 06:44:44 INFO - PID 1664 | Values: 194.0 196.0 201.0 205.0 210.0 219.0 217.0 207.0 199.0 200.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#3] audio-fft Cycles:10 Average:66.60 Median:63.50 stddev:14.05 (22.1%) stddev-sans-first:14.39 06:44:44 INFO - PID 1664 | Values: 77.0 57.0 59.0 82.0 62.0 58.0 60.0 65.0 96.0 50.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:69.50 stddev:12.07 (17.4%) stddev-sans-first:12.43 06:44:44 INFO - PID 1664 | Values: 84.0 70.0 85.0 69.0 68.0 69.0 85.0 63.0 64.0 100.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:89.00 stddev:1.56 (1.8%) stddev-sans-first:1.62 06:44:44 INFO - PID 1664 | Values: 88.0 90.0 88.0 88.0 89.0 89.0 88.0 88.0 89.0 93.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#6] imaging-darkroom Cycles:10 Average:108.40 Median:95.50 stddev:19.16 (20.1%) stddev-sans-first:20.32 06:44:44 INFO - PID 1664 | Values: 108.0 95.0 149.0 130.0 96.0 96.0 96.0 96.0 123.0 95.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#7] imaging-desaturate Cycles:10 Average:101.20 Median:91.00 stddev:24.30 (26.7%) stddev-sans-first:25.54 06:44:44 INFO - PID 1664 | Values: 92.0 99.0 148.0 91.0 91.0 89.0 92.0 90.0 144.0 76.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.50 stddev:2.76 (5.2%) stddev-sans-first:2.86 06:44:44 INFO - PID 1664 | Values: 52.0 52.0 52.0 53.0 54.0 54.0 52.0 61.0 52.0 54.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:5.54 (9.2%) stddev-sans-first:5.71 06:44:44 INFO - PID 1664 | Values: 56.0 61.0 59.0 61.0 72.0 65.0 59.0 54.0 54.0 56.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:88.00 stddev:11.25 (12.8%) stddev-sans-first:11.89 06:44:44 INFO - PID 1664 | Values: 88.0 84.0 84.0 86.0 88.0 88.0 87.0 93.0 122.0 88.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:141.60 Median:143.00 stddev:6.65 (4.7%) stddev-sans-first:6.93 06:44:44 INFO - PID 1664 | Values: 138.0 137.0 136.0 142.0 144.0 147.0 131.0 142.0 144.0 155.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.80 Median:186.50 stddev:10.91 (5.9%) stddev-sans-first:11.55 06:44:44 INFO - PID 1664 | Values: 187.0 213.0 201.0 185.0 189.0 182.0 186.0 186.0 186.0 173.0 06:44:44 INFO - PID 1664 | 06:44:44 INFO - PID 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:72.50 stddev:11.65 (16.1%) stddev-sans-first:12.08 06:44:44 INFO - PID 1664 | Values: 67.0 65.0 70.0 89.0 73.0 64.0 88.0 59.0 72.0 92.0 06:44:44 INFO - PID 1664 | -------- Summary: end -------- 06:44:44 INFO - PID 1664 | 06:44:44 INFO - TEST-INFO | 1664: exit 0 06:44:44 INFO - TEST-OK | kraken | took 89902ms 06:44:44 INFO - SUITE-END | took 1234s 06:44:45 INFO - Completed test suite (00:20:34) 06:44:45 INFO - javascript benchmark 06:44:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8180.651530108499, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3509.6490350964923, 3602.135487483004, 3609.368360536907, 3635.672789448949, 3668.3396988542054, 5147.665401748884, 5823.806119745442, 5947.256592925884, 6118.192813661906, 6224.346443623423, 6212.316645169028, 6226.910290699132, 6259.186305780242, 6282.623042617434, 6326.873462530747, 5063.569596584295, 5326.094563924142, 5438.619296649236, 5492.313460817398, 5501.064818980783, 5576.9163462548095, 5611.859703507404, 5614.185943038257, 5637.295338082739, 5643.379228284885, 5408.161734931092, 5487.6981766002855, 5507.421720719307, 5518.91721624174, 5527.060399732015, 5022.748862556894, 5254.92117618236, 5524.226608274845, 5524.558035357162, 5540.639858409231, 5438.211459338374, 5452.781888724447, 5482.698451585201, 5490.231367608538, 5531.944680553223, 6126.181801870944, 6167.229096362955, 6269.686515674243, 6280.183576135073, 6295.307516173218, 5526.861828454321, 5598.888022239573, 6559.2128944526485, 6709.765158219488, 6723.831904202385, 6806.829829254259, 6814.0, 6815.216250131249, 6816.216135144423, 6824.249332573413, 6266.62400255977, 6320.778772742932, 6320.93679063208, 6341.239051313872, 6376.872462550816, 6693.397594216543, 6777.220619628707, 6882.931170688329, 6914.51598388108, 6939.861202775916, 6374.394432528902, 6386.489080873519, 6471.6764161791725, 6603.7358505660195, 6639.900401493981, 6689.866202675919, 6772.627505487246, 6784.253732089466, 6790.626515541692, 6799.626020568818, 6656.966715166393, 6659.101021362151, 6675.46596272297, 6675.9332406676285, 6679.198496180489, 6447.709853056624, 6568.244651865001, 6613.3056029117215, 6667.533272670867, 6694.899576506356, 5272.367315922113, 5328.200769884548, 5372.167314066326, 5382.0, 5418.078926582491, 4101.159262351211, 4357.651387888961, 4400.713953593006, 4769.499202583748, 4816.0, 6256.562040657202, 6276.6547839869245, 6297.811065668037, 6310.652914089769, 6373.362663733682, 6759.357861003196, 6807.829804254933, 6823.727050917908, 6825.215100263434, 6832.760853370109, 6378.043293506012, 6384.904226436607, 6387.776427825004, 6394.488440924715, 6406.903896441557, 5771.422857714195, 5857.531397488191, 5888.146218798251, 5951.9107213391835, 5975.43233392827, 6834.555753875982, 6941.895871561931, 6963.512554121366, 6967.1291088613925, 6986.755463558751, 6691.59850408977, 6811.182658081062, 6818.624975626388, 6830.043793868763, 6844.076049733222, 5718.571107166896, 5948.5538584605465, 6011.489023433057, 6947.166340039228, 6970.686319115754, 6399.0, 6584.572002819801, 6640.037194606852, 6693.899591506034, 6713.765018224534, 6812.829679258059, 6821.317868213139, 6829.590224586541, 6834.9316506836285, 6867.3476019778045, 6411.8397040072505, 6470.417662410406, 6537.084808126961, 6581.605103693793, 6598.175228096488, 6500.122483464861, 6528.37980391862, 6545.21457425112, 6582.80251592453, 6587.868242635216, 6359.459445947146, 6455.870882582416, 6461.869172894819, 6462.063000864942, 6469.223693156851, 6578.671066446755, 6593.142891424276, 6596.472282217315, 6632.767853125118, 6634.369734875179, 6267.310595834546, 6341.112244285896, 6355.682215889095, 6441.872672282426, 6442.420182183626], "value": 6108.026854077802, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.9070530125782, 699.244815599153, 747.7495039161882, 1297.1892567145528, 1314.4545013819265, 37451.81274093632, 38434.0, 39055.80472097635, 39062.60937390625, 39151.60848391515, 40458.78623641288, 40910.0, 40921.59078409215, 40995.0, 41020.58979410205, 8295.253427191567, 8435.156484351546, 8439.282660973824, 8441.831163376728, 8449.028361738387, 8286.00567931849, 8356.331493480508, 8367.874481882776, 8374.916250837474, 8406.0, 8340.249377556018, 8362.456440331389, 8384.664613415456, 8387.0, 8410.747677569683, 7214.819629509304, 8332.83334333313, 8383.95808020962, 8398.538080405575, 8402.87395689062, 6943.583384996917, 6996.6851491683, 8143.837123257502, 8211.34309255258, 8215.0, 36876.81561592175, 37096.258074838624, 37109.07227319312, 37116.0, 37147.81426092879, 3497.7376696747847, 3505.263894582141, 3523.365794157051, 3531.5697142657264, 3545.1846075402545, 30125.69874301251, 30318.84840575805, 30340.393192136253, 30380.54429183564, 30436.847815761, 2903.0, 2954.793164478487, 3076.615423072116, 3082.969170308291, 3092.443360195164, 245555.77222113954, 248686.0, 268788.0, 293939.0, 294188.0, 46452.5354746455, 47149.5285047152, 47316.52683473121, 47372.0, 47466.0, 49034.264486032735, 49136.26295605569, 49291.50708492869, 49714.0, 50473.49526504761, 14602.97779155449, 14739.041962272418, 14739.189344586151, 14748.483803066842, 14766.335514901855, 11676.76646467066, 11800.881991179978, 11870.050395968474, 11884.227525210834, 11919.761604767857, 14058.437662493387, 14076.014678972579, 14084.0, 14090.013699040968, 14221.360038798071, 4977.32806071183, 5277.445868183785, 6249.562530622904, 6275.246970363494, 6281.560290779602, 933.7572231219796, 971.3880255439028, 1003.8494225866178, 1014.8071866345371, 1022.9283950123569, 20692.068856901475, 24235.272941811774, 24274.757252427604, 24466.633000505008, 31230.0, 1049.2497864027257, 1088.5156105532963, 1114.637742733615, 1119.289251325398, 1120.1878637987395, 12034.458449369624, 12350.135490515757, 12382.876171238173, 12400.56598019083, 12436.81344779829, 285617.0, 290122.0, 290311.54844225646, 290865.0, 323128.0, 55955.44044559502, 56380.71809641008, 56569.0, 56637.43362566404, 56646.0, 1457.6574504991318, 1481.0891301849501, 1492.2315007771006, 1496.738070837599, 1507.9924600376928, 26339.604905926044, 28942.855285723435, 29585.704142958293, 29686.40627187401, 30048.69951300459, 37002.44496332611, 37521.0, 37547.0, 37627.0, 37738.62261377461, 276597.0, 276673.61663191556, 278821.0, 279382.0, 279621.0, 33287.33425331528, 33573.83213083919, 34739.82630086935, 34827.477587835696, 35018.474722879684, 42699.50376037181, 43889.78055109704, 44065.339019914085, 44178.779106104266, 44179.558204417546, 24755.381115472257, 27705.44589108166, 28601.713982859907, 30421.391572168875, 30495.695043050175, 28354.85822570874, 29287.974920877667, 29810.0, 30045.0, 30450.0, 31069.067927962198, 35547.82226088957, 36180.4572931401, 36474.0, 36626.0, 26043.0, 26446.41321520715, 28561.428771424038, 28792.856035719687, 29834.850825745732, 21948.34154975353, 23645.29064128079, 23872.880635597405, 23932.28203153908, 23950.88024559866], "value": 17376.039439543933, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.50641051244156, 376.410916915028, 429.13957515182057, 434.2942718083116, 453.62802501948414, 21667.45831354217, 22540.098396064157, 22558.88720556397, 22972.425689357773, 24139.637905431387, 17582.0, 23734.525309493798, 24275.27174184773, 24346.513069738594, 24357.512849742994, 6083.665398403086, 6181.690915454232, 6212.316645169028, 6227.190465239512, 6231.501479881598, 6163.352847950968, 6170.259568851744, 6203.720832562545, 6216.720247588869, 6247.125402443661, 6001.339852616209, 6553.901691474608, 6917.0, 6990.720371185172, 7006.789796306119, 6471.546991710582, 6751.966240168792, 6944.930550694479, 6992.091028166356, 6992.475564332695, 6818.659067046678, 6831.2144103428245, 6833.4191593714595, 6844.4182244509275, 6849.349311815369, 30777.0, 30786.23034424135, 31341.05976820699, 31414.0, 32063.0, 2155.6227660159566, 2212.5021870079104, 2214.767449417804, 2217.7782221777693, 2221.7889300516426, 19556.902215488975, 19656.80343196578, 19755.80244197554, 19817.0, 19844.404667859988, 2305.1586171047447, 2320.3271051395077, 2390.151496218852, 2393.6050551658955, 2404.8076153907646, 150170.0, 155892.0, 201000.99999999854, 208309.0, 212122.0, 22442.0, 24966.37584060413, 25626.28418879252, 27380.494072825786, 27518.862405687843, 42744.78627606842, 42950.0, 43506.69479915544, 43514.47699330572, 43583.56416435795, 11448.484818183202, 11627.244229125079, 11674.182807203584, 11678.94160529209, 11716.355600441888, 9228.26173906086, 9270.814583708423, 9287.442753434816, 9347.813043739086, 9360.672376466791, 12244.938775306244, 12495.187812792139, 12536.498540058477, 12578.748425031448, 12601.0, 4469.754163520973, 4682.41469816273, 4865.416150062015, 4947.876303092379, 5085.7202853842655, 829.2868133405267, 860.9741707748777, 876.369014309696, 949.126803340928, 991.5785791038779, 21160.365189044045, 21531.67702484464, 21572.244971425924, 21812.672809907865, 21838.675589996452, 950.6862735297474, 978.2516374973009, 989.0653332600767, 1000.8190335404292, 1029.891861354562, 9410.858837117448, 10096.192304615613, 11262.662120136409, 11309.830352544719, 11370.0, 521858.0, 527572.0, 540509.0, 546313.0, 547955.0, 139971.0, 144812.0, 146409.0, 147197.0, 148364.2581787106, 979.020979020979, 1023.1916785739182, 1183.1954271095565, 1207.1489599832098, 1266.9556565520163, 22208.0, 23052.538949221256, 23116.422089447227, 23257.0, 23303.18438854632, 64136.71726565536, 64151.679241603495, 67944.0, 70760.64619676869, 71147.0, 85983.57008214919, 86189.56905215434, 87187.0, 87322.0, 87823.0, 10167.034131757471, 10288.588456461657, 10407.94796026015, 10408.0, 10416.166706663296, 13670.453181872614, 18079.0, 18254.904705717698, 18478.260869565067, 18505.889646621246, 15061.698766024838, 15116.924415378293, 15205.087694738351, 15227.695446091238, 15322.0, 13169.341532923125, 13762.24307663088, 13855.515056972958, 13924.0, 13979.860201398262, 12642.367881606067, 15283.15942623122, 15443.382264709286, 15510.302036408162, 15570.455034073753, 9716.813716913637, 9794.804103917739, 10284.537195826355, 10293.53679084428, 10381.11760500366, 14922.0, 15131.940764146404, 15256.618584535476, 15263.3894644213, 15275.312610932313], "value": 12397.793033117563, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.5888992192263, 1474.4249742600393, 1498.7227362937858, 1501.6020754500053, 1508.2684897824554, 11645.30128192308, 12081.214721043138, 12104.939475302623, 12156.0, 12571.120021598468, 11975.341356225405, 12018.51925922962, 12164.756704865897, 12177.512899484032, 12192.207506512093, 2545.0710490670913, 2591.7019542752637, 2602.323395917056, 2605.6221847832053, 2607.804414668898, 2540.5554028045017, 2551.9744802552013, 2576.2915198320425, 2577.9742202577922, 2580.7677309042183, 2547.6942766868, 2557.9104731334405, 2558.616207568861, 2573.652556904822, 2574.9227523174336, 2488.825782195247, 2524.192258477288, 2528.329992551966, 2534.9873250633814, 2536.4039450729065, 2348.8120950323932, 2365.053978408633, 2366.148186652804, 2375.09746296408, 2494.2018554062706, 11768.529258829722, 11828.408579570987, 11851.459310289694, 11871.169018168732, 11907.16649834512, 6431.389018043278, 6633.9668301658185, 6683.832904177386, 6700.530962832604, 6710.0, 4083.1833633273463, 4250.447441832542, 4320.891977700551, 4328.718633288826, 4356.150550642595, 1220.4385982448084, 1235.4008305971597, 1272.694553307209, 1274.961751147467, 1278.0925542864484, 15303.617409564738, 15730.764038539432, 16557.58606034847, 16680.0, 16711.83288167115, 5547.140193270049, 5624.887502249932, 5705.74324155414, 5761.107028410603, 5779.682117483495, 10371.429571373646, 12358.320292384005, 12363.196392234477, 12376.62870113898, 12386.442610082568, 6385.840353991188, 6432.903506447407, 6502.837429064217, 6514.348565143448, 6517.055027021055, 6032.638041717511, 6146.5390095743, 6156.753729850845, 6174.166487524217, 6215.844603884939, 4175.227582897113, 4266.6160045595425, 4280.871573852789, 4290.742555446684, 4325.329573916048, 1269.5590504777144, 1290.5289569307135, 1292.8513220979519, 1305.451710281684, 1306.8366454193226, 753.8492301539713, 773.1302284929454, 776.1423626892316, 776.3556248313886, 782.8199514111786, 6084.178635884099, 6125.84685382869, 6182.0, 6288.119663247149, 6356.711583829818, 568.6417556939102, 578.9549862498147, 593.7981086430634, 599.8320470268332, 892.5314210039782, 4219.599138081878, 4370.672199585044, 4460.910781784346, 4500.897280166315, 4526.547345265513, 14533.128012319296, 15235.923820380827, 15286.312115954808, 15315.31081101353, 15339.23303834826, 14603.926980365242, 14969.326380312694, 15257.6185595361, 15339.002964807252, 15342.616434589225, 8469.957650211833, 8604.709293606009, 8617.870731939025, 8674.479531228146, 8688.6524539019, 5506.614536982533, 6905.654717264017, 6995.720171193096, 7203.567785932861, 7211.3870321023305, 12961.351932403531, 13608.251546165056, 13633.0, 13846.723065538834, 13866.029377943447, 13748.106373085717, 13775.655608609864, 14047.735703786708, 14072.78890816659, 14161.716765664423, 8819.647214111365, 8868.423552469068, 8987.46075235488, 9005.459672419676, 9009.0, 10798.244122911321, 10867.29362591429, 10959.0, 10961.890381096406, 10968.725781855517, 9203.309751768511, 9293.72118836436, 9432.009638988082, 9542.618295268112, 9579.760505987406, 9018.503982280776, 9033.322500812334, 9103.681371151977, 9168.495732734764, 9197.540122993958, 8252.133525979925, 8260.710875119583, 8310.293625041939, 8349.624266908126, 8361.874571881304, 6046.546509011754, 6109.0, 6111.54163437735, 6154.446099851035, 6283.8114856554375, 6122.591803885042, 6242.968785156045, 6249.406306400884, 6252.9374706254175, 6263.342349053466], "value": 5384.202066723293, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.3647070565926, 384.55199692358394, 394.49504634068387, 397.08868147601254, 410.282006488645, 4652.651051171163, 4697.953020469794, 5244.921326180101, 6037.849053773658, 6252.624842509441, 4991.7504124793795, 5282.5245727884485, 6273.0, 6275.46658534024, 6298.7165577549, 4497.617702495292, 4618.4688760792515, 4652.511486293941, 4669.953300467003, 4703.835365762199, 4409.492908315537, 4660.766961651921, 4674.807923979398, 4675.953240467603, 4695.201815691341, 3854.4025676020124, 4648.2562789953445, 4648.9767551162195, 4656.0, 4658.953410465902, 4049.800657480587, 4635.165670179366, 4647.512011238805, 4650.162970665296, 4658.767061646921, 3560.922743458725, 3814.9890279076067, 4017.4375587417785, 4028.053407449247, 4030.234255491447, 6168.198134242564, 6181.443670069671, 6199.50403967686, 6235.9376406235815, 6246.937530624681, 5539.25220095286, 5544.473275038865, 5551.250581171531, 5554.305711786027, 5560.165975103767, 5688.63023903445, 6750.797476075725, 6803.2856550062525, 6820.454363650896, 6827.52207345486, 3931.5282166140246, 4226.5773422657485, 4529.230030894756, 4531.705439146445, 4532.864014079583, 6897.172339319263, 6972.163340399185, 7173.5337203081635, 7285.125784905846, 7293.380062694624, 4260.552641972548, 4543.909121817545, 4553.5218802025975, 4559.863204103882, 4573.908521829545, 3913.021744563859, 4644.535546445329, 4645.581897629229, 4647.837325693585, 4678.47949416441, 4562.817487300471, 5140.434552199255, 5291.497307755757, 5424.728763561806, 5443.564514838803, 6834.2482326944, 6843.3840954314355, 6849.794506164823, 6850.451963842881, 6864.553804002724, 1586.3178833101647, 1591.124881315272, 1597.8641815445585, 1605.117185547944, 1608.6165897328287, 1023.7798873242289, 1045.9895401046044, 1065.648336049102, 1066.861308029951, 1069.823479125951, 5636.830895073154, 6389.8722025560155, 6403.135576697179, 6448.613083215022, 6456.903146452807, 6134.78528251509, 6990.685419156151, 7021.29787021304, 7066.434685225169, 7089.0, 5585.776568937197, 6392.689498652765, 6394.136791533177, 6421.261554921244, 6441.1626488556185, 3210.598675165604, 3321.568196134487, 3362.8654853805574, 3651.050726811048, 3658.652428019334, 6574.783665021891, 6744.224414192333, 7207.639618019078, 7224.602646854473, 7267.491275610762, 6255.499560035186, 6493.448056915215, 7192.496525243288, 7195.89206161908, 7243.203247642755, 6377.904331435032, 6506.0, 6512.72054780823, 7107.005019297302, 7193.64031798408, 6154.323024467394, 6962.3037696231995, 6981.5112942093565, 6989.5107342485535, 7054.4003759681, 6433.871322573429, 6885.414739747178, 6888.793336199922, 6900.309969003059, 6946.37482626566, 5787.987102257004, 6540.444062254762, 7162.964185179041, 7171.0, 7182.820429489096, 4564.634829213722, 4575.450945886515, 4578.359029735773, 4603.723776573416, 4604.792784324646, 6211.875762484816, 6929.7228110875, 6956.721731130698, 6960.0, 6968.303169683194, 5633.718314084197, 5906.438888305604, 6639.2696803352555, 6713.0, 6748.089007983859, 6040.818775436568, 6105.419985101408, 6315.273743519554, 6420.229572451336, 6691.76578819739, 5359.544438722753, 6130.448259656474, 6592.077109204811, 6600.669966501751, 6609.372109649576, 5962.227564182675, 6691.397774200345, 6751.49363797727, 6751.615918736746, 6772.255051944184, 5153.943441594539, 5673.503658390295, 6513.892638251604, 6549.443297319781, 6594.670266486561], "value": 4857.445765019615, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.2930415190859, 831.3307787231277, 838.2832678060262, 866.5225790028, 879.3492815316664, 16640.417585384497, 16775.245113969886, 16857.24142413591, 17103.059331736764, 17140.0, 13079.346032698351, 15386.845986550998, 16145.43490977816, 16190.190490475488, 16382.508524744248, 963.4123184857232, 976.8144052629978, 983.2281658897766, 984.3159004491881, 988.4514094677451, 989.2135752077116, 1005.2711783956646, 1005.9446730429823, 1008.3899240959224, 1009.5709323537504, 997.9002099790035, 1035.4926086217774, 1041.5052849896315, 1047.8899715529874, 1048.1509976918683, 1026.2867307221484, 1034.3690348887174, 1040.2510192661273, 1041.812473754724, 1042.9935112616324, 753.8567672142275, 753.9246075392472, 754.5020286610866, 761.0182864105298, 761.4022991951292, 16930.576735581584, 16955.0, 17092.0, 17131.828681713272, 17202.827971720373, 14673.413063477448, 17803.554911127325, 17856.28574857004, 17910.462686119437, 17914.28342866284, 12352.0, 12696.87303126966, 12769.936150319281, 12775.0, 12793.232406055666, 3571.2857428514403, 3596.478510615946, 3602.657747513982, 3606.206634540397, 3606.495090687306, 152412.0, 154586.72479651257, 155503.0, 155621.0, 156328.21835890747, 12810.9359453204, 12847.614571562868, 12856.935715321364, 12904.935475322563, 12974.286414247306, 26589.734102658727, 27044.05345812887, 27063.1881043569, 27071.729282707314, 27125.0, 7851.136374998746, 7934.761957141295, 7936.6031698414845, 7939.166387529342, 7940.563269020259, 3902.1220225449615, 3949.071968087497, 3974.3044967130636, 3980.522337319482, 3981.7810020448574, 3890.824912878927, 4874.488178741252, 4904.117258893433, 4906.828261010847, 4922.015596880638, 941.3081385181886, 955.2071780422233, 962.6775030364906, 978.0708327089152, 978.8531720242021, 589.9203607512931, 631.3528633151057, 632.3265722006049, 655.9275584419509, 668.0847239282117, 11165.776684466266, 11558.56224955787, 12018.699532511582, 12645.747085058247, 12734.853863152175, 638.2978723404218, 644.7002144003061, 648.6043513456788, 659.6899457255082, 670.2258890980925, 5565.551157490691, 6468.706258748269, 6496.675166241669, 6535.640539770263, 6683.6658167091455, 141188.0, 142059.0, 142099.28950355388, 142348.0, 145176.0, 104464.0, 123231.0, 124055.0, 124545.0, 129095.35452322678, 86290.13709862946, 91397.54301228584, 93074.0, 95511.0, 97949.0, 11095.445227738743, 12088.91199792023, 12368.690782730504, 12597.433115510008, 12670.303133327756, 33572.32855342928, 43590.56409435992, 43673.34489982589, 44100.77949610232, 45048.54951450443, 60346.0, 60361.69819150876, 60802.695986519786, 61660.38339616547, 61896.69051654713, 16481.258343374815, 16515.504534863972, 16983.773083114167, 17003.979761214367, 17123.058231796873, 18393.356232531798, 18638.627227455647, 18639.90680046591, 18816.435506934813, 18830.0, 16764.58088547796, 17142.314307428063, 17327.220275087904, 17523.21145548479, 17604.119794010505, 16628.251728671996, 16943.830561694227, 17477.863938843933, 17540.473785786446, 17573.0, 16422.014679119293, 16452.341906323614, 16463.670726585162, 16667.91658542191, 16729.414470493473, 10005.0, 11861.466234019663, 12008.63974080744, 12080.09399295074, 12227.755444890874, 13251.13867598603, 13282.269475178959, 13315.334233288491, 13340.132891362031, 13436.664083397602], "value": 8709.022462297471, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1485.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 112.0, 114.0, 113.0, 115.0, 114.0, 112.0, 115.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 110.0, 109.0, 147.0, 109.0, 113.0, 108.0, 116.0, 134.0, 113.0], "value": 119.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 196.0, 201.0, 205.0, 210.0, 219.0, 217.0, 207.0, 199.0, 200.0], "value": 204.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 59.0, 82.0, 62.0, 58.0, 60.0, 65.0, 96.0, 50.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 85.0, 69.0, 68.0, 69.0, 85.0, 63.0, 64.0, 100.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 88.0, 88.0, 89.0, 89.0, 88.0, 88.0, 89.0, 93.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 95.0, 149.0, 130.0, 96.0, 96.0, 96.0, 96.0, 123.0, 95.0], "value": 108.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 148.0, 91.0, 91.0, 89.0, 92.0, 90.0, 144.0, 76.0], "value": 101.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 53.0, 54.0, 54.0, 52.0, 61.0, 52.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 59.0, 61.0, 72.0, 65.0, 59.0, 54.0, 54.0, 56.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 84.0, 86.0, 88.0, 88.0, 87.0, 93.0, 122.0, 88.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 142.0, 144.0, 147.0, 131.0, 142.0, 144.0, 155.0], "value": 141.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 213.0, 201.0, 185.0, 189.0, 182.0, 186.0, 186.0, 186.0, 173.0], "value": 188.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 70.0, 89.0, 73.0, 64.0, 88.0, 59.0, 72.0, 92.0], "value": 73.9, "unit": "score"}]}]} 06:44:45 INFO - Return code: 0 06:44:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:44:45 INFO - # TBPL SUCCESS # 06:44:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:44:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:44:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:44:45 INFO - 2017-06-01 06:44:45.348 screenresolution[1672:11663] starting screenresolution argv=screenresolution get 06:44:45 INFO - 2017-06-01 06:44:45.351 screenresolution[1672:11663] Display 0: 1600x1200x32@60 06:44:45 INFO - 2017-06-01 06:44:45.357 screenresolution[1673:11666] starting screenresolution argv=screenresolution list 06:44:45 INFO - Available Modes on Display 0 06:44:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:44:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:44:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:44:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:44:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:44:45 INFO - Intel Iris: 06:44:45 INFO - Chipset Model: Intel Iris 06:44:45 INFO - Type: GPU 06:44:45 INFO - Bus: Built-In 06:44:45 INFO - VRAM (Dynamic, Max): 1536 MB 06:44:45 INFO - Vendor: Intel (0x8086) 06:44:45 INFO - Device ID: 0x0a2e 06:44:45 INFO - Revision ID: 0x0009 06:44:45 INFO - Displays: 06:44:45 INFO - Display: 06:44:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:44:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:44:45 INFO - Display Serial Number: 1600x1200 60 06:44:45 INFO - Main Display: Yes 06:44:45 INFO - Mirror: Off 06:44:45 INFO - Online: Yes 06:44:45 INFO - Rotation: Supported 06:44:45 INFO - Return code: 0 06:44:45 INFO - Running post-action listener: _resource_record_post_action 06:44:45 INFO - [mozharness: 2017-06-01 13:44:45.591875Z] Finished run-tests step (success) 06:44:45 INFO - Running post-run listener: _resource_record_post_run 06:44:45 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 35906560; Write bytes: 568710656; Read time: 336; Write time: 1876 06:44:45 INFO - TinderboxPrint: CPU usage
53.1% 06:44:45 INFO - TinderboxPrint: I/O read bytes / time
35,906,560 / 336 06:44:45 INFO - TinderboxPrint: I/O write bytes / time
568,710,656 / 1,876 06:44:45 INFO - TinderboxPrint: CPU idle
2,354.9 (46.9%) 06:44:45 INFO - TinderboxPrint: CPU system
1,011.5 (20.1%) 06:44:45 INFO - TinderboxPrint: CPU user
1,659.4 (33.0%) 06:44:45 INFO - TinderboxPrint: Swap in / out
291,844,096 / 0 06:44:45 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229827072; Write bytes: 229983744; Read time: 16791; Write time: 452 06:44:45 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:44:45 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 34907136; Write bytes: 320262144; Read time: 316; Write time: 1380 06:44:46 INFO - Running post-run listener: _upload_blobber_files 06:44:46 INFO - Blob upload gear active. 06:44:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:44:46 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 06:44:46 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'] 06:44:46 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 06:44:46 INFO - (blobuploader) - INFO - Open directory for files ... 06:44:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:44:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:44:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:47 INFO - (blobuploader) - INFO - Done attempting. 06:44:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:44:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:44:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:48 INFO - (blobuploader) - INFO - Done attempting. 06:44:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:44:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:44:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:49 INFO - (blobuploader) - INFO - Done attempting. 06:44:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:44:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:44:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:44:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:44:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:44:51 INFO - (blobuploader) - INFO - Done attempting. 06:44:51 INFO - (blobuploader) - INFO - Iteration through files over. 06:44:51 INFO - Return code: 0 06:44:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:44:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:44:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8963f4123a7987f15225294a57accdfaeb3650ce91d24496999a1efc4d577fa169a6de1eb469067aec3ac31c870570ea48dfe96e7b78fb334370583dc9470e4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aaf83257576fc7943abb771c6299d0ffafbcd21305280e1e30dc7778e36763b184f9ff5008f28b76680c541460ec75e117fd6357562f3e7b294ac01b9090f3d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb197bceb1043ef73c0875e25647e06f0eeded5b0a0c5443a45643da3d6f6742e90f2c18068e3472e198a86e3d35028bdc62fc127f886ef5819d9dc1085daf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8075a670199722b6ad4d5f310b5142ebcf526780c8a1e31dd5703750a03d9045a4cc8232cdaed81af1c49ba523b10e3d874540058d94b0174276cb899f6dcd5"} 06:44:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:44:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:44:51 INFO - Contents: 06:44:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8963f4123a7987f15225294a57accdfaeb3650ce91d24496999a1efc4d577fa169a6de1eb469067aec3ac31c870570ea48dfe96e7b78fb334370583dc9470e4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aaf83257576fc7943abb771c6299d0ffafbcd21305280e1e30dc7778e36763b184f9ff5008f28b76680c541460ec75e117fd6357562f3e7b294ac01b9090f3d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb197bceb1043ef73c0875e25647e06f0eeded5b0a0c5443a45643da3d6f6742e90f2c18068e3472e198a86e3d35028bdc62fc127f886ef5819d9dc1085daf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8075a670199722b6ad4d5f310b5142ebcf526780c8a1e31dd5703750a03d9045a4cc8232cdaed81af1c49ba523b10e3d874540058d94b0174276cb899f6dcd5"} 06:44:51 INFO - Running post-run listener: copy_logs_to_upload_dir 06:44:51 INFO - Copying logs to upload dir... 06:44:51 INFO - mkdir: /builds/slave/test/build/upload/logs 06:44:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.684638 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-01 06:44:51.383801) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 06:44:51.387489) ========= 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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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/8963f4123a7987f15225294a57accdfaeb3650ce91d24496999a1efc4d577fa169a6de1eb469067aec3ac31c870570ea48dfe96e7b78fb334370583dc9470e4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aaf83257576fc7943abb771c6299d0ffafbcd21305280e1e30dc7778e36763b184f9ff5008f28b76680c541460ec75e117fd6357562f3e7b294ac01b9090f3d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb197bceb1043ef73c0875e25647e06f0eeded5b0a0c5443a45643da3d6f6742e90f2c18068e3472e198a86e3d35028bdc62fc127f886ef5819d9dc1085daf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8075a670199722b6ad4d5f310b5142ebcf526780c8a1e31dd5703750a03d9045a4cc8232cdaed81af1c49ba523b10e3d874540058d94b0174276cb899f6dcd5"} build_url:https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/QV5zZ7ObTCC0mimt3wLK3w/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/8963f4123a7987f15225294a57accdfaeb3650ce91d24496999a1efc4d577fa169a6de1eb469067aec3ac31c870570ea48dfe96e7b78fb334370583dc9470e4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aaf83257576fc7943abb771c6299d0ffafbcd21305280e1e30dc7778e36763b184f9ff5008f28b76680c541460ec75e117fd6357562f3e7b294ac01b9090f3d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fb197bceb1043ef73c0875e25647e06f0eeded5b0a0c5443a45643da3d6f6742e90f2c18068e3472e198a86e3d35028bdc62fc127f886ef5819d9dc1085daf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8075a670199722b6ad4d5f310b5142ebcf526780c8a1e31dd5703750a03d9045a4cc8232cdaed81af1c49ba523b10e3d874540058d94b0174276cb899f6dcd5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 06:44:51.421523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:44:51.421869) ========= 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.3qMnheLZjD/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.6ZGSo6fyNe/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.004779 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:44:51.470125) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 06:44:51.470449) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 06:44:51.470773) ========= ========= Total master_lag: 2.24 =========