builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0147 starttime: 1495731866.22 results: success (0) revision: f7adbf457ee20eeffde72694e0d17d73616e3cfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:26.221066) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:26.221530) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:26.221805) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:26.247504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:26.247925) ========= 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.HiSsddX3um/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.N9tbc7o9Mq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-25 10:04:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 184M=0s 2017-05-25 10:04:26 (184 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.217706 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:26.489147) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:26.489471) ========= 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.HiSsddX3um/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.N9tbc7o9Mq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.028346 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:26.538860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 10:04:26.539280) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f7adbf457ee20eeffde72694e0d17d73616e3cfd --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 f7adbf457ee20eeffde72694e0d17d73616e3cfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HiSsddX3um/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.N9tbc7o9Mq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-25 10:04:26,633 truncating revision to first 12 chars 2017-05-25 10:04:26,633 Setting DEBUG logging. 2017-05-25 10:04:26,633 attempt 1/10 2017-05-25 10:04:26,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f7adbf457ee2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 10:04:28,991 unpacking tar archive at: mozilla-central-f7adbf457ee2/testing/mozharness/ program finished with exit code 0 elapsedTime=2.635782 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 10:04:29.199073) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:29.199550) ========= script_repo_revision: f7adbf457ee20eeffde72694e0d17d73616e3cfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:29.200114) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:29.200481) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 10:04:29.220647) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-25 10:04:29.221230) ========= /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.HiSsddX3um/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.N9tbc7o9Mq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:04:29 INFO - MultiFileLogger online at 20170525 10:04:29 in /builds/slave/test 10:04:29 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 10:04:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:04:29 INFO - {'append_to_log': False, 10:04:29 INFO - 'base_work_dir': '/builds/slave/test', 10:04:29 INFO - 'blob_upload_branch': 'Firefox', 10:04:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:04:29 INFO - 'branch': 'Firefox', 10:04:29 INFO - 'buildbot_json_path': 'buildprops.json', 10:04:29 INFO - 'config_files': ('talos/mac_config.py',), 10:04:29 INFO - 'default_actions': ('clobber', 10:04:29 INFO - 'read-buildbot-config', 10:04:29 INFO - 'download-and-extract', 10:04:29 INFO - 'populate-webroot', 10:04:29 INFO - 'create-virtualenv', 10:04:29 INFO - 'install', 10:04:29 INFO - 'run-tests'), 10:04:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:04:29 INFO - 'download_minidump_stackwalk': True, 10:04:29 INFO - 'download_symbols': 'ondemand', 10:04:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:04:29 INFO - 'tooltool.py': '/tools/tooltool.py', 10:04:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:04:29 INFO - '/tools/misc-python/virtualenv.py')}, 10:04:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:04:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:04:29 INFO - 'gecko_profile': False, 10:04:29 INFO - 'gecko_profile_interval': 0, 10:04:29 INFO - 'installer_path': 'installer.exe', 10:04:29 INFO - 'log_level': 'info', 10:04:29 INFO - 'log_name': 'talos', 10:04:29 INFO - 'log_to_console': True, 10:04:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:04:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:04:29 INFO - 'opt_config_files': (), 10:04:29 INFO - 'pip_index': False, 10:04:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:04:29 INFO - 'cmd': ('bash', 10:04:29 INFO - '-c', 10:04:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:04:29 INFO - 'enabled': True, 10:04:29 INFO - 'halt_on_failure': False, 10:04:29 INFO - 'name': 'check_screen_resolution'},), 10:04:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:04:29 INFO - 'cmd': ('bash', 10:04:29 INFO - '-c', 10:04:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:04:29 INFO - 'enabled': True, 10:04:29 INFO - 'halt_on_failure': False, 10:04:29 INFO - 'name': 'check_screen_resolution'},), 10:04:29 INFO - 'run_cmd_checks_enabled': True, 10:04:29 INFO - 'suite': 'dromaeojs-e10s', 10:04:29 INFO - 'system_bits': '32', 10:04:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:04:29 INFO - 'title': 't-yosemite-r7-0147', 10:04:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:04:29 INFO - 'use_talos_json': True, 10:04:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:04:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:04:29 INFO - 'work_dir': 'build'} 10:04:29 INFO - [mozharness: 2017-05-25 17:04:29.432401Z] Running clobber step. 10:04:29 INFO - Running pre-action listener: _resource_record_pre_action 10:04:29 INFO - Running main action method: clobber 10:04:29 INFO - rmtree: /builds/slave/test/build 10:04:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:04:31 INFO - Running post-action listener: _resource_record_post_action 10:04:31 INFO - [mozharness: 2017-05-25 17:04:31.968674Z] Finished clobber step (success) 10:04:31 INFO - [mozharness: 2017-05-25 17:04:31.968802Z] Running read-buildbot-config step. 10:04:31 INFO - Running pre-action listener: _resource_record_pre_action 10:04:31 INFO - Running main action method: read_buildbot_config 10:04:31 INFO - Using buildbot properties: 10:04:31 INFO - { 10:04:31 INFO - "project": "", 10:04:31 INFO - "product": "firefox", 10:04:31 INFO - "who": "ffxbld@noreply.mozilla.org", 10:04:31 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:04:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:04:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:04:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:04:31 INFO - "stage_platform": "macosx64", 10:04:31 INFO - "basedir": "/builds/slave/test", 10:04:31 INFO - "buildnumber": 165, 10:04:31 INFO - "platform": "yosemite_r7", 10:04:31 INFO - "parent_task_id": "LME-yLTsTMurfTdIcHUJVQ", 10:04:31 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:04:31 INFO - "slavebuilddir": "test", 10:04:31 INFO - "taskId": "axPCCM06QZCR51zgGS3O6Q", 10:04:31 INFO - "branch": "mozilla-central", 10:04:31 INFO - "script_repo_revision": "production", 10:04:31 INFO - "revision": "f7adbf457ee20eeffde72694e0d17d73616e3cfd", 10:04:31 INFO - "slavename": "t-yosemite-r7-0147", 10:04:31 INFO - "repo_path": "mozilla-central" 10:04:31 INFO - } 10:04:31 INFO - Finding installer, test and symbols from parent task. 10:04:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/axPCCM06QZCR51zgGS3O6Q'}, attempt #1 10:04:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/axPCCM06QZCR51zgGS3O6Q'}, attempt #1 10:04:33 INFO - Task dependencies: e-qKpawxT2q8qVVC37DYMQ LME-yLTsTMurfTdIcHUJVQ 10:04:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ'}, attempt #1 10:04:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:04:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/test_packages.json 10:04:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/target.crashreporter-symbols.zip 10:04:33 INFO - Running post-action listener: _resource_record_post_action 10:04:33 INFO - [mozharness: 2017-05-25 17:04:33.725615Z] Finished read-buildbot-config step (success) 10:04:33 INFO - [mozharness: 2017-05-25 17:04:33.725737Z] Running download-and-extract step. 10:04:33 INFO - Running pre-action listener: _resource_record_pre_action 10:04:33 INFO - Running main action method: download_and_extract 10:04:33 INFO - mkdir: /builds/slave/test/build/tests 10:04: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')]} 10:04:33 INFO - trying https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:04:33 INFO - Downloading https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/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 10:04:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/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 10:04:34 INFO - Downloaded 1325 bytes. 10:04:34 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:04:34 INFO - Using the following test package requirements: 10:04:34 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:04:34 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:04:34 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:04:34 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:04:34 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'jsshell-mac.zip'], 10:04:34 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:04:34 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:04:34 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:04:34 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:04:34 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:04:34 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:04:34 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:04:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:04:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:04:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:04:34 INFO - Fetch https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:04:36 INFO - Content-Length response header: 57626742 10:04:36 INFO - Bytes received: 57626742 10:04:54 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 10:04:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:04:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:04:54 INFO - Fetch https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:04:56 INFO - Content-Length response header: 57626742 10:04:56 INFO - Bytes received: 57626742 10:05:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:05:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:05:14 INFO - Fetch https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:05:15 INFO - Content-Length response header: 14411859 10:05:15 INFO - Bytes received: 14411859 10:05:15 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')]} 10:05:15 INFO - trying https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:05:15 INFO - Downloading https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:05:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/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 10:05:17 INFO - Downloaded 67164337 bytes. 10:05:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:05:17 INFO - mkdir: /builds/slave/test/properties 10:05:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:05:17 INFO - Writing to file /builds/slave/test/properties/build_url 10:05:17 INFO - Contents: 10:05:17 INFO - build_url:https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:05:17 INFO - Running post-action listener: _resource_record_post_action 10:05:17 INFO - Running post-action listener: set_extra_try_arguments 10:05:17 INFO - [mozharness: 2017-05-25 17:05:17.691649Z] Finished download-and-extract step (success) 10:05:17 INFO - [mozharness: 2017-05-25 17:05:17.691867Z] Running populate-webroot step. 10:05:17 INFO - Running pre-action listener: _resource_record_pre_action 10:05:17 INFO - Running main action method: populate_webroot 10:05:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:05:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:05:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:05:17 INFO - '--tppagecycles', 10:05:17 INFO - '7'], 10:05:17 INFO - 'tests': ['tsvgm']}}, 10:05:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:05:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:05:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:05:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:05:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:05:17 INFO - 'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:05:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:05:17 INFO - 'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['damp', 'tps']}, 10:05:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:05:17 INFO - 'tests': ['damp', 'tps']}, 10:05:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['dromaeo_dom']}, 10:05:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:05:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:05:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:05:17 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:05:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:05:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['a11yr', 10:05:17 INFO - 'ts_paint', 10:05:17 INFO - 'tpaint', 10:05:17 INFO - 'sessionrestore', 10:05:17 INFO - 'sessionrestore_no_auto_restore', 10:05:17 INFO - 'tabpaint']}, 10:05:17 INFO - 'other-e10s': {'tests': ['a11yr', 10:05:17 INFO - 'ts_paint', 10:05:17 INFO - 'tpaint', 10:05:17 INFO - 'sessionrestore', 10:05:17 INFO - 'sessionrestore_no_auto_restore', 10:05:17 INFO - 'tabpaint']}, 10:05:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:05:17 INFO - 'ts_paint', 10:05:17 INFO - 'tpaint', 10:05:17 INFO - 'sessionrestore', 10:05:17 INFO - 'sessionrestore_no_auto_restore', 10:05:17 INFO - 'tabpaint']}, 10:05:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:05:17 INFO - 'ts_paint', 10:05:17 INFO - 'tpaint', 10:05:17 INFO - 'sessionrestore', 10:05:17 INFO - 'sessionrestore_no_auto_restore', 10:05:17 INFO - 'tabpaint']}, 10:05:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['a11yr', 10:05:17 INFO - 'ts_paint', 10:05:17 INFO - 'tpaint', 10:05:17 INFO - 'sessionrestore', 10:05:17 INFO - 'sessionrestore_no_auto_restore', 10:05:17 INFO - 'tabpaint']}, 10:05:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['a11yr', 10:05:17 INFO - 'ts_paint', 10:05:17 INFO - 'tpaint', 10:05:17 INFO - 'sessionrestore', 10:05:17 INFO - 'sessionrestore_no_auto_restore', 10:05:17 INFO - 'tabpaint']}, 10:05:17 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:05:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:05:17 INFO - 'bloom_basic_ref']}, 10:05:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['tsvgx', 10:05:17 INFO - 'tsvgr_opacity', 10:05:17 INFO - 'tart', 10:05:17 INFO - 'tscrollx', 10:05:17 INFO - 'cart', 10:05:17 INFO - 'tsvg_static']}, 10:05:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:05:17 INFO - 'tsvgr_opacity', 10:05:17 INFO - 'tart', 10:05:17 INFO - 'tscrollx', 10:05:17 INFO - 'cart', 10:05:17 INFO - 'tsvg_static']}, 10:05:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:05:17 INFO - 'talos_options': ['--disable-e10s'], 10:05:17 INFO - 'tests': ['tp5o']}, 10:05:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:05:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:05:17 INFO - 'talos_options': ['--disable-e10s', 10:05:17 INFO - '--xperf_path', 10:05:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:17 INFO - 'tests': ['tp5n']}, 10:05:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:05:17 INFO - 'talos_options': ['--xperf_path', 10:05:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:17 INFO - 'tests': ['tp5n']}}, 10:05:17 INFO - 'talos.zip': {'path': '', 10:05:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:05:17 INFO - Running post-action listener: _resource_record_post_action 10:05:17 INFO - [mozharness: 2017-05-25 17:05:17.726290Z] Finished populate-webroot step (success) 10:05:17 INFO - [mozharness: 2017-05-25 17:05:17.726542Z] Running create-virtualenv step. 10:05:17 INFO - Running pre-action listener: _resource_record_pre_action 10:05:17 INFO - Running main action method: create_virtualenv 10:05:17 INFO - Creating virtualenv /builds/slave/test/build/venv 10:05:17 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 10:05:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:05:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:05:17 INFO - Using real prefix '/tools/python27' 10:05:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:05:18 INFO - Installing distribute.............................................................................................................................................................................................done. 10:05:21 INFO - Installing pip.................done. 10:05:21 INFO - Return code: 0 10:05:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:05:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:05:21 INFO - Reading from file tmpfile_stdout 10:05:21 INFO - Output received: 10:05:21 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) 10:05:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:05:21 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')]} 10:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:22 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')]} 10:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:22 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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05:22 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 10:05:22 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 10:05:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:22 INFO - 'HOME': '/Users/cltbld', 10:05:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:22 INFO - 'LOGNAME': 'cltbld', 10:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:22 INFO - 'MOZ_NO_REMOTE': '1', 10:05:22 INFO - 'NO_EM_RESTART': '1', 10:05:22 INFO - 'PAGER': '/bin/cat', 10:05:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:22 INFO - 'PWD': '/builds/slave/test', 10:05:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:22 INFO - 'SHELL': '/bin/bash', 10:05:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:22 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:22 INFO - 'USER': 'cltbld', 10:05:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:22 INFO - 'XPC_FLAGS': '0x0', 10:05:22 INFO - 'XPC_SERVICE_NAME': '0', 10:05:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:23 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 10:05:23 INFO - Cleaning up... 10:05:23 INFO - Return code: 0 10:05:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:05:23 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')]} 10:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:23 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')]} 10:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:23 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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05:23 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 10:05:23 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 10:05:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:23 INFO - 'HOME': '/Users/cltbld', 10:05:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:23 INFO - 'LOGNAME': 'cltbld', 10:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:23 INFO - 'MOZ_NO_REMOTE': '1', 10:05:23 INFO - 'NO_EM_RESTART': '1', 10:05:23 INFO - 'PAGER': '/bin/cat', 10:05:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:23 INFO - 'PWD': '/builds/slave/test', 10:05:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:23 INFO - 'SHELL': '/bin/bash', 10:05:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:23 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:23 INFO - 'USER': 'cltbld', 10:05:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:23 INFO - 'XPC_FLAGS': '0x0', 10:05:23 INFO - 'XPC_SERVICE_NAME': '0', 10:05:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:23 INFO - Downloading/unpacking psutil>=3.1.1 10:05:23 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 10:05:23 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 10:05:23 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 10:05:23 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 10:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:05:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:26 INFO - Installing collected packages: psutil 10:05:26 INFO - Running setup.py install for psutil 10:05:26 INFO - building 'psutil._psutil_osx' extension 10:05:26 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 10:05:26 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 10:05:26 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 10:05:26 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 10:05:27 INFO - building 'psutil._psutil_posix' extension 10:05:27 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 10:05:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:05:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:05:27 INFO - ^ 10:05:27 INFO - 1 warning generated. 10:05:27 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 10:05:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:27 INFO - Successfully installed psutil 10:05:27 INFO - Cleaning up... 10:05:27 INFO - Return code: 0 10:05:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:05:27 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')]} 10:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:27 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')]} 10:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:27 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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05:27 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 10:05:27 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 10:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:27 INFO - 'HOME': '/Users/cltbld', 10:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:27 INFO - 'LOGNAME': 'cltbld', 10:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:27 INFO - 'MOZ_NO_REMOTE': '1', 10:05:27 INFO - 'NO_EM_RESTART': '1', 10:05:27 INFO - 'PAGER': '/bin/cat', 10:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:27 INFO - 'PWD': '/builds/slave/test', 10:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:27 INFO - 'SHELL': '/bin/bash', 10:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:27 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:27 INFO - 'USER': 'cltbld', 10:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:27 INFO - 'XPC_FLAGS': '0x0', 10:05:27 INFO - 'XPC_SERVICE_NAME': '0', 10:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:05:27 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 10:05:27 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 10:05:27 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 10:05:27 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 10:05:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:05:29 INFO - Installing collected packages: mozsystemmonitor 10:05:29 INFO - Running setup.py install for mozsystemmonitor 10:05:29 INFO - Successfully installed mozsystemmonitor 10:05:29 INFO - Cleaning up... 10:05:29 INFO - Return code: 0 10:05:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05: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')]} 10:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05: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', '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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:05: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 jsonschema==2.5.1 10:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:29 INFO - 'HOME': '/Users/cltbld', 10:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:29 INFO - 'LOGNAME': 'cltbld', 10:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:29 INFO - 'MOZ_NO_REMOTE': '1', 10:05:29 INFO - 'NO_EM_RESTART': '1', 10:05:29 INFO - 'PAGER': '/bin/cat', 10:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:29 INFO - 'PWD': '/builds/slave/test', 10:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:29 INFO - 'SHELL': '/bin/bash', 10:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:29 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:29 INFO - 'USER': 'cltbld', 10:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:29 INFO - 'XPC_FLAGS': '0x0', 10:05:29 INFO - 'XPC_SERVICE_NAME': '0', 10:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:29 INFO - Downloading/unpacking jsonschema==2.5.1 10:05: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 10:05: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 10:05: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 10:05: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 10:05:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:05:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:05:30 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 10:05:30 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 10:05:30 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 10:05:30 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 10:05:31 INFO - Downloading functools32-3.2.3-2.tar.gz 10:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:05:31 INFO - warning: no files found matching '*.txt' 10:05:31 INFO - no previously-included directories found matching 'build' 10:05:31 INFO - no previously-included directories found matching 'dist' 10:05:31 INFO - no previously-included directories found matching '.git*' 10:05:31 INFO - Installing collected packages: jsonschema, functools32 10:05:31 INFO - Running setup.py install for functools32 10:05:31 INFO - warning: no files found matching '*.txt' 10:05:31 INFO - no previously-included directories found matching 'build' 10:05:31 INFO - no previously-included directories found matching 'dist' 10:05:31 INFO - no previously-included directories found matching '.git*' 10:05:31 INFO - Successfully installed jsonschema functools32 10:05:31 INFO - Cleaning up... 10:05:31 INFO - Return code: 0 10:05:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05: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')]} 10:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05: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', '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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:05: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 functools32==3.2.3-2 10:05:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:31 INFO - 'HOME': '/Users/cltbld', 10:05:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:31 INFO - 'LOGNAME': 'cltbld', 10:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:31 INFO - 'MOZ_NO_REMOTE': '1', 10:05:31 INFO - 'NO_EM_RESTART': '1', 10:05:31 INFO - 'PAGER': '/bin/cat', 10:05:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:31 INFO - 'PWD': '/builds/slave/test', 10:05:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:31 INFO - 'SHELL': '/bin/bash', 10:05:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:31 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:31 INFO - 'USER': 'cltbld', 10:05:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:31 INFO - 'XPC_FLAGS': '0x0', 10:05:31 INFO - 'XPC_SERVICE_NAME': '0', 10:05:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:05:31 INFO - Cleaning up... 10:05:31 INFO - Return code: 0 10:05:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05: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')]} 10:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05: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', '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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:05: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 blobuploader==1.2.4 10:05:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:31 INFO - 'HOME': '/Users/cltbld', 10:05:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:31 INFO - 'LOGNAME': 'cltbld', 10:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:31 INFO - 'MOZ_NO_REMOTE': '1', 10:05:31 INFO - 'NO_EM_RESTART': '1', 10:05:31 INFO - 'PAGER': '/bin/cat', 10:05:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:31 INFO - 'PWD': '/builds/slave/test', 10:05:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:31 INFO - 'SHELL': '/bin/bash', 10:05:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:31 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:31 INFO - 'USER': 'cltbld', 10:05:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:31 INFO - 'XPC_FLAGS': '0x0', 10:05:31 INFO - 'XPC_SERVICE_NAME': '0', 10:05:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:31 INFO - Downloading/unpacking blobuploader==1.2.4 10:05: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 10:05: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 10:05: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 10:05: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 10:05:33 INFO - Downloading blobuploader-1.2.4.tar.gz 10:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:05:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:05: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 10:05: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 10:05: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 10:05: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 10:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:05:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:05: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 10:05: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 10:05: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 10:05: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 10:05:34 INFO - Downloading docopt-0.6.1.tar.gz 10:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:05:34 INFO - Installing collected packages: blobuploader, requests, docopt 10:05:34 INFO - Running setup.py install for blobuploader 10:05:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:05:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:05:34 INFO - Running setup.py install for requests 10:05:34 INFO - Running setup.py install for docopt 10:05:35 INFO - Successfully installed blobuploader requests docopt 10:05:35 INFO - Cleaning up... 10:05:35 INFO - Return code: 0 10:05:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:35 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')]} 10:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:35 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')]} 10:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:35 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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05:35 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 10:05:35 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 10:05:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:35 INFO - 'HOME': '/Users/cltbld', 10:05:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:35 INFO - 'LOGNAME': 'cltbld', 10:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:35 INFO - 'MOZ_NO_REMOTE': '1', 10:05:35 INFO - 'NO_EM_RESTART': '1', 10:05:35 INFO - 'PAGER': '/bin/cat', 10:05:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:35 INFO - 'PWD': '/builds/slave/test', 10:05:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:35 INFO - 'SHELL': '/bin/bash', 10:05:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:35 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:35 INFO - 'USER': 'cltbld', 10:05:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:35 INFO - 'XPC_FLAGS': '0x0', 10:05:35 INFO - 'XPC_SERVICE_NAME': '0', 10:05:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:35 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-weptsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:35 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-LnTXDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:35 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UIISfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:35 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-b4pF1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:35 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-YdIdWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:35 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-NHF1K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-GN2iHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-WprUGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-XoTDYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UIjsCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UNTYYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-cgPXQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-HfJtel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-r005S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:36 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-cuJINr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:37 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-io_ol0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:37 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Ln1gcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:05:37 INFO - Running setup.py install for manifestparser 10:05:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:05:37 INFO - Running setup.py install for mozcrash 10:05:37 INFO - Running setup.py install for mozdebug 10:05:37 INFO - Running setup.py install for mozdevice 10:05:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:05:37 INFO - Running setup.py install for mozfile 10:05:37 INFO - Running setup.py install for mozhttpd 10:05:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:05:38 INFO - Running setup.py install for mozinfo 10:05:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:05:38 INFO - Running setup.py install for mozInstall 10:05:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:05:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:05:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:05:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:05:38 INFO - Running setup.py install for mozleak 10:05:38 INFO - Running setup.py install for mozlog 10:05:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:05:38 INFO - Running setup.py install for moznetwork 10:05:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:05:38 INFO - Running setup.py install for mozprocess 10:05:39 INFO - Running setup.py install for mozprofile 10:05:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:05:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:05:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:05:39 INFO - Running setup.py install for mozrunner 10:05:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:05:39 INFO - Running setup.py install for mozscreenshot 10:05:39 INFO - Running setup.py install for moztest 10:05:39 INFO - Running setup.py install for mozversion 10:05:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:05:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:05:39 INFO - Cleaning up... 10:05:39 INFO - Return code: 0 10:05:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:39 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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05:39 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 10:05:39 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 10:05:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:39 INFO - 'HOME': '/Users/cltbld', 10:05:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:39 INFO - 'LOGNAME': 'cltbld', 10:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:39 INFO - 'MOZ_NO_REMOTE': '1', 10:05:39 INFO - 'NO_EM_RESTART': '1', 10:05:39 INFO - 'PAGER': '/bin/cat', 10:05:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:39 INFO - 'PWD': '/builds/slave/test', 10:05:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:39 INFO - 'SHELL': '/bin/bash', 10:05:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:39 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:39 INFO - 'USER': 'cltbld', 10:05:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:39 INFO - 'XPC_FLAGS': '0x0', 10:05:39 INFO - 'XPC_SERVICE_NAME': '0', 10:05:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-lsr90e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-1esklD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qskUR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-PDsMZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-36BHSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UMi1o3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Yf9ECQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-6tB216-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:40 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-0ifkLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:40 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)) 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:41 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_YbQHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:41 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)) 10:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:41 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-oZtwo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:41 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)) 10:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:41 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-g0z5QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:41 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)) 10:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:41 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-omdVWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:41 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)) 10:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:41 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-czLqZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:41 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)) 10:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:41 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Wm_PFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:41 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)) 10:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:41 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-wuDCCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:41 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)) 10:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:41 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-nen1wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:41 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)) 10:05:41 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)) 10:05:41 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)) 10:05:41 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)) 10:05:41 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)) 10:05:41 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)) 10:05:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:43 INFO - Downloading blessings-1.6.tar.gz 10:05:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:05:43 INFO - Installing collected packages: blessings 10:05:43 INFO - Running setup.py install for blessings 10:05:43 INFO - Successfully installed blessings 10:05:43 INFO - Cleaning up... 10:05:43 INFO - Return code: 0 10:05:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:05:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:43 INFO - Reading from file tmpfile_stdout 10:05:43 INFO - Current package versions: 10:05:43 INFO - blessings == 1.6 10:05:43 INFO - blobuploader == 1.2.4 10:05:43 INFO - docopt == 0.6.1 10:05:43 INFO - functools32 == 3.2.3-2 10:05:43 INFO - jsonschema == 2.5.1 10:05:43 INFO - manifestparser == 1.1 10:05:43 INFO - mozInstall == 1.12 10:05:43 INFO - mozcrash == 1.0 10:05:43 INFO - mozdebug == 0.1 10:05:43 INFO - mozdevice == 0.51 10:05:43 INFO - mozfile == 1.2 10:05:43 INFO - mozhttpd == 0.7 10:05:43 INFO - mozinfo == 0.9 10:05:43 INFO - mozleak == 0.1 10:05:43 INFO - mozlog == 3.4 10:05:43 INFO - moznetwork == 0.27 10:05:43 INFO - mozprocess == 0.25 10:05:43 INFO - mozprofile == 0.28 10:05:43 INFO - mozrunner == 6.13 10:05:43 INFO - mozscreenshot == 0.1 10:05:43 INFO - mozsystemmonitor == 0.3 10:05:43 INFO - moztest == 0.8 10:05:43 INFO - mozversion == 1.4 10:05:43 INFO - psutil == 3.1.1 10:05:43 INFO - requests == 1.2.3 10:05:43 INFO - wsgiref == 0.1.2 10:05:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:43 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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05:43 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 10:05:43 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 10:05:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:43 INFO - 'HOME': '/Users/cltbld', 10:05:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:43 INFO - 'LOGNAME': 'cltbld', 10:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:43 INFO - 'MOZ_NO_REMOTE': '1', 10:05:43 INFO - 'NO_EM_RESTART': '1', 10:05:43 INFO - 'PAGER': '/bin/cat', 10:05:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:43 INFO - 'PWD': '/builds/slave/test', 10:05:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:43 INFO - 'SHELL': '/bin/bash', 10:05:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:43 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:43 INFO - 'USER': 'cltbld', 10:05:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:43 INFO - 'XPC_FLAGS': '0x0', 10:05:43 INFO - 'XPC_SERVICE_NAME': '0', 10:05:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:44 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)) 10:05:44 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)) 10:05:44 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)) 10:05:44 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)) 10:05:44 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)) 10:05:44 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)) 10:05:44 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)) 10:05:44 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)) 10:05:44 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)) 10:05:44 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:45 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:05:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:05:45 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)) 10:05:45 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)) 10:05:45 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)) 10:05:45 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)) 10:05:45 INFO - Installing collected packages: marionette-driver 10:05:45 INFO - Running setup.py install for marionette-driver 10:05:45 INFO - Successfully installed marionette-driver 10:05:45 INFO - Cleaning up... 10:05:45 INFO - Return code: 0 10:05:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05: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')]} 10:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:45 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 0x102035b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102039768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc253492780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, '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.HiSsddX3um/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.N9tbc7o9Mq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:05:45 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 10:05:45 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 10:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:45 INFO - 'HOME': '/Users/cltbld', 10:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:45 INFO - 'LOGNAME': 'cltbld', 10:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:45 INFO - 'MOZ_NO_REMOTE': '1', 10:05:45 INFO - 'NO_EM_RESTART': '1', 10:05:45 INFO - 'PAGER': '/bin/cat', 10:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:45 INFO - 'PWD': '/builds/slave/test', 10:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:45 INFO - 'SHELL': '/bin/bash', 10:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:05:45 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:45 INFO - 'USER': 'cltbld', 10:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:45 INFO - 'XPC_FLAGS': '0x0', 10:05:45 INFO - 'XPC_SERVICE_NAME': '0', 10:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:05:46 INFO - Cleaning up... 10:05:46 INFO - Return code: 0 10:05:46 INFO - Running post-action listener: _resource_record_post_action 10:05:46 INFO - Running post-action listener: _start_resource_monitoring 10:05:46 INFO - Starting resource monitoring. 10:05:46 INFO - [mozharness: 2017-05-25 17:05:46.147304Z] Finished create-virtualenv step (success) 10:05:46 INFO - [mozharness: 2017-05-25 17:05:46.148255Z] Running install step. 10:05:46 INFO - Running pre-action listener: _resource_record_pre_action 10:05:46 INFO - Running main action method: install 10:05:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:46 INFO - Reading from file tmpfile_stdout 10:05:46 INFO - Detecting whether we're running mozinstall >=1.0... 10:05:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:05:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:05:46 INFO - Reading from file tmpfile_stdout 10:05:46 INFO - Output received: 10:05:46 INFO - Usage: mozinstall [options] installer 10:05:46 INFO - Options: 10:05:46 INFO - -h, --help show this help message and exit 10:05:46 INFO - -d DEST, --destination=DEST 10:05:46 INFO - Directory to install application into. [default: 10:05:46 INFO - "/builds/slave/test"] 10:05:46 INFO - --app=APP Application being installed. [default: firefox] 10:05:46 INFO - mkdir: /builds/slave/test/build/application 10:05:46 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'] 10:05:46 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 10:06:08 INFO - Reading from file tmpfile_stdout 10:06:08 INFO - Output received: 10:06:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:06:08 INFO - Running post-action listener: _resource_record_post_action 10:06:08 INFO - [mozharness: 2017-05-25 17:06:08.043809Z] Finished install step (success) 10:06:08 INFO - [mozharness: 2017-05-25 17:06:08.043963Z] Running run-tests step. 10:06:08 INFO - Running pre-action listener: _resource_record_pre_action 10:06:08 INFO - Running main action method: run_tests 10:06:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:06:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:06:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:06:08 INFO - 2017-05-25 10:06:08.058 screenresolution[1629:7459] starting screenresolution argv=screenresolution get 10:06:08 INFO - 2017-05-25 10:06:08.061 screenresolution[1629:7459] Display 0: 1600x1200x32@60 10:06:08 INFO - 2017-05-25 10:06:08.067 screenresolution[1630:7461] starting screenresolution argv=screenresolution list 10:06:08 INFO - Available Modes on Display 0 10:06:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:06:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:06:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:06:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:06:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:06:08 INFO - Intel Iris: 10:06:08 INFO - Chipset Model: Intel Iris 10:06:08 INFO - Type: GPU 10:06:08 INFO - Bus: Built-In 10:06:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:06:08 INFO - Vendor: Intel (0x8086) 10:06:08 INFO - Device ID: 0x0a2e 10:06:08 INFO - Revision ID: 0x0009 10:06:08 INFO - Displays: 10:06:08 INFO - Display: 10:06:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:06:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:06:08 INFO - Display Serial Number: 1600x1200 60 10:06:08 INFO - Main Display: Yes 10:06:08 INFO - Mirror: Off 10:06:08 INFO - Online: Yes 10:06:08 INFO - Rotation: Supported 10:06:08 INFO - Return code: 0 10:06:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:06:08 INFO - Python 2.7.3 10:06:08 INFO - Return code: 0 10:06:08 INFO - grabbing minidump binary from tooltool 10:06:08 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')]} 10:06:08 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 0x101d45d30>, '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 0x101cf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d3abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:06:08 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 10:06:08 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 10:06:08 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 10:06:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:06:08 INFO - Return code: 0 10:06:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:06:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:06:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:06:08 INFO - ENV: RUST_BACKTRACE is now 1 10:06:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:06:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:06:08 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/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 10:06:08 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/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 10:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HiSsddX3um/Render', 10:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:08 INFO - 'HOME': '/Users/cltbld', 10:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:08 INFO - 'LOGNAME': 'cltbld', 10:06:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:06:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:08 INFO - 'MOZ_NO_REMOTE': '1', 10:06:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:06:08 INFO - 'NO_EM_RESTART': '1', 10:06:08 INFO - 'PAGER': '/bin/cat', 10:06:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:08 INFO - 'PWD': '/builds/slave/test', 10:06:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:08 INFO - 'RUST_BACKTRACE': '1', 10:06:08 INFO - 'SHELL': '/bin/bash', 10:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N9tbc7o9Mq/Listeners', 10:06:08 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:08 INFO - 'USER': 'cltbld', 10:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:08 INFO - 'XPC_FLAGS': '0x0', 10:06:08 INFO - 'XPC_SERVICE_NAME': '0', 10:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:08 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/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 10:06:08 INFO - mozversion application_buildid: 20170525082324 10:06:08 INFO - mozversion application_changeset: f7adbf457ee20eeffde72694e0d17d73616e3cfd 10:06:08 INFO - mozversion application_display_name: Nightly 10:06:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:06:08 INFO - mozversion application_name: Firefox 10:06:08 INFO - mozversion application_remotingname: firefox 10:06:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:06:08 INFO - mozversion application_vendor: Mozilla 10:06:08 INFO - mozversion application_version: 55.0a1 10:06:08 INFO - mozversion platform_buildid: 20170525082324 10:06:08 INFO - mozversion platform_changeset: f7adbf457ee20eeffde72694e0d17d73616e3cfd 10:06:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:06:08 INFO - mozversion platform_version: 55.0a1 10:06:08 INFO - using testdate: 1495731968 10:06:08 INFO - actual date: 1495731968 10:06:08 INFO - starting webserver on 'localhost:49230' 10:06:08 INFO - SUITE-START | Running 2 tests 10:06:08 INFO - TEST-START | dromaeo_css 10:06:08 INFO - operating with platform_type : mac_ 10:06:08 INFO - Initialising browser for dromaeo_css test... 10:06:08 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpPpCgZH/profile 10:06:08 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 10:06:42 INFO - TEST-INFO | 1640: killed by SIGTERM 10:06:42 INFO - Browser initialized. 10:06:42 INFO - Running cycle 1/1 for dromaeo_css test... 10:06:42 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpPpCgZH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:44 INFO - PID 1650 | 2017-05-25 10:06:44.461 plugin-container[1651:8029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 10:06:44 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:44 INFO - PID 1650 | 2017-05-25 10:06:44.461 plugin-container[1651:8029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 10:06:44 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:44 INFO - PID 1650 | 2017-05-25 10:06:44.461 plugin-container[1651:8029] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:44 INFO - PID 1650 | 2017-05-25 10:06:44.950 plugin-container[1652:8075] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 10:06:44 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:44 INFO - PID 1650 | 2017-05-25 10:06:44.950 plugin-container[1652:8075] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 10:06:44 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:44 INFO - PID 1650 | 2017-05-25 10:06:44.950 plugin-container[1652:8075] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:54 INFO - PID 1650 | RSS: Main: 246145024 10:06:54 INFO - PID 1650 | 10:09:56 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 10:09:58 INFO - PID 1650 | RSS: Main: 263725056 10:09:58 INFO - PID 1650 | 10:13:00 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 10:13:01 INFO - PID 1650 | RSS: Main: 279379968 10:13:01 INFO - PID 1650 | 10:16:02 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 10:16:03 INFO - PID 1650 | RSS: Main: 278327296 10:16:03 INFO - PID 1650 | 10:19:05 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 10:19:05 INFO - PID 1650 | RSS: Main: 283025408 10:19:05 INFO - PID 1650 | 10:22:08 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 10:22:09 INFO - PID 1650 | RSS: Main: 283660288 10:22:09 INFO - PID 1650 | 10:25:10 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 10:25:10 INFO - PID 1650 | RSS: Main: 283099136 10:25:10 INFO - PID 1650 | 10:25:10 INFO - PID 1650 | __start_tp_report 10:25:10 INFO - PID 1650 | _x_x_mozilla_page_load 10:25:10 INFO - PID 1650 | _x_x_mozilla_page_load_details 10:25:10 INFO - PID 1650 | |i|pagename|runs| 10:25:10 INFO - PID 1650 | |0;dojo.html;2910.1996950838516;3031.7119873612005;3292.6378098409173;3601;3617.602063772983;6071.210742603466;6089.360617135203;6137.07943808429;6155;6159.538034647402;6107.5226075722985;6118.040554665182;6129.9395583570995;6143.481172719186;6146.139540464339;5405.18922161676;5451.754671039793;5456.863578410552;5461.371942226635;5555.250041244421;5341.385740639818;5522.6134170608075;5557.3331200256025;5584.078627026556;5604.523615492688;5437.891242175174;5482.91775623366;5538.861528461781;5544.861378465551;5585.58108141889;5378.166384210414;5399.244105825188;5457.372402173761;5468.9726551367385;5482.698451585161;5429.6199266051;5480.780768769245;5481.177823326493;5488.792465657549;5491.835244942658;5213.817516386946;6054.455099041107;6198.938010619881;6200.720967556471;6214.161088253073;6429.871402571969;6456.838579035561;6576.769813056568;6614.570052946591;6661.4004739573675;5878.588498805086;6154.8769024619705;6738.52830301879;6756.797296081126;6779.49153813466;6284.088807122942;6293.685315734241;6297.4332310091395;6301.275353334334;6307.779227727054;5464.9180262296095;6734.259231484532;6808.2510923798345;6810.693518791666;6908;5422.972885135549;6346.555741098079;6375.71309291083;6473;6552.148220731275;6589.80230593083;6613.14029176204;6694.794936911306;6703.564268322543;6711.295313992058;6565.934340656532;6602.3067577904585;6613.86772264552;6624.6025238485845;6645.036469711865;6402.071699603532;6454.838629034218;6538.1827271591055;6563.146790917246;6618.8345291368105;5360.035193665178;5361.919571206435;5368.785248590091;5373.528177038606;5393.946060539344;4786.688865223749;4795.280707893774;4800.591949684246;4803.471618122004;4838.975805121022;6114.144019837227;6243.625382477066;6256.468200202943;6261.530385221126;6270.968645156745;6731.32686731323;6744.359285867933;6778.966105169443;6780.457563395015;6795.660216989131;5428.619996600279;5777.393373695785;6226.377362263828;6270.090836828635;6299.6850157491945;5639;5830.7959221427045;5888.175655408247;5945.494632956247;5947.613405128653;6868.896966545506;6902.8619427612175;6929.341712537301;6946.79159625212;6948.444124469929;6766.627835469097;6770.221424536241;6782.660866956731;6791.796246112624;6808.080909077409;6686.765963191265;6734;6775.830604234933;6911.3779759821855;6925.896111558432;5451.563874890078;5759.51044161251;5994.850128746816;6116.39664026098;6521.054447105048;6729.5625784323865;6799.490038247251;6801.251862295243;6804.115464989718;6835.4189893859575;6317.96841015792;6417.48660107181;6497.285298617243;6562.409383155539;6571.01434784767;6568.474522038321;6572.441342485942;6579.276279609144;6604.900926486011;6610.504212184009;6376.776812811529;6448.45188158993;6450;6452.354764523698;6458.54790164684;5568.16477528374;6306.306306306211;6441.420272175526;6573;6619.470442364503;6372.617642941438;6407.743690252524;6408.551401402044;6409.903851442139;6427.58220715652 10:25:10 INFO - PID 1650 | |1;ext.html;764.3770327183345;804.7062822069944;826.6404114210319;1162.6105254739662;1261.8107283907407;38916;40012.39981400281;40062;40171;40240;40700;40740;41124.38313425301;41166.794166029205;41189.17621647573;7928.246816552418;8096.473729207582;8487.108853570378;8682.652693892236;8687.826243475127;8522.446041007346;8579.699710510133;8605.526696031715;8633.827323453526;8641.308695304391;8614.483131012128;8634.352423568225;8658.956705216497;8667.653293868238;8679.95660021699;8561.785955351104;8611.655533778641;8650.524221167832;8653.610587523577;8668.176523230282;8368.958155209246;8383.035950865607;8391.370647201424;8454.28138608219;8468.153184681543;37629.43555846665;37710.245795083945;37759.62240377589;37792.24415511702;37847.243055138744;3598.8200589970656;3628.983884512341;3630.8366123524506;3633.745637805355;3642.763220390693;30729.385412291853;30907.38185236305;30956.690433095606;30979;30981;3051.6032915721034;3083.491223948046;3091.8608662610236;3099.4575949208797;3115.112193024977;208508;210909;264790.9999999981;282156.17843821505;283204;47606.28590571144;47786.699197072434;47833.76083119562;47925.520744792804;47949.520504795204;49143.26285105726;49196.508034919905;49203.50796492061;49324.75337623361;49880.750596246784;14393.280335983369;14515.491957781638;14537.41850325975;14557.12657240569;14577.271136443347;11531.942340288246;11580.247283926687;11639.941800290944;11645.941770291094;11704.005159561362;11901.583444579572;12701.555445559547;14149.717005660035;14163.78754318686;14204.85795142056;5472.699001554953;5991.760329586855;6161.353057928943;6194.38056194377;6228.190335256388;975.590252094108;1002.7092143278513;1003.1373019203479;1018.1447584029451;1037.9481025948824;24570.140045098695;24913.12804051886;24922.5015499689;24951.251462456155;25008.37479063038;919.6919032124314;944.3153713557753;1059.9470026498643;1089.7384627689298;1122.8540289762443;11695.181337306307;12432.627021189379;12497.625071247878;12651.620451386472;12836.80744788829;322869;323169;323303;325793.74206257635;326492;55161.72419137959;55516;55570.610734731956;55646.443535564125;55800.441995579524;1281.6411404806483;1481.0669278354567;1520.6198450387403;1532.862042416188;1540.4146424358896;22754.544909102056;23992.64011039871;24138.517229654957;24206.75793242045;24291.75708242895;37796;37853.053673657276;37864.81067594644;37907;38011.809940950116;269472;271468.2132946613;271888;272189;272304;34212.48681269733;34339.82830085834;34677.65322346845;35466.93599191925;35515.46726799152;43505.34741978936;44782.10435791331;44958.953694167685;45030.324545131196;45090.323645146;29358.41283174282;30218.09345719632;30395;31100.911468099413;31397.52903706492;30306.84846575753;30819.53770693486;30825.075247742603;31178.220544485663;31247;36278.8186059068;36873.44689829709;37477.25045499129;37577.81211093927;37588.060298492754;30821;31264.374712506076;31505.8424707875;31523.527147092354;31564;22987;23546.52906941886;23655.408614784767;24083.397915052265;24563 10:25:10 INFO - PID 1650 | |2;jquery.html;407.6310938600564;426.60539001423695;435.6906596316616;449.08386890742884;452.15898428922196;18363.908180459097;19318.32385866495;21869.453263668416;21878.781212187874;22038.11847526099;21921.575097618643;22419.663705044437;22485;22773;22941.19705810297;5043.470435604264;5451.918221226685;6257.311695713468;6273.435390814826;6291.465225455841;5957.1957785698805;6070.605410648316;6160.229971253593;6266.248050233979;6274;6110.450059494645;6114.510839132881;6124.2957059938235;6131.63210207389;6134.2332208473945;5871.178035075093;6061.575689701723;6090.60411073281;6091.2995005574485;6121.418465245794;5420.512153906167;5421.376541697716;5425.294711687495;5447.400785913546;5459.863503412406;22422.430429869917;26963;27360.863195684095;27473.587896181572;27478.862605687045;2141.4432247615578;2226.9888650556645;2238.4963383238805;2244.5623103494827;2245.584566855137;17773.7333939991;17791.644167116716;18030;18115.72826407605;18440.815591844043;2095.497080700636;2117.735283089614;2142.817860481861;2150.236665983569;2157.169489746459;137371;137844;140383;141095.88356174767;141391;12326.50693972249;12419.316937568521;12529.31088790107;12731.87268127307;12786.808197877039;16031.19844007795;16081.276342564614;16085.034897906164;16087.597810054609;16135;8405.117462666454;8639.222469977733;8654.48073115615;8751.081136480705;8768.868466973001;8109.148539403395;8128.715494957766;8130.796730081677;8260.091389947102;8275.131111233355;7723.575203363869;7855.882161767464;7856.371490280764;7863.191465962861;7876.330511906552;2164.5129845784886;2179.5749828783237;2181.0512427094263;2185.7923497267734;2211.6682497625484;906.5240748606902;939.0890835889177;1001.4777538141983;1022.1771473963531;1049.7847941172042;17658.293668253384;18992.76547024439;19746;21511.56976860454;21540;960.0879164793474;961.4183419031525;1002.5438425516519;1021.22897212604;1029.3566520924421;10902;11060.115190784718;11088.889111108947;11124.888751112385;11136.944315278372;529583;548987;550109;557127;562990;114082;126450;126759;127276.36361818132;129315;1176.211938001541;1212.308983879198;1239.603326935372;1241.1994263699862;1247.1581682364367;18513;20624.381268561967;20634.896825515778;21688.783112168676;21994.89002554977;59864;59874;59989.700051499465;60148.39851601428;60674;77413;78060.60969695116;78314;78698.81951770843;78882.605586974;8734.170253825876;8803.911960880308;8922.330825188266;9175.816483670156;9188.678396256098;12007.979321757748;13894.235817029756;14235.857641423867;14568;14618.488352907598;10881;11456.140789440991;12038.939805300917;12194.75610487803;12327.50689972391;10889.564417423215;10982.890171098186;11125.610603628835;11520.308781473139;11632.651020469399;11234.606788762354;12008.819867702166;12285.754284914074;12390.132690711564;12625.558105466265;9011.143941325563;9050.502222377832;9109.590068446803;9136.725898223063;9140.131687489678;10580.735481613021;11939;11952.76094478088;12199.87800122023;12298.385080746106 10:25:10 INFO - PID 1650 | |3;mootools.html;1400.1738974005336;1454.556360310105;1455.4032846532923;1463.3414963266525;1481.8073650425447;11445.942770286147;11872.881271187285;12049.518019279229;12105.213161144531;12196.634100976962;10332.12176964957;11593.536258549648;12081.818772718416;12089.516419343237;12113.697157571065;2486.1422809130822;2541.275736415122;2541.313845261778;2548.7323830997757;2551.3876669599254;2457.8771061446946;2504.799616030713;2504.949901001979;2508.523380557688;2514.1200579797014;2454.067454367338;2483.5902076157413;2488.912888048919;2491.9127830525936;2492.4890397468475;2432.3189506937997;2453.58289090855;2462.101333013455;2463.7289898111194;2465.8027357811334;2416.903323867043;2446.987765061181;2452.350127216289;2453.141400509825;2521.067205134094;11644.883551164465;11688.47401866918;11775.764484710344;11784.528618855236;11795.41022948849;6334.809955701337;6516.77191298307;6599.9670001650165;6603.900941485882;6610.107635469199;3961.1087505311075;4139.254934111858;4196.958030419687;4241.660667146621;4269.6370808481315;1209.1596340543326;1215.1858254969193;1221.5968730318973;1227.6808029912197;1232.433080782841;15200.16399098049;15672.138032408327;16445.177741112897;16586.502404927873;16592.66814663712;4729.408823897013;4818.204996175592;5410.702411367412;5558.44415558441;5614.157876318503;10020.298579099539;11832.349220792767;11838.644840654793;11839.704007399712;11861.762764744657;5409.729513524387;6374.649394283266;6465.612063276219;6497.090407342976;6503.6097834130105;6067.150598916067;6120.234970628671;6123.479504242189;6170.629762214192;6185.41238582334;3602.9819850901104;3628.618995005539;4115.259253334429;4237.745735255895;4290.120525292307;1163.8021536338842;1164.8835116488453;1171.824226366035;1174.770919670673;1184.0586733546852;671.4058058618158;756.7048850948134;782.7690831204694;787.7203592724615;791.3194652598761;6421.8073457796345;6478.967605161944;6487.837804054842;6619.966900165468;6713.462922966249;802.1336956087412;896.8699538566872;909.4134283387178;909.6997990663066;918.8300164469595;3725.9068523287133;3985.8206380712936;4047.7166598337835;4056.350983842571;4161.2509748245;14706.33821478036;14796.630084247765;14927.25363731809;15046.247687615794;15175.924120379548;14576.416943322149;14755.40978360875;14943.701125977419;14953;15169.165695886612;8443.915560844436;8552.144785521397;8606.182412670787;8637.956810215908;8650.6972255972;5819.563532734977;6741.561798483083;6742.797716068526;6770.6614669265355;6828.5902845829405;13138.474461021453;13262;13364.198148111145;13608.86391136076;13730.862691373359;13653.249071301174;13877.444902203799;14026.649333766738;14148.43406263738;14216;9080.727578172666;9149.130832570896;9190.54047297619;9219.585118669542;9277.48973806447;10619.840702389625;10656.25406221557;10716.89283107159;10727.731806704895;10736.30214036085;8937.329700272376;8982.685606003759;9062;9095.954520227357;9268.980412154522;8854.601542930455;8880.600372983103;8939.463632182089;8954.238889694449;8975.820483590422;7800.297973182441;7920.326772224425;7923.286904178652;7981.082175549886;7981.880271796043;5747.683877386784;5791.768329266951;5895.882082358243;5949.52403807703;5968;5488.725563721878;5701.914471283017;5907.556933229939;6028.487578555872;6195.039768835838 10:25:10 INFO - PID 1650 | |4;prototype.html;346.60140838036256;386.76020867062425;390.451415760856;393.55724809589213;396.7520299812616;5627;6159.106929495226;6181.814545563635;6204.751809927598;6218.968905155479;5517.2551705519745;5534.75093620787;5995.062419414488;6023.819285421433;6058.545609079315;3809.3524100902773;3835;4149.7095203335775;4306.375575541545;4604.585587297142;4170.311898536738;4594.448666160066;4598.8620341389815;4607.516210797869;4614.861554153381;3995.9231391828275;4444.533324000982;4500.437445319335;4504.558541266796;4588.7476188809605;4156.584341565849;4577.8397756078375;4577.9542204577865;4584.426946631671;4599.103174880899;3372.426687463137;3697.9830546599633;3841.8666493384517;4017.176478821864;4038.818253178614;4869.269609558594;5744.540436765049;5766.0197766379815;5843.918875008095;5962.135490353874;4686.507916668735;5317.787288508455;5339.839804805862;5340.973295133499;5350.973245133789;4993.675411098267;5945.554083443759;6033.909491357633;6598.581305019396;6638.336166383371;4102.979485102585;4144.254034273829;4298.398224248608;4335.241332766753;4351.999040220767;5141.74291285438;6398.552101352907;6867.896981545281;6880.65596720162;6969.6515174241085;3550.467429885486;4385.780710964503;4387.736735795798;4408.206522825923;4413.44831896013;4447.021075621317;4452.220861349316;4478.865634030984;4486.68593198473;4495.820167193341;5116.667416617983;5254.579633629301;5297.3643162819935;5300.575953923754;5309.362876454773;6648.368405001517;6663.167104111986;6668.366505182;6700.788739715882;6710.865782684319;1505.2172870107702;1517.7116347893864;1525.8321584625683;1527.2974431761513;1527.5493729349869;981.8723565936383;992.712113487095;1000.4697510319543;1002.4185972136144;1007.6674697349857;5144.536991670768;5240;6189.35011823752;6234.846553387638;6245.75016999324;6694;6748.865022699519;6857.348551887562;6862.073620061327;6874.346937041073;5362.731863406814;6180.011198208355;6188.638499530096;6212.782552610637;6229.190205273376;2999.6850330715397;3306.7354611631013;3498.2828520153307;3516.4725291206523;3589.6410358963894;6812.6934287957165;6960.702968020541;7007.404370628452;7052.33002864727;7100.751473698396;5921.467067963904;6951.374376306157;7083.7520686775715;7089.539179953286;7112.502124851314;6388.4569811566535;6723.260441351447;7032.268701008662;7094.751683691046;7110.537815042006;5456.590755693418;5472.425395333433;6318.557700960889;6891.276415976451;6891.965540172468;5439.728013599225;5791.884162316645;6232.407921247474;6546.24718157418;6625.4699624029;6002.579819412599;6058.818235452944;6671.532992690659;6741.426978706865;7076.22161562238;4411.602955733999;4985.975070124626;5053.772580233972;5056.317397151484;5064.645474816727;5727.799527016535;6436.613803171825;6777.356151165632;6910.412614927787;6925.6190909500465;5417.268668729828;6493.623315875812;6560.475161986934;6591.747567962072;6685.665716714242;5479.561635069105;5859.648421094748;6430.260520040255;6577.934220657732;6675.7997260082275;6489.513286503626;6500.804975850732;6527.640979746159;6574.4740420765265;6587.2543775900385;6322.49420046386;6676.465882729273;6686.598804071771;6691.427514534178;6764.830879228059;5924.318703348998;6180.918339290696;6451.1290975717675;6468.417842394207;6472.15861937945 10:25:10 INFO - PID 1650 | |5;yui.html;812.512492504497;820.1757233979849;829.2743849132006;847.1443841719861;848.3806821020655;17076.573085672866;17578.47264582063;17617.207225674843;19399.515012124702;20267.189312427523;18327.17527711253;18632.72050919237;18658.90670546649;18760.530986725338;18801.247950082015;928.0765638189994;952.8904176019777;952.9523523823816;954.2747511890979;976.1800087926141;959.7600599850037;973.2360097323602;974.9658761943333;976.677696360203;977.1986970684042;1010.1111022300402;1023.7850051489196;1026.58423338548;1029.474967766437;1031.0411317474745;1020.5815615597644;1024.5133561558275;1028.9691309260734;1030.1707125763758;1033.3902997231633;747.4543583184305;753.6269546574481;754.7773406844939;755.6221889055472;755.8526087412956;15988.680226395407;16198.190090495546;17253.482395528154;17870.91064544669;17969.640607187914;17566.82433175665;17838.37565685195;17852.91073544624;17923.641527169515;18081.638367232714;12899.548515801902;14188.006839521238;14645.19451430171;14675.77986330206;14828;3293.522439246296;3491.1970246843116;3545.2732189901008;3563.55455568054;3564.7861128332124;139358.90961635482;143500.28249858788;156496;157980;158578;13428.19430834153;13510.527131550349;13582.524611638546;13622.727545449035;13689.794653080211;25815.612765808528;26299.07953221628;26489.867550662122;26888;27164.1850744478;7561.735339263099;7603.885941710879;7622.656980435893;7650.349720273728;7650.38796896247;3258.185453636591;3839.2705385976574;3842.634949679776;3869.361555343337;3894.2211557688574;3738.27103714773;4301.612854843079;4348.326009468474;4381.926428025155;4409.426774519292;702.8859258075905;837.656560810065;843.7595285343648;844.075737067922;844.2697067036969;626.8307556959595;639.1371648274885;650.632392698124;658.6838317607479;661.9871596457427;11244.212905096729;11833;11864;12192;12486.438110285058;619.767587154817;625.9436650701459;633.832034510855;645.1870642989867;665.3280187011056;5336.866578335573;5430.728463576885;5463.3717122530625;5466.6993315367245;5508.779648814083;144028;146111.5388846098;147232.2638386801;147653;150553;122222;123235;123995;124681.75318246883;125085;106466.46766766117;107054.46472767742;107163.46418267858;107476;108333.99999999843;10454.947725261325;10752.946235268773;10837.945810270898;10897.618583349862;10981.505832237408;42584.78707606442;43507.78246108749;43556.56443435652;43794.34308485311;46834.76582617065;60649.69675151596;75354.24645753473;82535.17464825275;84307.5784621073;86241;16883.83116168872;16940.57648558796;17123.315067397165;17156.227969741143;17162;18773.90613046926;18865.71701424452;18952.0523973798;19027.524311892314;19046.333378332245;16880.746788798424;17049.82950170532;17602.11989400499;17687.911560442117;17725.822741772416;16869.493915182564;16983.150842458075;17382;17547.561310966816;17616.55958601045;14906.403743850124;15069.760001599709;16304.184790760652;16385.91807040957;16393.42623008189;10306.639267625596;11941.16411851162;12180.147389682637;12305.630831075083;12604.180728252633;12946.611601651966;13513.932430337785;13684;13684.726305473636;13701.862981370059 10:25:10 INFO - PID 1650 | __end_tp_report 10:25:10 INFO - PID 1650 | __start_cc_report 10:25:10 INFO - PID 1650 | _x_x_mozilla_cycle_collect,408 10:25:10 INFO - PID 1650 | __end_cc_report 10:25:10 INFO - PID 1650 | __startTimestamp1495733110983__endTimestamp 10:25:10 INFO - PID 1650 | 10:25:10 INFO - PID 1650 | ------- Summary: start ------- 10:25:10 INFO - PID 1650 | Number of tests: 6 10:25:10 INFO - PID 1650 | 10:25:11 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6100.31 Median:6301.28 stddev:739.87 (11.7%) stddev-sans-first:698.53 10:25:11 INFO - PID 1650 | Values: 2910.2 3031.7 3292.6 3601.0 3617.6 6071.2 6089.4 6137.1 6155.0 6159.5 6107.5 6118.0 6129.9 6143.5 6146.1 5405.2 5451.8 5456.9 5461.4 5555.3 5341.4 5522.6 5557.3 5584.1 5604.5 5437.9 5482.9 5538.9 5544.9 5585.6 5378.2 5399.2 5457.4 5469.0 5482.7 5429.6 5480.8 5481.2 5488.8 5491.8 5213.8 6054.5 6198.9 6200.7 6214.2 6429.9 6456.8 6576.8 6614.6 6661.4 5878.6 6154.9 6738.5 6756.8 6779.5 6284.1 6293.7 6297.4 6301.3 6307.8 5464.9 6734.3 6808.3 6810.7 6908.0 5423.0 6346.6 6375.7 6473.0 6552.1 6589.8 6613.1 6694.8 6703.6 6711.3 6565.9 6602.3 6613.9 6624.6 6645.0 6402.1 6454.8 6538.2 6563.1 6618.8 5360.0 5361.9 5368.8 5373.5 5393.9 4786.7 4795.3 4800.6 4803.5 4839.0 6114.1 6243.6 6256.5 6261.5 6271.0 6731.3 6744.4 6779.0 6780.5 6795.7 5428.6 5777.4 6226.4 6270.1 6299.7 5639.0 5830.8 5888.2 5945.5 5947.6 6868.9 6902.9 6929.3 6946.8 6948.4 6766.6 6770.2 6782.7 6791.8 6808.1 6686.8 6734.0 6775.8 6911.4 6925.9 5451.6 5759.5 5994.9 6116.4 6521.1 6729.6 6799.5 6801.3 6804.1 6835.4 6318.0 6417.5 6497.3 6562.4 6571.0 6568.5 6572.4 6579.3 6604.9 6610.5 6376.8 6448.5 6450.0 6452.4 6458.5 5568.2 6306.3 6441.4 6573.0 6619.5 6372.6 6407.7 6408.6 6409.9 6427.6 10:25:11 INFO - PID 1650 | 10:25:11 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:43876.78 Median:34508.74 stddev:74438.13 (215.7%) stddev-sans-first:74576.57 10:25:11 INFO - PID 1650 | Values: 764.4 804.7 826.6 1162.6 1261.8 38916.0 40012.4 40062.0 40171.0 40240.0 40700.0 40740.0 41124.4 41166.8 41189.2 7928.2 8096.5 8487.1 8682.7 8687.8 8522.4 8579.7 8605.5 8633.8 8641.3 8614.5 8634.4 8659.0 8667.7 8680.0 8561.8 8611.7 8650.5 8653.6 8668.2 8369.0 8383.0 8391.4 8454.3 8468.2 37629.4 37710.2 37759.6 37792.2 37847.2 3598.8 3629.0 3630.8 3633.7 3642.8 30729.4 30907.4 30956.7 30979.0 30981.0 3051.6 3083.5 3091.9 3099.5 3115.1 208508.0 210909.0 264791.0 282156.2 283204.0 47606.3 47786.7 47833.8 47925.5 47949.5 49143.3 49196.5 49203.5 49324.8 49880.8 14393.3 14515.5 14537.4 14557.1 14577.3 11531.9 11580.2 11639.9 11645.9 11704.0 11901.6 12701.6 14149.7 14163.8 14204.9 5472.7 5991.8 6161.4 6194.4 6228.2 975.6 1002.7 1003.1 1018.1 1037.9 24570.1 24913.1 24922.5 24951.3 25008.4 919.7 944.3 1059.9 1089.7 1122.9 11695.2 12432.6 12497.6 12651.6 12836.8 322869.0 323169.0 323303.0 325793.7 326492.0 55161.7 55516.0 55570.6 55646.4 55800.4 1281.6 1481.1 1520.6 1532.9 1540.4 22754.5 23992.6 24138.5 24206.8 24291.8 37796.0 37853.1 37864.8 37907.0 38011.8 269472.0 271468.2 271888.0 272189.0 272304.0 34212.5 34339.8 34677.7 35466.9 35515.5 43505.3 44782.1 44959.0 45030.3 45090.3 29358.4 30218.1 30395.0 31100.9 31397.5 30306.8 30819.5 30825.1 31178.2 31247.0 36278.8 36873.4 37477.3 37577.8 37588.1 30821.0 31264.4 31505.8 31523.5 31564.0 22987.0 23546.5 23655.4 24083.4 24563.0 10:25:11 INFO - PID 1650 | 10:25:11 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:35102.06 Median:22016.50 stddev:92674.59 (420.9%) stddev-sans-first:92897.95 10:25:11 INFO - PID 1650 | Values: 407.6 426.6 435.7 449.1 452.2 18363.9 19318.3 21869.5 21878.8 22038.1 21921.6 22419.7 22485.0 22773.0 22941.2 5043.5 5451.9 6257.3 6273.4 6291.5 5957.2 6070.6 6160.2 6266.2 6274.0 6110.5 6114.5 6124.3 6131.6 6134.2 5871.2 6061.6 6090.6 6091.3 6121.4 5420.5 5421.4 5425.3 5447.4 5459.9 22422.4 26963.0 27360.9 27473.6 27478.9 2141.4 2227.0 2238.5 2244.6 2245.6 17773.7 17791.6 18030.0 18115.7 18440.8 2095.5 2117.7 2142.8 2150.2 2157.2 137371.0 137844.0 140383.0 141095.9 141391.0 12326.5 12419.3 12529.3 12731.9 12786.8 16031.2 16081.3 16085.0 16087.6 16135.0 8405.1 8639.2 8654.5 8751.1 8768.9 8109.1 8128.7 8130.8 8260.1 8275.1 7723.6 7855.9 7856.4 7863.2 7876.3 2164.5 2179.6 2181.1 2185.8 2211.7 906.5 939.1 1001.5 1022.2 1049.8 17658.3 18992.8 19746.0 21511.6 21540.0 960.1 961.4 1002.5 1021.2 1029.4 10902.0 11060.1 11088.9 11124.9 11136.9 529583.0 548987.0 550109.0 557127.0 562990.0 114082.0 126450.0 126759.0 127276.4 129315.0 1176.2 1212.3 1239.6 1241.2 1247.2 18513.0 20624.4 20634.9 21688.8 21994.9 59864.0 59874.0 59989.7 60148.4 60674.0 77413.0 78060.6 78314.0 78698.8 78882.6 8734.2 8803.9 8922.3 9175.8 9188.7 12008.0 13894.2 14235.9 14568.0 14618.5 10881.0 11456.1 12038.9 12194.8 12327.5 10889.6 10982.9 11125.6 11520.3 11632.7 11234.6 12008.8 12285.8 12390.1 12625.6 9011.1 9050.5 9109.6 9136.7 9140.1 10580.7 11939.0 11952.8 12199.9 12298.4 10:25:11 INFO - PID 1650 | 10:25:11 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7042.33 Median:3615.80 stddev:4510.51 (124.7%) stddev-sans-first:4503.24 10:25:11 INFO - PID 1650 | Values: 1400.2 1454.6 1455.4 1463.3 1481.8 11445.9 11872.9 12049.5 12105.2 12196.6 10332.1 11593.5 12081.8 12089.5 12113.7 2486.1 2541.3 2541.3 2548.7 2551.4 2457.9 2504.8 2504.9 2508.5 2514.1 2454.1 2483.6 2488.9 2491.9 2492.5 2432.3 2453.6 2462.1 2463.7 2465.8 2416.9 2447.0 2452.4 2453.1 2521.1 11644.9 11688.5 11775.8 11784.5 11795.4 6334.8 6516.8 6600.0 6603.9 6610.1 3961.1 4139.3 4197.0 4241.7 4269.6 1209.2 1215.2 1221.6 1227.7 1232.4 15200.2 15672.1 16445.2 16586.5 16592.7 4729.4 4818.2 5410.7 5558.4 5614.2 10020.3 11832.3 11838.6 11839.7 11861.8 5409.7 6374.6 6465.6 6497.1 6503.6 6067.2 6120.2 6123.5 6170.6 6185.4 3603.0 3628.6 4115.3 4237.7 4290.1 1163.8 1164.9 1171.8 1174.8 1184.1 671.4 756.7 782.8 787.7 791.3 6421.8 6479.0 6487.8 6620.0 6713.5 802.1 896.9 909.4 909.7 918.8 3725.9 3985.8 4047.7 4056.4 4161.3 14706.3 14796.6 14927.3 15046.2 15175.9 14576.4 14755.4 14943.7 14953.0 15169.2 8443.9 8552.1 8606.2 8638.0 8650.7 5819.6 6741.6 6742.8 6770.7 6828.6 13138.5 13262.0 13364.2 13608.9 13730.9 13653.2 13877.4 14026.6 14148.4 14216.0 9080.7 9149.1 9190.5 9219.6 9277.5 10619.8 10656.3 10716.9 10727.7 10736.3 8937.3 8982.7 9062.0 9096.0 9269.0 8854.6 8880.6 8939.5 8954.2 8975.8 7800.3 7920.3 7923.3 7981.1 7981.9 5747.7 5791.8 5895.9 5949.5 5968.0 5488.7 5701.9 5907.6 6028.5 6195.0 10:25:11 INFO - PID 1650 | 10:25:11 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5262.15 Median:5817.90 stddev:1666.68 (28.6%) stddev-sans-first:1630.01 10:25:11 INFO - PID 1650 | Values: 346.6 386.8 390.5 393.6 396.8 5627.0 6159.1 6181.8 6204.8 6219.0 5517.3 5534.8 5995.1 6023.8 6058.5 3809.4 3835.0 4149.7 4306.4 4604.6 4170.3 4594.4 4598.9 4607.5 4614.9 3995.9 4444.5 4500.4 4504.6 4588.7 4156.6 4577.8 4578.0 4584.4 4599.1 3372.4 3698.0 3841.9 4017.2 4038.8 4869.3 5744.5 5766.0 5843.9 5962.1 4686.5 5317.8 5339.8 5341.0 5351.0 4993.7 5945.6 6033.9 6598.6 6638.3 4103.0 4144.3 4298.4 4335.2 4352.0 5141.7 6398.6 6867.9 6880.7 6969.7 3550.5 4385.8 4387.7 4408.2 4413.4 4447.0 4452.2 4478.9 4486.7 4495.8 5116.7 5254.6 5297.4 5300.6 5309.4 6648.4 6663.2 6668.4 6700.8 6710.9 1505.2 1517.7 1525.8 1527.3 1527.5 981.9 992.7 1000.5 1002.4 1007.7 5144.5 5240.0 6189.4 6234.8 6245.8 6694.0 6748.9 6857.3 6862.1 6874.3 5362.7 6180.0 6188.6 6212.8 6229.2 2999.7 3306.7 3498.3 3516.5 3589.6 6812.7 6960.7 7007.4 7052.3 7100.8 5921.5 6951.4 7083.8 7089.5 7112.5 6388.5 6723.3 7032.3 7094.8 7110.5 5456.6 5472.4 6318.6 6891.3 6892.0 5439.7 5791.9 6232.4 6546.2 6625.5 6002.6 6058.8 6671.5 6741.4 7076.2 4411.6 4986.0 5053.8 5056.3 5064.6 5727.8 6436.6 6777.4 6910.4 6925.6 5417.3 6493.6 6560.5 6591.7 6685.7 5479.6 5859.6 6430.3 6577.9 6675.8 6489.5 6500.8 6527.6 6574.5 6587.3 6322.5 6676.5 6686.6 6691.4 6764.8 5924.3 6180.9 6451.1 6468.4 6472.2 10:25:11 INFO - PID 1650 | 10:25:11 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:26231.43 Median:17946.64 stddev:40715.30 (226.9%) stddev-sans-first:40784.78 10:25:11 INFO - PID 1650 | Values: 812.5 820.2 829.3 847.1 848.4 17076.6 17578.5 17617.2 19399.5 20267.2 18327.2 18632.7 18658.9 18760.5 18801.2 928.1 952.9 953.0 954.3 976.2 959.8 973.2 975.0 976.7 977.2 1010.1 1023.8 1026.6 1029.5 1031.0 1020.6 1024.5 1029.0 1030.2 1033.4 747.5 753.6 754.8 755.6 755.9 15988.7 16198.2 17253.5 17870.9 17969.6 17566.8 17838.4 17852.9 17923.6 18081.6 12899.5 14188.0 14645.2 14675.8 14828.0 3293.5 3491.2 3545.3 3563.6 3564.8 139358.9 143500.3 156496.0 157980.0 158578.0 13428.2 13510.5 13582.5 13622.7 13689.8 25815.6 26299.1 26489.9 26888.0 27164.2 7561.7 7603.9 7622.7 7650.3 7650.4 3258.2 3839.3 3842.6 3869.4 3894.2 3738.3 4301.6 4348.3 4381.9 4409.4 702.9 837.7 843.8 844.1 844.3 626.8 639.1 650.6 658.7 662.0 11244.2 11833.0 11864.0 12192.0 12486.4 619.8 625.9 633.8 645.2 665.3 5336.9 5430.7 5463.4 5466.7 5508.8 144028.0 146111.5 147232.3 147653.0 150553.0 122222.0 123235.0 123995.0 124681.8 125085.0 106466.5 107054.5 107163.5 107476.0 108334.0 10454.9 10752.9 10837.9 10897.6 10981.5 42584.8 43507.8 43556.6 43794.3 46834.8 60649.7 75354.2 82535.2 84307.6 86241.0 16883.8 16940.6 17123.3 17156.2 17162.0 18773.9 18865.7 18952.1 19027.5 19046.3 16880.7 17049.8 17602.1 17687.9 17725.8 16869.5 16983.2 17382.0 17547.6 17616.6 14906.4 15069.8 16304.2 16385.9 16393.4 10306.6 11941.2 12180.1 12305.6 12604.2 12946.6 13513.9 13684.0 13684.7 13701.9 10:25:11 INFO - PID 1650 | -------- Summary: end -------- 10:25:11 INFO - PID 1650 | 10:25:11 INFO - TEST-INFO | 1650: exit 0 10:25:11 INFO - TEST-OK | dromaeo_css | took 1142842ms 10:25:11 INFO - TEST-START | kraken 10:25:11 INFO - operating with platform_type : mac_ 10:25:11 INFO - Initialising browser for kraken test... 10:25:11 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpSxtvza/profile 10:25:11 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 10:25:45 INFO - TEST-INFO | 1658: killed by SIGTERM 10:25:45 INFO - Browser initialized. 10:25:45 INFO - Running cycle 1/1 for kraken test... 10:25:45 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpSxtvza/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:25:47 INFO - PID 1662 | 2017-05-25 10:25:47.193 plugin-container[1663:10516] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 10:25:47 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:47 INFO - PID 1662 | 2017-05-25 10:25:47.194 plugin-container[1663:10516] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9827, name = 'com.apple.CFPasteboardClient' 10:25:47 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:47 INFO - PID 1662 | 2017-05-25 10:25:47.194 plugin-container[1663:10516] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:25:47 INFO - PID 1662 | 2017-05-25 10:25:47.576 plugin-container[1664:10557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:25:47 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:47 INFO - PID 1662 | 2017-05-25 10:25:47.576 plugin-container[1664:10557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.CFPasteboardClient' 10:25:47 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:47 INFO - PID 1662 | 2017-05-25 10:25:47.576 plugin-container[1664:10557] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:25:56 INFO - PID 1662 | RSS: Main: 246915072 10:25:56 INFO - PID 1662 | 10:25:59 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 10:26:00 INFO - PID 1662 | RSS: Main: 261259264 10:26:00 INFO - PID 1662 | 10:26:03 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 10:26:03 INFO - PID 1662 | RSS: Main: 269959168 10:26:03 INFO - PID 1662 | 10:26:07 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 10:26:08 INFO - PID 1662 | RSS: Main: 271892480 10:26:08 INFO - PID 1662 | 10:26:09 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 10:26:10 INFO - PID 1662 | RSS: Main: 272928768 10:26:10 INFO - PID 1662 | 10:26:12 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:26:13 INFO - PID 1662 | RSS: Main: 274710528 10:26:13 INFO - PID 1662 | 10:26:16 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 10:26:16 INFO - PID 1662 | RSS: Main: 278962176 10:26:16 INFO - PID 1662 | 10:26:20 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 10:26:20 INFO - PID 1662 | RSS: Main: 279248896 10:26:20 INFO - PID 1662 | 10:26:23 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 10:26:24 INFO - PID 1662 | RSS: Main: 278945792 10:26:24 INFO - PID 1662 | 10:26:25 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:26:26 INFO - PID 1662 | RSS: Main: 278941696 10:26:26 INFO - PID 1662 | 10:26:28 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:26:28 INFO - PID 1662 | RSS: Main: 278933504 10:26:28 INFO - PID 1662 | 10:26:31 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:26:32 INFO - PID 1662 | RSS: Main: 278958080 10:26:32 INFO - PID 1662 | 10:26:34 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:26:35 INFO - PID 1662 | RSS: Main: 279584768 10:26:35 INFO - PID 1662 | 10:26:38 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:26:39 INFO - PID 1662 | RSS: Main: 279289856 10:26:39 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 10:26:41 INFO - PID 1662 | RSS: Main: 281124864 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | __start_tp_report 10:26:41 INFO - PID 1662 | _x_x_mozilla_page_load 10:26:41 INFO - PID 1662 | _x_x_mozilla_page_load_details 10:26:41 INFO - PID 1662 | |i|pagename|runs| 10:26:41 INFO - PID 1662 | |0;ai-astar;103;132;151;115;121;165;141;115;114;113 10:26:41 INFO - PID 1662 | |1;audio-beat-detection;145;109;110;147;110;112;112;114;166;141 10:26:41 INFO - PID 1662 | |2;audio-dft;200;206;198;209;246;229;237;289;255;209 10:26:41 INFO - PID 1662 | |3;audio-fft;80;57;59;82;57;58;60;62;82;59 10:26:41 INFO - PID 1662 | |4;audio-oscillator;77;70;81;71;68;69;86;70;71;91 10:26:41 INFO - PID 1662 | |5;imaging-gaussian-blur;91;90;111;90;132;78;133;131;98;91 10:26:41 INFO - PID 1662 | |6;imaging-darkroom;104;92;127;148;91;150;95;137;95;134 10:26:41 INFO - PID 1662 | |7;imaging-desaturate;97;93;92;90;92;91;93;116;107;95 10:26:41 INFO - PID 1662 | |8;json-parse-financial;57;50;50;50;53;59;59;63;45;44 10:26:41 INFO - PID 1662 | |9;json-stringify-tinderbox;56;57;59;57;68;58;54;57;57;56 10:26:41 INFO - PID 1662 | |10;stanford-crypto-aes;85;85;87;111;107;77;103;107;130;77 10:26:41 INFO - PID 1662 | |11;stanford-crypto-ccm;151;141;150;158;147;148;145;142;152;151 10:26:41 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;179;189;185;186;188;227;187;188;189;187 10:26:41 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;69;66;68;69;70;67;69;68;69;69 10:26:41 INFO - PID 1662 | __end_tp_report 10:26:41 INFO - PID 1662 | __start_cc_report 10:26:41 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1075 10:26:41 INFO - PID 1662 | __end_cc_report 10:26:41 INFO - PID 1662 | __startTimestamp1495733201289__endTimestamp 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | ------- Summary: start ------- 10:26:41 INFO - PID 1662 | Number of tests: 14 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:127.00 Median:126.50 stddev:19.74 (15.6%) stddev-sans-first:18.93 10:26:41 INFO - PID 1662 | Values: 103.0 132.0 151.0 115.0 121.0 165.0 141.0 115.0 114.0 113.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:127.50 stddev:20.98 (16.5%) stddev-sans-first:21.17 10:26:41 INFO - PID 1662 | Values: 145.0 109.0 110.0 147.0 110.0 112.0 112.0 114.0 166.0 141.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:227.80 Median:233.00 stddev:29.34 (12.6%) stddev-sans-first:29.34 10:26:41 INFO - PID 1662 | Values: 200.0 206.0 198.0 209.0 246.0 229.0 237.0 289.0 255.0 209.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:65.60 Median:61.00 stddev:10.97 (18.0%) stddev-sans-first:10.32 10:26:41 INFO - PID 1662 | Values: 80.0 57.0 59.0 82.0 57.0 58.0 60.0 62.0 82.0 59.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:74.00 stddev:8.04 (10.9%) stddev-sans-first:8.51 10:26:41 INFO - PID 1662 | Values: 77.0 70.0 81.0 71.0 68.0 69.0 86.0 70.0 71.0 91.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:104.50 Median:90.00 stddev:20.66 (23.0%) stddev-sans-first:21.33 10:26:41 INFO - PID 1662 | Values: 91.0 90.0 111.0 90.0 132.0 78.0 133.0 131.0 98.0 91.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:117.30 Median:120.50 stddev:24.21 (20.1%) stddev-sans-first:25.20 10:26:41 INFO - PID 1662 | Values: 104.0 92.0 127.0 148.0 91.0 150.0 95.0 137.0 95.0 134.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:92.50 stddev:8.37 (9.0%) stddev-sans-first:8.88 10:26:41 INFO - PID 1662 | Values: 97.0 93.0 92.0 90.0 92.0 91.0 93.0 116.0 107.0 95.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:55.00 stddev:6.32 (11.5%) stddev-sans-first:6.54 10:26:41 INFO - PID 1662 | Values: 57.0 50.0 50.0 50.0 53.0 59.0 59.0 63.0 45.0 44.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:57.90 Median:57.00 stddev:3.78 (6.6%) stddev-sans-first:3.95 10:26:41 INFO - PID 1662 | Values: 56.0 57.0 59.0 57.0 68.0 58.0 54.0 57.0 57.0 56.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:96.90 Median:77.00 stddev:17.35 (22.5%) stddev-sans-first:17.86 10:26:41 INFO - PID 1662 | Values: 85.0 85.0 87.0 111.0 107.0 77.0 103.0 107.0 130.0 77.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:150.50 stddev:5.06 (3.4%) stddev-sans-first:5.29 10:26:41 INFO - PID 1662 | Values: 151.0 141.0 150.0 158.0 147.0 148.0 145.0 142.0 152.0 151.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.50 Median:188.00 stddev:13.15 (7.0%) stddev-sans-first:13.27 10:26:41 INFO - PID 1662 | Values: 179.0 189.0 185.0 186.0 188.0 227.0 187.0 188.0 189.0 187.0 10:26:41 INFO - PID 1662 | 10:26:41 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:1.17 (1.7%) stddev-sans-first:1.22 10:26:41 INFO - PID 1662 | Values: 69.0 66.0 68.0 69.0 70.0 67.0 69.0 68.0 69.0 69.0 10:26:41 INFO - PID 1662 | -------- Summary: end -------- 10:26:41 INFO - PID 1662 | 10:26:41 INFO - TEST-INFO | 1662: exit 0 10:26:42 INFO - TEST-OK | kraken | took 90499ms 10:26:42 INFO - SUITE-END | took 1233s 10:26:42 INFO - Completed test suite (00:20:33) 10:26:42 INFO - javascript benchmark 10:26:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7898.883058937857, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2910.1996950838516, 3031.7119873612005, 3292.6378098409173, 3601.0, 3617.602063772983, 6071.210742603466, 6089.360617135203, 6137.07943808429, 6155.0, 6159.538034647402, 6107.5226075722985, 6118.040554665182, 6129.9395583570995, 6143.481172719186, 6146.139540464339, 5405.18922161676, 5451.754671039793, 5456.863578410552, 5461.371942226635, 5555.250041244421, 5341.385740639818, 5522.6134170608075, 5557.3331200256025, 5584.078627026556, 5604.523615492688, 5437.891242175174, 5482.91775623366, 5538.861528461781, 5544.861378465551, 5585.58108141889, 5378.166384210414, 5399.244105825188, 5457.372402173761, 5468.9726551367385, 5482.698451585161, 5429.6199266051, 5480.780768769245, 5481.177823326493, 5488.792465657549, 5491.835244942658, 5213.817516386946, 6054.455099041107, 6198.938010619881, 6200.720967556471, 6214.161088253073, 6429.871402571969, 6456.838579035561, 6576.769813056568, 6614.570052946591, 6661.4004739573675, 5878.588498805086, 6154.8769024619705, 6738.52830301879, 6756.797296081126, 6779.49153813466, 6284.088807122942, 6293.685315734241, 6297.4332310091395, 6301.275353334334, 6307.779227727054, 5464.9180262296095, 6734.259231484532, 6808.2510923798345, 6810.693518791666, 6908.0, 5422.972885135549, 6346.555741098079, 6375.71309291083, 6473.0, 6552.148220731275, 6589.80230593083, 6613.14029176204, 6694.794936911306, 6703.564268322543, 6711.295313992058, 6565.934340656532, 6602.3067577904585, 6613.86772264552, 6624.6025238485845, 6645.036469711865, 6402.071699603532, 6454.838629034218, 6538.1827271591055, 6563.146790917246, 6618.8345291368105, 5360.035193665178, 5361.919571206435, 5368.785248590091, 5373.528177038606, 5393.946060539344, 4786.688865223749, 4795.280707893774, 4800.591949684246, 4803.471618122004, 4838.975805121022, 6114.144019837227, 6243.625382477066, 6256.468200202943, 6261.530385221126, 6270.968645156745, 6731.32686731323, 6744.359285867933, 6778.966105169443, 6780.457563395015, 6795.660216989131, 5428.619996600279, 5777.393373695785, 6226.377362263828, 6270.090836828635, 6299.6850157491945, 5639.0, 5830.7959221427045, 5888.175655408247, 5945.494632956247, 5947.613405128653, 6868.896966545506, 6902.8619427612175, 6929.341712537301, 6946.79159625212, 6948.444124469929, 6766.627835469097, 6770.221424536241, 6782.660866956731, 6791.796246112624, 6808.080909077409, 6686.765963191265, 6734.0, 6775.830604234933, 6911.3779759821855, 6925.896111558432, 5451.563874890078, 5759.51044161251, 5994.850128746816, 6116.39664026098, 6521.054447105048, 6729.5625784323865, 6799.490038247251, 6801.251862295243, 6804.115464989718, 6835.4189893859575, 6317.96841015792, 6417.48660107181, 6497.285298617243, 6562.409383155539, 6571.01434784767, 6568.474522038321, 6572.441342485942, 6579.276279609144, 6604.900926486011, 6610.504212184009, 6376.776812811529, 6448.45188158993, 6450.0, 6452.354764523698, 6458.54790164684, 5568.16477528374, 6306.306306306211, 6441.420272175526, 6573.0, 6619.470442364503, 6372.617642941438, 6407.743690252524, 6408.551401402044, 6409.903851442139, 6427.58220715652], "value": 6049.329762774745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.3770327183345, 804.7062822069944, 826.6404114210319, 1162.6105254739662, 1261.8107283907407, 38916.0, 40012.39981400281, 40062.0, 40171.0, 40240.0, 40700.0, 40740.0, 41124.38313425301, 41166.794166029205, 41189.17621647573, 7928.246816552418, 8096.473729207582, 8487.108853570378, 8682.652693892236, 8687.826243475127, 8522.446041007346, 8579.699710510133, 8605.526696031715, 8633.827323453526, 8641.308695304391, 8614.483131012128, 8634.352423568225, 8658.956705216497, 8667.653293868238, 8679.95660021699, 8561.785955351104, 8611.655533778641, 8650.524221167832, 8653.610587523577, 8668.176523230282, 8368.958155209246, 8383.035950865607, 8391.370647201424, 8454.28138608219, 8468.153184681543, 37629.43555846665, 37710.245795083945, 37759.62240377589, 37792.24415511702, 37847.243055138744, 3598.8200589970656, 3628.983884512341, 3630.8366123524506, 3633.745637805355, 3642.763220390693, 30729.385412291853, 30907.38185236305, 30956.690433095606, 30979.0, 30981.0, 3051.6032915721034, 3083.491223948046, 3091.8608662610236, 3099.4575949208797, 3115.112193024977, 208508.0, 210909.0, 264790.9999999981, 282156.17843821505, 283204.0, 47606.28590571144, 47786.699197072434, 47833.76083119562, 47925.520744792804, 47949.520504795204, 49143.26285105726, 49196.508034919905, 49203.50796492061, 49324.75337623361, 49880.750596246784, 14393.280335983369, 14515.491957781638, 14537.41850325975, 14557.12657240569, 14577.271136443347, 11531.942340288246, 11580.247283926687, 11639.941800290944, 11645.941770291094, 11704.005159561362, 11901.583444579572, 12701.555445559547, 14149.717005660035, 14163.78754318686, 14204.85795142056, 5472.699001554953, 5991.760329586855, 6161.353057928943, 6194.38056194377, 6228.190335256388, 975.590252094108, 1002.7092143278513, 1003.1373019203479, 1018.1447584029451, 1037.9481025948824, 24570.140045098695, 24913.12804051886, 24922.5015499689, 24951.251462456155, 25008.37479063038, 919.6919032124314, 944.3153713557753, 1059.9470026498643, 1089.7384627689298, 1122.8540289762443, 11695.181337306307, 12432.627021189379, 12497.625071247878, 12651.620451386472, 12836.80744788829, 322869.0, 323169.0, 323303.0, 325793.74206257635, 326492.0, 55161.72419137959, 55516.0, 55570.610734731956, 55646.443535564125, 55800.441995579524, 1281.6411404806483, 1481.0669278354567, 1520.6198450387403, 1532.862042416188, 1540.4146424358896, 22754.544909102056, 23992.64011039871, 24138.517229654957, 24206.75793242045, 24291.75708242895, 37796.0, 37853.053673657276, 37864.81067594644, 37907.0, 38011.809940950116, 269472.0, 271468.2132946613, 271888.0, 272189.0, 272304.0, 34212.48681269733, 34339.82830085834, 34677.65322346845, 35466.93599191925, 35515.46726799152, 43505.34741978936, 44782.10435791331, 44958.953694167685, 45030.324545131196, 45090.323645146, 29358.41283174282, 30218.09345719632, 30395.0, 31100.911468099413, 31397.52903706492, 30306.84846575753, 30819.53770693486, 30825.075247742603, 31178.220544485663, 31247.0, 36278.8186059068, 36873.44689829709, 37477.25045499129, 37577.81211093927, 37588.060298492754, 30821.0, 31264.374712506076, 31505.8424707875, 31523.527147092354, 31564.0, 22987.0, 23546.52906941886, 23655.408614784767, 24083.397915052265, 24563.0], "value": 17558.662784797645, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.6310938600564, 426.60539001423695, 435.6906596316616, 449.08386890742884, 452.15898428922196, 18363.908180459097, 19318.32385866495, 21869.453263668416, 21878.781212187874, 22038.11847526099, 21921.575097618643, 22419.663705044437, 22485.0, 22773.0, 22941.19705810297, 5043.470435604264, 5451.918221226685, 6257.311695713468, 6273.435390814826, 6291.465225455841, 5957.1957785698805, 6070.605410648316, 6160.229971253593, 6266.248050233979, 6274.0, 6110.450059494645, 6114.510839132881, 6124.2957059938235, 6131.63210207389, 6134.2332208473945, 5871.178035075093, 6061.575689701723, 6090.60411073281, 6091.2995005574485, 6121.418465245794, 5420.512153906167, 5421.376541697716, 5425.294711687495, 5447.400785913546, 5459.863503412406, 22422.430429869917, 26963.0, 27360.863195684095, 27473.587896181572, 27478.862605687045, 2141.4432247615578, 2226.9888650556645, 2238.4963383238805, 2244.5623103494827, 2245.584566855137, 17773.7333939991, 17791.644167116716, 18030.0, 18115.72826407605, 18440.815591844043, 2095.497080700636, 2117.735283089614, 2142.817860481861, 2150.236665983569, 2157.169489746459, 137371.0, 137844.0, 140383.0, 141095.88356174767, 141391.0, 12326.50693972249, 12419.316937568521, 12529.31088790107, 12731.87268127307, 12786.808197877039, 16031.19844007795, 16081.276342564614, 16085.034897906164, 16087.597810054609, 16135.0, 8405.117462666454, 8639.222469977733, 8654.48073115615, 8751.081136480705, 8768.868466973001, 8109.148539403395, 8128.715494957766, 8130.796730081677, 8260.091389947102, 8275.131111233355, 7723.575203363869, 7855.882161767464, 7856.371490280764, 7863.191465962861, 7876.330511906552, 2164.5129845784886, 2179.5749828783237, 2181.0512427094263, 2185.7923497267734, 2211.6682497625484, 906.5240748606902, 939.0890835889177, 1001.4777538141983, 1022.1771473963531, 1049.7847941172042, 17658.293668253384, 18992.76547024439, 19746.0, 21511.56976860454, 21540.0, 960.0879164793474, 961.4183419031525, 1002.5438425516519, 1021.22897212604, 1029.3566520924421, 10902.0, 11060.115190784718, 11088.889111108947, 11124.888751112385, 11136.944315278372, 529583.0, 548987.0, 550109.0, 557127.0, 562990.0, 114082.0, 126450.0, 126759.0, 127276.36361818132, 129315.0, 1176.211938001541, 1212.308983879198, 1239.603326935372, 1241.1994263699862, 1247.1581682364367, 18513.0, 20624.381268561967, 20634.896825515778, 21688.783112168676, 21994.89002554977, 59864.0, 59874.0, 59989.700051499465, 60148.39851601428, 60674.0, 77413.0, 78060.60969695116, 78314.0, 78698.81951770843, 78882.605586974, 8734.170253825876, 8803.911960880308, 8922.330825188266, 9175.816483670156, 9188.678396256098, 12007.979321757748, 13894.235817029756, 14235.857641423867, 14568.0, 14618.488352907598, 10881.0, 11456.140789440991, 12038.939805300917, 12194.75610487803, 12327.50689972391, 10889.564417423215, 10982.890171098186, 11125.610603628835, 11520.308781473139, 11632.651020469399, 11234.606788762354, 12008.819867702166, 12285.754284914074, 12390.132690711564, 12625.558105466265, 9011.143941325563, 9050.502222377832, 9109.590068446803, 9136.725898223063, 9140.131687489678, 10580.735481613021, 11939.0, 11952.76094478088, 12199.87800122023, 12298.385080746106], "value": 10410.084220989873, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.1738974005336, 1454.556360310105, 1455.4032846532923, 1463.3414963266525, 1481.8073650425447, 11445.942770286147, 11872.881271187285, 12049.518019279229, 12105.213161144531, 12196.634100976962, 10332.12176964957, 11593.536258549648, 12081.818772718416, 12089.516419343237, 12113.697157571065, 2486.1422809130822, 2541.275736415122, 2541.313845261778, 2548.7323830997757, 2551.3876669599254, 2457.8771061446946, 2504.799616030713, 2504.949901001979, 2508.523380557688, 2514.1200579797014, 2454.067454367338, 2483.5902076157413, 2488.912888048919, 2491.9127830525936, 2492.4890397468475, 2432.3189506937997, 2453.58289090855, 2462.101333013455, 2463.7289898111194, 2465.8027357811334, 2416.903323867043, 2446.987765061181, 2452.350127216289, 2453.141400509825, 2521.067205134094, 11644.883551164465, 11688.47401866918, 11775.764484710344, 11784.528618855236, 11795.41022948849, 6334.809955701337, 6516.77191298307, 6599.9670001650165, 6603.900941485882, 6610.107635469199, 3961.1087505311075, 4139.254934111858, 4196.958030419687, 4241.660667146621, 4269.6370808481315, 1209.1596340543326, 1215.1858254969193, 1221.5968730318973, 1227.6808029912197, 1232.433080782841, 15200.16399098049, 15672.138032408327, 16445.177741112897, 16586.502404927873, 16592.66814663712, 4729.408823897013, 4818.204996175592, 5410.702411367412, 5558.44415558441, 5614.157876318503, 10020.298579099539, 11832.349220792767, 11838.644840654793, 11839.704007399712, 11861.762764744657, 5409.729513524387, 6374.649394283266, 6465.612063276219, 6497.090407342976, 6503.6097834130105, 6067.150598916067, 6120.234970628671, 6123.479504242189, 6170.629762214192, 6185.41238582334, 3602.9819850901104, 3628.618995005539, 4115.259253334429, 4237.745735255895, 4290.120525292307, 1163.8021536338842, 1164.8835116488453, 1171.824226366035, 1174.770919670673, 1184.0586733546852, 671.4058058618158, 756.7048850948134, 782.7690831204694, 787.7203592724615, 791.3194652598761, 6421.8073457796345, 6478.967605161944, 6487.837804054842, 6619.966900165468, 6713.462922966249, 802.1336956087412, 896.8699538566872, 909.4134283387178, 909.6997990663066, 918.8300164469595, 3725.9068523287133, 3985.8206380712936, 4047.7166598337835, 4056.350983842571, 4161.2509748245, 14706.33821478036, 14796.630084247765, 14927.25363731809, 15046.247687615794, 15175.924120379548, 14576.416943322149, 14755.40978360875, 14943.701125977419, 14953.0, 15169.165695886612, 8443.915560844436, 8552.144785521397, 8606.182412670787, 8637.956810215908, 8650.6972255972, 5819.563532734977, 6741.561798483083, 6742.797716068526, 6770.6614669265355, 6828.5902845829405, 13138.474461021453, 13262.0, 13364.198148111145, 13608.86391136076, 13730.862691373359, 13653.249071301174, 13877.444902203799, 14026.649333766738, 14148.43406263738, 14216.0, 9080.727578172666, 9149.130832570896, 9190.54047297619, 9219.585118669542, 9277.48973806447, 10619.840702389625, 10656.25406221557, 10716.89283107159, 10727.731806704895, 10736.30214036085, 8937.329700272376, 8982.685606003759, 9062.0, 9095.954520227357, 9268.980412154522, 8854.601542930455, 8880.600372983103, 8939.463632182089, 8954.238889694449, 8975.820483590422, 7800.297973182441, 7920.326772224425, 7923.286904178652, 7981.082175549886, 7981.880271796043, 5747.683877386784, 5791.768329266951, 5895.882082358243, 5949.52403807703, 5968.0, 5488.725563721878, 5701.914471283017, 5907.556933229939, 6028.487578555872, 6195.039768835838], "value": 5296.320901355253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.60140838036256, 386.76020867062425, 390.451415760856, 393.55724809589213, 396.7520299812616, 5627.0, 6159.106929495226, 6181.814545563635, 6204.751809927598, 6218.968905155479, 5517.2551705519745, 5534.75093620787, 5995.062419414488, 6023.819285421433, 6058.545609079315, 3809.3524100902773, 3835.0, 4149.7095203335775, 4306.375575541545, 4604.585587297142, 4170.311898536738, 4594.448666160066, 4598.8620341389815, 4607.516210797869, 4614.861554153381, 3995.9231391828275, 4444.533324000982, 4500.437445319335, 4504.558541266796, 4588.7476188809605, 4156.584341565849, 4577.8397756078375, 4577.9542204577865, 4584.426946631671, 4599.103174880899, 3372.426687463137, 3697.9830546599633, 3841.8666493384517, 4017.176478821864, 4038.818253178614, 4869.269609558594, 5744.540436765049, 5766.0197766379815, 5843.918875008095, 5962.135490353874, 4686.507916668735, 5317.787288508455, 5339.839804805862, 5340.973295133499, 5350.973245133789, 4993.675411098267, 5945.554083443759, 6033.909491357633, 6598.581305019396, 6638.336166383371, 4102.979485102585, 4144.254034273829, 4298.398224248608, 4335.241332766753, 4351.999040220767, 5141.74291285438, 6398.552101352907, 6867.896981545281, 6880.65596720162, 6969.6515174241085, 3550.467429885486, 4385.780710964503, 4387.736735795798, 4408.206522825923, 4413.44831896013, 4447.021075621317, 4452.220861349316, 4478.865634030984, 4486.68593198473, 4495.820167193341, 5116.667416617983, 5254.579633629301, 5297.3643162819935, 5300.575953923754, 5309.362876454773, 6648.368405001517, 6663.167104111986, 6668.366505182, 6700.788739715882, 6710.865782684319, 1505.2172870107702, 1517.7116347893864, 1525.8321584625683, 1527.2974431761513, 1527.5493729349869, 981.8723565936383, 992.712113487095, 1000.4697510319543, 1002.4185972136144, 1007.6674697349857, 5144.536991670768, 5240.0, 6189.35011823752, 6234.846553387638, 6245.75016999324, 6694.0, 6748.865022699519, 6857.348551887562, 6862.073620061327, 6874.346937041073, 5362.731863406814, 6180.011198208355, 6188.638499530096, 6212.782552610637, 6229.190205273376, 2999.6850330715397, 3306.7354611631013, 3498.2828520153307, 3516.4725291206523, 3589.6410358963894, 6812.6934287957165, 6960.702968020541, 7007.404370628452, 7052.33002864727, 7100.751473698396, 5921.467067963904, 6951.374376306157, 7083.7520686775715, 7089.539179953286, 7112.502124851314, 6388.4569811566535, 6723.260441351447, 7032.268701008662, 7094.751683691046, 7110.537815042006, 5456.590755693418, 5472.425395333433, 6318.557700960889, 6891.276415976451, 6891.965540172468, 5439.728013599225, 5791.884162316645, 6232.407921247474, 6546.24718157418, 6625.4699624029, 6002.579819412599, 6058.818235452944, 6671.532992690659, 6741.426978706865, 7076.22161562238, 4411.602955733999, 4985.975070124626, 5053.772580233972, 5056.317397151484, 5064.645474816727, 5727.799527016535, 6436.613803171825, 6777.356151165632, 6910.412614927787, 6925.6190909500465, 5417.268668729828, 6493.623315875812, 6560.475161986934, 6591.747567962072, 6685.665716714242, 5479.561635069105, 5859.648421094748, 6430.260520040255, 6577.934220657732, 6675.7997260082275, 6489.513286503626, 6500.804975850732, 6527.640979746159, 6574.4740420765265, 6587.2543775900385, 6322.49420046386, 6676.465882729273, 6686.598804071771, 6691.427514534178, 6764.830879228059, 5924.318703348998, 6180.918339290696, 6451.1290975717675, 6468.417842394207, 6472.15861937945], "value": 4739.7139345276555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [812.512492504497, 820.1757233979849, 829.2743849132006, 847.1443841719861, 848.3806821020655, 17076.573085672866, 17578.47264582063, 17617.207225674843, 19399.515012124702, 20267.189312427523, 18327.17527711253, 18632.72050919237, 18658.90670546649, 18760.530986725338, 18801.247950082015, 928.0765638189994, 952.8904176019777, 952.9523523823816, 954.2747511890979, 976.1800087926141, 959.7600599850037, 973.2360097323602, 974.9658761943333, 976.677696360203, 977.1986970684042, 1010.1111022300402, 1023.7850051489196, 1026.58423338548, 1029.474967766437, 1031.0411317474745, 1020.5815615597644, 1024.5133561558275, 1028.9691309260734, 1030.1707125763758, 1033.3902997231633, 747.4543583184305, 753.6269546574481, 754.7773406844939, 755.6221889055472, 755.8526087412956, 15988.680226395407, 16198.190090495546, 17253.482395528154, 17870.91064544669, 17969.640607187914, 17566.82433175665, 17838.37565685195, 17852.91073544624, 17923.641527169515, 18081.638367232714, 12899.548515801902, 14188.006839521238, 14645.19451430171, 14675.77986330206, 14828.0, 3293.522439246296, 3491.1970246843116, 3545.2732189901008, 3563.55455568054, 3564.7861128332124, 139358.90961635482, 143500.28249858788, 156496.0, 157980.0, 158578.0, 13428.19430834153, 13510.527131550349, 13582.524611638546, 13622.727545449035, 13689.794653080211, 25815.612765808528, 26299.07953221628, 26489.867550662122, 26888.0, 27164.1850744478, 7561.735339263099, 7603.885941710879, 7622.656980435893, 7650.349720273728, 7650.38796896247, 3258.185453636591, 3839.2705385976574, 3842.634949679776, 3869.361555343337, 3894.2211557688574, 3738.27103714773, 4301.612854843079, 4348.326009468474, 4381.926428025155, 4409.426774519292, 702.8859258075905, 837.656560810065, 843.7595285343648, 844.075737067922, 844.2697067036969, 626.8307556959595, 639.1371648274885, 650.632392698124, 658.6838317607479, 661.9871596457427, 11244.212905096729, 11833.0, 11864.0, 12192.0, 12486.438110285058, 619.767587154817, 625.9436650701459, 633.832034510855, 645.1870642989867, 665.3280187011056, 5336.866578335573, 5430.728463576885, 5463.3717122530625, 5466.6993315367245, 5508.779648814083, 144028.0, 146111.5388846098, 147232.2638386801, 147653.0, 150553.0, 122222.0, 123235.0, 123995.0, 124681.75318246883, 125085.0, 106466.46766766117, 107054.46472767742, 107163.46418267858, 107476.0, 108333.99999999843, 10454.947725261325, 10752.946235268773, 10837.945810270898, 10897.618583349862, 10981.505832237408, 42584.78707606442, 43507.78246108749, 43556.56443435652, 43794.34308485311, 46834.76582617065, 60649.69675151596, 75354.24645753473, 82535.17464825275, 84307.5784621073, 86241.0, 16883.83116168872, 16940.57648558796, 17123.315067397165, 17156.227969741143, 17162.0, 18773.90613046926, 18865.71701424452, 18952.0523973798, 19027.524311892314, 19046.333378332245, 16880.746788798424, 17049.82950170532, 17602.11989400499, 17687.911560442117, 17725.822741772416, 16869.493915182564, 16983.150842458075, 17382.0, 17547.561310966816, 17616.55958601045, 14906.403743850124, 15069.760001599709, 16304.184790760652, 16385.91807040957, 16393.42623008189, 10306.639267625596, 11941.16411851162, 12180.147389682637, 12305.630831075083, 12604.180728252633, 12946.611601651966, 13513.932430337785, 13684.0, 13684.726305473636, 13701.862981370059], "value": 8749.497920719981, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 132.0, 151.0, 115.0, 121.0, 165.0, 141.0, 115.0, 114.0, 113.0], "value": 127.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 109.0, 110.0, 147.0, 110.0, 112.0, 112.0, 114.0, 166.0, 141.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 206.0, 198.0, 209.0, 246.0, 229.0, 237.0, 289.0, 255.0, 209.0], "value": 227.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 59.0, 82.0, 57.0, 58.0, 60.0, 62.0, 82.0, 59.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 70.0, 81.0, 71.0, 68.0, 69.0, 86.0, 70.0, 71.0, 91.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 111.0, 90.0, 132.0, 78.0, 133.0, 131.0, 98.0, 91.0], "value": 104.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 92.0, 127.0, 148.0, 91.0, 150.0, 95.0, 137.0, 95.0, 134.0], "value": 117.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 92.0, 90.0, 92.0, 91.0, 93.0, 116.0, 107.0, 95.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 50.0, 50.0, 50.0, 53.0, 59.0, 59.0, 63.0, 45.0, 44.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 59.0, 57.0, 68.0, 58.0, 54.0, 57.0, 57.0, 56.0], "value": 57.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 111.0, 107.0, 77.0, 103.0, 107.0, 130.0, 77.0], "value": 96.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 141.0, 150.0, 158.0, 147.0, 148.0, 145.0, 142.0, 152.0, 151.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 189.0, 185.0, 186.0, 188.0, 227.0, 187.0, 188.0, 189.0, 187.0], "value": 190.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 68.0, 69.0, 70.0, 67.0, 69.0, 68.0, 69.0, 69.0], "value": 68.4, "unit": "score"}]}]} 10:26:42 INFO - Return code: 0 10:26:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:26:42 INFO - # TBPL SUCCESS # 10:26:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:26:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:26:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:26:42 INFO - 2017-05-25 10:26:42.628 screenresolution[1669:10928] starting screenresolution argv=screenresolution get 10:26:42 INFO - 2017-05-25 10:26:42.631 screenresolution[1669:10928] Display 0: 1600x1200x32@60 10:26:42 INFO - 2017-05-25 10:26:42.637 screenresolution[1670:10931] starting screenresolution argv=screenresolution list 10:26:42 INFO - Available Modes on Display 0 10:26:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:26:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:26:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:26:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:26:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:26:42 INFO - Intel Iris: 10:26:42 INFO - Chipset Model: Intel Iris 10:26:42 INFO - Type: GPU 10:26:42 INFO - Bus: Built-In 10:26:42 INFO - VRAM (Dynamic, Max): 1536 MB 10:26:42 INFO - Vendor: Intel (0x8086) 10:26:42 INFO - Device ID: 0x0a2e 10:26:42 INFO - Revision ID: 0x0009 10:26:42 INFO - Displays: 10:26:42 INFO - Display: 10:26:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:26:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:26:42 INFO - Display Serial Number: 1600x1200 60 10:26:42 INFO - Main Display: Yes 10:26:42 INFO - Mirror: Off 10:26:42 INFO - Online: Yes 10:26:42 INFO - Rotation: Supported 10:26:42 INFO - Return code: 0 10:26:42 INFO - Running post-action listener: _resource_record_post_action 10:26:42 INFO - [mozharness: 2017-05-25 17:26:42.868308Z] Finished run-tests step (success) 10:26:42 INFO - Running post-run listener: _resource_record_post_run 10:26:42 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 45005824; Write bytes: 561582080; Read time: 462; Write time: 1719 10:26:42 INFO - TinderboxPrint: CPU usage
53.4% 10:26:42 INFO - TinderboxPrint: I/O read bytes / time
45,005,824 / 462 10:26:42 INFO - TinderboxPrint: I/O write bytes / time
561,582,080 / 1,719 10:26:42 INFO - TinderboxPrint: CPU idle
2,344.1 (46.6%) 10:26:42 INFO - TinderboxPrint: CPU system
1,011.7 (20.1%) 10:26:42 INFO - TinderboxPrint: CPU user
1,669.9 (33.2%) 10:26:42 INFO - TinderboxPrint: Swap in / out
285,290,496 / 0 10:26:42 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 226454016; Write bytes: 219854848; Read time: 16782; Write time: 400 10:26:42 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 43977728; Write bytes: 315516928; Read time: 442; Write time: 1257 10:26:43 INFO - Running post-run listener: _upload_blobber_files 10:26:43 INFO - Blob upload gear active. 10:26:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:26:43 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 10:26:43 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'] 10:26:43 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 10:26:43 INFO - (blobuploader) - INFO - Open directory for files ... 10:26:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:26:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:26:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:44 INFO - (blobuploader) - INFO - Done attempting. 10:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:26:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:45 INFO - (blobuploader) - INFO - Done attempting. 10:26:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:26:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:26:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:46 INFO - (blobuploader) - INFO - Done attempting. 10:26:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:26:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:26:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:48 INFO - (blobuploader) - INFO - Done attempting. 10:26:48 INFO - (blobuploader) - INFO - Iteration through files over. 10:26:48 INFO - Return code: 0 10:26:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:26:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:26:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff33249567241f0e03da1821fecc0b72a769edd562beeaa2ac86ed19d499f2ab515994c1045bd302b0bb9523b725a567a2eb7f7715895a79df484cac590ab680", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83daeead6e82f4c0259e245bd38e4c299fc27deda6c289f0747b42f4a7ad9474fa47f28e9879685e880a2974c232649a90538a18c48aa43f42bfd0019dd84b07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f9c92a13e8b34c748fdb68b11ac07e1eb9ca0d5e7b02904a8f7956a7f0025095c701d1fd3540d0eea2b2820e17891bf818cafbe12c0fe39ff8bf5c16ffdf1e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60709e976f3299359d12cfd0ab56fc949feb6efecac838f8b15ba75501a5dca5ab19dc161291d27967a056a4af38e0be1e2b553313cb4d90dcb944e5a557dd19"} 10:26:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:26:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:26:48 INFO - Contents: 10:26:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff33249567241f0e03da1821fecc0b72a769edd562beeaa2ac86ed19d499f2ab515994c1045bd302b0bb9523b725a567a2eb7f7715895a79df484cac590ab680", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83daeead6e82f4c0259e245bd38e4c299fc27deda6c289f0747b42f4a7ad9474fa47f28e9879685e880a2974c232649a90538a18c48aa43f42bfd0019dd84b07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f9c92a13e8b34c748fdb68b11ac07e1eb9ca0d5e7b02904a8f7956a7f0025095c701d1fd3540d0eea2b2820e17891bf818cafbe12c0fe39ff8bf5c16ffdf1e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60709e976f3299359d12cfd0ab56fc949feb6efecac838f8b15ba75501a5dca5ab19dc161291d27967a056a4af38e0be1e2b553313cb4d90dcb944e5a557dd19"} 10:26:48 INFO - Running post-run listener: copy_logs_to_upload_dir 10:26:48 INFO - Copying logs to upload dir... 10:26:48 INFO - mkdir: /builds/slave/test/build/upload/logs 10:26:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.106969 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-25 10:26:48.366101) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:48.369373) ========= 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.HiSsddX3um/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.N9tbc7o9Mq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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/ff33249567241f0e03da1821fecc0b72a769edd562beeaa2ac86ed19d499f2ab515994c1045bd302b0bb9523b725a567a2eb7f7715895a79df484cac590ab680", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83daeead6e82f4c0259e245bd38e4c299fc27deda6c289f0747b42f4a7ad9474fa47f28e9879685e880a2974c232649a90538a18c48aa43f42bfd0019dd84b07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f9c92a13e8b34c748fdb68b11ac07e1eb9ca0d5e7b02904a8f7956a7f0025095c701d1fd3540d0eea2b2820e17891bf818cafbe12c0fe39ff8bf5c16ffdf1e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60709e976f3299359d12cfd0ab56fc949feb6efecac838f8b15ba75501a5dca5ab19dc161291d27967a056a4af38e0be1e2b553313cb4d90dcb944e5a557dd19"} build_url:https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/LME-yLTsTMurfTdIcHUJVQ/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/ff33249567241f0e03da1821fecc0b72a769edd562beeaa2ac86ed19d499f2ab515994c1045bd302b0bb9523b725a567a2eb7f7715895a79df484cac590ab680", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83daeead6e82f4c0259e245bd38e4c299fc27deda6c289f0747b42f4a7ad9474fa47f28e9879685e880a2974c232649a90538a18c48aa43f42bfd0019dd84b07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f9c92a13e8b34c748fdb68b11ac07e1eb9ca0d5e7b02904a8f7956a7f0025095c701d1fd3540d0eea2b2820e17891bf818cafbe12c0fe39ff8bf5c16ffdf1e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60709e976f3299359d12cfd0ab56fc949feb6efecac838f8b15ba75501a5dca5ab19dc161291d27967a056a4af38e0be1e2b553313cb4d90dcb944e5a557dd19"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:48.402846) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:48.403190) ========= 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.HiSsddX3um/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.N9tbc7o9Mq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.005217 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:48.451735) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 10:26:48.452064) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 10:26:48.452392) ========= ========= Total master_lag: 0.17 =========