builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0059 starttime: 1495087492.56 results: success (0) revision: 0b529227685dc071774480f756bf41dff7068452 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:52.558672) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:52.559109) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:52.559382) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:52.585176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:52.585579) ========= 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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners TMPDIR=/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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-17 23:04:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-05-17 23:04:52 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:52.738700) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:52.739011) ========= 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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners TMPDIR=/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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.027638 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:52.787330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 23:04:52.787727) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0b529227685dc071774480f756bf41dff7068452 --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 0b529227685dc071774480f756bf41dff7068452 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GeAY0AQB1U/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.H9bgWvNMkm/Listeners TMPDIR=/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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-17 23:04:52,888 truncating revision to first 12 chars 2017-05-17 23:04:52,888 Setting DEBUG logging. 2017-05-17 23:04:52,888 attempt 1/10 2017-05-17 23:04:52,888 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0b529227685d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-17 23:04:53,755 unpacking tar archive at: mozilla-central-0b529227685d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.146609 ========= master_lag: 0.63 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 23:04:54.561847) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:54.562175) ========= script_repo_revision: 0b529227685dc071774480f756bf41dff7068452 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:54.562569) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:54.562835) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 23:04:54.583510) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-17 23:04:54.583903) ========= /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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners TMPDIR=/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:04:54 INFO - MultiFileLogger online at 20170517 23:04:54 in /builds/slave/test 23:04:54 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 23:04:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:04:54 INFO - {'append_to_log': False, 23:04:54 INFO - 'base_work_dir': '/builds/slave/test', 23:04:54 INFO - 'blob_upload_branch': 'Firefox', 23:04:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:04:54 INFO - 'branch': 'Firefox', 23:04:54 INFO - 'buildbot_json_path': 'buildprops.json', 23:04:54 INFO - 'config_files': ('talos/mac_config.py',), 23:04:54 INFO - 'default_actions': ('clobber', 23:04:54 INFO - 'read-buildbot-config', 23:04:54 INFO - 'download-and-extract', 23:04:54 INFO - 'populate-webroot', 23:04:54 INFO - 'create-virtualenv', 23:04:54 INFO - 'install', 23:04:54 INFO - 'run-tests'), 23:04:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:04:54 INFO - 'download_minidump_stackwalk': True, 23:04:54 INFO - 'download_symbols': 'ondemand', 23:04:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:04:54 INFO - 'tooltool.py': '/tools/tooltool.py', 23:04:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:04:54 INFO - '/tools/misc-python/virtualenv.py')}, 23:04:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:04:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:04:54 INFO - 'gecko_profile': False, 23:04:54 INFO - 'gecko_profile_interval': 0, 23:04:54 INFO - 'installer_path': 'installer.exe', 23:04:54 INFO - 'log_level': 'info', 23:04:54 INFO - 'log_name': 'talos', 23:04:54 INFO - 'log_to_console': True, 23:04:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:04:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:04:54 INFO - 'opt_config_files': (), 23:04:54 INFO - 'pip_index': False, 23:04:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:04:54 INFO - 'cmd': ('bash', 23:04:54 INFO - '-c', 23:04:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:04:54 INFO - 'enabled': True, 23:04:54 INFO - 'halt_on_failure': False, 23:04:54 INFO - 'name': 'check_screen_resolution'},), 23:04:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:04:54 INFO - 'cmd': ('bash', 23:04:54 INFO - '-c', 23:04:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:04:54 INFO - 'enabled': True, 23:04:54 INFO - 'halt_on_failure': False, 23:04:54 INFO - 'name': 'check_screen_resolution'},), 23:04:54 INFO - 'run_cmd_checks_enabled': True, 23:04:54 INFO - 'suite': 'dromaeojs-e10s', 23:04:54 INFO - 'system_bits': '32', 23:04:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:04:54 INFO - 'title': 't-yosemite-r7-0059', 23:04:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:04:54 INFO - 'use_talos_json': True, 23:04:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:04:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:04:54 INFO - 'work_dir': 'build'} 23:04:54 INFO - [mozharness: 2017-05-18 06:04:54.793031Z] Running clobber step. 23:04:54 INFO - Running pre-action listener: _resource_record_pre_action 23:04:54 INFO - Running main action method: clobber 23:04:54 INFO - rmtree: /builds/slave/test/build 23:04:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:04:57 INFO - Running post-action listener: _resource_record_post_action 23:04:57 INFO - [mozharness: 2017-05-18 06:04:57.351084Z] Finished clobber step (success) 23:04:57 INFO - [mozharness: 2017-05-18 06:04:57.351355Z] Running read-buildbot-config step. 23:04:57 INFO - Running pre-action listener: _resource_record_pre_action 23:04:57 INFO - Running main action method: read_buildbot_config 23:04:57 INFO - Using buildbot properties: 23:04:57 INFO - { 23:04:57 INFO - "project": "", 23:04:57 INFO - "product": "firefox", 23:04:57 INFO - "who": "ihsiao@mozilla.com", 23:04:57 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 23:04:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 23:04:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 23:04:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 23:04:57 INFO - "stage_platform": "macosx64", 23:04:57 INFO - "basedir": "/builds/slave/test", 23:04:57 INFO - "buildnumber": 74, 23:04:57 INFO - "platform": "yosemite_r7", 23:04:57 INFO - "parent_task_id": "XDd_OtorTQ2FSqlshpkODQ", 23:04:57 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 23:04:57 INFO - "slavebuilddir": "test", 23:04:57 INFO - "taskId": "J1Lm5ftPSKCDqCRtDWgeUw", 23:04:57 INFO - "branch": "mozilla-central", 23:04:57 INFO - "script_repo_revision": "production", 23:04:57 INFO - "revision": "0b529227685dc071774480f756bf41dff7068452", 23:04:57 INFO - "slavename": "t-yosemite-r7-0059", 23:04:57 INFO - "repo_path": "mozilla-central" 23:04:57 INFO - } 23:04:57 INFO - Finding installer, test and symbols from parent task. 23:04:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1Lm5ftPSKCDqCRtDWgeUw'}, attempt #1 23:04:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1Lm5ftPSKCDqCRtDWgeUw'}, attempt #1 23:04:58 INFO - Task dependencies: BPxFbXEzQCW0BCZQCMnu2A 23:04:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ'}, attempt #1 23:04:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:04:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/test_packages.json 23:04:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/target.crashreporter-symbols.zip 23:04:58 INFO - Running post-action listener: _resource_record_post_action 23:04:58 INFO - [mozharness: 2017-05-18 06:04:58.734613Z] Finished read-buildbot-config step (success) 23:04:58 INFO - [mozharness: 2017-05-18 06:04:58.734744Z] Running download-and-extract step. 23:04:58 INFO - Running pre-action listener: _resource_record_pre_action 23:04:58 INFO - Running main action method: download_and_extract 23:04:58 INFO - mkdir: /builds/slave/test/build/tests 23:04:58 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')]} 23:04:58 INFO - trying https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 23:04:58 INFO - Downloading https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/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 23:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/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 23:04:59 INFO - Downloaded 1325 bytes. 23:04:59 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 23:04:59 INFO - Using the following test package requirements: 23:04:59 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 23:04:59 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 23:04:59 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 23:04:59 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 23:04:59 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'jsshell-mac.zip'], 23:04:59 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 23:04:59 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 23:04:59 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 23:04:59 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 23:04:59 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 23:04:59 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:04:59 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 23:04:59 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:04:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 23:04:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:04:59 INFO - Fetch https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 23:05:01 INFO - Content-Length response header: 56567513 23:05:01 INFO - Bytes received: 56567513 23:05:19 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 23:05:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 23:05:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:05:19 INFO - Fetch https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 23:05:21 INFO - Content-Length response header: 56567513 23:05:21 INFO - Bytes received: 56567513 23:05:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 23:05:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:05:38 INFO - Fetch https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 23:05:38 INFO - Content-Length response header: 14410001 23:05:38 INFO - Bytes received: 14410001 23: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')]} 23:05:39 INFO - trying https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:05:39 INFO - Downloading https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 23:05:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/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 23:05:41 INFO - Downloaded 71682677 bytes. 23:05:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:05:41 INFO - mkdir: /builds/slave/test/properties 23:05:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:05:41 INFO - Writing to file /builds/slave/test/properties/build_url 23:05:41 INFO - Contents: 23:05:41 INFO - build_url:https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:05:41 INFO - Running post-action listener: _resource_record_post_action 23:05:41 INFO - Running post-action listener: set_extra_try_arguments 23:05:41 INFO - [mozharness: 2017-05-18 06:05:41.544557Z] Finished download-and-extract step (success) 23:05:41 INFO - [mozharness: 2017-05-18 06:05:41.544713Z] Running populate-webroot step. 23:05:41 INFO - Running pre-action listener: _resource_record_pre_action 23:05:41 INFO - Running main action method: populate_webroot 23:05:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:05:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:05:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:05:41 INFO - '--tppagecycles', 23:05:41 INFO - '7'], 23:05:41 INFO - 'tests': ['tsvgm']}}, 23:05:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:05:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:05:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:05:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:05:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:05:41 INFO - 'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:05:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:05:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:05:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:05:41 INFO - 'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['damp', 'tps']}, 23:05:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:05:41 INFO - 'tests': ['damp', 'tps']}, 23:05:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['dromaeo_dom']}, 23:05:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:05:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 23:05:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 23:05:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 23:05:41 INFO - 'tests': ['ts_paint', 'tp5o']}, 23:05:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['a11yr', 23:05:41 INFO - 'ts_paint', 23:05:41 INFO - 'tpaint', 23:05:41 INFO - 'sessionrestore', 23:05:41 INFO - 'sessionrestore_no_auto_restore', 23:05:41 INFO - 'tabpaint']}, 23:05:41 INFO - 'other-e10s': {'tests': ['a11yr', 23:05:41 INFO - 'ts_paint', 23:05:41 INFO - 'tpaint', 23:05:41 INFO - 'sessionrestore', 23:05:41 INFO - 'sessionrestore_no_auto_restore', 23:05:41 INFO - 'tabpaint']}, 23:05:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:05:41 INFO - 'ts_paint', 23:05:41 INFO - 'tpaint', 23:05:41 INFO - 'sessionrestore', 23:05:41 INFO - 'sessionrestore_no_auto_restore', 23:05:41 INFO - 'tabpaint']}, 23:05:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:05:41 INFO - 'ts_paint', 23:05:41 INFO - 'tpaint', 23:05:41 INFO - 'sessionrestore', 23:05:41 INFO - 'sessionrestore_no_auto_restore', 23:05:41 INFO - 'tabpaint']}, 23:05:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['a11yr', 23:05:41 INFO - 'ts_paint', 23:05:41 INFO - 'tpaint', 23:05:41 INFO - 'sessionrestore', 23:05:41 INFO - 'sessionrestore_no_auto_restore', 23:05:41 INFO - 'tabpaint']}, 23:05:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['a11yr', 23:05:41 INFO - 'ts_paint', 23:05:41 INFO - 'tpaint', 23:05:41 INFO - 'sessionrestore', 23:05:41 INFO - 'sessionrestore_no_auto_restore', 23:05:41 INFO - 'tabpaint']}, 23:05:41 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 23:05:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 23:05:41 INFO - 'bloom_basic_ref']}, 23:05:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['tsvgx', 23:05:41 INFO - 'tsvgr_opacity', 23:05:41 INFO - 'tart', 23:05:41 INFO - 'tscrollx', 23:05:41 INFO - 'cart', 23:05:41 INFO - 'tsvg_static']}, 23:05:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:05:41 INFO - 'tsvgr_opacity', 23:05:41 INFO - 'tart', 23:05:41 INFO - 'tscrollx', 23:05:41 INFO - 'cart', 23:05:41 INFO - 'tsvg_static']}, 23:05:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:05:41 INFO - 'talos_options': ['--disable-e10s'], 23:05:41 INFO - 'tests': ['tp5o']}, 23:05:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:05:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:05:41 INFO - 'talos_options': ['--disable-e10s', 23:05:41 INFO - '--xperf_path', 23:05:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:05:41 INFO - 'tests': ['tp5n']}, 23:05:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:05:41 INFO - 'talos_options': ['--xperf_path', 23:05:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:05:41 INFO - 'tests': ['tp5n']}}, 23:05:41 INFO - 'talos.zip': {'path': '', 23:05:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:05:41 INFO - Running post-action listener: _resource_record_post_action 23:05:41 INFO - [mozharness: 2017-05-18 06:05:41.566467Z] Finished populate-webroot step (success) 23:05:41 INFO - [mozharness: 2017-05-18 06:05:41.566630Z] Running create-virtualenv step. 23:05:41 INFO - Running pre-action listener: _resource_record_pre_action 23:05:41 INFO - Running main action method: create_virtualenv 23:05:41 INFO - Creating virtualenv /builds/slave/test/build/venv 23:05:41 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 23:05:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:05:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:05:41 INFO - Using real prefix '/tools/python27' 23:05:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:05:42 INFO - Installing distribute.............................................................................................................................................................................................done. 23:05:45 INFO - Installing pip.................done. 23:05:45 INFO - Return code: 0 23:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:05:45 INFO - Reading from file tmpfile_stdout 23:05:45 INFO - Output received: 23:05:45 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) 23:05:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23: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')]} 23:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23: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')]} 23:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23: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', 'pip>=1.5'] in /builds/slave/test/build 23: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 pip>=1.5 23:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:45 INFO - 'HOME': '/Users/cltbld', 23:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:45 INFO - 'LOGNAME': 'cltbld', 23:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:45 INFO - 'MOZ_NO_REMOTE': '1', 23:05:45 INFO - 'NO_EM_RESTART': '1', 23:05:45 INFO - 'PAGER': '/bin/cat', 23:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:45 INFO - 'PWD': '/builds/slave/test', 23:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:45 INFO - 'SHELL': '/bin/bash', 23:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:05:45 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:45 INFO - 'USER': 'cltbld', 23:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:45 INFO - 'XPC_FLAGS': '0x0', 23:05:45 INFO - 'XPC_SERVICE_NAME': '0', 23:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:45 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 23:05:45 INFO - Cleaning up... 23:05:45 INFO - Return code: 0 23:05:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23: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')]} 23:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23: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')]} 23:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23: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', '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 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23: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', 'psutil>=3.1.1'] in /builds/slave/test/build 23: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 psutil>=3.1.1 23:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:45 INFO - 'HOME': '/Users/cltbld', 23:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:45 INFO - 'LOGNAME': 'cltbld', 23:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:45 INFO - 'MOZ_NO_REMOTE': '1', 23:05:45 INFO - 'NO_EM_RESTART': '1', 23:05:45 INFO - 'PAGER': '/bin/cat', 23:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:45 INFO - 'PWD': '/builds/slave/test', 23:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:45 INFO - 'SHELL': '/bin/bash', 23:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:05:45 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:45 INFO - 'USER': 'cltbld', 23:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:45 INFO - 'XPC_FLAGS': '0x0', 23:05:45 INFO - 'XPC_SERVICE_NAME': '0', 23:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:46 INFO - Downloading/unpacking psutil>=3.1.1 23:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:05:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:05:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:05:47 INFO - Installing collected packages: psutil 23:05:47 INFO - Running setup.py install for psutil 23:05:48 INFO - building 'psutil._psutil_osx' extension 23:05:48 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 23:05:48 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 23:05:48 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 23:05:48 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 23:05:48 INFO - building 'psutil._psutil_posix' extension 23:05:48 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 23:05:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:05:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:05:48 INFO - ^ 23:05:48 INFO - 1 warning generated. 23:05:48 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 23:05:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:05:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:05:49 INFO - Successfully installed psutil 23:05:49 INFO - Cleaning up... 23:05:49 INFO - Return code: 0 23:05:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:05:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:50 INFO - 'HOME': '/Users/cltbld', 23:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:50 INFO - 'LOGNAME': 'cltbld', 23:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:50 INFO - 'MOZ_NO_REMOTE': '1', 23:05:50 INFO - 'NO_EM_RESTART': '1', 23:05:50 INFO - 'PAGER': '/bin/cat', 23:05:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:50 INFO - 'PWD': '/builds/slave/test', 23:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:50 INFO - 'SHELL': '/bin/bash', 23:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:05:50 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:50 INFO - 'USER': 'cltbld', 23:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:50 INFO - 'XPC_FLAGS': '0x0', 23:05:50 INFO - 'XPC_SERVICE_NAME': '0', 23:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:05:52 INFO - Installing collected packages: mozsystemmonitor 23:05:52 INFO - Running setup.py install for mozsystemmonitor 23:05:52 INFO - Successfully installed mozsystemmonitor 23:05:52 INFO - Cleaning up... 23:05:52 INFO - Return code: 0 23:05:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:05:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:05:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:52 INFO - 'HOME': '/Users/cltbld', 23:05:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:52 INFO - 'LOGNAME': 'cltbld', 23:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:52 INFO - 'MOZ_NO_REMOTE': '1', 23:05:52 INFO - 'NO_EM_RESTART': '1', 23:05:52 INFO - 'PAGER': '/bin/cat', 23:05:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:52 INFO - 'PWD': '/builds/slave/test', 23:05:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:52 INFO - 'SHELL': '/bin/bash', 23:05:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:05:52 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:05:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:52 INFO - 'USER': 'cltbld', 23:05:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:52 INFO - 'XPC_FLAGS': '0x0', 23:05:52 INFO - 'XPC_SERVICE_NAME': '0', 23:05:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:52 INFO - Downloading/unpacking jsonschema==2.5.1 23:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:05:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:54 INFO - Downloading functools32-3.2.3-2.tar.gz 23:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:05:54 INFO - warning: no files found matching '*.txt' 23:05:54 INFO - no previously-included directories found matching 'build' 23:05:54 INFO - no previously-included directories found matching 'dist' 23:05:54 INFO - no previously-included directories found matching '.git*' 23:05:54 INFO - Installing collected packages: jsonschema, functools32 23:05:54 INFO - Running setup.py install for functools32 23:05:54 INFO - warning: no files found matching '*.txt' 23:05:54 INFO - no previously-included directories found matching 'build' 23:05:54 INFO - no previously-included directories found matching 'dist' 23:05:54 INFO - no previously-included directories found matching '.git*' 23:05:54 INFO - Successfully installed jsonschema functools32 23:05:54 INFO - Cleaning up... 23:05:54 INFO - Return code: 0 23:05:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:05:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:05:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:05:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:54 INFO - 'HOME': '/Users/cltbld', 23:05:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:54 INFO - 'LOGNAME': 'cltbld', 23:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:54 INFO - 'MOZ_NO_REMOTE': '1', 23:05:54 INFO - 'NO_EM_RESTART': '1', 23:05:54 INFO - 'PAGER': '/bin/cat', 23:05:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:54 INFO - 'PWD': '/builds/slave/test', 23:05:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:54 INFO - 'SHELL': '/bin/bash', 23:05:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:05:54 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:05:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:54 INFO - 'USER': 'cltbld', 23:05:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:54 INFO - 'XPC_FLAGS': '0x0', 23:05:54 INFO - 'XPC_SERVICE_NAME': '0', 23:05:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:05:55 INFO - Cleaning up... 23:05:55 INFO - Return code: 0 23:05:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:05:55 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')]} 23:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:55 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')]} 23:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:55 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 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:05:55 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 23:05:55 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 23:05:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:05:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:55 INFO - 'HOME': '/Users/cltbld', 23:05:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:55 INFO - 'LOGNAME': 'cltbld', 23:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:55 INFO - 'MOZ_NO_REMOTE': '1', 23:05:55 INFO - 'NO_EM_RESTART': '1', 23:05:55 INFO - 'PAGER': '/bin/cat', 23:05:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:55 INFO - 'PWD': '/builds/slave/test', 23:05:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:55 INFO - 'SHELL': '/bin/bash', 23:05:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:05:55 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:05:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:55 INFO - 'USER': 'cltbld', 23:05:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:55 INFO - 'XPC_FLAGS': '0x0', 23:05:55 INFO - 'XPC_SERVICE_NAME': '0', 23:05:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:55 INFO - Downloading/unpacking blobuploader==1.2.4 23:05:55 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 23:05:55 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 23:05:55 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 23:05:55 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 23:05:56 INFO - Downloading blobuploader-1.2.4.tar.gz 23:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:05:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:05:56 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 23:05:56 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 23:05:56 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 23:05:56 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 23:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:05:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:05:57 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 23:05:57 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 23:05:57 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 23:05:57 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 23:05:57 INFO - Downloading docopt-0.6.1.tar.gz 23:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:05:57 INFO - Installing collected packages: blobuploader, requests, docopt 23:05:57 INFO - Running setup.py install for blobuploader 23:05:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:05:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:05:57 INFO - Running setup.py install for requests 23:05:58 INFO - Running setup.py install for docopt 23:05:58 INFO - Successfully installed blobuploader requests docopt 23:05:58 INFO - Cleaning up... 23:05:58 INFO - Return code: 0 23:05:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:05:58 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')]} 23:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:58 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')]} 23:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:58 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 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:05:58 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 23:05:58 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 23:05:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:05:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:58 INFO - 'HOME': '/Users/cltbld', 23:05:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:58 INFO - 'LOGNAME': 'cltbld', 23:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:58 INFO - 'MOZ_NO_REMOTE': '1', 23:05:58 INFO - 'NO_EM_RESTART': '1', 23:05:58 INFO - 'PAGER': '/bin/cat', 23:05:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:58 INFO - 'PWD': '/builds/slave/test', 23:05:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:58 INFO - 'SHELL': '/bin/bash', 23:05:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:05:58 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:05:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:58 INFO - 'USER': 'cltbld', 23:05:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:58 INFO - 'XPC_FLAGS': '0x0', 23:05:58 INFO - 'XPC_SERVICE_NAME': '0', 23:05:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:05:58 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-F_0mHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-nYkyjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-ctG82M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-Im_aCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-olCTsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-hWauG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-4CP0Pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-eJCWMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-aO5VFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-gOQoDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:05:59 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-8lr_CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:06:00 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-rdd5V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:06:00 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-IXA4y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:06:00 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-B1yEju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:06:00 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-Zy3qOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:06:00 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-Mbxz5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:06:00 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-YxImYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:06:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:06:00 INFO - Running setup.py install for manifestparser 23:06:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:06:00 INFO - Running setup.py install for mozcrash 23:06:00 INFO - Running setup.py install for mozdebug 23:06:01 INFO - Running setup.py install for mozdevice 23:06:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:06:01 INFO - Running setup.py install for mozfile 23:06:01 INFO - Running setup.py install for mozhttpd 23:06:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:06:01 INFO - Running setup.py install for mozinfo 23:06:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:06:01 INFO - Running setup.py install for mozInstall 23:06:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:06:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:06:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:06:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:06:01 INFO - Running setup.py install for mozleak 23:06:01 INFO - Running setup.py install for mozlog 23:06:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:06:02 INFO - Running setup.py install for moznetwork 23:06:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:06:02 INFO - Running setup.py install for mozprocess 23:06:02 INFO - Running setup.py install for mozprofile 23:06:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:06:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:06:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:06:02 INFO - Running setup.py install for mozrunner 23:06:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:06:02 INFO - Running setup.py install for mozscreenshot 23:06:02 INFO - Running setup.py install for moztest 23:06:03 INFO - Running setup.py install for mozversion 23:06:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:06:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:06:03 INFO - Cleaning up... 23:06:03 INFO - Return code: 0 23:06:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:03 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 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:06:03 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 23:06:03 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 23:06:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:06:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:03 INFO - 'HOME': '/Users/cltbld', 23:06:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:03 INFO - 'LOGNAME': 'cltbld', 23:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:03 INFO - 'MOZ_NO_REMOTE': '1', 23:06:03 INFO - 'NO_EM_RESTART': '1', 23:06:03 INFO - 'PAGER': '/bin/cat', 23:06:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:03 INFO - 'PWD': '/builds/slave/test', 23:06:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:03 INFO - 'SHELL': '/bin/bash', 23:06:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:06:03 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:06:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:03 INFO - 'USER': 'cltbld', 23:06:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:03 INFO - 'XPC_FLAGS': '0x0', 23:06:03 INFO - 'XPC_SERVICE_NAME': '0', 23:06:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:06:03 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-umIRr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:06:03 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)) 23:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:06:03 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-Fm1NPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:06:03 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)) 23:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:06:03 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-G6Zqz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:06:03 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)) 23:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:06:03 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-3RUX5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 23:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:06:03 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-Qg57Zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-vZ4vTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-xIElaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-chVHtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-q3Z_2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-PF3ZeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-hhy3Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-KQM3Ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-N8vqJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:06:04 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)) 23:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:06:04 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-s7YUZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:06:05 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)) 23:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:06:05 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-Vs17SR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:06:05 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)) 23:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:06:05 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-WZYqMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:06:05 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)) 23:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:06:05 INFO - Running setup.py (path:/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/pip-dgTzY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:06:05 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)) 23:06:05 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)) 23:06:05 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)) 23:06:05 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)) 23:06:05 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:06:05 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:06:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:06 INFO - Downloading blessings-1.6.tar.gz 23:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:06:06 INFO - Installing collected packages: blessings 23:06:06 INFO - Running setup.py install for blessings 23:06:06 INFO - Successfully installed blessings 23:06:06 INFO - Cleaning up... 23:06:07 INFO - Return code: 0 23:06:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:06:07 INFO - Reading from file tmpfile_stdout 23:06:07 INFO - Current package versions: 23:06:07 INFO - blessings == 1.6 23:06:07 INFO - blobuploader == 1.2.4 23:06:07 INFO - docopt == 0.6.1 23:06:07 INFO - functools32 == 3.2.3-2 23:06:07 INFO - jsonschema == 2.5.1 23:06:07 INFO - manifestparser == 1.1 23:06:07 INFO - mozInstall == 1.12 23:06:07 INFO - mozcrash == 1.0 23:06:07 INFO - mozdebug == 0.1 23:06:07 INFO - mozdevice == 0.50 23:06:07 INFO - mozfile == 1.2 23:06:07 INFO - mozhttpd == 0.7 23:06:07 INFO - mozinfo == 0.9 23:06:07 INFO - mozleak == 0.1 23:06:07 INFO - mozlog == 3.4 23:06:07 INFO - moznetwork == 0.27 23:06:07 INFO - mozprocess == 0.25 23:06:07 INFO - mozprofile == 0.28 23:06:07 INFO - mozrunner == 6.13 23:06:07 INFO - mozscreenshot == 0.1 23:06:07 INFO - mozsystemmonitor == 0.3 23:06:07 INFO - moztest == 0.8 23:06:07 INFO - mozversion == 1.4 23:06:07 INFO - psutil == 3.1.1 23:06:07 INFO - requests == 1.2.3 23:06:07 INFO - wsgiref == 0.1.2 23:06:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:06:07 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')]} 23:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:07 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')]} 23:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:07 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 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:06:07 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 23:06:07 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 23:06:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:06:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:07 INFO - 'HOME': '/Users/cltbld', 23:06:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:07 INFO - 'LOGNAME': 'cltbld', 23:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:07 INFO - 'MOZ_NO_REMOTE': '1', 23:06:07 INFO - 'NO_EM_RESTART': '1', 23:06:07 INFO - 'PAGER': '/bin/cat', 23:06:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:07 INFO - 'PWD': '/builds/slave/test', 23:06:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:07 INFO - 'SHELL': '/bin/bash', 23:06:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:06:07 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:06:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:07 INFO - 'USER': 'cltbld', 23:06:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:07 INFO - 'XPC_FLAGS': '0x0', 23:06:07 INFO - 'XPC_SERVICE_NAME': '0', 23:06:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 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)) 23:06:07 INFO - Cleaning up... 23:06:07 INFO - Return code: 0 23:06:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:06:07 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')]} 23:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:07 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')]} 23:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:07 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 0x10465cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104660768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a5a4c1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, '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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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 23:06:07 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 23:06:07 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 23:06:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:06:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:07 INFO - 'HOME': '/Users/cltbld', 23:06:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:07 INFO - 'LOGNAME': 'cltbld', 23:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:07 INFO - 'MOZ_NO_REMOTE': '1', 23:06:07 INFO - 'NO_EM_RESTART': '1', 23:06:07 INFO - 'PAGER': '/bin/cat', 23:06:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:07 INFO - 'PWD': '/builds/slave/test', 23:06:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:07 INFO - 'SHELL': '/bin/bash', 23:06:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:06:07 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:06:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:07 INFO - 'USER': 'cltbld', 23:06:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:07 INFO - 'XPC_FLAGS': '0x0', 23:06:07 INFO - 'XPC_SERVICE_NAME': '0', 23:06:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:06:07 INFO - Cleaning up... 23:06:07 INFO - Return code: 0 23:06:07 INFO - Running post-action listener: _resource_record_post_action 23:06:07 INFO - Running post-action listener: _start_resource_monitoring 23:06:07 INFO - Starting resource monitoring. 23:06:07 INFO - [mozharness: 2017-05-18 06:06:07.899054Z] Finished create-virtualenv step (success) 23:06:07 INFO - [mozharness: 2017-05-18 06:06:07.899981Z] Running install step. 23:06:07 INFO - Running pre-action listener: _resource_record_pre_action 23:06:07 INFO - Running main action method: install 23:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:06:08 INFO - Reading from file tmpfile_stdout 23:06:08 INFO - Detecting whether we're running mozinstall >=1.0... 23:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:06:08 INFO - Reading from file tmpfile_stdout 23:06:08 INFO - Output received: 23:06:08 INFO - Usage: mozinstall [options] installer 23:06:08 INFO - Options: 23:06:08 INFO - -h, --help show this help message and exit 23:06:08 INFO - -d DEST, --destination=DEST 23:06:08 INFO - Directory to install application into. [default: 23:06:08 INFO - "/builds/slave/test"] 23:06:08 INFO - --app=APP Application being installed. [default: firefox] 23:06:08 INFO - mkdir: /builds/slave/test/build/application 23:06:08 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'] 23:06:08 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 23:06:30 INFO - Reading from file tmpfile_stdout 23:06:30 INFO - Output received: 23:06:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:06:30 INFO - Running post-action listener: _resource_record_post_action 23:06:30 INFO - [mozharness: 2017-05-18 06:06:30.686946Z] Finished install step (success) 23:06:30 INFO - [mozharness: 2017-05-18 06:06:30.687085Z] Running run-tests step. 23:06:30 INFO - Running pre-action listener: _resource_record_pre_action 23:06:30 INFO - Running main action method: run_tests 23:06:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:06:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:06:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:06:30 INFO - 2017-05-17 23:06:30.701 screenresolution[1621:7349] starting screenresolution argv=screenresolution get 23:06:30 INFO - 2017-05-17 23:06:30.704 screenresolution[1621:7349] Display 0: 1600x1200x32@60 23:06:30 INFO - 2017-05-17 23:06:30.709 screenresolution[1622:7351] starting screenresolution argv=screenresolution list 23:06:30 INFO - Available Modes on Display 0 23:06:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:06:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:06:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:06:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:06:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:06:30 INFO - Intel Iris: 23:06:30 INFO - Chipset Model: Intel Iris 23:06:30 INFO - Type: GPU 23:06:30 INFO - Bus: Built-In 23:06:30 INFO - VRAM (Dynamic, Max): 1536 MB 23:06:30 INFO - Vendor: Intel (0x8086) 23:06:30 INFO - Device ID: 0x0a2e 23:06:30 INFO - Revision ID: 0x0009 23:06:30 INFO - Displays: 23:06:30 INFO - Display: 23:06:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:06:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:06:30 INFO - Display Serial Number: 1600x1200 60 23:06:30 INFO - Main Display: Yes 23:06:30 INFO - Mirror: Off 23:06:30 INFO - Online: Yes 23:06:30 INFO - Rotation: Supported 23:06:30 INFO - Return code: 0 23:06:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:06:30 INFO - Python 2.7.3 23:06:30 INFO - Return code: 0 23:06:30 INFO - grabbing minidump binary from tooltool 23:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:06:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10436cd30>, '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 0x104320db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104361be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:06:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:06:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 23:06:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:06:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:06:31 INFO - Return code: 0 23:06:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:06:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:06:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:06:31 INFO - ENV: RUST_BACKTRACE is now 1 23:06:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:06:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:06:31 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/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--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 23:06:31 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/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0059 --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 23:06:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GeAY0AQB1U/Render', 23:06:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:31 INFO - 'HOME': '/Users/cltbld', 23:06:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:31 INFO - 'LOGNAME': 'cltbld', 23:06:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:06:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:31 INFO - 'MOZ_NO_REMOTE': '1', 23:06:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:06:31 INFO - 'NO_EM_RESTART': '1', 23:06:31 INFO - 'PAGER': '/bin/cat', 23:06:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:31 INFO - 'PWD': '/builds/slave/test', 23:06:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:06:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:31 INFO - 'RUST_BACKTRACE': '1', 23:06:31 INFO - 'SHELL': '/bin/bash', 23:06:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H9bgWvNMkm/Listeners', 23:06:31 INFO - 'TMPDIR': '/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/', 23:06:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:31 INFO - 'USER': 'cltbld', 23:06:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:31 INFO - 'XPC_FLAGS': '0x0', 23:06:31 INFO - 'XPC_SERVICE_NAME': '0', 23:06:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:31 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/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--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 23:06:31 INFO - mozversion application_buildid: 20170517210544 23:06:31 INFO - mozversion application_changeset: 0b529227685dc071774480f756bf41dff7068452 23:06:31 INFO - mozversion application_display_name: Nightly 23:06:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:06:31 INFO - mozversion application_name: Firefox 23:06:31 INFO - mozversion application_remotingname: firefox 23:06:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:06:31 INFO - mozversion application_vendor: Mozilla 23:06:31 INFO - mozversion application_version: 55.0a1 23:06:31 INFO - mozversion platform_buildid: 20170517210544 23:06:31 INFO - mozversion platform_changeset: 0b529227685dc071774480f756bf41dff7068452 23:06:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:06:31 INFO - mozversion platform_version: 55.0a1 23:06:31 INFO - using testdate: 1495087591 23:06:31 INFO - actual date: 1495087591 23:06:31 INFO - starting webserver on 'localhost:49222' 23:06:31 INFO - SUITE-START | Running 2 tests 23:06:31 INFO - TEST-START | dromaeo_css 23:06:31 INFO - operating with platform_type : mac_ 23:06:31 INFO - Initialising browser for dromaeo_css test... 23:06:31 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/tmpajqLDF/profile http://localhost:49222/getInfo.html) 23:06:31 INFO - PID 1632 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2939: String.localeCompare is deprecated; use String.prototype.localeCompare instead 23:06:33 INFO - PID 1632 | 2017-05-17 23:06:33.693 plugin-container[1634:7520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 23:06:33 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:06:33 INFO - PID 1632 | 2017-05-17 23:06:33.696 plugin-container[1634:7520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa20b, name = 'com.apple.CFPasteboardClient' 23:06:33 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:06:33 INFO - PID 1632 | 2017-05-17 23:06:33.696 plugin-container[1634:7520] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:06:33 INFO - PID 1632 | __metrics Screen width/height:1600/1200 23:06:33 INFO - PID 1632 | colorDepth:24 23:06:33 INFO - PID 1632 | Browser inner width/height: 1024/691 23:06:33 INFO - PID 1632 | __metrics 23:06:34 INFO - PID 1632 | 23:06:34 INFO - PID 1632 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 23:06:34 INFO - PID 1632 | 23:06:34 INFO - TEST-INFO | 1632: exit 0 23:06:34 INFO - Browser initialized. 23:06:34 INFO - Running cycle 1/1 for dromaeo_css test... 23:06:34 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/tmpajqLDF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:06:36 INFO - PID 1636 | 2017-05-17 23:06:36.579 plugin-container[1637:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 23:06:36 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:06:36 INFO - PID 1636 | 2017-05-17 23:06:36.579 plugin-container[1637:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b1f, name = 'com.apple.CFPasteboardClient' 23:06:36 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:06:36 INFO - PID 1636 | 2017-05-17 23:06:36.579 plugin-container[1637:7666] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:06:37 INFO - PID 1636 | 2017-05-17 23:06:37.273 plugin-container[1638:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 23:06:37 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:06:37 INFO - PID 1636 | 2017-05-17 23:06:37.273 plugin-container[1638:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 23:06:37 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:06:37 INFO - PID 1636 | 2017-05-17 23:06:37.273 plugin-container[1638:7712] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:06:46 INFO - PID 1636 | RSS: Main: 237957120 23:06:46 INFO - PID 1636 | 23:09:49 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 23:09:51 INFO - PID 1636 | RSS: Main: 263622656 23:09:51 INFO - PID 1636 | 23:12:52 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 23:12:53 INFO - PID 1636 | RSS: Main: 271745024 23:12:53 INFO - PID 1636 | 23:15:55 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 23:15:55 INFO - PID 1636 | RSS: Main: 276246528 23:15:55 INFO - PID 1636 | 23:18:57 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 23:18:58 INFO - PID 1636 | RSS: Main: 274128896 23:18:58 INFO - PID 1636 | 23:22:01 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 23:22:01 INFO - PID 1636 | RSS: Main: 276365312 23:22:01 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 23:25:03 INFO - PID 1636 | RSS: Main: 277635072 23:25:03 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | __start_tp_report 23:25:03 INFO - PID 1636 | _x_x_mozilla_page_load 23:25:03 INFO - PID 1636 | _x_x_mozilla_page_load_details 23:25:03 INFO - PID 1636 | |i|pagename|runs| 23:25:03 INFO - PID 1636 | |0;dojo.html;2882.4523340565293;3532.823358832061;3540.2919416116765;3547.3082748863967;3582.5521809773777;6037.39626037396;6107.328193898667;6194.907076393858;6196.349383314755;6221.968890155548;6160.599561028541;6171.783987560425;6172.197614310145;6185.969070154654;6209.254889413265;5355.946440535584;5407.134858422653;5422.457754224575;5433.022056029913;5450.29146210994;5466.125419932811;5507.091329930557;5522.171674248854;5522.5581953443825;5550.195221692852;5318.601104917108;5387.946120538783;5483.506484416422;5484.61607687458;5528.198411230369;5417.918731219035;5495.203195536665;5502.119660854244;5505;5515.696636684981;5391.32608423947;5402.405735369106;5435.864103397407;5442.455754424565;5455;5962.582619216657;6203.7828675996125;6204.317525072239;6214.844628884269;6226.252849658024;5356.35723713153;6467;6527.543071984963;6554.80335589933;6626.83432914181;6327.525435592349;6787.287334829821;6808.863822723567;6808.931910680879;6809.319068093151;5516.310461192351;6233.096201050822;6284.905726414107;6285.497160227171;6288.685565721741;6085.391460853879;6736.427403670743;6802.319768023158;6905.309469053155;6925.826854328581;6512.283648798629;6551.836204094936;6582.506312026521;6602.636854972927;6614.10709554204;6499.8700025999215;6754.3921047106;6774.187097548326;6779.525433219627;6784.830379240559;6460.806175814733;6547;6659.600423974577;6675.966620166868;6700.865982680319;6413.102165696806;6516.804495865132;6684.598924064571;6687.799366019027;6699.162604674416;4504.797284122223;5142.665726727751;5314.176302673091;5389.892202156013;5398.757055932492;4725;4730.101280756645;4733.479317275097;4741.336212930192;4767.976160119247;6195.4424101831055;6295.905561416583;6313.147725057151;6323.083152942798;6332.176817013851;5903.409659034062;6284.12022316876;6667.19993600771;6759.763408280785;6777.898331525031;6217.782377616762;6285.77999770015;6303.495720342362;6307.779227727007;6335.23977122739;5874.911876321773;5924.5852790305125;5941.524678025748;5945.316288626776;5995.670238136944;6680.5991640501725;6721.395074443323;6807.965960170168;6808.353206445379;6904.861902761816;5580.609357345109;6710.362515561014;6718.428933540508;6741.426978706865;6742.460603151638;6094.878102438015;6684.83287917806;6847.760328388483;6917.066196063668;6938.653067346511;5387.892242155213;6423.454006409321;6463.418292353711;6496.187976502937;6616.272210056986;6701.32986701326;6740.090087838079;6745.52781305304;6791;6798.252192258947;5300.575953923754;6520.250171230179;6547.901781473377;6553.4101930826455;6579.309172536815;5285.841424757264;6402.1357116790505;6409.35906409374;6482.157319548617;6485.77299794505;5419;6346.143270658402;6405.903911441425;6446.774362897276;6470.773522926864;6540.640264785482;6554.737810487527;6592.274849766426;6603.603783772989;6605.834854128685;6217.502599792097;6289.811305660837;6322.525810564318;6415.230172379344;6442.645654488861 23:25:03 INFO - PID 1636 | |1;ext.html;618.4619381138409;751.3350684644088;809.9514029158249;1215.4105258949407;1275.5790589105595;37224.25551488972;38207.23585528291;38302;38327.42508862369;38440.80779596098;38021.809890950506;38139;38243.80878095605;38447.80776096122;38472.80763596185;7679.654415551299;7832.060152781674;7859.6463159157975;8088.676452941875;8194.713185038525;8177.018757749049;8270.917290827105;8297.128801475848;8303.750887473385;8315.750527484186;8231.83536329273;8294.958525207365;8297.543635100066;8304.543250121242;8353.289970352527;8194.795130121795;8216.630251638691;8258.545779982129;8280.710175143871;8296.460730052528;8105.31104856082;8165.795855103611;8186.877196841992;8188.631511581993;8189.8362032759605;35578.8221058893;35810;35876.82061589701;35909.64090359089;35972.46041309356;3623.981880090609;3673.430618254175;3678.8712395066045;3686.5391826021746;3689.262147570497;29367.85316073406;29540.113796585923;29554.408911821643;29569;29579.556306655417;3086.9691303086906;3104.4567200740007;3107.254258977829;3125.109343837018;3145.7168854803176;245473;297901;298264;310776;312839;44612.776936115115;44816.55183448189;44997.77501112474;45125;45149.87125321893;46033;46523;46703;46807.829804254485;46925.5307446928;12315.50737970489;13279.535216267384;13371.598852034454;13382.197068175941;13388.799168012487;11099.722506937229;11104.944475277573;11114.277571957797;11115.055220306203;11151.940565646251;12468.625941221779;12645.557405490763;12675.873241267655;12680.112392132647;12798.936005319914;4954.058728841508;5883.117532370179;5897.734601942923;5908.940910590839;5952.970235148883;1023.370627064365;1040.9687709368732;1044.6657069737612;1065.5844220753913;1069.1393428290146;22849.657255141185;24010.19923505744;24093.156739514034;24195.637065444032;24195.637065444032;1004.4927311707687;1130.8982191602631;1156.7686462707325;1185.25921299188;1188.9048876089892;12302.384880755926;12354.320512371905;12374.690632734073;12378.071644626689;12387.132900696864;284519.57740211167;293409;317264;319707.8029219678;321214;43429.34855977163;44363.778181109534;46792.06415871663;48387.75806120947;50151.498485015414;1502.4741340530509;1522.7639715844061;1543.5523698127643;1550.7983962084857;1556.4785796758213;17885.0163241023;20467.07898144557;20726.378208653765;23774;23848.16531421392;36134.27731445409;36149.27701446009;36245.27509449849;36283;36289.45565816462;262758;262776;263456.0481592816;263596;264338;31761.841190793897;32011.519827202144;33341.83329083339;34882.825585872924;35102;41228.38157427701;43215;43524.782376089184;43635.34546981861;43756;29063;29127.85436072806;29645.9623913162;30874.382512349177;30887.764489420173;24718.899432065264;27758;28003.299917502227;30339.544906826857;30486.237844054078;34710.65289347074;35100.473492897116;35611.82194089013;36146.638533615376;36563.537458502426;28824.711752883042;29472.263193420335;30208.09375718732;30277.545836812023;30333.241668958453;22622;23240.140788737197;23499.29502114939;23589.52820943606;23817.166399175945 23:25:03 INFO - PID 1636 | |2;jquery.html;384.62883317598516;424.55633862613564;427.85880659382406;429.32595824555443;447.0767864360096;16090.436834710787;20441.795582044175;20922.581548369024;21048.368548943556;21183.258585949494;19131.808681913175;20724.067416966234;20821.375358739224;21026.579468410622;21247.468813279676;4898.681585696918;6085.6003119282595;6092;6128.264608247016;6147.969260153693;5341.359036915575;5865.677387743673;6045.6674882881425;6052.001419765756;6066.423689749467;6058.697065146747;6065.120557519157;6071.719437796365;6078.240219972504;6079.48324392425;4672.883177920545;5957.314908785458;5961.940380596204;6038.426349496791;6042.728077236513;5333.759980800874;5358.196270559409;5363.463653634644;5369.221462887859;5382.30030096085;25412;26562.468750625074;26810.195694129012;27612.309692257855;27772.444551108863;2197.3298144066052;2246.3597874605657;2260.400993736661;2263.6038693228784;2266.05958527211;14236.786448203286;16950.3219871205;17514.562135946577;17875.821241787547;18909.81090189094;1807.4396936949588;2048.549319149785;2092.9476763080893;2122.7983341582526;2193.5283913958574;140538.8919166203;153236;164322;166855;167021.3297867018;10193.694189174337;10233.48832558369;12447.564335248222;12628.368581570885;12737.87262127367;16049.358025678843;16101.19494025294;16345.918270408572;16346.918265408834;16353.26410311515;8596;8869.423487473294;8882.200601945857;9004.50475223869;9042.502662353501;8327.541985190872;8341.41610087288;8343.374246931504;8490.15098490146;8502.957485212535;7126.109236345457;7513;7880.921190788134;8019.43863929531;8024.719134830253;2138.925137620176;2151.3223334649847;2162.329677799887;2162.740471143473;2355.905763769464;827.6027506796771;967.6129548180643;989.9308746553849;1002.2733518199393;1013.6148263659762;19560.510987225432;20762.584748305253;21068.26261080855;21269.68095478569;21306.680399794015;991.2714155095998;996.5415908682088;998.7453199434098;1020.6886899495726;1032.4218437674926;9054.86417703735;10651.360918344924;10761;10833.620823271147;10854.29447085937;447436;492710.5364473155;511512.4243787796;520477;537727;113641;116431.8356816421;117428;120747.58504829854;123629;1133.1784456269138;1134.262729225993;1181.6750393641817;1183.3373310945897;1183.526589364261;17859.553511161805;18095.45713628593;18115.728264075788;18402.539936501693;18451.538711532317;53826.73086634698;54301;54438.45561544334;54652.08717694862;54840.35478935638;70107;70649.64675176592;70677.29322706912;71422.28577714156;72029;8335.08314085438;8532.44539104981;8878;8960.1935825776;8972.596233169654;13052.976788597782;13346;13531.391087401287;13585.913126950018;13591.86408135906;9789.657361992297;11517.942410287895;11761.88238117608;11988.820167697666;12036;9505.762355941157;9788.82534095913;10775.676729698122;10899.618513351994;10995.725106872393;9696.9030309696;10952.123830093733;11072.280301780358;11748.412579371168;11950.223235489666;7705.383569314329;8226.341892648454;8690.522021288889;8786.780330491789;8865;9680.419174849532;11141.610043648434;11184.273022253528;11448;11545.307281563133 23:25:03 INFO - PID 1636 | |3;mootools.html;1406.570995846267;1467.1197281631016;1486.821581410231;1493.4474244529533;1503.6090616439722;10069.798604027934;10351.792964140712;11321.377324247163;11617.941910290447;11811.055115590754;11425.771484570325;11642.301461912291;11646.592369267077;11647.941760291207;11651.009664178533;2463.4703538739213;2508.3603681061304;2523.8107141964424;2528.1404322530334;2530.544501989641;2446.388402899275;2508.3352911478382;2509.912153074643;2510.535550923085;2510.899564017437;2464.408541949937;2468.469279104992;2468.790152837011;2488.726240113586;2492.190038237571;2453.595156799126;2461.729209786922;2462.495188486356;2464.8767561621935;2466.0382450844186;2119.74934788474;2222.4332795137298;2414.167112346238;2438.3904023994;2449.020391843277;11377.2035957483;11572.47923843429;11575.884241157564;11587.957083862408;11599.246049006788;6427.260865000491;6602.60384376939;6678.966605166943;6680.565763225375;6680.86638267232;3991.2217117662067;4276.18752437036;4289.485261768607;4338.631216346615;4344.826206951718;1165.7260543772206;1218.3603608105727;1262.2300396757971;1271.1229251816217;1276.8276282701809;15927.203639818079;16233.2695028724;16341.836581634268;16365.509034728977;16401.753973690404;5090.440051594322;5641.984442800335;5812.360640329561;6012.458878700938;6117.051856962089;13254.801177982337;13325.66685832862;13353.866461335456;13375.665608359868;13400.731985360433;6583.604983700898;6618.768343107968;6637.86724265512;6647.002949557508;6647.135872336662;5131.281620573122;6067.605605635619;6142.938570614326;6206.565540412218;6207.96896015517;4509.458864936229;4624;4703.412073490814;4734.668573199911;4754.072955773591;1200.6398080575793;1201.8677945425995;1382.5161193582367;1455.105110357144;1466.4280930437137;661.6427129350097;795.3597354129981;818.378032695156;831.7962099285713;834.8246868157755;5466.753996070186;6637.6349300788925;6694.694534565713;6733.3939945405145;6830.453563714891;923.3075193604797;929.4702019848756;964.8890377606525;971.5190980464574;978.8776402949632;3403.0602556543026;3797.9430308545393;3797.981010094987;4414.359917811899;4493.865184044484;12193.817092743617;14164.929175354056;14799.334029968677;15046.623834404227;15047.924760376129;11521.366324852046;12870.841624253675;14949.103053816805;15000.174990375634;15013.924930375279;8391.202835730595;8450.450720703135;8560.357973152039;8597.656093756179;8629.827403451896;6582;6589.110470086669;6822.556533825285;6834.077399551195;6894.276101009322;13178.934105329412;13228.470861165446;13245.337733113498;13502.32488375597;13541.255230962392;13570.59288221355;13687.315634218448;13689.794653080013;13937.930310348384;13974;8209.589520524069;8492.872606910778;9135.31485138604;9461.621535138518;9507.809843803223;10814.29707069038;10890.128789696646;10899.509522071685;10907.945460272647;10926.890731092906;8887.600057997277;9103;9155.267578593563;9260.722178334661;9293.210077143249;8889.688860889837;8890.511021893857;8899.332550058642;8991.730248092568;9101;8054.516728996279;8176.018877734472;8200.54896980648;8269.917300827155;8296.626651800563;5908.734106965283;5929.495992840657;5959.165716799738;6108.908366374419;6125.663088529996;6089.695515224133;6092.664903430176;6108;6133;6169.938300617116 23:25:03 INFO - PID 1636 | |4;prototype.html;386.60566222453093;386.69837526729145;389.9025243689078;398.43621275894594;398.98803035908924;5089.821856235032;6047.485963693086;6132.7853525126575;6165.907511387333;6347.7460901564;4904.705717656934;5968.701564921758;6083.969580152093;6101.237345331833;6114;4063.349864021742;4310.935335969963;4439.356293337472;4446.244138496463;4467.932981005288;4412.580804823553;4427.8708929223;4441.666874984373;4453.5323791001965;4461.263891457906;4009.5389030261604;4164.396162556428;4358.694891357606;4412.646988240933;4421.38100665907;4334.046509767846;4392.297232442826;4408.82364705413;4413.933790993137;4420.226460369438;2959.837208953507;3744.251149770057;3752.587215406303;3771.17034252464;3779.9794055604834;4357.629601483878;5052.772625231836;5714.571407144481;5910.645361278337;5969.343372229051;4364.2324858432385;4463.397441345409;4530.29780384041;4627.791749371253;5258.553022993012;5219.71291578963;6541.331960350128;6609.933900661028;6657.567258128206;6667.399934005963;4307.590778876001;4325.264705000126;4331.826726930919;4337.937205384669;4353.673474489395;6806.387425131762;6954.279460488665;7008.26413226614;7055.823604409879;7058.329458701415;3646.197836475971;3848.3842585186235;4385.539518350591;4390.529172727172;4402.625776809007;3732.9626703733156;4468.4637843458995;4473.306637471197;4481.395011673448;4494.70784399013;4943.615787579483;5225.216217567319;5283.630145889828;5348.021232144054;5376;6615.636140012345;6629.966850165718;6673.432758215509;6676.732930682815;6680.398764111156;1212.921160124589;1458.358322338168;1513.4551561437875;1516.6284260356063;1522.444307827635;939.1313035442189;980.5832521178471;996.8554559588819;1003.4531180506641;1007.2395341517108;5384.9461505385225;5419.539339156216;5919.644821310735;6009.489193418608;6169.981952977798;5542.501174894279;6714.462842972551;6734;6832.521723479408;6871.656417179221;5056.409948936296;5634.85912852182;6097.725602347905;6175.660338681416;6246.625202487865;3326.9021222996353;3539.557555305587;3559.964400356015;3573.3210689968823;3573.8927832165077;5898.64608123514;6911.205211400653;7054.92945070553;7065.92934070663;7105.538140020882;5774.797882074191;5867.442592953748;5946.672932988728;6433.646149461824;6904.930950690429;5978.850528736816;6269.4984401246875;6345.650989195638;6792;7021.29787021304;5670.461306176072;6806.693698783468;6824.488163387667;6857.7256909723055;6916.23921368639;5564.081926482085;6492.253390860112;6731.436849072115;6777.627230502172;6796.76211332601;6109.511239100772;7051.224365319913;7053;7064.293570642894;7074.823129421806;4089.631933126033;4131.628153466082;4309.3966844641145;4970.850874473772;5019.372578427697;5118.25979167085;6152.415520525543;6245.600371440953;6816.48876334267;6939.918024592501;5758.337791154071;6127.693615319127;6449.871002580016;6498.3826536478955;6652.467802575879;5215.739213039409;6545.21457425112;6579.861544997991;6620.66896655175;6632.900506492503;5542.916856247079;6413.583117097373;6525.347465253437;6535.934640653532;6540;6072.574919755575;6547.345265473605;6577.901331480128;6629.7348106075215;6645.102911107132;5329.1206950852675;5895.20414744004;6039.214902062879;6252.499800015897;6502.894507933757 23:25:03 INFO - PID 1636 | |5;yui.html;725.5429079679801;798.3014861995754;815.7715839564922;828.3083625172984;840.9075001749807;13539.864601353996;16286.83713162868;16850.410235641753;18185.45443636693;19178.904105479487;17868.55328616785;18048.4585462436;18298.268069277212;18367.72448413275;18622.78951868131;822.8601137806558;997.3367710472527;998.5556427389796;1018.5416562546847;1027.193652982409;969.3456916581315;975.902409759022;1007.3804610164733;1012.6050840172302;1015.2943704125636;1051.2588625019343;1067.151614466501;1069.978600427991;1070.3845288958842;1070.9625163119292;1065.3075500924422;1066.7919755647652;1067.2689207892618;1067.7063807452935;1076.4079756134133;787.5668382389663;787.9527228366317;792.6274650914061;795.586295126531;801.4149670740334;14607.561773146715;16334.509964701077;16738.74891876623;16894.986300821867;17556.561085972826;14840.183789891551;16752.162391880356;17148.971061736334;17154.313827446887;17451;11704;14437;14492.637684057983;14515.838732901448;14686.045407048508;3557.875474358411;3575.463680447928;3578.910527236814;3581.3374525712825;3586.3544561978833;127423;127855;128857;129686.35156824249;130082;13306.933465332611;13350.532731354357;13450.663733406744;13466.596002119952;13472.86527134716;24220.39449013789;25877;25894.870525647628;26078.34804129912;26374.945002200082;7488.5506869588;7630.38956883448;7707.884381734279;7711.151773304932;7756.68973241064;3822.2164456286396;3867.2652196082654;3893.396523538856;3906.374980003186;3911.902202444962;4263.253930562139;4417.977910110429;4422.491413487426;4427.756473393994;4430.556944305608;915.844306467902;934.1359242700611;946.2004606107828;953.3374304858196;954.2699834626517;633.1642232253381;654.4077609763171;657.3065415986181;667.6661669165418;677.0048029396779;10290.434026128485;10835.241533092609;11780;11885.5840045598;12015.339156346485;641.355437785023;646.4085361893908;647.1037612906159;657.7237560224717;679.1748026148155;4428.092241090569;4994.600431965434;5180.171172612364;5364.865878353073;5493.697846618475;105496.89006219832;106935;108486.45756771324;108512.45743771389;108581.91418085876;88633;88896.22207555812;89017.21965560653;90186.09813901778;90321.09678903258;83803.5809820947;84108;85528.71706924401;85882.57058714791;85912.57043714866;10607.522661480407;10692.465376731288;10944.960228778253;11052.778944421227;11087.501062452371;29714.55428168536;36148.638513614525;40877.79561102176;41136.97157571085;42059.36910946277;69039.61920761658;70512.64743676249;73642;74148;74313.62843185966;16150.434734784227;16405;16430.178491075636;16515.256813443662;16838.747418788484;17793.555161121076;18348.90825545864;18521.88868667884;18589.814101858807;18651.533711657317;16108.436204732778;16635.750463743294;17170.742438863675;17324.04717740486;17373.39193128285;16570.135475960647;16921.576960575592;17221.65556688834;17301.221445034753;17360.739588905923;15927.442539511525;16094.195290235208;16563;16661.333546658;16798;10967.232293739362;11360.034397076011;11492.827607585725;11615.6515304541;11823.586174483851;12131.75736485283;12742.74514509686;13848.09987350819;13859.514916977858;13866.930665347012 23:25:03 INFO - PID 1636 | __end_tp_report 23:25:03 INFO - PID 1636 | __start_cc_report 23:25:03 INFO - PID 1636 | _x_x_mozilla_cycle_collect,347 23:25:03 INFO - PID 1636 | __end_cc_report 23:25:03 INFO - PID 1636 | __startTimestamp1495088703805__endTimestamp 23:25:03 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | ------- Summary: start ------- 23:25:03 INFO - PID 1636 | Number of tests: 6 23:25:03 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6092.93 Median:6307.78 stddev:739.40 (11.7%) stddev-sans-first:697.46 23:25:03 INFO - PID 1636 | Values: 2882.5 3532.8 3540.3 3547.3 3582.6 6037.4 6107.3 6194.9 6196.3 6222.0 6160.6 6171.8 6172.2 6186.0 6209.3 5355.9 5407.1 5422.5 5433.0 5450.3 5466.1 5507.1 5522.2 5522.6 5550.2 5318.6 5387.9 5483.5 5484.6 5528.2 5417.9 5495.2 5502.1 5505.0 5515.7 5391.3 5402.4 5435.9 5442.5 5455.0 5962.6 6203.8 6204.3 6214.8 6226.3 5356.4 6467.0 6527.5 6554.8 6626.8 6327.5 6787.3 6808.9 6808.9 6809.3 5516.3 6233.1 6284.9 6285.5 6288.7 6085.4 6736.4 6802.3 6905.3 6925.8 6512.3 6551.8 6582.5 6602.6 6614.1 6499.9 6754.4 6774.2 6779.5 6784.8 6460.8 6547.0 6659.6 6676.0 6700.9 6413.1 6516.8 6684.6 6687.8 6699.2 4504.8 5142.7 5314.2 5389.9 5398.8 4725.0 4730.1 4733.5 4741.3 4768.0 6195.4 6295.9 6313.1 6323.1 6332.2 5903.4 6284.1 6667.2 6759.8 6777.9 6217.8 6285.8 6303.5 6307.8 6335.2 5874.9 5924.6 5941.5 5945.3 5995.7 6680.6 6721.4 6808.0 6808.4 6904.9 5580.6 6710.4 6718.4 6741.4 6742.5 6094.9 6684.8 6847.8 6917.1 6938.7 5387.9 6423.5 6463.4 6496.2 6616.3 6701.3 6740.1 6745.5 6791.0 6798.3 5300.6 6520.3 6547.9 6553.4 6579.3 5285.8 6402.1 6409.4 6482.2 6485.8 5419.0 6346.1 6405.9 6446.8 6470.8 6540.6 6554.7 6592.3 6603.6 6605.8 6217.5 6289.8 6322.5 6415.2 6442.6 23:25:03 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:43216.52 Median:175734.82 stddev:75746.99 (43.1%) stddev-sans-first:75891.96 23:25:03 INFO - PID 1636 | Values: 618.5 751.3 810.0 1215.4 1275.6 37224.3 38207.2 38302.0 38327.4 38440.8 38021.8 38139.0 38243.8 38447.8 38472.8 7679.7 7832.1 7859.6 8088.7 8194.7 8177.0 8270.9 8297.1 8303.8 8315.8 8231.8 8295.0 8297.5 8304.5 8353.3 8194.8 8216.6 8258.5 8280.7 8296.5 8105.3 8165.8 8186.9 8188.6 8189.8 35578.8 35810.0 35876.8 35909.6 35972.5 3624.0 3673.4 3678.9 3686.5 3689.3 29367.9 29540.1 29554.4 29569.0 29579.6 3087.0 3104.5 3107.3 3125.1 3145.7 245473.0 297901.0 298264.0 310776.0 312839.0 44612.8 44816.6 44997.8 45125.0 45149.9 46033.0 46523.0 46703.0 46807.8 46925.5 12315.5 13279.5 13371.6 13382.2 13388.8 11099.7 11104.9 11114.3 11115.1 11151.9 12468.6 12645.6 12675.9 12680.1 12798.9 4954.1 5883.1 5897.7 5908.9 5953.0 1023.4 1041.0 1044.7 1065.6 1069.1 22849.7 24010.2 24093.2 24195.6 24195.6 1004.5 1130.9 1156.8 1185.3 1188.9 12302.4 12354.3 12374.7 12378.1 12387.1 284519.6 293409.0 317264.0 319707.8 321214.0 43429.3 44363.8 46792.1 48387.8 50151.5 1502.5 1522.8 1543.6 1550.8 1556.5 17885.0 20467.1 20726.4 23774.0 23848.2 36134.3 36149.3 36245.3 36283.0 36289.5 262758.0 262776.0 263456.0 263596.0 264338.0 31761.8 32011.5 33341.8 34882.8 35102.0 41228.4 43215.0 43524.8 43635.3 43756.0 29063.0 29127.9 29646.0 30874.4 30887.8 24718.9 27758.0 28003.3 30339.5 30486.2 34710.7 35100.5 35611.8 36146.6 36563.5 28824.7 29472.3 30208.1 30277.5 30333.2 22622.0 23240.1 23499.3 23589.5 23817.2 23:25:03 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:33454.11 Median:2264.83 stddev:85903.14 (3792.9%) stddev-sans-first:86108.23 23:25:03 INFO - PID 1636 | Values: 384.6 424.6 427.9 429.3 447.1 16090.4 20441.8 20922.6 21048.4 21183.3 19131.8 20724.1 20821.4 21026.6 21247.5 4898.7 6085.6 6092.0 6128.3 6148.0 5341.4 5865.7 6045.7 6052.0 6066.4 6058.7 6065.1 6071.7 6078.2 6079.5 4672.9 5957.3 5961.9 6038.4 6042.7 5333.8 5358.2 5363.5 5369.2 5382.3 25412.0 26562.5 26810.2 27612.3 27772.4 2197.3 2246.4 2260.4 2263.6 2266.1 14236.8 16950.3 17514.6 17875.8 18909.8 1807.4 2048.5 2092.9 2122.8 2193.5 140538.9 153236.0 164322.0 166855.0 167021.3 10193.7 10233.5 12447.6 12628.4 12737.9 16049.4 16101.2 16345.9 16346.9 16353.3 8596.0 8869.4 8882.2 9004.5 9042.5 8327.5 8341.4 8343.4 8490.2 8503.0 7126.1 7513.0 7880.9 8019.4 8024.7 2138.9 2151.3 2162.3 2162.7 2355.9 827.6 967.6 989.9 1002.3 1013.6 19560.5 20762.6 21068.3 21269.7 21306.7 991.3 996.5 998.7 1020.7 1032.4 9054.9 10651.4 10761.0 10833.6 10854.3 447436.0 492710.5 511512.4 520477.0 537727.0 113641.0 116431.8 117428.0 120747.6 123629.0 1133.2 1134.3 1181.7 1183.3 1183.5 17859.6 18095.5 18115.7 18402.5 18451.5 53826.7 54301.0 54438.5 54652.1 54840.4 70107.0 70649.6 70677.3 71422.3 72029.0 8335.1 8532.4 8878.0 8960.2 8972.6 13053.0 13346.0 13531.4 13585.9 13591.9 9789.7 11517.9 11761.9 11988.8 12036.0 9505.8 9788.8 10775.7 10899.6 10995.7 9696.9 10952.1 11072.3 11748.4 11950.2 7705.4 8226.3 8690.5 8786.8 8865.0 9680.4 11141.6 11184.3 11448.0 11545.3 23:25:03 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7080.84 Median:3600.50 stddev:4444.17 (123.4%) stddev-sans-first:4436.18 23:25:03 INFO - PID 1636 | Values: 1406.6 1467.1 1486.8 1493.4 1503.6 10069.8 10351.8 11321.4 11617.9 11811.1 11425.8 11642.3 11646.6 11647.9 11651.0 2463.5 2508.4 2523.8 2528.1 2530.5 2446.4 2508.3 2509.9 2510.5 2510.9 2464.4 2468.5 2468.8 2488.7 2492.2 2453.6 2461.7 2462.5 2464.9 2466.0 2119.7 2222.4 2414.2 2438.4 2449.0 11377.2 11572.5 11575.9 11588.0 11599.2 6427.3 6602.6 6679.0 6680.6 6680.9 3991.2 4276.2 4289.5 4338.6 4344.8 1165.7 1218.4 1262.2 1271.1 1276.8 15927.2 16233.3 16341.8 16365.5 16401.8 5090.4 5642.0 5812.4 6012.5 6117.1 13254.8 13325.7 13353.9 13375.7 13400.7 6583.6 6618.8 6637.9 6647.0 6647.1 5131.3 6067.6 6142.9 6206.6 6208.0 4509.5 4624.0 4703.4 4734.7 4754.1 1200.6 1201.9 1382.5 1455.1 1466.4 661.6 795.4 818.4 831.8 834.8 5466.8 6637.6 6694.7 6733.4 6830.5 923.3 929.5 964.9 971.5 978.9 3403.1 3797.9 3798.0 4414.4 4493.9 12193.8 14164.9 14799.3 15046.6 15047.9 11521.4 12870.8 14949.1 15000.2 15013.9 8391.2 8450.5 8560.4 8597.7 8629.8 6582.0 6589.1 6822.6 6834.1 6894.3 13178.9 13228.5 13245.3 13502.3 13541.3 13570.6 13687.3 13689.8 13937.9 13974.0 8209.6 8492.9 9135.3 9461.6 9507.8 10814.3 10890.1 10899.5 10907.9 10926.9 8887.6 9103.0 9155.3 9260.7 9293.2 8889.7 8890.5 8899.3 8991.7 9101.0 8054.5 8176.0 8200.5 8269.9 8296.6 5908.7 5929.5 5959.2 6108.9 6125.7 6089.7 6092.7 6108.0 6133.0 6169.9 23:25:03 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5194.82 Median:5821.12 stddev:1672.34 (28.7%) stddev-sans-first:1637.62 23:25:03 INFO - PID 1636 | Values: 386.6 386.7 389.9 398.4 399.0 5089.8 6047.5 6132.8 6165.9 6347.7 4904.7 5968.7 6084.0 6101.2 6114.0 4063.3 4310.9 4439.4 4446.2 4467.9 4412.6 4427.9 4441.7 4453.5 4461.3 4009.5 4164.4 4358.7 4412.6 4421.4 4334.0 4392.3 4408.8 4413.9 4420.2 2959.8 3744.3 3752.6 3771.2 3780.0 4357.6 5052.8 5714.6 5910.6 5969.3 4364.2 4463.4 4530.3 4627.8 5258.6 5219.7 6541.3 6609.9 6657.6 6667.4 4307.6 4325.3 4331.8 4337.9 4353.7 6806.4 6954.3 7008.3 7055.8 7058.3 3646.2 3848.4 4385.5 4390.5 4402.6 3733.0 4468.5 4473.3 4481.4 4494.7 4943.6 5225.2 5283.6 5348.0 5376.0 6615.6 6630.0 6673.4 6676.7 6680.4 1212.9 1458.4 1513.5 1516.6 1522.4 939.1 980.6 996.9 1003.5 1007.2 5384.9 5419.5 5919.6 6009.5 6170.0 5542.5 6714.5 6734.0 6832.5 6871.7 5056.4 5634.9 6097.7 6175.7 6246.6 3326.9 3539.6 3560.0 3573.3 3573.9 5898.6 6911.2 7054.9 7065.9 7105.5 5774.8 5867.4 5946.7 6433.6 6904.9 5978.9 6269.5 6345.7 6792.0 7021.3 5670.5 6806.7 6824.5 6857.7 6916.2 5564.1 6492.3 6731.4 6777.6 6796.8 6109.5 7051.2 7053.0 7064.3 7074.8 4089.6 4131.6 4309.4 4970.9 5019.4 5118.3 6152.4 6245.6 6816.5 6939.9 5758.3 6127.7 6449.9 6498.4 6652.5 5215.7 6545.2 6579.9 6620.7 6632.9 5542.9 6413.6 6525.3 6535.9 6540.0 6072.6 6547.3 6577.9 6629.7 6645.1 5329.1 5895.2 6039.2 6252.5 6502.9 23:25:03 INFO - PID 1636 | 23:25:03 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:22421.04 Median:18116.96 stddev:31866.89 (175.9%) stddev-sans-first:31914.65 23:25:03 INFO - PID 1636 | Values: 725.5 798.3 815.8 828.3 840.9 13539.9 16286.8 16850.4 18185.5 19178.9 17868.6 18048.5 18298.3 18367.7 18622.8 822.9 997.3 998.6 1018.5 1027.2 969.3 975.9 1007.4 1012.6 1015.3 1051.3 1067.2 1070.0 1070.4 1071.0 1065.3 1066.8 1067.3 1067.7 1076.4 787.6 788.0 792.6 795.6 801.4 14607.6 16334.5 16738.7 16895.0 17556.6 14840.2 16752.2 17149.0 17154.3 17451.0 11704.0 14437.0 14492.6 14515.8 14686.0 3557.9 3575.5 3578.9 3581.3 3586.4 127423.0 127855.0 128857.0 129686.4 130082.0 13306.9 13350.5 13450.7 13466.6 13472.9 24220.4 25877.0 25894.9 26078.3 26374.9 7488.6 7630.4 7707.9 7711.2 7756.7 3822.2 3867.3 3893.4 3906.4 3911.9 4263.3 4418.0 4422.5 4427.8 4430.6 915.8 934.1 946.2 953.3 954.3 633.2 654.4 657.3 667.7 677.0 10290.4 10835.2 11780.0 11885.6 12015.3 641.4 646.4 647.1 657.7 679.2 4428.1 4994.6 5180.2 5364.9 5493.7 105496.9 106935.0 108486.5 108512.5 108581.9 88633.0 88896.2 89017.2 90186.1 90321.1 83803.6 84108.0 85528.7 85882.6 85912.6 10607.5 10692.5 10945.0 11052.8 11087.5 29714.6 36148.6 40877.8 41137.0 42059.4 69039.6 70512.6 73642.0 74148.0 74313.6 16150.4 16405.0 16430.2 16515.3 16838.7 17793.6 18348.9 18521.9 18589.8 18651.5 16108.4 16635.8 17170.7 17324.0 17373.4 16570.1 16921.6 17221.7 17301.2 17360.7 15927.4 16094.2 16563.0 16661.3 16798.0 10967.2 11360.0 11492.8 11615.7 11823.6 12131.8 12742.7 13848.1 13859.5 13866.9 23:25:03 INFO - PID 1636 | -------- Summary: end -------- 23:25:03 INFO - PID 1636 | 23:25:04 INFO - TEST-INFO | 1636: exit 0 23:25:04 INFO - TEST-OK | dromaeo_css | took 1113144ms 23:25:04 INFO - TEST-START | kraken 23:25:04 INFO - operating with platform_type : mac_ 23:25:04 INFO - Initialising browser for kraken test... 23:25:04 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/tmp7QrleT/profile http://localhost:49222/getInfo.html) 23:25:04 INFO - PID 1649 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2939: String.localeCompare is deprecated; use String.prototype.localeCompare instead 23:25:07 INFO - PID 1649 | 2017-05-17 23:25:07.030 plugin-container[1650:10009] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 23:25:07 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:25:07 INFO - PID 1649 | 2017-05-17 23:25:07.035 plugin-container[1650:10009] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 23:25:07 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:25:07 INFO - PID 1649 | 2017-05-17 23:25:07.035 plugin-container[1650:10009] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:25:07 INFO - PID 1649 | __metrics Screen width/height:1600/1200 23:25:07 INFO - PID 1649 | colorDepth:24 23:25:07 INFO - PID 1649 | Browser inner width/height: 1024/691 23:25:07 INFO - PID 1649 | __metrics 23:25:07 INFO - PID 1649 | 2017-05-17 23:25:07.601 plugin-container[1651:10061] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 23:25:07 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:25:07 INFO - PID 1649 | 2017-05-17 23:25:07.602 plugin-container[1651:10061] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 23:25:07 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:25:07 INFO - PID 1649 | 2017-05-17 23:25:07.602 plugin-container[1651:10061] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:25:08 INFO - TEST-INFO | 1649: exit 0 23:25:08 INFO - Browser initialized. 23:25:08 INFO - Running cycle 1/1 for kraken test... 23:25:08 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/T/tmp7QrleT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:25:10 INFO - PID 1652 | 2017-05-17 23:25:10.379 plugin-container[1653:10152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 23:25:10 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:25:10 INFO - PID 1652 | 2017-05-17 23:25:10.379 plugin-container[1653:10152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9737, name = 'com.apple.CFPasteboardClient' 23:25:10 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:25:10 INFO - PID 1652 | 2017-05-17 23:25:10.379 plugin-container[1653:10152] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:25:10 INFO - PID 1652 | 2017-05-17 23:25:10.917 plugin-container[1654:10196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x942b, name = 'com.apple.tsm.portname' 23:25:10 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:25:10 INFO - PID 1652 | 2017-05-17 23:25:10.917 plugin-container[1654:10196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 23:25:10 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:25:10 INFO - PID 1652 | 2017-05-17 23:25:10.917 plugin-container[1654:10196] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:25:20 INFO - PID 1652 | RSS: Main: 239132672 23:25:20 INFO - PID 1652 | 23:25:22 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 23:25:23 INFO - PID 1652 | RSS: Main: 251904000 23:25:23 INFO - PID 1652 | 23:25:26 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 23:25:27 INFO - PID 1652 | RSS: Main: 260096000 23:25:27 INFO - PID 1652 | 23:25:30 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 23:25:31 INFO - PID 1652 | RSS: Main: 261959680 23:25:31 INFO - PID 1652 | 23:25:33 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 23:25:33 INFO - PID 1652 | RSS: Main: 264089600 23:25:33 INFO - PID 1652 | 23:25:35 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:25:36 INFO - PID 1652 | RSS: Main: 264847360 23:25:36 INFO - PID 1652 | 23:25:39 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 23:25:39 INFO - PID 1652 | RSS: Main: 270327808 23:25:39 INFO - PID 1652 | 23:25:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 23:25:43 INFO - PID 1652 | RSS: Main: 270434304 23:25:43 INFO - PID 1652 | 23:25:46 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 23:25:47 INFO - PID 1652 | RSS: Main: 269541376 23:25:47 INFO - PID 1652 | 23:25:48 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:25:49 INFO - PID 1652 | RSS: Main: 269275136 23:25:49 INFO - PID 1652 | 23:25:51 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:25:51 INFO - PID 1652 | RSS: Main: 269250560 23:25:51 INFO - PID 1652 | 23:25:54 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:25:54 INFO - PID 1652 | RSS: Main: 268926976 23:25:54 INFO - PID 1652 | 23:25:57 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:25:58 INFO - PID 1652 | RSS: Main: 269086720 23:25:58 INFO - PID 1652 | 23:26:01 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:26:02 INFO - PID 1652 | RSS: Main: 266620928 23:26:02 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 23:26:04 INFO - PID 1652 | RSS: Main: 269041664 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | __start_tp_report 23:26:04 INFO - PID 1652 | _x_x_mozilla_page_load 23:26:04 INFO - PID 1652 | _x_x_mozilla_page_load_details 23:26:04 INFO - PID 1652 | |i|pagename|runs| 23:26:04 INFO - PID 1652 | |0;ai-astar;104;115;115;116;115;114;114;115;116;115 23:26:04 INFO - PID 1652 | |1;audio-beat-detection;148;111;99;199;102;135;154;151;141;143 23:26:04 INFO - PID 1652 | |2;audio-dft;198;198;198;206;240;277;245;201;197;198 23:26:04 INFO - PID 1652 | |3;audio-fft;78;57;58;83;59;65;91;80;79;56 23:26:04 INFO - PID 1652 | |4;audio-oscillator;84;82;69;69;76;105;109;103;70;95 23:26:04 INFO - PID 1652 | |5;imaging-gaussian-blur;91;90;90;90;90;91;91;109;93;92 23:26:04 INFO - PID 1652 | |6;imaging-darkroom;106;99;97;98;97;97;97;97;98;97 23:26:04 INFO - PID 1652 | |7;imaging-desaturate;144;146;90;92;97;91;104;146;80;136 23:26:04 INFO - PID 1652 | |8;json-parse-financial;49;50;50;51;50;51;51;50;53;51 23:26:04 INFO - PID 1652 | |9;json-stringify-tinderbox;51;69;59;60;87;88;63;48;84;52 23:26:04 INFO - PID 1652 | |10;stanford-crypto-aes;109;109;82;84;85;83;86;84;83;84 23:26:04 INFO - PID 1652 | |11;stanford-crypto-ccm;138;151;156;159;138;139;162;139;204;165 23:26:04 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;187;191;184;188;222;180;247;246;222;232 23:26:04 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;68;69;81;100;101;99;73;71;68;69 23:26:04 INFO - PID 1652 | __end_tp_report 23:26:04 INFO - PID 1652 | __start_cc_report 23:26:04 INFO - PID 1652 | _x_x_mozilla_cycle_collect,916 23:26:04 INFO - PID 1652 | __end_cc_report 23:26:04 INFO - PID 1652 | __startTimestamp1495088764597__endTimestamp 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | ------- Summary: start ------- 23:26:04 INFO - PID 1652 | Number of tests: 14 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.54 (3.1%) stddev-sans-first:0.71 23:26:04 INFO - PID 1652 | Values: 104.0 115.0 115.0 116.0 115.0 114.0 114.0 115.0 116.0 115.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:149.50 stddev:29.50 (19.7%) stddev-sans-first:31.08 23:26:04 INFO - PID 1652 | Values: 148.0 111.0 99.0 199.0 102.0 135.0 154.0 151.0 141.0 143.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:215.80 Median:203.50 stddev:28.13 (13.8%) stddev-sans-first:29.09 23:26:04 INFO - PID 1652 | Values: 198.0 198.0 198.0 206.0 240.0 277.0 245.0 201.0 197.0 198.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:70.60 Median:78.50 stddev:12.94 (16.5%) stddev-sans-first:13.44 23:26:04 INFO - PID 1652 | Values: 78.0 57.0 58.0 83.0 59.0 65.0 91.0 80.0 79.0 56.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:73.00 stddev:15.68 (21.5%) stddev-sans-first:16.61 23:26:04 INFO - PID 1652 | Values: 84.0 82.0 69.0 69.0 76.0 105.0 109.0 103.0 70.0 95.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:91.00 stddev:5.81 (6.4%) stddev-sans-first:6.13 23:26:04 INFO - PID 1652 | Values: 91.0 90.0 90.0 90.0 90.0 91.0 91.0 109.0 93.0 92.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:97.00 stddev:2.79 (2.9%) stddev-sans-first:0.73 23:26:04 INFO - PID 1652 | Values: 106.0 99.0 97.0 98.0 97.0 97.0 97.0 97.0 98.0 97.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:112.60 Median:85.00 stddev:26.97 (31.7%) stddev-sans-first:26.10 23:26:04 INFO - PID 1652 | Values: 144.0 146.0 90.0 92.0 97.0 91.0 104.0 146.0 80.0 136.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.07 (2.1%) stddev-sans-first:0.97 23:26:04 INFO - PID 1652 | Values: 49.0 50.0 50.0 51.0 50.0 51.0 51.0 50.0 53.0 51.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:15.26 (23.1%) stddev-sans-first:15.18 23:26:04 INFO - PID 1652 | Values: 51.0 69.0 59.0 60.0 87.0 88.0 63.0 48.0 84.0 52.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:84.00 stddev:10.65 (12.7%) stddev-sans-first:8.46 23:26:04 INFO - PID 1652 | Values: 109.0 109.0 82.0 84.0 85.0 83.0 86.0 84.0 83.0 84.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:157.50 stddev:20.20 (12.8%) stddev-sans-first:20.46 23:26:04 INFO - PID 1652 | Values: 138.0 151.0 156.0 159.0 138.0 139.0 162.0 139.0 204.0 165.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.90 Median:222.00 stddev:26.64 (12.0%) stddev-sans-first:26.94 23:26:04 INFO - PID 1652 | Values: 187.0 191.0 184.0 188.0 222.0 180.0 247.0 246.0 222.0 232.0 23:26:04 INFO - PID 1652 | 23:26:04 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.90 Median:70.00 stddev:14.39 (20.6%) stddev-sans-first:14.60 23:26:04 INFO - PID 1652 | Values: 68.0 69.0 81.0 100.0 101.0 99.0 73.0 71.0 68.0 69.0 23:26:04 INFO - PID 1652 | -------- Summary: end -------- 23:26:04 INFO - PID 1652 | 23:26:05 INFO - TEST-INFO | 1652: exit 0 23:26:05 INFO - TEST-OK | kraken | took 60991ms 23:26:05 INFO - SUITE-END | took 1174s 23:26:05 INFO - Completed test suite (00:19:34) 23:26:05 INFO - javascript benchmark 23:26:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7765.349300141513, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2882.4523340565293, 3532.823358832061, 3540.2919416116765, 3547.3082748863967, 3582.5521809773777, 6037.39626037396, 6107.328193898667, 6194.907076393858, 6196.349383314755, 6221.968890155548, 6160.599561028541, 6171.783987560425, 6172.197614310145, 6185.969070154654, 6209.254889413265, 5355.946440535584, 5407.134858422653, 5422.457754224575, 5433.022056029913, 5450.29146210994, 5466.125419932811, 5507.091329930557, 5522.171674248854, 5522.5581953443825, 5550.195221692852, 5318.601104917108, 5387.946120538783, 5483.506484416422, 5484.61607687458, 5528.198411230369, 5417.918731219035, 5495.203195536665, 5502.119660854244, 5505.0, 5515.696636684981, 5391.32608423947, 5402.405735369106, 5435.864103397407, 5442.455754424565, 5455.0, 5962.582619216657, 6203.7828675996125, 6204.317525072239, 6214.844628884269, 6226.252849658024, 5356.35723713153, 6467.0, 6527.543071984963, 6554.80335589933, 6626.83432914181, 6327.525435592349, 6787.287334829821, 6808.863822723567, 6808.931910680879, 6809.319068093151, 5516.310461192351, 6233.096201050822, 6284.905726414107, 6285.497160227171, 6288.685565721741, 6085.391460853879, 6736.427403670743, 6802.319768023158, 6905.309469053155, 6925.826854328581, 6512.283648798629, 6551.836204094936, 6582.506312026521, 6602.636854972927, 6614.10709554204, 6499.8700025999215, 6754.3921047106, 6774.187097548326, 6779.525433219627, 6784.830379240559, 6460.806175814733, 6547.0, 6659.600423974577, 6675.966620166868, 6700.865982680319, 6413.102165696806, 6516.804495865132, 6684.598924064571, 6687.799366019027, 6699.162604674416, 4504.797284122223, 5142.665726727751, 5314.176302673091, 5389.892202156013, 5398.757055932492, 4725.0, 4730.101280756645, 4733.479317275097, 4741.336212930192, 4767.976160119247, 6195.4424101831055, 6295.905561416583, 6313.147725057151, 6323.083152942798, 6332.176817013851, 5903.409659034062, 6284.12022316876, 6667.19993600771, 6759.763408280785, 6777.898331525031, 6217.782377616762, 6285.77999770015, 6303.495720342362, 6307.779227727007, 6335.23977122739, 5874.911876321773, 5924.5852790305125, 5941.524678025748, 5945.316288626776, 5995.670238136944, 6680.5991640501725, 6721.395074443323, 6807.965960170168, 6808.353206445379, 6904.861902761816, 5580.609357345109, 6710.362515561014, 6718.428933540508, 6741.426978706865, 6742.460603151638, 6094.878102438015, 6684.83287917806, 6847.760328388483, 6917.066196063668, 6938.653067346511, 5387.892242155213, 6423.454006409321, 6463.418292353711, 6496.187976502937, 6616.272210056986, 6701.32986701326, 6740.090087838079, 6745.52781305304, 6791.0, 6798.252192258947, 5300.575953923754, 6520.250171230179, 6547.901781473377, 6553.4101930826455, 6579.309172536815, 5285.841424757264, 6402.1357116790505, 6409.35906409374, 6482.157319548617, 6485.77299794505, 5419.0, 6346.143270658402, 6405.903911441425, 6446.774362897276, 6470.773522926864, 6540.640264785482, 6554.737810487527, 6592.274849766426, 6603.603783772989, 6605.834854128685, 6217.502599792097, 6289.811305660837, 6322.525810564318, 6415.230172379344, 6442.645654488861], "value": 6044.657385622626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.4619381138409, 751.3350684644088, 809.9514029158249, 1215.4105258949407, 1275.5790589105595, 37224.25551488972, 38207.23585528291, 38302.0, 38327.42508862369, 38440.80779596098, 38021.809890950506, 38139.0, 38243.80878095605, 38447.80776096122, 38472.80763596185, 7679.654415551299, 7832.060152781674, 7859.6463159157975, 8088.676452941875, 8194.713185038525, 8177.018757749049, 8270.917290827105, 8297.128801475848, 8303.750887473385, 8315.750527484186, 8231.83536329273, 8294.958525207365, 8297.543635100066, 8304.543250121242, 8353.289970352527, 8194.795130121795, 8216.630251638691, 8258.545779982129, 8280.710175143871, 8296.460730052528, 8105.31104856082, 8165.795855103611, 8186.877196841992, 8188.631511581993, 8189.8362032759605, 35578.8221058893, 35810.0, 35876.82061589701, 35909.64090359089, 35972.46041309356, 3623.981880090609, 3673.430618254175, 3678.8712395066045, 3686.5391826021746, 3689.262147570497, 29367.85316073406, 29540.113796585923, 29554.408911821643, 29569.0, 29579.556306655417, 3086.9691303086906, 3104.4567200740007, 3107.254258977829, 3125.109343837018, 3145.7168854803176, 245473.0, 297901.0, 298264.0, 310776.0, 312839.0, 44612.776936115115, 44816.55183448189, 44997.77501112474, 45125.0, 45149.87125321893, 46033.0, 46523.0, 46703.0, 46807.829804254485, 46925.5307446928, 12315.50737970489, 13279.535216267384, 13371.598852034454, 13382.197068175941, 13388.799168012487, 11099.722506937229, 11104.944475277573, 11114.277571957797, 11115.055220306203, 11151.940565646251, 12468.625941221779, 12645.557405490763, 12675.873241267655, 12680.112392132647, 12798.936005319914, 4954.058728841508, 5883.117532370179, 5897.734601942923, 5908.940910590839, 5952.970235148883, 1023.370627064365, 1040.9687709368732, 1044.6657069737612, 1065.5844220753913, 1069.1393428290146, 22849.657255141185, 24010.19923505744, 24093.156739514034, 24195.637065444032, 24195.637065444032, 1004.4927311707687, 1130.8982191602631, 1156.7686462707325, 1185.25921299188, 1188.9048876089892, 12302.384880755926, 12354.320512371905, 12374.690632734073, 12378.071644626689, 12387.132900696864, 284519.57740211167, 293409.0, 317264.0, 319707.8029219678, 321214.0, 43429.34855977163, 44363.778181109534, 46792.06415871663, 48387.75806120947, 50151.498485015414, 1502.4741340530509, 1522.7639715844061, 1543.5523698127643, 1550.7983962084857, 1556.4785796758213, 17885.0163241023, 20467.07898144557, 20726.378208653765, 23774.0, 23848.16531421392, 36134.27731445409, 36149.27701446009, 36245.27509449849, 36283.0, 36289.45565816462, 262758.0, 262776.0, 263456.0481592816, 263596.0, 264338.0, 31761.841190793897, 32011.519827202144, 33341.83329083339, 34882.825585872924, 35102.0, 41228.38157427701, 43215.0, 43524.782376089184, 43635.34546981861, 43756.0, 29063.0, 29127.85436072806, 29645.9623913162, 30874.382512349177, 30887.764489420173, 24718.899432065264, 27758.0, 28003.299917502227, 30339.544906826857, 30486.237844054078, 34710.65289347074, 35100.473492897116, 35611.82194089013, 36146.638533615376, 36563.537458502426, 28824.711752883042, 29472.263193420335, 30208.09375718732, 30277.545836812023, 30333.241668958453, 22622.0, 23240.140788737197, 23499.29502114939, 23589.52820943606, 23817.166399175945], "value": 16972.368559803122, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.62883317598516, 424.55633862613564, 427.85880659382406, 429.32595824555443, 447.0767864360096, 16090.436834710787, 20441.795582044175, 20922.581548369024, 21048.368548943556, 21183.258585949494, 19131.808681913175, 20724.067416966234, 20821.375358739224, 21026.579468410622, 21247.468813279676, 4898.681585696918, 6085.6003119282595, 6092.0, 6128.264608247016, 6147.969260153693, 5341.359036915575, 5865.677387743673, 6045.6674882881425, 6052.001419765756, 6066.423689749467, 6058.697065146747, 6065.120557519157, 6071.719437796365, 6078.240219972504, 6079.48324392425, 4672.883177920545, 5957.314908785458, 5961.940380596204, 6038.426349496791, 6042.728077236513, 5333.759980800874, 5358.196270559409, 5363.463653634644, 5369.221462887859, 5382.30030096085, 25412.0, 26562.468750625074, 26810.195694129012, 27612.309692257855, 27772.444551108863, 2197.3298144066052, 2246.3597874605657, 2260.400993736661, 2263.6038693228784, 2266.05958527211, 14236.786448203286, 16950.3219871205, 17514.562135946577, 17875.821241787547, 18909.81090189094, 1807.4396936949588, 2048.549319149785, 2092.9476763080893, 2122.7983341582526, 2193.5283913958574, 140538.8919166203, 153236.0, 164322.0, 166855.0, 167021.3297867018, 10193.694189174337, 10233.48832558369, 12447.564335248222, 12628.368581570885, 12737.87262127367, 16049.358025678843, 16101.19494025294, 16345.918270408572, 16346.918265408834, 16353.26410311515, 8596.0, 8869.423487473294, 8882.200601945857, 9004.50475223869, 9042.502662353501, 8327.541985190872, 8341.41610087288, 8343.374246931504, 8490.15098490146, 8502.957485212535, 7126.109236345457, 7513.0, 7880.921190788134, 8019.43863929531, 8024.719134830253, 2138.925137620176, 2151.3223334649847, 2162.329677799887, 2162.740471143473, 2355.905763769464, 827.6027506796771, 967.6129548180643, 989.9308746553849, 1002.2733518199393, 1013.6148263659762, 19560.510987225432, 20762.584748305253, 21068.26261080855, 21269.68095478569, 21306.680399794015, 991.2714155095998, 996.5415908682088, 998.7453199434098, 1020.6886899495726, 1032.4218437674926, 9054.86417703735, 10651.360918344924, 10761.0, 10833.620823271147, 10854.29447085937, 447436.0, 492710.5364473155, 511512.4243787796, 520477.0, 537727.0, 113641.0, 116431.8356816421, 117428.0, 120747.58504829854, 123629.0, 1133.1784456269138, 1134.262729225993, 1181.6750393641817, 1183.3373310945897, 1183.526589364261, 17859.553511161805, 18095.45713628593, 18115.728264075788, 18402.539936501693, 18451.538711532317, 53826.73086634698, 54301.0, 54438.45561544334, 54652.08717694862, 54840.35478935638, 70107.0, 70649.64675176592, 70677.29322706912, 71422.28577714156, 72029.0, 8335.08314085438, 8532.44539104981, 8878.0, 8960.1935825776, 8972.596233169654, 13052.976788597782, 13346.0, 13531.391087401287, 13585.913126950018, 13591.86408135906, 9789.657361992297, 11517.942410287895, 11761.88238117608, 11988.820167697666, 12036.0, 9505.762355941157, 9788.82534095913, 10775.676729698122, 10899.618513351994, 10995.725106872393, 9696.9030309696, 10952.123830093733, 11072.280301780358, 11748.412579371168, 11950.223235489666, 7705.383569314329, 8226.341892648454, 8690.522021288889, 8786.780330491789, 8865.0, 9680.419174849532, 11141.610043648434, 11184.273022253528, 11448.0, 11545.307281563133], "value": 10076.127620000567, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.570995846267, 1467.1197281631016, 1486.821581410231, 1493.4474244529533, 1503.6090616439722, 10069.798604027934, 10351.792964140712, 11321.377324247163, 11617.941910290447, 11811.055115590754, 11425.771484570325, 11642.301461912291, 11646.592369267077, 11647.941760291207, 11651.009664178533, 2463.4703538739213, 2508.3603681061304, 2523.8107141964424, 2528.1404322530334, 2530.544501989641, 2446.388402899275, 2508.3352911478382, 2509.912153074643, 2510.535550923085, 2510.899564017437, 2464.408541949937, 2468.469279104992, 2468.790152837011, 2488.726240113586, 2492.190038237571, 2453.595156799126, 2461.729209786922, 2462.495188486356, 2464.8767561621935, 2466.0382450844186, 2119.74934788474, 2222.4332795137298, 2414.167112346238, 2438.3904023994, 2449.020391843277, 11377.2035957483, 11572.47923843429, 11575.884241157564, 11587.957083862408, 11599.246049006788, 6427.260865000491, 6602.60384376939, 6678.966605166943, 6680.565763225375, 6680.86638267232, 3991.2217117662067, 4276.18752437036, 4289.485261768607, 4338.631216346615, 4344.826206951718, 1165.7260543772206, 1218.3603608105727, 1262.2300396757971, 1271.1229251816217, 1276.8276282701809, 15927.203639818079, 16233.2695028724, 16341.836581634268, 16365.509034728977, 16401.753973690404, 5090.440051594322, 5641.984442800335, 5812.360640329561, 6012.458878700938, 6117.051856962089, 13254.801177982337, 13325.66685832862, 13353.866461335456, 13375.665608359868, 13400.731985360433, 6583.604983700898, 6618.768343107968, 6637.86724265512, 6647.002949557508, 6647.135872336662, 5131.281620573122, 6067.605605635619, 6142.938570614326, 6206.565540412218, 6207.96896015517, 4509.458864936229, 4624.0, 4703.412073490814, 4734.668573199911, 4754.072955773591, 1200.6398080575793, 1201.8677945425995, 1382.5161193582367, 1455.105110357144, 1466.4280930437137, 661.6427129350097, 795.3597354129981, 818.378032695156, 831.7962099285713, 834.8246868157755, 5466.753996070186, 6637.6349300788925, 6694.694534565713, 6733.3939945405145, 6830.453563714891, 923.3075193604797, 929.4702019848756, 964.8890377606525, 971.5190980464574, 978.8776402949632, 3403.0602556543026, 3797.9430308545393, 3797.981010094987, 4414.359917811899, 4493.865184044484, 12193.817092743617, 14164.929175354056, 14799.334029968677, 15046.623834404227, 15047.924760376129, 11521.366324852046, 12870.841624253675, 14949.103053816805, 15000.174990375634, 15013.924930375279, 8391.202835730595, 8450.450720703135, 8560.357973152039, 8597.656093756179, 8629.827403451896, 6582.0, 6589.110470086669, 6822.556533825285, 6834.077399551195, 6894.276101009322, 13178.934105329412, 13228.470861165446, 13245.337733113498, 13502.32488375597, 13541.255230962392, 13570.59288221355, 13687.315634218448, 13689.794653080013, 13937.930310348384, 13974.0, 8209.589520524069, 8492.872606910778, 9135.31485138604, 9461.621535138518, 9507.809843803223, 10814.29707069038, 10890.128789696646, 10899.509522071685, 10907.945460272647, 10926.890731092906, 8887.600057997277, 9103.0, 9155.267578593563, 9260.722178334661, 9293.210077143249, 8889.688860889837, 8890.511021893857, 8899.332550058642, 8991.730248092568, 9101.0, 8054.516728996279, 8176.018877734472, 8200.54896980648, 8269.917300827155, 8296.626651800563, 5908.734106965283, 5929.495992840657, 5959.165716799738, 6108.908366374419, 6125.663088529996, 6089.695515224133, 6092.664903430176, 6108.0, 6133.0, 6169.938300617116], "value": 5379.017344901063, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.60566222453093, 386.69837526729145, 389.9025243689078, 398.43621275894594, 398.98803035908924, 5089.821856235032, 6047.485963693086, 6132.7853525126575, 6165.907511387333, 6347.7460901564, 4904.705717656934, 5968.701564921758, 6083.969580152093, 6101.237345331833, 6114.0, 4063.349864021742, 4310.935335969963, 4439.356293337472, 4446.244138496463, 4467.932981005288, 4412.580804823553, 4427.8708929223, 4441.666874984373, 4453.5323791001965, 4461.263891457906, 4009.5389030261604, 4164.396162556428, 4358.694891357606, 4412.646988240933, 4421.38100665907, 4334.046509767846, 4392.297232442826, 4408.82364705413, 4413.933790993137, 4420.226460369438, 2959.837208953507, 3744.251149770057, 3752.587215406303, 3771.17034252464, 3779.9794055604834, 4357.629601483878, 5052.772625231836, 5714.571407144481, 5910.645361278337, 5969.343372229051, 4364.2324858432385, 4463.397441345409, 4530.29780384041, 4627.791749371253, 5258.553022993012, 5219.71291578963, 6541.331960350128, 6609.933900661028, 6657.567258128206, 6667.399934005963, 4307.590778876001, 4325.264705000126, 4331.826726930919, 4337.937205384669, 4353.673474489395, 6806.387425131762, 6954.279460488665, 7008.26413226614, 7055.823604409879, 7058.329458701415, 3646.197836475971, 3848.3842585186235, 4385.539518350591, 4390.529172727172, 4402.625776809007, 3732.9626703733156, 4468.4637843458995, 4473.306637471197, 4481.395011673448, 4494.70784399013, 4943.615787579483, 5225.216217567319, 5283.630145889828, 5348.021232144054, 5376.0, 6615.636140012345, 6629.966850165718, 6673.432758215509, 6676.732930682815, 6680.398764111156, 1212.921160124589, 1458.358322338168, 1513.4551561437875, 1516.6284260356063, 1522.444307827635, 939.1313035442189, 980.5832521178471, 996.8554559588819, 1003.4531180506641, 1007.2395341517108, 5384.9461505385225, 5419.539339156216, 5919.644821310735, 6009.489193418608, 6169.981952977798, 5542.501174894279, 6714.462842972551, 6734.0, 6832.521723479408, 6871.656417179221, 5056.409948936296, 5634.85912852182, 6097.725602347905, 6175.660338681416, 6246.625202487865, 3326.9021222996353, 3539.557555305587, 3559.964400356015, 3573.3210689968823, 3573.8927832165077, 5898.64608123514, 6911.205211400653, 7054.92945070553, 7065.92934070663, 7105.538140020882, 5774.797882074191, 5867.442592953748, 5946.672932988728, 6433.646149461824, 6904.930950690429, 5978.850528736816, 6269.4984401246875, 6345.650989195638, 6792.0, 7021.29787021304, 5670.461306176072, 6806.693698783468, 6824.488163387667, 6857.7256909723055, 6916.23921368639, 5564.081926482085, 6492.253390860112, 6731.436849072115, 6777.627230502172, 6796.76211332601, 6109.511239100772, 7051.224365319913, 7053.0, 7064.293570642894, 7074.823129421806, 4089.631933126033, 4131.628153466082, 4309.3966844641145, 4970.850874473772, 5019.372578427697, 5118.25979167085, 6152.415520525543, 6245.600371440953, 6816.48876334267, 6939.918024592501, 5758.337791154071, 6127.693615319127, 6449.871002580016, 6498.3826536478955, 6652.467802575879, 5215.739213039409, 6545.21457425112, 6579.861544997991, 6620.66896655175, 6632.900506492503, 5542.916856247079, 6413.583117097373, 6525.347465253437, 6535.934640653532, 6540.0, 6072.574919755575, 6547.345265473605, 6577.901331480128, 6629.7348106075215, 6645.102911107132, 5329.1206950852675, 5895.20414744004, 6039.214902062879, 6252.499800015897, 6502.894507933757], "value": 4676.4496895051225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.5429079679801, 798.3014861995754, 815.7715839564922, 828.3083625172984, 840.9075001749807, 13539.864601353996, 16286.83713162868, 16850.410235641753, 18185.45443636693, 19178.904105479487, 17868.55328616785, 18048.4585462436, 18298.268069277212, 18367.72448413275, 18622.78951868131, 822.8601137806558, 997.3367710472527, 998.5556427389796, 1018.5416562546847, 1027.193652982409, 969.3456916581315, 975.902409759022, 1007.3804610164733, 1012.6050840172302, 1015.2943704125636, 1051.2588625019343, 1067.151614466501, 1069.978600427991, 1070.3845288958842, 1070.9625163119292, 1065.3075500924422, 1066.7919755647652, 1067.2689207892618, 1067.7063807452935, 1076.4079756134133, 787.5668382389663, 787.9527228366317, 792.6274650914061, 795.586295126531, 801.4149670740334, 14607.561773146715, 16334.509964701077, 16738.74891876623, 16894.986300821867, 17556.561085972826, 14840.183789891551, 16752.162391880356, 17148.971061736334, 17154.313827446887, 17451.0, 11704.0, 14437.0, 14492.637684057983, 14515.838732901448, 14686.045407048508, 3557.875474358411, 3575.463680447928, 3578.910527236814, 3581.3374525712825, 3586.3544561978833, 127423.0, 127855.0, 128857.0, 129686.35156824249, 130082.0, 13306.933465332611, 13350.532731354357, 13450.663733406744, 13466.596002119952, 13472.86527134716, 24220.39449013789, 25877.0, 25894.870525647628, 26078.34804129912, 26374.945002200082, 7488.5506869588, 7630.38956883448, 7707.884381734279, 7711.151773304932, 7756.68973241064, 3822.2164456286396, 3867.2652196082654, 3893.396523538856, 3906.374980003186, 3911.902202444962, 4263.253930562139, 4417.977910110429, 4422.491413487426, 4427.756473393994, 4430.556944305608, 915.844306467902, 934.1359242700611, 946.2004606107828, 953.3374304858196, 954.2699834626517, 633.1642232253381, 654.4077609763171, 657.3065415986181, 667.6661669165418, 677.0048029396779, 10290.434026128485, 10835.241533092609, 11780.0, 11885.5840045598, 12015.339156346485, 641.355437785023, 646.4085361893908, 647.1037612906159, 657.7237560224717, 679.1748026148155, 4428.092241090569, 4994.600431965434, 5180.171172612364, 5364.865878353073, 5493.697846618475, 105496.89006219832, 106935.0, 108486.45756771324, 108512.45743771389, 108581.91418085876, 88633.0, 88896.22207555812, 89017.21965560653, 90186.09813901778, 90321.09678903258, 83803.5809820947, 84108.0, 85528.71706924401, 85882.57058714791, 85912.57043714866, 10607.522661480407, 10692.465376731288, 10944.960228778253, 11052.778944421227, 11087.501062452371, 29714.55428168536, 36148.638513614525, 40877.79561102176, 41136.97157571085, 42059.36910946277, 69039.61920761658, 70512.64743676249, 73642.0, 74148.0, 74313.62843185966, 16150.434734784227, 16405.0, 16430.178491075636, 16515.256813443662, 16838.747418788484, 17793.555161121076, 18348.90825545864, 18521.88868667884, 18589.814101858807, 18651.533711657317, 16108.436204732778, 16635.750463743294, 17170.742438863675, 17324.04717740486, 17373.39193128285, 16570.135475960647, 16921.576960575592, 17221.65556688834, 17301.221445034753, 17360.739588905923, 15927.442539511525, 16094.195290235208, 16563.0, 16661.333546658, 16798.0, 10967.232293739362, 11360.034397076011, 11492.827607585725, 11615.6515304541, 11823.586174483851, 12131.75736485283, 12742.74514509686, 13848.09987350819, 13859.514916977858, 13866.930665347012], "value": 8431.578340702776, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 116.0, 115.0, 114.0, 114.0, 115.0, 116.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 99.0, 199.0, 102.0, 135.0, 154.0, 151.0, 141.0, 143.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 198.0, 206.0, 240.0, 277.0, 245.0, 201.0, 197.0, 198.0], "value": 215.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 58.0, 83.0, 59.0, 65.0, 91.0, 80.0, 79.0, 56.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 69.0, 69.0, 76.0, 105.0, 109.0, 103.0, 70.0, 95.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 90.0, 90.0, 90.0, 91.0, 91.0, 109.0, 93.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 99.0, 97.0, 98.0, 97.0, 97.0, 97.0, 97.0, 98.0, 97.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 146.0, 90.0, 92.0, 97.0, 91.0, 104.0, 146.0, 80.0, 136.0], "value": 112.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 50.0, 51.0, 50.0, 51.0, 51.0, 50.0, 53.0, 51.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 69.0, 59.0, 60.0, 87.0, 88.0, 63.0, 48.0, 84.0, 52.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 82.0, 84.0, 85.0, 83.0, 86.0, 84.0, 83.0, 84.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 151.0, 156.0, 159.0, 138.0, 139.0, 162.0, 139.0, 204.0, 165.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 191.0, 184.0, 188.0, 222.0, 180.0, 247.0, 246.0, 222.0, 232.0], "value": 209.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 81.0, 100.0, 101.0, 99.0, 73.0, 71.0, 68.0, 69.0], "value": 79.9, "unit": "score"}]}]} 23:26:05 INFO - Return code: 0 23:26:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:26:05 INFO - # TBPL SUCCESS # 23:26:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:26:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:26:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:26:05 INFO - 2017-05-17 23:26:05.893 screenresolution[1659:10572] starting screenresolution argv=screenresolution get 23:26:05 INFO - 2017-05-17 23:26:05.895 screenresolution[1659:10572] Display 0: 1600x1200x32@60 23:26:05 INFO - 2017-05-17 23:26:05.901 screenresolution[1660:10575] starting screenresolution argv=screenresolution list 23:26:05 INFO - Available Modes on Display 0 23:26:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:26:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:26:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:26:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:26:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:26:06 INFO - Intel Iris: 23:26:06 INFO - Chipset Model: Intel Iris 23:26:06 INFO - Type: GPU 23:26:06 INFO - Bus: Built-In 23:26:06 INFO - VRAM (Dynamic, Max): 1536 MB 23:26:06 INFO - Vendor: Intel (0x8086) 23:26:06 INFO - Device ID: 0x0a2e 23:26:06 INFO - Revision ID: 0x0009 23:26:06 INFO - Displays: 23:26:06 INFO - Display: 23:26:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:26:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:26:06 INFO - Display Serial Number: 1600x1200 60 23:26:06 INFO - Main Display: Yes 23:26:06 INFO - Mirror: Off 23:26:06 INFO - Online: Yes 23:26:06 INFO - Rotation: Supported 23:26:06 INFO - Return code: 0 23:26:06 INFO - Running post-action listener: _resource_record_post_action 23:26:06 INFO - [mozharness: 2017-05-18 06:26:06.135267Z] Finished run-tests step (success) 23:26:06 INFO - Running post-run listener: _resource_record_post_run 23:26:06 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 38120448; Write bytes: 574586880; Read time: 386; Write time: 1783 23:26:06 INFO - TinderboxPrint: CPU usage
54.0% 23:26:06 INFO - TinderboxPrint: I/O read bytes / time
38,120,448 / 386 23:26:06 INFO - TinderboxPrint: I/O write bytes / time
574,586,880 / 1,783 23:26:06 INFO - TinderboxPrint: CPU idle
2,201.4 (46.0%) 23:26:06 INFO - TinderboxPrint: CPU system
963.6 (20.1%) 23:26:06 INFO - TinderboxPrint: CPU user
1,624.8 (33.9%) 23:26:06 INFO - TinderboxPrint: Swap in / out
297,881,600 / 0 23:26:06 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 243206656; Write bytes: 238245888; Read time: 17483; Write time: 456 23:26:06 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 37161984; Write bytes: 311273472; Read time: 370; Write time: 1275 23:26:06 INFO - Running post-run listener: _upload_blobber_files 23:26:06 INFO - Blob upload gear active. 23:26:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:26:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:26:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:26:06 INFO - (blobuploader) - INFO - Open directory for files ... 23:26:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:26:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:08 INFO - (blobuploader) - INFO - Done attempting. 23:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:08 INFO - (blobuploader) - INFO - Done attempting. 23:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:09 INFO - (blobuploader) - INFO - Done attempting. 23:26:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:26:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:26:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:11 INFO - (blobuploader) - INFO - Done attempting. 23:26:11 INFO - (blobuploader) - INFO - Iteration through files over. 23:26:11 INFO - Return code: 0 23:26:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:26:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:26:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a20afdac447e6e5b1998eeac0c7754f5f689a322c2b65d8ec8c3c33cc59c3ab73c20281c5d40aea625e2d23ab191353e7c075e013f226154900575737bd97d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd08fb42913452bfdf40f3a5d528ed41b02ed288c290e8fcc7c1e234f97eeae796895f761b3c7c5fe5613aa0b74ba48f601e4f092993dd51e7e6bb44c6db0356", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8474d3781b1402e011ba1516ae077eeb89d0e63721786ee12ee0739116467b89325ad2b6238f5ce11c30765613f47ec010b01dcfe00265004f1eacf1f896e083", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb31050f874eb3846684c50d889f01dea88bc34a26601b045d2c5ac53eed333e86419f44a700abe193ad8fcffb8fbd58ef88ac22c725b457c42b16875c9fd119"} 23:26:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:26:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:26:11 INFO - Contents: 23:26:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a20afdac447e6e5b1998eeac0c7754f5f689a322c2b65d8ec8c3c33cc59c3ab73c20281c5d40aea625e2d23ab191353e7c075e013f226154900575737bd97d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd08fb42913452bfdf40f3a5d528ed41b02ed288c290e8fcc7c1e234f97eeae796895f761b3c7c5fe5613aa0b74ba48f601e4f092993dd51e7e6bb44c6db0356", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8474d3781b1402e011ba1516ae077eeb89d0e63721786ee12ee0739116467b89325ad2b6238f5ce11c30765613f47ec010b01dcfe00265004f1eacf1f896e083", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb31050f874eb3846684c50d889f01dea88bc34a26601b045d2c5ac53eed333e86419f44a700abe193ad8fcffb8fbd58ef88ac22c725b457c42b16875c9fd119"} 23:26:11 INFO - Running post-run listener: copy_logs_to_upload_dir 23:26:11 INFO - Copying logs to upload dir... 23:26:11 INFO - mkdir: /builds/slave/test/build/upload/logs 23:26:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.150922 ========= master_lag: 1.42 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-17 23:26:13.157277) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 23:26:13.164876) ========= 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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners TMPDIR=/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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/6a20afdac447e6e5b1998eeac0c7754f5f689a322c2b65d8ec8c3c33cc59c3ab73c20281c5d40aea625e2d23ab191353e7c075e013f226154900575737bd97d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd08fb42913452bfdf40f3a5d528ed41b02ed288c290e8fcc7c1e234f97eeae796895f761b3c7c5fe5613aa0b74ba48f601e4f092993dd51e7e6bb44c6db0356", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8474d3781b1402e011ba1516ae077eeb89d0e63721786ee12ee0739116467b89325ad2b6238f5ce11c30765613f47ec010b01dcfe00265004f1eacf1f896e083", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb31050f874eb3846684c50d889f01dea88bc34a26601b045d2c5ac53eed333e86419f44a700abe193ad8fcffb8fbd58ef88ac22c725b457c42b16875c9fd119"} build_url:https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010728 build_url: 'https://queue.taskcluster.net/v1/task/XDd_OtorTQ2FSqlshpkODQ/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/6a20afdac447e6e5b1998eeac0c7754f5f689a322c2b65d8ec8c3c33cc59c3ab73c20281c5d40aea625e2d23ab191353e7c075e013f226154900575737bd97d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd08fb42913452bfdf40f3a5d528ed41b02ed288c290e8fcc7c1e234f97eeae796895f761b3c7c5fe5613aa0b74ba48f601e4f092993dd51e7e6bb44c6db0356", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8474d3781b1402e011ba1516ae077eeb89d0e63721786ee12ee0739116467b89325ad2b6238f5ce11c30765613f47ec010b01dcfe00265004f1eacf1f896e083", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb31050f874eb3846684c50d889f01dea88bc34a26601b045d2c5ac53eed333e86419f44a700abe193ad8fcffb8fbd58ef88ac22c725b457c42b16875c9fd119"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 23:26:13.197992) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 23:26:13.198313) ========= 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.GeAY0AQB1U/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.H9bgWvNMkm/Listeners TMPDIR=/var/folders/l3/698npc0163s3r3bxv5kkw36r00000w/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.005150 ========= master_lag: 0.68 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 23:26:13.883144) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 23:26:13.883464) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 23:26:13.884139) ========= ========= Total master_lag: 2.80 =========