builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-071 starttime: 1502473577.95 results: success (0) revision: 2261de3ebaaa934e43f4e12835e114ec0c8fec5c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:17.952976) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:17.953441) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:17.953713) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:17.969930) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:17.970209) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-11 10:46:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (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% 46.2M=0s 2017-08-11 10:46:18 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125387 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:18.123484) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:18.123755) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018945 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:18.185457) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-11 10:46:18.185755) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2261de3ebaaa934e43f4e12835e114ec0c8fec5c --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 2261de3ebaaa934e43f4e12835e114ec0c8fec5c --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-11 10:46:18,720 truncating revision to first 12 chars 2017-08-11 10:46:18,720 Setting DEBUG logging. 2017-08-11 10:46:18,720 attempt 1/10 2017-08-11 10:46:18,720 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2261de3ebaaa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-11 10:46:19,504 unpacking tar archive at: mozilla-central-2261de3ebaaa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.691528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-11 10:46:19.897351) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:19.897647) ========= script_repo_revision: 2261de3ebaaa934e43f4e12835e114ec0c8fec5c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:19.898074) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:19.898364) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 10:46:19.909670) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2017-08-11 10:46:19.910210) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:46:20 INFO - MultiFileLogger online at 20170811 10:46:20 in /builds/slave/test 10:46:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:46:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:46:20 INFO - {'append_to_log': False, 10:46:20 INFO - 'base_work_dir': '/builds/slave/test', 10:46:20 INFO - 'blob_upload_branch': 'Firefox', 10:46:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:46:20 INFO - 'branch': 'Firefox', 10:46:20 INFO - 'buildbot_json_path': 'buildprops.json', 10:46:20 INFO - 'code_coverage': False, 10:46:20 INFO - 'config_files': ('talos/linux_config.py',), 10:46:20 INFO - 'default_actions': ('clobber', 10:46:20 INFO - 'read-buildbot-config', 10:46:20 INFO - 'download-and-extract', 10:46:20 INFO - 'populate-webroot', 10:46:20 INFO - 'create-virtualenv', 10:46:20 INFO - 'install', 10:46:20 INFO - 'setup-mitmproxy', 10:46:20 INFO - 'run-tests'), 10:46:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:46:20 INFO - 'disable_ccov_upload': False, 10:46:20 INFO - 'download_minidump_stackwalk': True, 10:46:20 INFO - 'download_symbols': 'ondemand', 10:46:20 INFO - 'enable_stylo': False, 10:46:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:46:20 INFO - 'tooltool.py': '/tools/tooltool.py', 10:46:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:46:20 INFO - '/tools/misc-python/virtualenv.py')}, 10:46:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:46:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:46:20 INFO - 'gecko_profile': False, 10:46:20 INFO - 'gecko_profile_interval': 0, 10:46:20 INFO - 'installer_path': 'installer.exe', 10:46:20 INFO - 'log_level': 'info', 10:46:20 INFO - 'log_name': 'talos', 10:46:20 INFO - 'log_to_console': True, 10:46:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:46:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:46:20 INFO - 'opt_config_files': (), 10:46:20 INFO - 'pip_index': False, 10:46:20 INFO - 'suite': 'dromaeojs-e10s', 10:46:20 INFO - 'system_bits': '32', 10:46:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:46:20 INFO - 'title': 'talos-linux64-ix-071', 10:46:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:46:20 INFO - 'use_talos_json': True, 10:46:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:46:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:46:20 INFO - 'work_dir': 'build'} 10:46:20 INFO - [mozharness: 2017-08-11 17:46:20.546280Z] Running clobber step. 10:46:20 INFO - Running pre-action listener: _resource_record_pre_action 10:46:20 INFO - Running main action method: clobber 10:46:20 INFO - rmtree: /builds/slave/test/build 10:46:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:46:21 INFO - Running post-action listener: _resource_record_post_action 10:46:21 INFO - [mozharness: 2017-08-11 17:46:21.583022Z] Finished clobber step (success) 10:46:21 INFO - [mozharness: 2017-08-11 17:46:21.583125Z] Running read-buildbot-config step. 10:46:21 INFO - Running pre-action listener: _resource_record_pre_action 10:46:21 INFO - Running main action method: read_buildbot_config 10:46:21 INFO - Using buildbot properties: 10:46:21 INFO - { 10:46:21 INFO - "project": "", 10:46:21 INFO - "product": "firefox", 10:46:21 INFO - "who": "ffxbld@noreply.mozilla.org", 10:46:21 INFO - "installer_path": "public/build/target.tar.bz2", 10:46:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 10:46:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 10:46:21 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 10:46:21 INFO - "stage_platform": "linux64-stylo", 10:46:21 INFO - "basedir": "/builds/slave/test", 10:46:21 INFO - "buildnumber": 23, 10:46:21 INFO - "platform": "ubuntu64_hw_stylo", 10:46:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:46:21 INFO - "slavebuilddir": "test", 10:46:21 INFO - "taskId": "F2KusKgmRW2vjoGwdoeUHQ", 10:46:21 INFO - "branch": "mozilla-central", 10:46:21 INFO - "script_repo_revision": "production", 10:46:21 INFO - "revision": "2261de3ebaaa934e43f4e12835e114ec0c8fec5c", 10:46:21 INFO - "slavename": "talos-linux64-ix-071", 10:46:21 INFO - "repo_path": "mozilla-central" 10:46:21 INFO - } 10:46:21 INFO - Finding installer, test and symbols from parent task. 10:46:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F2KusKgmRW2vjoGwdoeUHQ'}, attempt #1 10:46:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F2KusKgmRW2vjoGwdoeUHQ'}, attempt #1 10:46:22 INFO - Task dependencies: E4iAGlFXSzGuQDDtIxOgHQ 10:46:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ'}, attempt #1 10:46:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2 10:46:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.test_packages.json 10:46:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.crashreporter-symbols.zip 10:46:22 INFO - Running post-action listener: _resource_record_post_action 10:46:22 INFO - [mozharness: 2017-08-11 17:46:22.948935Z] Finished read-buildbot-config step (success) 10:46:22 INFO - [mozharness: 2017-08-11 17:46:22.949156Z] Running download-and-extract step. 10:46:22 INFO - Running pre-action listener: _resource_record_pre_action 10:46:22 INFO - Running main action method: download_and_extract 10:46:22 INFO - mkdir: /builds/slave/test/build/tests 10:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:46:22 INFO - trying https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.test_packages.json 10:46:22 INFO - Downloading https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:46:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:46:23 INFO - Downloaded 989 bytes. 10:46:23 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:46:23 INFO - Using the following test package requirements: 10:46:23 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 10:46:23 INFO - u'common': [u'target.common.tests.zip'], 10:46:23 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:46:23 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:46:23 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:46:23 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:46:23 INFO - u'mozbase': [u'target.common.tests.zip'], 10:46:23 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:46:23 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:46:23 INFO - u'web-platform': [u'target.common.tests.zip', 10:46:23 INFO - u'target.web-platform.tests.tar.gz'], 10:46:23 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:46:23 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:46:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.common.tests.zip 10:46:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:46:23 INFO - Fetch https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.common.tests.zip into memory 10:46:26 INFO - Content-Length response header: 54036669 10:46:26 INFO - Bytes received: 54036669 10:46:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:46:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.common.tests.zip 10:46:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:46:37 INFO - Fetch https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.common.tests.zip into memory 10:46:38 INFO - Content-Length response header: 54036669 10:46:38 INFO - Bytes received: 54036669 10:46:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.talos.tests.zip 10:46:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:46:50 INFO - Fetch https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.talos.tests.zip into memory 10:46:51 INFO - Content-Length response header: 14496050 10:46:51 INFO - Bytes received: 14496050 10:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:46:51 INFO - trying https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2 10:46:51 INFO - Downloading https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:46:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:47:24 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2: The read operation timed out 10:47:24 INFO - retry: attempt #1 caught SSLError exception: The read operation timed out 10:47:24 INFO - retry: Failed, sleeping 30 seconds before retrying 10:47:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #2 10:47:55 INFO - Downloaded 61817910 bytes. 10:47:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2 10:47:55 INFO - mkdir: /builds/slave/test/properties 10:47:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:47:55 INFO - Writing to file /builds/slave/test/properties/build_url 10:47:55 INFO - Contents: 10:47:55 INFO - build_url:https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2 10:47:55 INFO - Running post-action listener: _resource_record_post_action 10:47:55 INFO - Running post-action listener: set_extra_try_arguments 10:47:55 INFO - [mozharness: 2017-08-11 17:47:55.574347Z] Finished download-and-extract step (success) 10:47:55 INFO - [mozharness: 2017-08-11 17:47:55.574448Z] Running populate-webroot step. 10:47:55 INFO - Running pre-action listener: _resource_record_pre_action 10:47:55 INFO - Running main action method: populate_webroot 10:47:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:47:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:47:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:47:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:47:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:47:55 INFO - 'tests': ['damp', 'tps']}, 10:47:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:47:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:47:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:47:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:47:55 INFO - 'other-e10s': {'tests': ['a11yr', 10:47:55 INFO - 'ts_paint', 10:47:55 INFO - 'tpaint', 10:47:55 INFO - 'sessionrestore', 10:47:55 INFO - 'sessionrestore_many_windows', 10:47:55 INFO - 'sessionrestore_no_auto_restore', 10:47:55 INFO - 'tabpaint']}, 10:47:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 10:47:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 10:47:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:47:55 INFO - 'tsvgr_opacity', 10:47:55 INFO - 'tart', 10:47:55 INFO - 'tscrollx', 10:47:55 INFO - 'tsvg_static']}, 10:47:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:47:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:47:55 INFO - 'talos_options': ['--mitmproxy', 10:47:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:47:55 INFO - '--firstNonBlankPaint'], 10:47:55 INFO - 'tests': ['tp6_google', 10:47:55 INFO - 'tp6_youtube', 10:47:55 INFO - 'tp6_amazon', 10:47:55 INFO - 'tp6_facebook']}, 10:47:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:47:55 INFO - 'talos_options': ['--stylo', 10:47:55 INFO - '--mitmproxy', 10:47:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:47:55 INFO - '--firstNonBlankPaint'], 10:47:55 INFO - 'tests': ['tp6_google', 10:47:55 INFO - 'tp6_youtube', 10:47:55 INFO - 'tp6_amazon', 10:47:55 INFO - 'tp6_facebook']}, 10:47:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:47:55 INFO - 'talos_options': ['--stylo', 10:47:55 INFO - '--stylo-threads=1', 10:47:55 INFO - '--mitmproxy', 10:47:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:47:55 INFO - '--firstNonBlankPaint'], 10:47:55 INFO - 'tests': ['tp6_google', 10:47:55 INFO - 'tp6_youtube', 10:47:55 INFO - 'tp6_amazon', 10:47:55 INFO - 'tp6_facebook']}, 10:47:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:47:55 INFO - 'talos_options': ['--xperf_path', 10:47:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:47:55 INFO - 'tests': ['tp5n']}}} 10:47:55 INFO - Running post-action listener: _resource_record_post_action 10:47:55 INFO - [mozharness: 2017-08-11 17:47:55.582986Z] Finished populate-webroot step (success) 10:47:55 INFO - [mozharness: 2017-08-11 17:47:55.583076Z] Running create-virtualenv step. 10:47:55 INFO - Running pre-action listener: _resource_record_pre_action 10:47:55 INFO - Running main action method: create_virtualenv 10:47:55 INFO - Creating virtualenv /builds/slave/test/build/venv 10:47:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:47:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:47:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:47:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:47:55 INFO - Using real prefix '/usr' 10:47:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:47:56 INFO - Installing distribute.............................................................................................................................................................................................done. 10:47:58 INFO - Installing pip.................done. 10:47:58 INFO - Return code: 0 10:47:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:47:58 INFO - Reading from file tmpfile_stdout 10:47:58 INFO - Output received: 10:47:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:47:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:47: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')]} 10:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47: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')]} 10:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:58 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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:47:58 INFO - Using env: {'DISPLAY': ':0', 10:47:58 INFO - 'HOME': '/home/cltbld', 10:47:58 INFO - 'LANG': 'en_US.UTF-8', 10:47:58 INFO - 'LANGUAGE': 'en_US:en', 10:47:58 INFO - 'LOGNAME': 'cltbld', 10:47:58 INFO - 'MAIL': '/var/mail/cltbld', 10:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:58 INFO - 'MOZ_NO_REMOTE': '1', 10:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:47:58 INFO - 'NO_EM_RESTART': '1', 10:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:58 INFO - 'PWD': '/builds/slave/test', 10:47:58 INFO - 'SHELL': '/bin/bash', 10:47:58 INFO - 'SHLVL': '1', 10:47:58 INFO - 'TERM': 'linux', 10:47:58 INFO - 'TMOUT': '86400', 10:47:58 INFO - 'USER': 'cltbld', 10:47:58 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:58 INFO - '_': '/tools/buildbot/bin/python'} 10:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:47:58 INFO - Cleaning up... 10:47:58 INFO - Return code: 0 10:47:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:47: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')]} 10:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47: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')]} 10:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:58 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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:47:58 INFO - Using env: {'DISPLAY': ':0', 10:47:58 INFO - 'HOME': '/home/cltbld', 10:47:58 INFO - 'LANG': 'en_US.UTF-8', 10:47:58 INFO - 'LANGUAGE': 'en_US:en', 10:47:58 INFO - 'LOGNAME': 'cltbld', 10:47:58 INFO - 'MAIL': '/var/mail/cltbld', 10:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:58 INFO - 'MOZ_NO_REMOTE': '1', 10:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:47:58 INFO - 'NO_EM_RESTART': '1', 10:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:58 INFO - 'PWD': '/builds/slave/test', 10:47:58 INFO - 'SHELL': '/bin/bash', 10:47:58 INFO - 'SHLVL': '1', 10:47:58 INFO - 'TERM': 'linux', 10:47:58 INFO - 'TMOUT': '86400', 10:47:58 INFO - 'USER': 'cltbld', 10:47:58 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:58 INFO - '_': '/tools/buildbot/bin/python'} 10:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:58 INFO - Downloading/unpacking psutil>=3.1.1 10:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:47:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:47:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:00 INFO - Installing collected packages: psutil 10:48:00 INFO - Running setup.py install for psutil 10:48:00 INFO - building 'psutil._psutil_linux' extension 10:48:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:48:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:48:00 INFO - building 'psutil._psutil_posix' extension 10:48:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:48:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:48:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:00 INFO - Successfully installed psutil 10:48:00 INFO - Cleaning up... 10:48:00 INFO - Return code: 0 10:48:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:00 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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:48:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:48:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:48:00 INFO - Using env: {'DISPLAY': ':0', 10:48:00 INFO - 'HOME': '/home/cltbld', 10:48:00 INFO - 'LANG': 'en_US.UTF-8', 10:48:00 INFO - 'LANGUAGE': 'en_US:en', 10:48:00 INFO - 'LOGNAME': 'cltbld', 10:48:00 INFO - 'MAIL': '/var/mail/cltbld', 10:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:00 INFO - 'MOZ_NO_REMOTE': '1', 10:48:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:00 INFO - 'NO_EM_RESTART': '1', 10:48:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:00 INFO - 'PWD': '/builds/slave/test', 10:48:00 INFO - 'SHELL': '/bin/bash', 10:48:00 INFO - 'SHLVL': '1', 10:48:00 INFO - 'TERM': 'linux', 10:48:00 INFO - 'TMOUT': '86400', 10:48:00 INFO - 'USER': 'cltbld', 10:48:00 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:00 INFO - '_': '/tools/buildbot/bin/python'} 10:48:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:48:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:48:01 INFO - Installing collected packages: mozsystemmonitor 10:48:01 INFO - Running setup.py install for mozsystemmonitor 10:48:02 INFO - Successfully installed mozsystemmonitor 10:48:02 INFO - Cleaning up... 10:48:02 INFO - Return code: 0 10:48:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:48:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:48:02 INFO - Using env: {'DISPLAY': ':0', 10:48:02 INFO - 'HOME': '/home/cltbld', 10:48:02 INFO - 'LANG': 'en_US.UTF-8', 10:48:02 INFO - 'LANGUAGE': 'en_US:en', 10:48:02 INFO - 'LOGNAME': 'cltbld', 10:48:02 INFO - 'MAIL': '/var/mail/cltbld', 10:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:02 INFO - 'MOZ_NO_REMOTE': '1', 10:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:02 INFO - 'NO_EM_RESTART': '1', 10:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:02 INFO - 'PWD': '/builds/slave/test', 10:48:02 INFO - 'SHELL': '/bin/bash', 10:48:02 INFO - 'SHLVL': '1', 10:48:02 INFO - 'TERM': 'linux', 10:48:02 INFO - 'TMOUT': '86400', 10:48:02 INFO - 'USER': 'cltbld', 10:48:02 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:02 INFO - '_': '/tools/buildbot/bin/python'} 10:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:02 INFO - Downloading/unpacking jsonschema==2.5.1 10:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:48:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:48:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:48:03 INFO - warning: no files found matching '*.txt' 10:48:03 INFO - no previously-included directories found matching 'build' 10:48:03 INFO - no previously-included directories found matching 'dist' 10:48:03 INFO - no previously-included directories found matching '.git*' 10:48:03 INFO - Installing collected packages: jsonschema, functools32 10:48:03 INFO - Running setup.py install for functools32 10:48:03 INFO - warning: no files found matching '*.txt' 10:48:03 INFO - no previously-included directories found matching 'build' 10:48:03 INFO - no previously-included directories found matching 'dist' 10:48:03 INFO - no previously-included directories found matching '.git*' 10:48:03 INFO - Successfully installed jsonschema functools32 10:48:03 INFO - Cleaning up... 10:48:03 INFO - Return code: 0 10:48:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48: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')]} 10:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:48:03 INFO - Using env: {'DISPLAY': ':0', 10:48:03 INFO - 'HOME': '/home/cltbld', 10:48:03 INFO - 'LANG': 'en_US.UTF-8', 10:48:03 INFO - 'LANGUAGE': 'en_US:en', 10:48:03 INFO - 'LOGNAME': 'cltbld', 10:48:03 INFO - 'MAIL': '/var/mail/cltbld', 10:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:03 INFO - 'MOZ_NO_REMOTE': '1', 10:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:03 INFO - 'NO_EM_RESTART': '1', 10:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:03 INFO - 'PWD': '/builds/slave/test', 10:48:03 INFO - 'SHELL': '/bin/bash', 10:48:03 INFO - 'SHLVL': '1', 10:48:03 INFO - 'TERM': 'linux', 10:48:03 INFO - 'TMOUT': '86400', 10:48:03 INFO - 'USER': 'cltbld', 10:48:03 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:03 INFO - '_': '/tools/buildbot/bin/python'} 10:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:48:04 INFO - Cleaning up... 10:48:04 INFO - Return code: 0 10:48:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:04 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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:48:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:48:04 INFO - Using env: {'DISPLAY': ':0', 10:48:04 INFO - 'HOME': '/home/cltbld', 10:48:04 INFO - 'LANG': 'en_US.UTF-8', 10:48:04 INFO - 'LANGUAGE': 'en_US:en', 10:48:04 INFO - 'LOGNAME': 'cltbld', 10:48:04 INFO - 'MAIL': '/var/mail/cltbld', 10:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:04 INFO - 'MOZ_NO_REMOTE': '1', 10:48:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:04 INFO - 'NO_EM_RESTART': '1', 10:48:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:04 INFO - 'PWD': '/builds/slave/test', 10:48:04 INFO - 'SHELL': '/bin/bash', 10:48:04 INFO - 'SHLVL': '1', 10:48:04 INFO - 'TERM': 'linux', 10:48:04 INFO - 'TMOUT': '86400', 10:48:04 INFO - 'USER': 'cltbld', 10:48:04 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:04 INFO - '_': '/tools/buildbot/bin/python'} 10:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:04 INFO - Downloading/unpacking blobuploader==1.2.4 10:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:48:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:48: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 10:48: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 10:48: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 10:48: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 10:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:48:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:48: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 10:48: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 10:48: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 10:48: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 10:48:06 INFO - Downloading docopt-0.6.1.tar.gz 10:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:48:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:48:06 INFO - Running setup.py install for blobuploader 10:48:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:48:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:48:06 INFO - Running setup.py install for requests 10:48:06 INFO - Running setup.py install for docopt 10:48:06 INFO - Successfully installed blobuploader requests docopt 10:48:06 INFO - Cleaning up... 10:48:06 INFO - Return code: 0 10:48:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:06 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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:48:06 INFO - Using env: {'DISPLAY': ':0', 10:48:06 INFO - 'HOME': '/home/cltbld', 10:48:06 INFO - 'LANG': 'en_US.UTF-8', 10:48:06 INFO - 'LANGUAGE': 'en_US:en', 10:48:06 INFO - 'LOGNAME': 'cltbld', 10:48:06 INFO - 'MAIL': '/var/mail/cltbld', 10:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:06 INFO - 'MOZ_NO_REMOTE': '1', 10:48:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:06 INFO - 'NO_EM_RESTART': '1', 10:48:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:06 INFO - 'PWD': '/builds/slave/test', 10:48:06 INFO - 'SHELL': '/bin/bash', 10:48:06 INFO - 'SHLVL': '1', 10:48:06 INFO - 'TERM': 'linux', 10:48:06 INFO - 'TMOUT': '86400', 10:48:06 INFO - 'USER': 'cltbld', 10:48:06 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:06 INFO - '_': '/tools/buildbot/bin/python'} 10:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:06 INFO - Running setup.py (path:/tmp/pip-pT8qEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:06 INFO - Running setup.py (path:/tmp/pip-bRTvaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:07 INFO - Running setup.py (path:/tmp/pip-3zSrCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:07 INFO - Running setup.py (path:/tmp/pip-3zaXov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:07 INFO - Running setup.py (path:/tmp/pip-O0GAcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:07 INFO - Running setup.py (path:/tmp/pip-pRNnha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:07 INFO - Running setup.py (path:/tmp/pip-LHs3F0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:07 INFO - Running setup.py (path:/tmp/pip-RuPt7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:07 INFO - Running setup.py (path:/tmp/pip-oA_lRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:07 INFO - Running setup.py (path:/tmp/pip-YuN9fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:07 INFO - Running setup.py (path:/tmp/pip-VA5keG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:07 INFO - Running setup.py (path:/tmp/pip-XHn3ZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:07 INFO - Running setup.py (path:/tmp/pip-4N_kxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:07 INFO - Running setup.py (path:/tmp/pip-bZm4II-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:07 INFO - Running setup.py (path:/tmp/pip-YJoUuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:07 INFO - Running setup.py (path:/tmp/pip-ME_HCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:08 INFO - Running setup.py (path:/tmp/pip-3Fq_tS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:48:08 INFO - Running setup.py install for manifestparser 10:48:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:48:08 INFO - Running setup.py install for mozcrash 10:48:08 INFO - Running setup.py install for mozdebug 10:48:08 INFO - Running setup.py install for mozdevice 10:48:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:48:08 INFO - Running setup.py install for mozfile 10:48:08 INFO - Running setup.py install for mozhttpd 10:48:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:48:08 INFO - Running setup.py install for mozinfo 10:48:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:48:08 INFO - Running setup.py install for mozInstall 10:48:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:48:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:48:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:48:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:48:08 INFO - Running setup.py install for mozleak 10:48:08 INFO - Running setup.py install for mozlog 10:48:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:48:09 INFO - Running setup.py install for moznetwork 10:48:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:48:09 INFO - Running setup.py install for mozprocess 10:48:09 INFO - Running setup.py install for mozprofile 10:48:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:48:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:48:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:48:09 INFO - Running setup.py install for mozrunner 10:48:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:48:09 INFO - Running setup.py install for mozscreenshot 10:48:09 INFO - Running setup.py install for moztest 10:48:09 INFO - Running setup.py install for mozversion 10:48:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:48:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:48:09 INFO - Cleaning up... 10:48:09 INFO - Return code: 0 10:48:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:09 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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:48:09 INFO - Using env: {'DISPLAY': ':0', 10:48:09 INFO - 'HOME': '/home/cltbld', 10:48:09 INFO - 'LANG': 'en_US.UTF-8', 10:48:09 INFO - 'LANGUAGE': 'en_US:en', 10:48:09 INFO - 'LOGNAME': 'cltbld', 10:48:09 INFO - 'MAIL': '/var/mail/cltbld', 10:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:09 INFO - 'MOZ_NO_REMOTE': '1', 10:48:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:09 INFO - 'NO_EM_RESTART': '1', 10:48:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:09 INFO - 'PWD': '/builds/slave/test', 10:48:09 INFO - 'SHELL': '/bin/bash', 10:48:09 INFO - 'SHLVL': '1', 10:48:09 INFO - 'TERM': 'linux', 10:48:09 INFO - 'TMOUT': '86400', 10:48:09 INFO - 'USER': 'cltbld', 10:48:09 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:09 INFO - '_': '/tools/buildbot/bin/python'} 10:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:10 INFO - Running setup.py (path:/tmp/pip-JYtqbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:10 INFO - Running setup.py (path:/tmp/pip-L4bMOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:10 INFO - Running setup.py (path:/tmp/pip-i3l1hE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:10 INFO - Running setup.py (path:/tmp/pip-4q2DTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:10 INFO - Running setup.py (path:/tmp/pip-_ohj9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:10 INFO - Running setup.py (path:/tmp/pip-BArd5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:10 INFO - Running setup.py (path:/tmp/pip-42rUAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:10 INFO - Running setup.py (path:/tmp/pip-XP_RBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:10 INFO - Running setup.py (path:/tmp/pip-rbNAGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:10 INFO - Running setup.py (path:/tmp/pip-jbXG_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:10 INFO - Running setup.py (path:/tmp/pip-6MP_fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:10 INFO - Running setup.py (path:/tmp/pip-dmq06t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:10 INFO - Running setup.py (path:/tmp/pip-emQo0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:11 INFO - Running setup.py (path:/tmp/pip-ATm3F4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:11 INFO - Running setup.py (path:/tmp/pip-xlTVte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:11 INFO - Running setup.py (path:/tmp/pip-wgPujR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:11 INFO - Running setup.py (path:/tmp/pip-AI37FL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:48:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:12 INFO - Downloading blessings-1.6.tar.gz 10:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:48:12 INFO - Installing collected packages: blessings 10:48:12 INFO - Running setup.py install for blessings 10:48:12 INFO - Successfully installed blessings 10:48:12 INFO - Cleaning up... 10:48:12 INFO - Return code: 0 10:48:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:48:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:12 INFO - Reading from file tmpfile_stdout 10:48:12 INFO - Current package versions: 10:48:12 INFO - argparse == 1.2.1 10:48:12 INFO - blessings == 1.6 10:48:12 INFO - blobuploader == 1.2.4 10:48:12 INFO - docopt == 0.6.1 10:48:12 INFO - functools32 == 3.2.3-2 10:48:12 INFO - jsonschema == 2.5.1 10:48:12 INFO - manifestparser == 1.1 10:48:12 INFO - mozInstall == 1.13 10:48:12 INFO - mozcrash == 1.0 10:48:12 INFO - mozdebug == 0.1 10:48:12 INFO - mozdevice == 0.51 10:48:12 INFO - mozfile == 1.2 10:48:12 INFO - mozhttpd == 0.7 10:48:12 INFO - mozinfo == 0.10 10:48:12 INFO - mozleak == 0.1 10:48:12 INFO - mozlog == 3.5 10:48:12 INFO - moznetwork == 0.27 10:48:12 INFO - mozprocess == 0.25 10:48:12 INFO - mozprofile == 0.28 10:48:12 INFO - mozrunner == 6.13 10:48:12 INFO - mozscreenshot == 0.1 10:48:12 INFO - mozsystemmonitor == 0.3 10:48:12 INFO - moztest == 0.8 10:48:12 INFO - mozversion == 1.4 10:48:12 INFO - psutil == 3.1.1 10:48:12 INFO - requests == 1.2.3 10:48:12 INFO - wsgiref == 0.1.2 10:48:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:12 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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:48:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:48:12 INFO - Using env: {'DISPLAY': ':0', 10:48:12 INFO - 'HOME': '/home/cltbld', 10:48:12 INFO - 'LANG': 'en_US.UTF-8', 10:48:12 INFO - 'LANGUAGE': 'en_US:en', 10:48:12 INFO - 'LOGNAME': 'cltbld', 10:48:12 INFO - 'MAIL': '/var/mail/cltbld', 10:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:12 INFO - 'MOZ_NO_REMOTE': '1', 10:48:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:12 INFO - 'NO_EM_RESTART': '1', 10:48:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:12 INFO - 'PWD': '/builds/slave/test', 10:48:12 INFO - 'SHELL': '/bin/bash', 10:48:12 INFO - 'SHLVL': '1', 10:48:12 INFO - 'TERM': 'linux', 10:48:12 INFO - 'TMOUT': '86400', 10:48:12 INFO - 'USER': 'cltbld', 10:48:12 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:12 INFO - '_': '/tools/buildbot/bin/python'} 10:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:48:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 10:48:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:48:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:48:14 INFO - Installing collected packages: simplejson 10:48:14 INFO - Running setup.py install for simplejson 10:48:14 INFO - building 'simplejson._speedups' extension 10:48:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 10:48:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 10:48:15 INFO - Successfully installed simplejson 10:48:15 INFO - Cleaning up... 10:48:15 INFO - Return code: 0 10:48:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:15 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 0x17961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:48:15 INFO - Using env: {'DISPLAY': ':0', 10:48:15 INFO - 'HOME': '/home/cltbld', 10:48:15 INFO - 'LANG': 'en_US.UTF-8', 10:48:15 INFO - 'LANGUAGE': 'en_US:en', 10:48:15 INFO - 'LOGNAME': 'cltbld', 10:48:15 INFO - 'MAIL': '/var/mail/cltbld', 10:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:15 INFO - 'MOZ_NO_REMOTE': '1', 10:48:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:15 INFO - 'NO_EM_RESTART': '1', 10:48:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:15 INFO - 'PWD': '/builds/slave/test', 10:48:15 INFO - 'SHELL': '/bin/bash', 10:48:15 INFO - 'SHLVL': '1', 10:48:15 INFO - 'TERM': 'linux', 10:48:15 INFO - 'TMOUT': '86400', 10:48:15 INFO - 'USER': 'cltbld', 10:48:15 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:15 INFO - '_': '/tools/buildbot/bin/python'} 10:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:48:15 INFO - Cleaning up... 10:48:15 INFO - Return code: 0 10:48:15 INFO - Running post-action listener: _resource_record_post_action 10:48:15 INFO - Running post-action listener: _start_resource_monitoring 10:48:15 INFO - Starting resource monitoring. 10:48:15 INFO - [mozharness: 2017-08-11 17:48:15.618669Z] Finished create-virtualenv step (success) 10:48:15 INFO - [mozharness: 2017-08-11 17:48:15.619183Z] Running install step. 10:48:15 INFO - Running pre-action listener: _resource_record_pre_action 10:48:15 INFO - Running main action method: install 10:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:15 INFO - Reading from file tmpfile_stdout 10:48:15 INFO - Detecting whether we're running mozinstall >=1.0... 10:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:48:15 INFO - Reading from file tmpfile_stdout 10:48:15 INFO - Output received: 10:48:15 INFO - Usage: mozinstall [options] installer 10:48:15 INFO - Options: 10:48:15 INFO - -h, --help show this help message and exit 10:48:15 INFO - -d DEST, --destination=DEST 10:48:15 INFO - Directory to install application into. [default: 10:48:15 INFO - "/builds/slave/test"] 10:48:15 INFO - --app=APP Application being installed. [default: firefox] 10:48:15 INFO - mkdir: /builds/slave/test/build/application 10:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:48:33 INFO - Reading from file tmpfile_stdout 10:48:33 INFO - Output received: 10:48:33 INFO - /builds/slave/test/build/application/firefox/firefox 10:48:33 INFO - Running post-action listener: _resource_record_post_action 10:48:33 INFO - [mozharness: 2017-08-11 17:48:33.900131Z] Finished install step (success) 10:48:33 INFO - [mozharness: 2017-08-11 17:48:33.900280Z] Running setup-mitmproxy step. 10:48:33 INFO - Running pre-action listener: _resource_record_pre_action 10:48:33 INFO - Running main action method: setup_mitmproxy 10:48:33 INFO - Skipping: mitmproxy is not required 10:48:33 INFO - Running post-action listener: _resource_record_post_action 10:48:33 INFO - [mozharness: 2017-08-11 17:48:33.900817Z] Finished setup-mitmproxy step (success) 10:48:33 INFO - [mozharness: 2017-08-11 17:48:33.900922Z] Running run-tests step. 10:48:33 INFO - Running pre-action listener: _resource_record_pre_action 10:48:33 INFO - Running pre-action listener: _set_gcov_prefix 10:48:33 INFO - Running main action method: run_tests 10:48:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:48:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:48:33 INFO - Python 2.7.3 10:48:33 INFO - Return code: 0 10:48:33 INFO - grabbing minidump binary from tooltool 10:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:33 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/linux64/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 0x1b06f00>, '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 0x1b01ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b02050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:48:33 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:48:33 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/linux64/releng.manifest -o -c /builds/tooltool_cache 10:48:33 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:48:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:48:34 INFO - Return code: 0 10:48:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:48:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:48:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:48:34 INFO - ENV: RUST_BACKTRACE is now full 10:48:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:48:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:48:34 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/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:48:34 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/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-071 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:48:34 INFO - Using env: {'DISPLAY': ':0', 10:48:34 INFO - 'HOME': '/home/cltbld', 10:48:34 INFO - 'LANG': 'en_US.UTF-8', 10:48:34 INFO - 'LANGUAGE': 'en_US:en', 10:48:34 INFO - 'LOGNAME': 'cltbld', 10:48:34 INFO - 'MAIL': '/var/mail/cltbld', 10:48:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:48:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:34 INFO - 'MOZ_NO_REMOTE': '1', 10:48:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:48:34 INFO - 'NO_EM_RESTART': '1', 10:48:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:34 INFO - 'PWD': '/builds/slave/test', 10:48:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:48:34 INFO - 'RUST_BACKTRACE': 'full', 10:48:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:48:34 INFO - 'SHELL': '/bin/bash', 10:48:34 INFO - 'SHLVL': '1', 10:48:34 INFO - 'STYLO_FORCE_ENABLED': '1', 10:48:34 INFO - 'TERM': 'linux', 10:48:34 INFO - 'TMOUT': '86400', 10:48:34 INFO - 'USER': 'cltbld', 10:48:34 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725', 10:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:34 INFO - '_': '/tools/buildbot/bin/python'} 10:48:34 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/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:48:34 INFO - mozversion application_buildid: 20170811170435 10:48:34 INFO - mozversion application_changeset: 2261de3ebaaa934e43f4e12835e114ec0c8fec5c 10:48:34 INFO - mozversion application_display_name: Nightly 10:48:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:48:34 INFO - mozversion application_name: Firefox 10:48:34 INFO - mozversion application_remotingname: firefox 10:48:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:48:34 INFO - mozversion application_vendor: Mozilla 10:48:34 INFO - mozversion application_version: 57.0a1 10:48:34 INFO - mozversion platform_buildid: 20170811170435 10:48:34 INFO - mozversion platform_changeset: 2261de3ebaaa934e43f4e12835e114ec0c8fec5c 10:48:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:48:34 INFO - mozversion platform_version: 57.0a1 10:48:34 INFO - using testdate: 1502473714 10:48:34 INFO - actual date: 1502473714 10:48:34 INFO - starting webserver on 'localhost:55061' 10:48:34 INFO - SUITE-START | Running 2 tests 10:48:34 INFO - TEST-START | dromaeo_css 10:48:34 INFO - Initialising browser for dromaeo_css test... 10:48:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55061/getInfo.html -profile /tmp/tmpfMA8IY/profile 10:48:34 INFO - TEST-INFO | started process 412 (/builds/slave/test/build/application/firefox/firefox http://localhost:55061/getInfo.html) 10:48:40 INFO - TEST-INFO | 412: exit 0 10:48:40 INFO - Browser initialized. 10:48:40 INFO - Running cycle 1/1 for dromaeo_css test... 10:48:40 INFO - TEST-INFO | started process 653 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfMA8IY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:48:41 INFO - PID 653 | 10:48:41 INFO - PID 653 | (/builds/slave/test/build/application/firefox/firefox:708): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 10:48:41 INFO - PID 653 | 10:48:52 INFO - PID 653 | RSS: Main: 167546880 10:48:52 INFO - PID 653 | 10:51:51 INFO - PID 653 | Cycle 1(1): loaded http://localhost:55061/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55061/tests/dromaeo/cssquery-ext.html) 10:51:53 INFO - PID 653 | RSS: Main: 175157248 10:51:53 INFO - PID 653 | 10:54:54 INFO - PID 653 | Cycle 1(1): loaded http://localhost:55061/tests/dromaeo/cssquery-ext.html (next: http://localhost:55061/tests/dromaeo/cssquery-jquery.html) 10:54:55 INFO - PID 653 | RSS: Main: 170962944 10:54:55 INFO - PID 653 | 10:57:57 INFO - PID 653 | Cycle 1(1): loaded http://localhost:55061/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55061/tests/dromaeo/cssquery-mootools.html) 10:57:57 INFO - PID 653 | RSS: Main: 174317568 10:57:57 INFO - PID 653 | 11:00:59 INFO - PID 653 | Cycle 1(1): loaded http://localhost:55061/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55061/tests/dromaeo/cssquery-prototype.html) 11:01:00 INFO - PID 653 | RSS: Main: 172752896 11:01:00 INFO - PID 653 | 11:04:03 INFO - PID 653 | Cycle 1(1): loaded http://localhost:55061/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55061/tests/dromaeo/cssquery-yui.html) 11:04:04 INFO - PID 653 | RSS: Main: 176136192 11:04:04 INFO - PID 653 | 11:07:06 INFO - PID 653 | Cycle 1(1): loaded http://localhost:55061/tests/dromaeo/cssquery-yui.html (next: http://localhost:55061/tests/dromaeo/cssquery-dojo.html) 11:07:06 INFO - PID 653 | RSS: Main: 177106944 11:07:06 INFO - PID 653 | 11:07:06 INFO - PID 653 | __start_tp_report 11:07:06 INFO - PID 653 | _x_x_mozilla_page_load 11:07:06 INFO - PID 653 | _x_x_mozilla_page_load_details 11:07:06 INFO - PID 653 | |i|pagename|runs| 11:07:06 INFO - PID 653 | |0;dojo.html;3129.3584815112904;3131.264152924063;3166.7149956503918;3180.4911214205717;3224;6146.692665366731;6171.47542458891;6306.968465157668;6323.968380158104;6331.683415829213;6134.539909506782;6248;6254.093156492306;6265.216847894013;6284.371562843725;5682.431756824305;5689.6586204827845;5713.028785106542;5724.169995350671;5730.7421166047625;5705.457981491772;5745.856353591173;5754.338251101114;5781.82654520365;5786.479216870481;5696.743646535916;5773.451522105393;5794.333651630053;5803.187553742479;5838.824835254949;5538.0585300499015;5614.663120212759;5732.684702341369;5737.254156959611;5801.82594522165;5229.581633469314;5612.7754889804355;5629.746661400206;5690.459406356389;5712.200291959129;6035.7283922223605;6126.264848218242;6199.87600247997;6205.658688772116;6215.782447614357;6575.704093315812;6639.933600664028;6681.265060843303;6684.966575167142;6799.5920244784975;6701.195856497252;6706;6707.966460167717;6717.764878229287;6722.226943901416;6188.535859810532;6201.069839524108;6205.875882482325;6214;6214.347493513161;6742.764003259862;6813.318668133246;6813.488988325896;6826;6831.726730930707;6248.937510624926;6497.350264973465;6503.577267477599;6518.217813862367;6519.119918810899;6697.799066028027;6729.05793188954;6734.696938637773;6741.494387920926;6787.728490860409;6665.866682666319;6694.531382803254;6747.527673062937;6755.527113102134;6765.627890466071;6690.59856408617;6715.46276297895;6730.293319201413;6825.078614386995;6829.282925292872;5302.257683924254;5390.838274851761;5402.027635025733;5522.779088836402;5531.087370583889;4616.492185859553;4682.976585117052;4685.882852928636;4705.95294047062;4728.40894888139;5735.569832262597;5794.652320860761;5809.506191973645;5828.300603927556;5861.062230043172;6587.769428069994;6682.198136223587;6691;6748.223954245226;6749.122614060141;5673.092305231144;5678.375378708339;5682.346530149086;5705.686187259657;5715.685637289906;5796.159556864315;5802.245708057925;5808.361080281165;5815.011448053841;5819.82540523785;5954.642721436728;6433.131527243856;6439.7424103035355;6476.805695829133;6489.610623362613;6648.667566621552;6661.067450556829;6751.358620931003;6768.323167683192;6780.660966951731;6339.112524246517;6377.489800815831;6416.839579010562;6419.293877673359;6503.447206987459;6514.511411644051;6532.706028228646;6538.346165383424;6541.8691626168165;6541.901871472027;6672.199336079701;6676.098726672031;6691.76578819739;6692.83267918306;6693.732250710112;6447.451966582893;6448.677566121769;6453.354664533509;6457.644829534421;6459.321771213955;5654.491095801398;6408.391202835724;6471.514636402195;6478;6573.1783527059115;6297;6388.616682999035;6390.936090639034;6395.680215989275;6398.808035758934;6472.126262954441;6496.123023391781;6516.73933042673;6525.184351956005;6536.281009089092;6357.90463143044;6378.266499352634;6430.549861509649;6449.4517965971545;6471.7734879279 11:07:06 INFO - PID 653 | |1;ext.html;753.1715113375288;819.6106849246609;842.4524059361418;843.3421930893904;1283.8651941546143;40899;41430.58569414305;41447.37828932564;41461.792691036506;41487.17025659489;38966;39431.211375772466;39462.210755784865;39495.802520987425;39522.604773952255;7247.855042899138;7376.336129748322;7377.299156580117;7377.446691498132;7380.815479613026;7215.639218039077;7319.634018299091;7321.231270716578;7322.597257150855;7326.560406375608;7215.567065976058;7386.556806591595;7739.264769846884;7750.728724494644;7750.7674769756995;7649.311561959423;7709.344705700022;7710.7686769397005;7712.421568382338;7713.190115037953;7437.962810185969;7585.393168546503;7591.620418979029;7628.504147230412;7672.846543069163;37720;38125.237495250214;38240.235195295936;38285.23429531394;38376.61623383759;3330.833458327098;3383.3233353329433;3385.712214461749;3389.271306669078;3393.3722261381477;31007.22481937947;31056;31137.22156946072;31149.532757008663;31165;4815.0610630926985;4818.975905120452;4838.516148385169;4845;4851.781669824831;196809;198545;198869.00565497225;199074;199198;49096.50903490919;49117.75441122843;49273.26090108651;49385;49392.25911611329;50390.244146337835;50446.495535044916;50669.49330506721;50924.745376273626;50974.235386469234;16761.664766704598;16794.6641067178;16841.32634694623;16858.072805995787;16896.915515422344;13836.23900685452;13906.374213160432;13980.44078236859;13983.580492585239;14000.719985600232;13012.674683132807;14416.711665766836;14691.77962330566;14731.04248223862;14770.92614536942;5204.609654275944;5781.4507621775865;5955.434233747787;5959.731812068468;5960.731767070405;993.2848349188573;1004.231762701534;1026.830572955454;1029.7033639295653;1036.8652075230323;22729.659055114185;22809;22921.770782292297;23020;23068.423289417562;1098.4123493930867;1107.5182295701234;1110.7001109700336;1114.5065612079852;1116.9609063682733;12149;12280.447379867928;12285.447154877873;12311.56909508163;12406.379681015913;228661;229687;229962;230102;230179;52659;52693.47306526962;52812.73593632084;52879.47120528745;52919.20621190685;1411.2943528235883;1451.4992327646944;1452.8329242137079;1461.298576683198;1499.4077339450855;22282;22626;22712.886435567718;22714.205002824823;23211.419714507272;37440.81279593694;37562.81218593889;37599.81200093982;37632.247355053296;37659.05852353713;181178;181811;182762;183261;183304;33659.49510757292;33825.661743383236;33976.66023339834;34009.31981360408;34097;41430.378544321255;43129;44152.77923610361;44184.558154418046;44258.55741442544;31388.529172062896;31436.52845207278;31604.841975790892;31617.52573711442;31636.683633163375;30939;31226.843865780527;31492.685073148976;31596.684033160294;31626.525602116446;34474.310513790086;34501.827490862386;34939.47590786089;35023.824880875436;35028.64971350254;31377.215569610944;31434.528482073245;32866.83566582152;33580.328393432486;34157.31685366329;24175.274741757774;24228.63657045181;24714;24904.37739056538;25220.74779252184 11:07:06 INFO - PID 653 | |2;jquery.html;565.2905603467648;568.1789813719175;589.672731633943;589.8938191125598;592.2951687491884;22368.776312236874;23185.536289274205;23202.767972320293;23237.302880913558;23274.76725232747;24830.379240518996;25319.11383101592;26557.73442265577;26603.86698066507;26724.86637566814;6376.489880809536;6513.283538810727;6525.119108920307;6527.771527996521;6533.411992920636;6288.371162883721;6336.936630633704;6391.8402039948905;6403.743850246008;6576.6711664416825;6537.77117800877;6538.084668146464;6546.672666366687;6550.11573437587;6568.211814582257;6504.837379065514;6516.641584712839;6529.31442198567;6529.869402611921;6534.60792352458;6068.150458935753;6086.665233412161;6088.4520393164385;6091.908621370639;6099.756009759604;28790.136295911158;29330.853345733136;29704.10887673373;29815.850920745474;29896.850515747283;1917.3672688013062;1952.8535359848067;1964.0768838645813;1967.8720883142694;1971.5465442948203;19509.9024504878;20077.698834517494;20257.088431020493;20265.8986705067;20287.695684564744;2406.1217655555597;2415.1064106280787;2496.3010357100043;2513.836600620972;2514.585093459577;141792.29103854517;144705.27647361695;144867;145149;145580;13848;14429.85570144306;14565.1597938401;14863.405463781328;15119.244037798066;22849.771502284766;23631.409214769767;23631.645525317133;23710.407239818796;23745.40636484067;10597.89402105969;10694.358338499715;10694.679159625224;10731.248812583044;10857.185711071701;10050.69847904564;10064.849027264598;10083.899161008296;10109.64616238428;10165.288429809842;9236;9388.906110938804;9454.763630909283;9469.763255918657;9522.571484283224;2834.3055951292067;2843.289177705574;2844.587534807483;2866.3407416294367;2938.8383638899636;952.8142012307673;955.8183945050419;973.4110862927968;986.4722373530127;991.509202944547;19629.901850490656;19985.60028799445;20027;20485.59028819445;20655.69016464754;1017.5014243020867;1031.2832581356035;1044.9634262800766;1046.6284469013542;1060.0883240413239;11063.668089957315;11358.602448914413;11385.316880987168;11438.656840294805;11490;452944;455733;463730;466166;469231;106847;110867.44566277118;112222;114560;116738;1171.912106592009;1214.7084699672016;1215.471269997554;1217.257472941505;1218.7075101975463;17557.12214389301;17755.822441775417;17832.93002419859;17867.821321786614;17893.910530447785;52566;54232.72883635556;55272;55419.722901385234;55606.44393556174;68333.65833170802;69988;69998.30001699917;70063.64968175127;70755;14448.205348705922;14750.483733069292;14782.704345913236;14799.852001480278;14820;23084.76915230826;23279.650805237598;23410;23434.64848027315;23626.64560031635;18470.076496175407;18674.719879202094;18808.811911880704;18856.057197140362;18995.240190392782;16826.242819073417;16882.24029918632;16938.49184524466;17157.828421716124;17242.137893105544;18801.90599046996;18853.811461885205;19044.143013564146;19235.1344189509;19254.518637034187;14539;14578.708425831212;14647;14679.266036697909;14696.338664759898;18200.908995454938;18276.908615456836;18530.81469185291;18616.627667446846;18762.15570299367 11:07:06 INFO - PID 653 | |3;mootools.html;1354.3634491788862;1358.5109360630172;1382.536850155198;1387.0498708384757;1415.50457339931;13899.513517026893;14110.858891411108;14532.782008269884;14539.563813085599;14559.05366151202;14631.122132672048;14865.479708210214;14947.40210391583;14957.252137393114;15044.924775376108;2632.0656167060706;2691.8923243070253;2692.8115031947773;2695.582184761365;2697.4335389568214;2715.633389492413;2822.6895041545517;2829.052267490393;2829.39168078863;2830.6886242513306;2707.620933069372;2730.563109902416;2760.7377299156547;2768.045024466556;2768.7369699878477;2650.4169082801754;2656.375751698349;2685.9194224173207;2710.4850078485224;2720.510308144533;2594.3125071855975;2623.9081632142784;2630.2898217481365;2631.67104111986;2641.0624228398833;14151.150930944177;14213.14721116726;14271.07238029535;14284.071535350273;14295.642608934755;7131.215566287704;7332.230115837813;7343.375813055896;7354.154272258652;7369;4869.440014398354;5027.673201241944;5053.545180933734;5105.055564720538;5122.257272695439;1877.0239475472815;1881.3885487216573;1895.8104189580931;1921.730957665928;1927.7975812539762;24193.153239636704;24408.38979025521;24421.877890610613;24893.62659560108;25019.874900625382;6451.225852897683;6533.444657204096;6568.901466478007;6586.209654841449;6732.091167692397;15938.362465501483;15985.680286394207;16035.599110022344;16326.346946122023;16379.59051023734;7402.7409040683315;7635;7643.617819109022;7644.885326720104;7647.464677472635;7045.823854403681;7063.187733410724;7104.538205016657;7288.70845166198;7357.8160545986775;5079.187330027178;5162.199859021783;5197.66215196011;5234.3718753749035;5269.235960785664;1498.1909768724768;1506.1716056169064;1506.5932198306396;1522.1399909051352;1528.740114180592;838.1660248053226;892.4422236102436;898.2544488074882;905.764501229685;908.3323757038571;7254.637268136572;7273.709051637875;8623.31013518917;8936.508492033001;9053.456792592466;978.4129522286571;985.7437066362797;994.6717583197527;995.2684776689127;1004.317064396203;5227.006868694936;5389.461053894657;5496.367917689517;5512.200730894078;5520.944790552043;19378.903105484384;19870.403887883385;20406.69389959152;20653.277135300494;20713.896430518053;20011.59976800485;20241.696374554394;20307.898460507604;20434.284800032226;20445.2844150454;10594.152467802558;10866.293690910066;11013.174011949135;11081.667549973514;11170;7909.446338756232;8277.62750676233;8360.498370097814;8364.456310340052;8559.871601925852;17411.738823917905;17648.823511765233;17935.103244837966;17951.73072403941;18088.366907158186;17777.555561111076;18133.184006719992;18309.54226144357;18468.722969155206;18533.443996680122;10079.69760907174;10158.28891977584;10336.17310615134;10337.37975721459;10351;12827.615171544869;13040.804387933998;13073.67315817074;13097.476100955855;13181.538646147723;10929.125669946226;10975.890241097486;10995.835062473909;11066.39134847591;11229.101671866394;10463.895361046596;10793.460326983777;10936.398498082364;11088.112950964065;11131.610393636183;9263.861042084509;9403.529823508934;9424.198943089914;9431.669891554036;9433.858492122761;6834.658267086526;6840.55536390153;6868.107146070979;6930.757423490154;6948.513604047668;7195.280471952763;7226.927730722626;7237.819054523468;7262.564246145248;7303.123625165014 11:07:06 INFO - PID 653 | |4;prototype.html;493.9629527785417;497.9145462890896;513.9126348520754;514.979400823967;521.4863359590803;6163.137160797486;6223.128761973322;6283.905741413877;6286.748530058803;6345.4923606111515;6128.20333356663;6144.784932527363;6229.0067178502895;6242;6248.656323902183;4837.9758101209445;4940.580050695695;5044.873878153048;5048.596112311006;5134.614903882205;4935.605151587864;4939.234418665113;4954.87612809679;4985.5819927335215;5016.799328026875;4931.654784165092;4938.333325001115;4946.1838796598695;4955.0089982003565;4972.453030166679;4919.3604831371695;4920.704757714549;4937.901241975158;4947.480514545957;4978.701277923336;4453.064856380164;4453.37652728615;4476.216662084123;4482.282834746457;4482.8655140345845;5961.850953726148;6006.279246490405;6048.153258543807;6104.046705054385;6128.479079278267;5619.167515936291;5652.773889044433;5672.914906276409;5679.176519404663;5743.942560574383;6443.83890402739;6554.606723596599;6584.868302633969;6587.80236592903;6612.2726500084955;4833.975830120827;4879;4889.608831293488;4904.117258893398;4923.513625619075;6606.207255129367;6692;6767.221769496472;6825.965870170667;6940.30596940307;4809.831655892027;4969.975150124226;4975.751212439436;5007.37407824022;5021.593953692972;5060.2156665716875;5064.594832413398;5104.2900628289935;5105.463926287761;5106.846794596168;5615.775368985195;5619.325680918316;5673.546116310686;5678.148277758287;5685.800996965087;6498.54510184292;6505.21937367519;6543.672816359163;6565.770198043046;6575.506836987245;1748.6153046329787;1766.3199668127863;1779.6084861330487;1793.6906058577313;1794.7846258448808;1148.5176225985106;1198.3109711915508;1200.2318516149671;1231.8152277158497;1232.8425255802347;5778.855528611734;5784.88430231393;5789.594728369058;5844.357120716718;5895.852603684943;6717.764878229238;6800.557963732342;6801;6803.353681400259;6914.792556223321;5750.338711048199;5764.164196191528;5789.218455508452;5794.246747922743;5866.178734977107;3576.40989236773;3577.94633080504;3588.3002814450933;3600.3978229687536;3621.3300539400293;6489.513286503437;6543.934560654333;6625.138731964814;6658.3341665834;6677.762949412001;6871.316527450706;6951.16586009682;7005.719771209095;7015;7018.052562903943;6560.901586476207;6571.901421478778;6612.7354905804195;6615.966920165464;6646.634435106115;6544.803655890331;6731.32686731323;6790.72837086511;6816.318368163144;6864.38220560152;6143.416375444325;6686.632235227109;6701.229358623821;6735.979206238207;6801.863962720817;6487.253965793994;6515.804525864231;6523.967380163259;6527.2167339919515;6588.374104460068;4959.156943319572;4973.428055773487;4983.259207435968;4998.575121114746;5023;6553.967230163818;6734.306277489043;6760.363458251188;6842.247352791289;6851.588904665736;6558.557117434157;6572.14562106923;6577.013447982841;6577.079208910661;6607.405333520006;6596.30738772422;6597.703103360264;6602.735890564323;6628.602283862983;6632.535722499379;6424.196975378078;6478.870422591616;6518.869622607616;6522.315156908457;6536.803895883131;6514.892468280498;6560.507961902781;6591.758802007341;6669.333066693292;6696.062551242732;6275.152854364603;6330.303666596763;6343.809685709436;6344.587601805868;6399.264084630141 11:07:06 INFO - PID 653 | |5;yui.html;875.3872289397419;899.6716198587518;908.3823000359753;910.1171863292604;917.8164367126574;18169.364072257475;19124.713129303054;19135.521611959706;19147.521311967208;22229.333120006424;21065.57868842626;21975.670364944537;22040.228591999283;22170.88914555429;22182;1022.5398570643204;1057.9735506612358;1063.106990128296;1066.4827558634056;1066.4934156275747;973.8052389522088;1055.3826011783078;1056.6935588679273;1056.7094049136472;1058.2433560004595;1114.0530549033297;1114.9108071354312;1123.7303047268638;1124.454639499842;1125.083057308291;1105.2318638546174;1107.3521989636072;1109.328856042095;1109.522905150785;1110.1507346879644;799.9600019998977;807.8020884883182;811.4482152136545;812.2040400407634;813.5240884082818;19333.61332773337;19664.508387290432;19868.00659966996;19874.602507949905;19920.900395498076;21614.783852161436;22151.33545993607;22385.328440146823;22857;22871.771282287296;16528.586785330343;16644.916775416044;16749.581260468585;16754.246058927376;17199.398021069326;3652.3790955537625;3674.7427680062406;3693.316736403747;3694.3350196964534;3695.575008873987;123206;125640.37179814042;125810;128045;128397;16544.090075045748;17063.829361706474;17095.316187352368;17109.401170958958;17193.312267509158;31407.21481962969;31478.370432591677;31486;31652.525212121836;32166;7995;8199.467034642728;8571.314294856396;8726.345524085718;8874.64501419936;4120.896977575585;4169.4162817205615;4178.8537401190815;4195.454590903224;4197.118605092965;5251.238570407346;5314.548263397577;5315.813946511854;5327;5383.730813459311;1017.8524114003576;1043.7025447747355;1055.37205362809;1056.3872953686996;1061.7717190804015;681.111149949311;690.8445599740119;697.5256825358704;700.8037749430167;706.0715159565156;11814.232074915102;12020.939895300467;12148.149629525848;12560.81158782619;13183;694.6040756768593;697.3584302441766;698.664640972326;712.4015826705593;718.5006420537676;5994.940050599438;6027.578069535178;6069.332373438918;6245.375462453809;6454.612723236621;107840.9215907831;110403;111687.44156279329;112249.43875280734;112373;96726;97277;98790;99300;104829;83748.58125709332;85191.57404212939;86191.56904215438;87994;89387;12763.744725105631;12808.167469114851;12985.598209020767;13051.92890940787;13103.344832758514;38808.41787373135;39585.60414395819;39774.20451590894;39798.60201397949;40298.59701402948;50656.746716266185;52892.73553632207;53018;59679.70160149172;71724.28275717176;17726.822731772518;19328;19743.703844442633;19766.604667906275;19839.30562430366;19060.61878762389;19770.604587907874;20028.599428011068;20082.59834803325;20220.59558808845;18296.451106466826;18738.156782944527;18803.153858076694;18898.244070237037;18910.810891891455;17957.640847183244;19078.523036924187;19155.90422047881;20300.59398812045;20499.28252511155;16877.156142192594;16940.745888811925;17006.404775832787;17040.06279654631;18402.72395914089;14626.122432654074;14786.778198326818;14804.407823687363;14816.555503334917;14846.480373187318;14591.708165836411;15179.241037948279;15302.387904484141;15314.770278445609;15342.23288835576 11:07:06 INFO - PID 653 | __end_tp_report 11:07:06 INFO - PID 653 | __start_cc_report 11:07:06 INFO - PID 653 | _x_x_mozilla_cycle_collect,385 11:07:06 INFO - PID 653 | __end_cc_report 11:07:06 INFO - PID 653 | __startTimestamp1502474826671__endTimestamp 11:07:06 INFO - PID 653 | 11:07:06 INFO - PID 653 | ------- Summary: start ------- 11:07:06 INFO - PID 653 | Number of tests: 6 11:07:06 INFO - PID 653 | 11:07:06 INFO - PID 653 | [#0] dojo.html Cycles:165 Average:6130.02 Median:6378.27 stddev:720.35 (11.3%) stddev-sans-first:683.01 11:07:06 INFO - PID 653 | Values: 3129.4 3131.3 3166.7 3180.5 3224.0 6146.7 6171.5 6307.0 6324.0 6331.7 6134.5 6248.0 6254.1 6265.2 6284.4 5682.4 5689.7 5713.0 5724.2 5730.7 5705.5 5745.9 5754.3 5781.8 5786.5 5696.7 5773.5 5794.3 5803.2 5838.8 5538.1 5614.7 5732.7 5737.3 5801.8 5229.6 5612.8 5629.7 5690.5 5712.2 6035.7 6126.3 6199.9 6205.7 6215.8 6575.7 6639.9 6681.3 6685.0 6799.6 6701.2 6706.0 6708.0 6717.8 6722.2 6188.5 6201.1 6205.9 6214.0 6214.3 6742.8 6813.3 6813.5 6826.0 6831.7 6248.9 6497.4 6503.6 6518.2 6519.1 6697.8 6729.1 6734.7 6741.5 6787.7 6665.9 6694.5 6747.5 6755.5 6765.6 6690.6 6715.5 6730.3 6825.1 6829.3 5302.3 5390.8 5402.0 5522.8 5531.1 4616.5 4683.0 4685.9 4706.0 4728.4 5735.6 5794.7 5809.5 5828.3 5861.1 6587.8 6682.2 6691.0 6748.2 6749.1 5673.1 5678.4 5682.3 5705.7 5715.7 5796.2 5802.2 5808.4 5815.0 5819.8 5954.6 6433.1 6439.7 6476.8 6489.6 6648.7 6661.1 6751.4 6768.3 6780.7 6339.1 6377.5 6416.8 6419.3 6503.4 6514.5 6532.7 6538.3 6541.9 6541.9 6672.2 6676.1 6691.8 6692.8 6693.7 6447.5 6448.7 6453.4 6457.6 6459.3 5654.5 6408.4 6471.5 6478.0 6573.2 6297.0 6388.6 6390.9 6395.7 6398.8 6472.1 6496.1 6516.7 6525.2 6536.3 6357.9 6378.3 6430.5 6449.5 6471.8 11:07:06 INFO - PID 653 | 11:07:06 INFO - PID 653 | [#1] ext.html Cycles:180 Average:37286.75 Median:18685.02 stddev:52964.34 (283.5%) stddev-sans-first:53041.88 11:07:06 INFO - PID 653 | Values: 753.2 819.6 842.5 843.3 1283.9 40899.0 41430.6 41447.4 41461.8 41487.2 38966.0 39431.2 39462.2 39495.8 39522.6 7247.9 7376.3 7377.3 7377.4 7380.8 7215.6 7319.6 7321.2 7322.6 7326.6 7215.6 7386.6 7739.3 7750.7 7750.8 7649.3 7709.3 7710.8 7712.4 7713.2 7438.0 7585.4 7591.6 7628.5 7672.8 37720.0 38125.2 38240.2 38285.2 38376.6 3330.8 3383.3 3385.7 3389.3 3393.4 31007.2 31056.0 31137.2 31149.5 31165.0 4815.1 4819.0 4838.5 4845.0 4851.8 196809.0 198545.0 198869.0 199074.0 199198.0 49096.5 49117.8 49273.3 49385.0 49392.3 50390.2 50446.5 50669.5 50924.7 50974.2 16761.7 16794.7 16841.3 16858.1 16896.9 13836.2 13906.4 13980.4 13983.6 14000.7 13012.7 14416.7 14691.8 14731.0 14770.9 5204.6 5781.5 5955.4 5959.7 5960.7 993.3 1004.2 1026.8 1029.7 1036.9 22729.7 22809.0 22921.8 23020.0 23068.4 1098.4 1107.5 1110.7 1114.5 1117.0 12149.0 12280.4 12285.4 12311.6 12406.4 228661.0 229687.0 229962.0 230102.0 230179.0 52659.0 52693.5 52812.7 52879.5 52919.2 1411.3 1451.5 1452.8 1461.3 1499.4 22282.0 22626.0 22712.9 22714.2 23211.4 37440.8 37562.8 37599.8 37632.2 37659.1 181178.0 181811.0 182762.0 183261.0 183304.0 33659.5 33825.7 33976.7 34009.3 34097.0 41430.4 43129.0 44152.8 44184.6 44258.6 31388.5 31436.5 31604.8 31617.5 31636.7 30939.0 31226.8 31492.7 31596.7 31626.5 34474.3 34501.8 34939.5 35023.8 35028.6 31377.2 31434.5 32866.8 33580.3 34157.3 24175.3 24228.6 24714.0 24904.4 25220.7 11:07:06 INFO - PID 653 | 11:07:06 INFO - PID 653 | [#2] jquery.html Cycles:180 Average:34112.63 Median:22609.27 stddev:78297.10 (346.3%) stddev-sans-first:78476.23 11:07:06 INFO - PID 653 | Values: 565.3 568.2 589.7 589.9 592.3 22368.8 23185.5 23202.8 23237.3 23274.8 24830.4 25319.1 26557.7 26603.9 26724.9 6376.5 6513.3 6525.1 6527.8 6533.4 6288.4 6336.9 6391.8 6403.7 6576.7 6537.8 6538.1 6546.7 6550.1 6568.2 6504.8 6516.6 6529.3 6529.9 6534.6 6068.2 6086.7 6088.5 6091.9 6099.8 28790.1 29330.9 29704.1 29815.9 29896.9 1917.4 1952.9 1964.1 1967.9 1971.5 19509.9 20077.7 20257.1 20265.9 20287.7 2406.1 2415.1 2496.3 2513.8 2514.6 141792.3 144705.3 144867.0 145149.0 145580.0 13848.0 14429.9 14565.2 14863.4 15119.2 22849.8 23631.4 23631.6 23710.4 23745.4 10597.9 10694.4 10694.7 10731.2 10857.2 10050.7 10064.8 10083.9 10109.6 10165.3 9236.0 9388.9 9454.8 9469.8 9522.6 2834.3 2843.3 2844.6 2866.3 2938.8 952.8 955.8 973.4 986.5 991.5 19629.9 19985.6 20027.0 20485.6 20655.7 1017.5 1031.3 1045.0 1046.6 1060.1 11063.7 11358.6 11385.3 11438.7 11490.0 452944.0 455733.0 463730.0 466166.0 469231.0 106847.0 110867.4 112222.0 114560.0 116738.0 1171.9 1214.7 1215.5 1217.3 1218.7 17557.1 17755.8 17832.9 17867.8 17893.9 52566.0 54232.7 55272.0 55419.7 55606.4 68333.7 69988.0 69998.3 70063.6 70755.0 14448.2 14750.5 14782.7 14799.9 14820.0 23084.8 23279.7 23410.0 23434.6 23626.6 18470.1 18674.7 18808.8 18856.1 18995.2 16826.2 16882.2 16938.5 17157.8 17242.1 18801.9 18853.8 19044.1 19235.1 19254.5 14539.0 14578.7 14647.0 14679.3 14696.3 18200.9 18276.9 18530.8 18616.6 18762.2 11:07:06 INFO - PID 653 | 11:07:06 INFO - PID 653 | [#3] mootools.html Cycles:180 Average:8929.17 Median:2636.56 stddev:6229.84 (236.3%) stddev-sans-first:6221.32 11:07:06 INFO - PID 653 | Values: 1354.4 1358.5 1382.5 1387.0 1415.5 13899.5 14110.9 14532.8 14539.6 14559.1 14631.1 14865.5 14947.4 14957.3 15044.9 2632.1 2691.9 2692.8 2695.6 2697.4 2715.6 2822.7 2829.1 2829.4 2830.7 2707.6 2730.6 2760.7 2768.0 2768.7 2650.4 2656.4 2685.9 2710.5 2720.5 2594.3 2623.9 2630.3 2631.7 2641.1 14151.2 14213.1 14271.1 14284.1 14295.6 7131.2 7332.2 7343.4 7354.2 7369.0 4869.4 5027.7 5053.5 5105.1 5122.3 1877.0 1881.4 1895.8 1921.7 1927.8 24193.2 24408.4 24421.9 24893.6 25019.9 6451.2 6533.4 6568.9 6586.2 6732.1 15938.4 15985.7 16035.6 16326.3 16379.6 7402.7 7635.0 7643.6 7644.9 7647.5 7045.8 7063.2 7104.5 7288.7 7357.8 5079.2 5162.2 5197.7 5234.4 5269.2 1498.2 1506.2 1506.6 1522.1 1528.7 838.2 892.4 898.3 905.8 908.3 7254.6 7273.7 8623.3 8936.5 9053.5 978.4 985.7 994.7 995.3 1004.3 5227.0 5389.5 5496.4 5512.2 5520.9 19378.9 19870.4 20406.7 20653.3 20713.9 20011.6 20241.7 20307.9 20434.3 20445.3 10594.2 10866.3 11013.2 11081.7 11170.0 7909.4 8277.6 8360.5 8364.5 8559.9 17411.7 17648.8 17935.1 17951.7 18088.4 17777.6 18133.2 18309.5 18468.7 18533.4 10079.7 10158.3 10336.2 10337.4 10351.0 12827.6 13040.8 13073.7 13097.5 13181.5 10929.1 10975.9 10995.8 11066.4 11229.1 10463.9 10793.5 10936.4 11088.1 11131.6 9263.9 9403.5 9424.2 9431.7 9433.9 6834.7 6840.6 6868.1 6930.8 6948.5 7195.3 7226.9 7237.8 7262.6 7303.1 11:07:06 INFO - PID 653 | 11:07:06 INFO - PID 653 | [#4] prototype.html Cycles:180 Average:5524.64 Median:6135.95 stddev:1556.94 (25.4%) stddev-sans-first:1514.83 11:07:06 INFO - PID 653 | Values: 494.0 497.9 513.9 515.0 521.5 6163.1 6223.1 6283.9 6286.7 6345.5 6128.2 6144.8 6229.0 6242.0 6248.7 4838.0 4940.6 5044.9 5048.6 5134.6 4935.6 4939.2 4954.9 4985.6 5016.8 4931.7 4938.3 4946.2 4955.0 4972.5 4919.4 4920.7 4937.9 4947.5 4978.7 4453.1 4453.4 4476.2 4482.3 4482.9 5961.9 6006.3 6048.2 6104.0 6128.5 5619.2 5652.8 5672.9 5679.2 5743.9 6443.8 6554.6 6584.9 6587.8 6612.3 4834.0 4879.0 4889.6 4904.1 4923.5 6606.2 6692.0 6767.2 6826.0 6940.3 4809.8 4970.0 4975.8 5007.4 5021.6 5060.2 5064.6 5104.3 5105.5 5106.8 5615.8 5619.3 5673.5 5678.1 5685.8 6498.5 6505.2 6543.7 6565.8 6575.5 1748.6 1766.3 1779.6 1793.7 1794.8 1148.5 1198.3 1200.2 1231.8 1232.8 5778.9 5784.9 5789.6 5844.4 5895.9 6717.8 6800.6 6801.0 6803.4 6914.8 5750.3 5764.2 5789.2 5794.2 5866.2 3576.4 3577.9 3588.3 3600.4 3621.3 6489.5 6543.9 6625.1 6658.3 6677.8 6871.3 6951.2 7005.7 7015.0 7018.1 6560.9 6571.9 6612.7 6616.0 6646.6 6544.8 6731.3 6790.7 6816.3 6864.4 6143.4 6686.6 6701.2 6736.0 6801.9 6487.3 6515.8 6524.0 6527.2 6588.4 4959.2 4973.4 4983.3 4998.6 5023.0 6554.0 6734.3 6760.4 6842.2 6851.6 6558.6 6572.1 6577.0 6577.1 6607.4 6596.3 6597.7 6602.7 6628.6 6632.5 6424.2 6478.9 6518.9 6522.3 6536.8 6514.9 6560.5 6591.8 6669.3 6696.1 6275.2 6330.3 6343.8 6344.6 6399.3 11:07:06 INFO - PID 653 | 11:07:06 INFO - PID 653 | [#5] yui.html Cycles:180 Average:23756.32 Median:19853.66 stddev:31845.40 (160.4%) stddev-sans-first:31888.38 11:07:06 INFO - PID 653 | Values: 875.4 899.7 908.4 910.1 917.8 18169.4 19124.7 19135.5 19147.5 22229.3 21065.6 21975.7 22040.2 22170.9 22182.0 1022.5 1058.0 1063.1 1066.5 1066.5 973.8 1055.4 1056.7 1056.7 1058.2 1114.1 1114.9 1123.7 1124.5 1125.1 1105.2 1107.4 1109.3 1109.5 1110.2 800.0 807.8 811.4 812.2 813.5 19333.6 19664.5 19868.0 19874.6 19920.9 21614.8 22151.3 22385.3 22857.0 22871.8 16528.6 16644.9 16749.6 16754.2 17199.4 3652.4 3674.7 3693.3 3694.3 3695.6 123206.0 125640.4 125810.0 128045.0 128397.0 16544.1 17063.8 17095.3 17109.4 17193.3 31407.2 31478.4 31486.0 31652.5 32166.0 7995.0 8199.5 8571.3 8726.3 8874.6 4120.9 4169.4 4178.9 4195.5 4197.1 5251.2 5314.5 5315.8 5327.0 5383.7 1017.9 1043.7 1055.4 1056.4 1061.8 681.1 690.8 697.5 700.8 706.1 11814.2 12020.9 12148.1 12560.8 13183.0 694.6 697.4 698.7 712.4 718.5 5994.9 6027.6 6069.3 6245.4 6454.6 107840.9 110403.0 111687.4 112249.4 112373.0 96726.0 97277.0 98790.0 99300.0 104829.0 83748.6 85191.6 86191.6 87994.0 89387.0 12763.7 12808.2 12985.6 13051.9 13103.3 38808.4 39585.6 39774.2 39798.6 40298.6 50656.7 52892.7 53018.0 59679.7 71724.3 17726.8 19328.0 19743.7 19766.6 19839.3 19060.6 19770.6 20028.6 20082.6 20220.6 18296.5 18738.2 18803.2 18898.2 18910.8 17957.6 19078.5 19155.9 20300.6 20499.3 16877.2 16940.7 17006.4 17040.1 18402.7 14626.1 14786.8 14804.4 14816.6 14846.5 14591.7 15179.2 15302.4 15314.8 15342.2 11:07:06 INFO - PID 653 | -------- Summary: end -------- 11:07:06 INFO - PID 653 | 11:07:07 INFO - TEST-INFO | 653: exit 0 11:07:07 INFO - TEST-OK | dromaeo_css | took 1113108ms 11:07:07 INFO - TEST-START | kraken 11:07:07 INFO - Initialising browser for kraken test... 11:07:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55061/getInfo.html -profile /tmp/tmpd0j7Oo/profile 11:07:07 INFO - TEST-INFO | started process 1045 (/builds/slave/test/build/application/firefox/firefox http://localhost:55061/getInfo.html) 11:07:13 INFO - TEST-INFO | 1045: exit 0 11:07:13 INFO - Browser initialized. 11:07:13 INFO - Running cycle 1/1 for kraken test... 11:07:13 INFO - TEST-INFO | started process 1363 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd0j7Oo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:07:14 INFO - PID 1363 | 11:07:14 INFO - PID 1363 | (/builds/slave/test/build/application/firefox/firefox:1418): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:07:14 INFO - PID 1363 | 11:07:25 INFO - PID 1363 | RSS: Main: 167985152 11:07:25 INFO - PID 1363 | 11:07:27 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55061/tests/kraken/driver.html?testName=audio-beat-detection) 11:07:28 INFO - PID 1363 | RSS: Main: 174338048 11:07:28 INFO - PID 1363 | 11:07:31 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55061/tests/kraken/driver.html?testName=audio-dft) 11:07:32 INFO - PID 1363 | RSS: Main: 175001600 11:07:32 INFO - PID 1363 | 11:07:35 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55061/tests/kraken/driver.html?testName=audio-fft) 11:07:35 INFO - PID 1363 | RSS: Main: 169746432 11:07:35 INFO - PID 1363 | 11:07:37 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55061/tests/kraken/driver.html?testName=audio-oscillator) 11:07:38 INFO - PID 1363 | RSS: Main: 170221568 11:07:38 INFO - PID 1363 | 11:07:40 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55061/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:07:41 INFO - PID 1363 | RSS: Main: 170143744 11:07:41 INFO - PID 1363 | 11:07:44 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55061/tests/kraken/driver.html?testName=imaging-darkroom) 11:07:45 INFO - PID 1363 | RSS: Main: 173072384 11:07:45 INFO - PID 1363 | 11:07:48 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55061/tests/kraken/driver.html?testName=imaging-desaturate) 11:07:49 INFO - PID 1363 | RSS: Main: 172941312 11:07:49 INFO - PID 1363 | 11:07:52 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55061/tests/kraken/driver.html?testName=json-parse-financial) 11:07:52 INFO - PID 1363 | RSS: Main: 173731840 11:07:52 INFO - PID 1363 | 11:07:54 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55061/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:07:55 INFO - PID 1363 | RSS: Main: 173797376 11:07:55 INFO - PID 1363 | 11:07:57 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55061/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:07:57 INFO - PID 1363 | RSS: Main: 174182400 11:07:57 INFO - PID 1363 | 11:07:59 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55061/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:08:00 INFO - PID 1363 | RSS: Main: 174993408 11:08:00 INFO - PID 1363 | 11:08:03 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55061/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:08:04 INFO - PID 1363 | RSS: Main: 174788608 11:08:04 INFO - PID 1363 | 11:08:07 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55061/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:08:07 INFO - PID 1363 | RSS: Main: 174739456 11:08:07 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | Cycle 1(1): loaded http://localhost:55061/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55061/tests/kraken/driver.html?testName=ai-astar) 11:08:09 INFO - PID 1363 | RSS: Main: 175857664 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | __start_tp_report 11:08:09 INFO - PID 1363 | _x_x_mozilla_page_load 11:08:09 INFO - PID 1363 | _x_x_mozilla_page_load_details 11:08:09 INFO - PID 1363 | |i|pagename|runs| 11:08:09 INFO - PID 1363 | |0;ai-astar;99;110;101;108;111;118;110;103;108;111 11:08:09 INFO - PID 1363 | |1;audio-beat-detection;147;124;159;178;124;155;150;118;174;118 11:08:09 INFO - PID 1363 | |2;audio-dft;164;165;164;162;162;167;169;173;171;164 11:08:09 INFO - PID 1363 | |3;audio-fft;79;74;74;177;74;77;75;90;96;74 11:08:09 INFO - PID 1363 | |4;audio-oscillator;80;80;127;79;80;82;141;86;78;130 11:08:09 INFO - PID 1363 | |5;imaging-gaussian-blur;113;123;114;116;118;114;114;117;114;119 11:08:09 INFO - PID 1363 | |6;imaging-darkroom;151;152;150;151;149;151;150;153;150;151 11:08:09 INFO - PID 1363 | |7;imaging-desaturate;94;97;94;94;98;99;95;94;96;99 11:08:09 INFO - PID 1363 | |8;json-parse-financial;56;59;55;56;58;53;56;53;56;54 11:08:09 INFO - PID 1363 | |9;json-stringify-tinderbox;54;62;53;54;54;59;54;54;58;59 11:08:09 INFO - PID 1363 | |10;stanford-crypto-aes;77;78;77;78;78;82;78;76;77;77 11:08:09 INFO - PID 1363 | |11;stanford-crypto-ccm;126;135;133;125;121;132;136;129;125;176 11:08:09 INFO - PID 1363 | |12;stanford-crypto-pbkdf2;182;174;173;183;178;176;176;194;183;184 11:08:09 INFO - PID 1363 | |13;stanford-crypto-sha256-iterative;72;72;70;76;70;69;66;69;66;74 11:08:09 INFO - PID 1363 | __end_tp_report 11:08:09 INFO - PID 1363 | __start_cc_report 11:08:09 INFO - PID 1363 | _x_x_mozilla_cycle_collect,1005 11:08:09 INFO - PID 1363 | __end_cc_report 11:08:09 INFO - PID 1363 | __startTimestamp1502474889965__endTimestamp 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | ------- Summary: start ------- 11:08:09 INFO - PID 1363 | Number of tests: 14 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#0] ai-astar Cycles:10 Average:107.90 Median:110.50 stddev:5.59 (5.1%) stddev-sans-first:4.91 11:08:09 INFO - PID 1363 | Values: 99.0 110.0 101.0 108.0 111.0 118.0 110.0 103.0 108.0 111.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:152.50 stddev:22.59 (14.8%) stddev-sans-first:23.95 11:08:09 INFO - PID 1363 | Values: 147.0 124.0 159.0 178.0 124.0 155.0 150.0 118.0 174.0 118.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#2] audio-dft Cycles:10 Average:166.10 Median:166.00 stddev:3.78 (2.3%) stddev-sans-first:3.94 11:08:09 INFO - PID 1363 | Values: 164.0 165.0 164.0 162.0 162.0 167.0 169.0 173.0 171.0 164.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#3] audio-fft Cycles:10 Average:89.00 Median:76.00 stddev:31.86 (41.9%) stddev-sans-first:33.58 11:08:09 INFO - PID 1363 | Values: 79.0 74.0 74.0 177.0 74.0 77.0 75.0 90.0 96.0 74.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:80.00 stddev:25.43 (31.8%) stddev-sans-first:26.27 11:08:09 INFO - PID 1363 | Values: 80.0 80.0 127.0 79.0 80.0 82.0 141.0 86.0 78.0 130.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#5] imaging-gaussian-blur Cycles:10 Average:116.20 Median:116.50 stddev:3.12 (2.7%) stddev-sans-first:3.09 11:08:09 INFO - PID 1363 | Values: 113.0 123.0 114.0 116.0 118.0 114.0 114.0 117.0 114.0 119.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:1.14 (0.8%) stddev-sans-first:1.20 11:08:09 INFO - PID 1363 | Values: 151.0 152.0 150.0 151.0 149.0 151.0 150.0 153.0 150.0 151.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.50 stddev:2.11 (2.2%) stddev-sans-first:2.11 11:08:09 INFO - PID 1363 | Values: 94.0 97.0 94.0 94.0 98.0 99.0 95.0 94.0 96.0 99.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.96 (3.5%) stddev-sans-first:2.07 11:08:09 INFO - PID 1363 | Values: 56.0 59.0 55.0 56.0 58.0 53.0 56.0 53.0 56.0 54.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:3.11 (5.5%) stddev-sans-first:3.20 11:08:09 INFO - PID 1363 | Values: 54.0 62.0 53.0 54.0 54.0 59.0 54.0 54.0 58.0 59.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#10] stanford-crypto-aes Cycles:10 Average:77.80 Median:78.00 stddev:1.62 (2.1%) stddev-sans-first:1.69 11:08:09 INFO - PID 1363 | Values: 77.0 78.0 77.0 78.0 78.0 82.0 78.0 76.0 77.0 77.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#11] stanford-crypto-ccm Cycles:10 Average:133.80 Median:132.50 stddev:15.61 (11.8%) stddev-sans-first:16.30 11:08:09 INFO - PID 1363 | Values: 126.0 135.0 133.0 125.0 121.0 132.0 136.0 129.0 125.0 176.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.30 Median:182.50 stddev:6.27 (3.4%) stddev-sans-first:6.62 11:08:09 INFO - PID 1363 | Values: 182.0 174.0 173.0 183.0 178.0 176.0 176.0 194.0 183.0 184.0 11:08:09 INFO - PID 1363 | 11:08:09 INFO - PID 1363 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:3.20 (4.5%) stddev-sans-first:3.35 11:08:09 INFO - PID 1363 | Values: 72.0 72.0 70.0 76.0 70.0 69.0 66.0 69.0 66.0 74.0 11:08:09 INFO - PID 1363 | -------- Summary: end -------- 11:08:09 INFO - PID 1363 | 11:08:10 INFO - PID 1363 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:08:10 INFO - TEST-INFO | 1363: exit 0 11:08:10 INFO - TEST-OK | kraken | took 63299ms 11:08:10 INFO - SUITE-END | took 1176s 11:08:11 INFO - Completed test suite (00:19:36) 11:08:11 INFO - javascript benchmark 11:08:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8527.895488597978, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.3584815112904, 3131.264152924063, 3166.7149956503918, 3180.4911214205717, 3224.0, 6146.692665366731, 6171.47542458891, 6306.968465157668, 6323.968380158104, 6331.683415829213, 6134.539909506782, 6248.0, 6254.093156492306, 6265.216847894013, 6284.371562843725, 5682.431756824305, 5689.6586204827845, 5713.028785106542, 5724.169995350671, 5730.7421166047625, 5705.457981491772, 5745.856353591173, 5754.338251101114, 5781.82654520365, 5786.479216870481, 5696.743646535916, 5773.451522105393, 5794.333651630053, 5803.187553742479, 5838.824835254949, 5538.0585300499015, 5614.663120212759, 5732.684702341369, 5737.254156959611, 5801.82594522165, 5229.581633469314, 5612.7754889804355, 5629.746661400206, 5690.459406356389, 5712.200291959129, 6035.7283922223605, 6126.264848218242, 6199.87600247997, 6205.658688772116, 6215.782447614357, 6575.704093315812, 6639.933600664028, 6681.265060843303, 6684.966575167142, 6799.5920244784975, 6701.195856497252, 6706.0, 6707.966460167717, 6717.764878229287, 6722.226943901416, 6188.535859810532, 6201.069839524108, 6205.875882482325, 6214.0, 6214.347493513161, 6742.764003259862, 6813.318668133246, 6813.488988325896, 6826.0, 6831.726730930707, 6248.937510624926, 6497.350264973465, 6503.577267477599, 6518.217813862367, 6519.119918810899, 6697.799066028027, 6729.05793188954, 6734.696938637773, 6741.494387920926, 6787.728490860409, 6665.866682666319, 6694.531382803254, 6747.527673062937, 6755.527113102134, 6765.627890466071, 6690.59856408617, 6715.46276297895, 6730.293319201413, 6825.078614386995, 6829.282925292872, 5302.257683924254, 5390.838274851761, 5402.027635025733, 5522.779088836402, 5531.087370583889, 4616.492185859553, 4682.976585117052, 4685.882852928636, 4705.95294047062, 4728.40894888139, 5735.569832262597, 5794.652320860761, 5809.506191973645, 5828.300603927556, 5861.062230043172, 6587.769428069994, 6682.198136223587, 6691.0, 6748.223954245226, 6749.122614060141, 5673.092305231144, 5678.375378708339, 5682.346530149086, 5705.686187259657, 5715.685637289906, 5796.159556864315, 5802.245708057925, 5808.361080281165, 5815.011448053841, 5819.82540523785, 5954.642721436728, 6433.131527243856, 6439.7424103035355, 6476.805695829133, 6489.610623362613, 6648.667566621552, 6661.067450556829, 6751.358620931003, 6768.323167683192, 6780.660966951731, 6339.112524246517, 6377.489800815831, 6416.839579010562, 6419.293877673359, 6503.447206987459, 6514.511411644051, 6532.706028228646, 6538.346165383424, 6541.8691626168165, 6541.901871472027, 6672.199336079701, 6676.098726672031, 6691.76578819739, 6692.83267918306, 6693.732250710112, 6447.451966582893, 6448.677566121769, 6453.354664533509, 6457.644829534421, 6459.321771213955, 5654.491095801398, 6408.391202835724, 6471.514636402195, 6478.0, 6573.1783527059115, 6297.0, 6388.616682999035, 6390.936090639034, 6395.680215989275, 6398.808035758934, 6472.126262954441, 6496.123023391781, 6516.73933042673, 6525.184351956005, 6536.281009089092, 6357.90463143044, 6378.266499352634, 6430.549861509649, 6449.4517965971545, 6471.7734879279], "value": 6076.936326823246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.1715113375288, 819.6106849246609, 842.4524059361418, 843.3421930893904, 1283.8651941546143, 40899.0, 41430.58569414305, 41447.37828932564, 41461.792691036506, 41487.17025659489, 38966.0, 39431.211375772466, 39462.210755784865, 39495.802520987425, 39522.604773952255, 7247.855042899138, 7376.336129748322, 7377.299156580117, 7377.446691498132, 7380.815479613026, 7215.639218039077, 7319.634018299091, 7321.231270716578, 7322.597257150855, 7326.560406375608, 7215.567065976058, 7386.556806591595, 7739.264769846884, 7750.728724494644, 7750.7674769756995, 7649.311561959423, 7709.344705700022, 7710.7686769397005, 7712.421568382338, 7713.190115037953, 7437.962810185969, 7585.393168546503, 7591.620418979029, 7628.504147230412, 7672.846543069163, 37720.0, 38125.237495250214, 38240.235195295936, 38285.23429531394, 38376.61623383759, 3330.833458327098, 3383.3233353329433, 3385.712214461749, 3389.271306669078, 3393.3722261381477, 31007.22481937947, 31056.0, 31137.22156946072, 31149.532757008663, 31165.0, 4815.0610630926985, 4818.975905120452, 4838.516148385169, 4845.0, 4851.781669824831, 196809.0, 198545.0, 198869.00565497225, 199074.0, 199198.0, 49096.50903490919, 49117.75441122843, 49273.26090108651, 49385.0, 49392.25911611329, 50390.244146337835, 50446.495535044916, 50669.49330506721, 50924.745376273626, 50974.235386469234, 16761.664766704598, 16794.6641067178, 16841.32634694623, 16858.072805995787, 16896.915515422344, 13836.23900685452, 13906.374213160432, 13980.44078236859, 13983.580492585239, 14000.719985600232, 13012.674683132807, 14416.711665766836, 14691.77962330566, 14731.04248223862, 14770.92614536942, 5204.609654275944, 5781.4507621775865, 5955.434233747787, 5959.731812068468, 5960.731767070405, 993.2848349188573, 1004.231762701534, 1026.830572955454, 1029.7033639295653, 1036.8652075230323, 22729.659055114185, 22809.0, 22921.770782292297, 23020.0, 23068.423289417562, 1098.4123493930867, 1107.5182295701234, 1110.7001109700336, 1114.5065612079852, 1116.9609063682733, 12149.0, 12280.447379867928, 12285.447154877873, 12311.56909508163, 12406.379681015913, 228661.0, 229687.0, 229962.0, 230102.0, 230179.0, 52659.0, 52693.47306526962, 52812.73593632084, 52879.47120528745, 52919.20621190685, 1411.2943528235883, 1451.4992327646944, 1452.8329242137079, 1461.298576683198, 1499.4077339450855, 22282.0, 22626.0, 22712.886435567718, 22714.205002824823, 23211.419714507272, 37440.81279593694, 37562.81218593889, 37599.81200093982, 37632.247355053296, 37659.05852353713, 181178.0, 181811.0, 182762.0, 183261.0, 183304.0, 33659.49510757292, 33825.661743383236, 33976.66023339834, 34009.31981360408, 34097.0, 41430.378544321255, 43129.0, 44152.77923610361, 44184.558154418046, 44258.55741442544, 31388.529172062896, 31436.52845207278, 31604.841975790892, 31617.52573711442, 31636.683633163375, 30939.0, 31226.843865780527, 31492.685073148976, 31596.684033160294, 31626.525602116446, 34474.310513790086, 34501.827490862386, 34939.47590786089, 35023.824880875436, 35028.64971350254, 31377.215569610944, 31434.528482073245, 32866.83566582152, 33580.328393432486, 34157.31685366329, 24175.274741757774, 24228.63657045181, 24714.0, 24904.37739056538, 25220.74779252184], "value": 17079.722785107777, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.2905603467648, 568.1789813719175, 589.672731633943, 589.8938191125598, 592.2951687491884, 22368.776312236874, 23185.536289274205, 23202.767972320293, 23237.302880913558, 23274.76725232747, 24830.379240518996, 25319.11383101592, 26557.73442265577, 26603.86698066507, 26724.86637566814, 6376.489880809536, 6513.283538810727, 6525.119108920307, 6527.771527996521, 6533.411992920636, 6288.371162883721, 6336.936630633704, 6391.8402039948905, 6403.743850246008, 6576.6711664416825, 6537.77117800877, 6538.084668146464, 6546.672666366687, 6550.11573437587, 6568.211814582257, 6504.837379065514, 6516.641584712839, 6529.31442198567, 6529.869402611921, 6534.60792352458, 6068.150458935753, 6086.665233412161, 6088.4520393164385, 6091.908621370639, 6099.756009759604, 28790.136295911158, 29330.853345733136, 29704.10887673373, 29815.850920745474, 29896.850515747283, 1917.3672688013062, 1952.8535359848067, 1964.0768838645813, 1967.8720883142694, 1971.5465442948203, 19509.9024504878, 20077.698834517494, 20257.088431020493, 20265.8986705067, 20287.695684564744, 2406.1217655555597, 2415.1064106280787, 2496.3010357100043, 2513.836600620972, 2514.585093459577, 141792.29103854517, 144705.27647361695, 144867.0, 145149.0, 145580.0, 13848.0, 14429.85570144306, 14565.1597938401, 14863.405463781328, 15119.244037798066, 22849.771502284766, 23631.409214769767, 23631.645525317133, 23710.407239818796, 23745.40636484067, 10597.89402105969, 10694.358338499715, 10694.679159625224, 10731.248812583044, 10857.185711071701, 10050.69847904564, 10064.849027264598, 10083.899161008296, 10109.64616238428, 10165.288429809842, 9236.0, 9388.906110938804, 9454.763630909283, 9469.763255918657, 9522.571484283224, 2834.3055951292067, 2843.289177705574, 2844.587534807483, 2866.3407416294367, 2938.8383638899636, 952.8142012307673, 955.8183945050419, 973.4110862927968, 986.4722373530127, 991.509202944547, 19629.901850490656, 19985.60028799445, 20027.0, 20485.59028819445, 20655.69016464754, 1017.5014243020867, 1031.2832581356035, 1044.9634262800766, 1046.6284469013542, 1060.0883240413239, 11063.668089957315, 11358.602448914413, 11385.316880987168, 11438.656840294805, 11490.0, 452944.0, 455733.0, 463730.0, 466166.0, 469231.0, 106847.0, 110867.44566277118, 112222.0, 114560.0, 116738.0, 1171.912106592009, 1214.7084699672016, 1215.471269997554, 1217.257472941505, 1218.7075101975463, 17557.12214389301, 17755.822441775417, 17832.93002419859, 17867.821321786614, 17893.910530447785, 52566.0, 54232.72883635556, 55272.0, 55419.722901385234, 55606.44393556174, 68333.65833170802, 69988.0, 69998.30001699917, 70063.64968175127, 70755.0, 14448.205348705922, 14750.483733069292, 14782.704345913236, 14799.852001480278, 14820.0, 23084.76915230826, 23279.650805237598, 23410.0, 23434.64848027315, 23626.64560031635, 18470.076496175407, 18674.719879202094, 18808.811911880704, 18856.057197140362, 18995.240190392782, 16826.242819073417, 16882.24029918632, 16938.49184524466, 17157.828421716124, 17242.137893105544, 18801.90599046996, 18853.811461885205, 19044.143013564146, 19235.1344189509, 19254.518637034187, 14539.0, 14578.708425831212, 14647.0, 14679.266036697909, 14696.338664759898, 18200.908995454938, 18276.908615456836, 18530.81469185291, 18616.627667446846, 18762.15570299367], "value": 12066.69938631307, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.3634491788862, 1358.5109360630172, 1382.536850155198, 1387.0498708384757, 1415.50457339931, 13899.513517026893, 14110.858891411108, 14532.782008269884, 14539.563813085599, 14559.05366151202, 14631.122132672048, 14865.479708210214, 14947.40210391583, 14957.252137393114, 15044.924775376108, 2632.0656167060706, 2691.8923243070253, 2692.8115031947773, 2695.582184761365, 2697.4335389568214, 2715.633389492413, 2822.6895041545517, 2829.052267490393, 2829.39168078863, 2830.6886242513306, 2707.620933069372, 2730.563109902416, 2760.7377299156547, 2768.045024466556, 2768.7369699878477, 2650.4169082801754, 2656.375751698349, 2685.9194224173207, 2710.4850078485224, 2720.510308144533, 2594.3125071855975, 2623.9081632142784, 2630.2898217481365, 2631.67104111986, 2641.0624228398833, 14151.150930944177, 14213.14721116726, 14271.07238029535, 14284.071535350273, 14295.642608934755, 7131.215566287704, 7332.230115837813, 7343.375813055896, 7354.154272258652, 7369.0, 4869.440014398354, 5027.673201241944, 5053.545180933734, 5105.055564720538, 5122.257272695439, 1877.0239475472815, 1881.3885487216573, 1895.8104189580931, 1921.730957665928, 1927.7975812539762, 24193.153239636704, 24408.38979025521, 24421.877890610613, 24893.62659560108, 25019.874900625382, 6451.225852897683, 6533.444657204096, 6568.901466478007, 6586.209654841449, 6732.091167692397, 15938.362465501483, 15985.680286394207, 16035.599110022344, 16326.346946122023, 16379.59051023734, 7402.7409040683315, 7635.0, 7643.617819109022, 7644.885326720104, 7647.464677472635, 7045.823854403681, 7063.187733410724, 7104.538205016657, 7288.70845166198, 7357.8160545986775, 5079.187330027178, 5162.199859021783, 5197.66215196011, 5234.3718753749035, 5269.235960785664, 1498.1909768724768, 1506.1716056169064, 1506.5932198306396, 1522.1399909051352, 1528.740114180592, 838.1660248053226, 892.4422236102436, 898.2544488074882, 905.764501229685, 908.3323757038571, 7254.637268136572, 7273.709051637875, 8623.31013518917, 8936.508492033001, 9053.456792592466, 978.4129522286571, 985.7437066362797, 994.6717583197527, 995.2684776689127, 1004.317064396203, 5227.006868694936, 5389.461053894657, 5496.367917689517, 5512.200730894078, 5520.944790552043, 19378.903105484384, 19870.403887883385, 20406.69389959152, 20653.277135300494, 20713.896430518053, 20011.59976800485, 20241.696374554394, 20307.898460507604, 20434.284800032226, 20445.2844150454, 10594.152467802558, 10866.293690910066, 11013.174011949135, 11081.667549973514, 11170.0, 7909.446338756232, 8277.62750676233, 8360.498370097814, 8364.456310340052, 8559.871601925852, 17411.738823917905, 17648.823511765233, 17935.103244837966, 17951.73072403941, 18088.366907158186, 17777.555561111076, 18133.184006719992, 18309.54226144357, 18468.722969155206, 18533.443996680122, 10079.69760907174, 10158.28891977584, 10336.17310615134, 10337.37975721459, 10351.0, 12827.615171544869, 13040.804387933998, 13073.67315817074, 13097.476100955855, 13181.538646147723, 10929.125669946226, 10975.890241097486, 10995.835062473909, 11066.39134847591, 11229.101671866394, 10463.895361046596, 10793.460326983777, 10936.398498082364, 11088.112950964065, 11131.610393636183, 9263.861042084509, 9403.529823508934, 9424.198943089914, 9431.669891554036, 9433.858492122761, 6834.658267086526, 6840.55536390153, 6868.107146070979, 6930.757423490154, 6948.513604047668, 7195.280471952763, 7226.927730722626, 7237.819054523468, 7262.564246145248, 7303.123625165014], "value": 6486.727569552349, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.9629527785417, 497.9145462890896, 513.9126348520754, 514.979400823967, 521.4863359590803, 6163.137160797486, 6223.128761973322, 6283.905741413877, 6286.748530058803, 6345.4923606111515, 6128.20333356663, 6144.784932527363, 6229.0067178502895, 6242.0, 6248.656323902183, 4837.9758101209445, 4940.580050695695, 5044.873878153048, 5048.596112311006, 5134.614903882205, 4935.605151587864, 4939.234418665113, 4954.87612809679, 4985.5819927335215, 5016.799328026875, 4931.654784165092, 4938.333325001115, 4946.1838796598695, 4955.0089982003565, 4972.453030166679, 4919.3604831371695, 4920.704757714549, 4937.901241975158, 4947.480514545957, 4978.701277923336, 4453.064856380164, 4453.37652728615, 4476.216662084123, 4482.282834746457, 4482.8655140345845, 5961.850953726148, 6006.279246490405, 6048.153258543807, 6104.046705054385, 6128.479079278267, 5619.167515936291, 5652.773889044433, 5672.914906276409, 5679.176519404663, 5743.942560574383, 6443.83890402739, 6554.606723596599, 6584.868302633969, 6587.80236592903, 6612.2726500084955, 4833.975830120827, 4879.0, 4889.608831293488, 4904.117258893398, 4923.513625619075, 6606.207255129367, 6692.0, 6767.221769496472, 6825.965870170667, 6940.30596940307, 4809.831655892027, 4969.975150124226, 4975.751212439436, 5007.37407824022, 5021.593953692972, 5060.2156665716875, 5064.594832413398, 5104.2900628289935, 5105.463926287761, 5106.846794596168, 5615.775368985195, 5619.325680918316, 5673.546116310686, 5678.148277758287, 5685.800996965087, 6498.54510184292, 6505.21937367519, 6543.672816359163, 6565.770198043046, 6575.506836987245, 1748.6153046329787, 1766.3199668127863, 1779.6084861330487, 1793.6906058577313, 1794.7846258448808, 1148.5176225985106, 1198.3109711915508, 1200.2318516149671, 1231.8152277158497, 1232.8425255802347, 5778.855528611734, 5784.88430231393, 5789.594728369058, 5844.357120716718, 5895.852603684943, 6717.764878229238, 6800.557963732342, 6801.0, 6803.353681400259, 6914.792556223321, 5750.338711048199, 5764.164196191528, 5789.218455508452, 5794.246747922743, 5866.178734977107, 3576.40989236773, 3577.94633080504, 3588.3002814450933, 3600.3978229687536, 3621.3300539400293, 6489.513286503437, 6543.934560654333, 6625.138731964814, 6658.3341665834, 6677.762949412001, 6871.316527450706, 6951.16586009682, 7005.719771209095, 7015.0, 7018.052562903943, 6560.901586476207, 6571.901421478778, 6612.7354905804195, 6615.966920165464, 6646.634435106115, 6544.803655890331, 6731.32686731323, 6790.72837086511, 6816.318368163144, 6864.38220560152, 6143.416375444325, 6686.632235227109, 6701.229358623821, 6735.979206238207, 6801.863962720817, 6487.253965793994, 6515.804525864231, 6523.967380163259, 6527.2167339919515, 6588.374104460068, 4959.156943319572, 4973.428055773487, 4983.259207435968, 4998.575121114746, 5023.0, 6553.967230163818, 6734.306277489043, 6760.363458251188, 6842.247352791289, 6851.588904665736, 6558.557117434157, 6572.14562106923, 6577.013447982841, 6577.079208910661, 6607.405333520006, 6596.30738772422, 6597.703103360264, 6602.735890564323, 6628.602283862983, 6632.535722499379, 6424.196975378078, 6478.870422591616, 6518.869622607616, 6522.315156908457, 6536.803895883131, 6514.892468280498, 6560.507961902781, 6591.758802007341, 6669.333066693292, 6696.062551242732, 6275.152854364603, 6330.303666596763, 6343.809685709436, 6344.587601805868, 6399.264084630141], "value": 5064.665837497387, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [875.3872289397419, 899.6716198587518, 908.3823000359753, 910.1171863292604, 917.8164367126574, 18169.364072257475, 19124.713129303054, 19135.521611959706, 19147.521311967208, 22229.333120006424, 21065.57868842626, 21975.670364944537, 22040.228591999283, 22170.88914555429, 22182.0, 1022.5398570643204, 1057.9735506612358, 1063.106990128296, 1066.4827558634056, 1066.4934156275747, 973.8052389522088, 1055.3826011783078, 1056.6935588679273, 1056.7094049136472, 1058.2433560004595, 1114.0530549033297, 1114.9108071354312, 1123.7303047268638, 1124.454639499842, 1125.083057308291, 1105.2318638546174, 1107.3521989636072, 1109.328856042095, 1109.522905150785, 1110.1507346879644, 799.9600019998977, 807.8020884883182, 811.4482152136545, 812.2040400407634, 813.5240884082818, 19333.61332773337, 19664.508387290432, 19868.00659966996, 19874.602507949905, 19920.900395498076, 21614.783852161436, 22151.33545993607, 22385.328440146823, 22857.0, 22871.771282287296, 16528.586785330343, 16644.916775416044, 16749.581260468585, 16754.246058927376, 17199.398021069326, 3652.3790955537625, 3674.7427680062406, 3693.316736403747, 3694.3350196964534, 3695.575008873987, 123206.0, 125640.37179814042, 125810.0, 128045.0, 128397.0, 16544.090075045748, 17063.829361706474, 17095.316187352368, 17109.401170958958, 17193.312267509158, 31407.21481962969, 31478.370432591677, 31486.0, 31652.525212121836, 32166.0, 7995.0, 8199.467034642728, 8571.314294856396, 8726.345524085718, 8874.64501419936, 4120.896977575585, 4169.4162817205615, 4178.8537401190815, 4195.454590903224, 4197.118605092965, 5251.238570407346, 5314.548263397577, 5315.813946511854, 5327.0, 5383.730813459311, 1017.8524114003576, 1043.7025447747355, 1055.37205362809, 1056.3872953686996, 1061.7717190804015, 681.111149949311, 690.8445599740119, 697.5256825358704, 700.8037749430167, 706.0715159565156, 11814.232074915102, 12020.939895300467, 12148.149629525848, 12560.81158782619, 13183.0, 694.6040756768593, 697.3584302441766, 698.664640972326, 712.4015826705593, 718.5006420537676, 5994.940050599438, 6027.578069535178, 6069.332373438918, 6245.375462453809, 6454.612723236621, 107840.9215907831, 110403.0, 111687.44156279329, 112249.43875280734, 112373.0, 96726.0, 97277.0, 98790.0, 99300.0, 104829.0, 83748.58125709332, 85191.57404212939, 86191.56904215438, 87994.0, 89387.0, 12763.744725105631, 12808.167469114851, 12985.598209020767, 13051.92890940787, 13103.344832758514, 38808.41787373135, 39585.60414395819, 39774.20451590894, 39798.60201397949, 40298.59701402948, 50656.746716266185, 52892.73553632207, 53018.0, 59679.70160149172, 71724.28275717176, 17726.822731772518, 19328.0, 19743.703844442633, 19766.604667906275, 19839.30562430366, 19060.61878762389, 19770.604587907874, 20028.599428011068, 20082.59834803325, 20220.59558808845, 18296.451106466826, 18738.156782944527, 18803.153858076694, 18898.244070237037, 18910.810891891455, 17957.640847183244, 19078.523036924187, 19155.90422047881, 20300.59398812045, 20499.28252511155, 16877.156142192594, 16940.745888811925, 17006.404775832787, 17040.06279654631, 18402.72395914089, 14626.122432654074, 14786.778198326818, 14804.407823687363, 14816.555503334917, 14846.480373187318, 14591.708165836411, 15179.241037948279, 15302.387904484141, 15314.770278445609, 15342.23288835576], "value": 9347.474337190737, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 101.0, 108.0, 111.0, 118.0, 110.0, 103.0, 108.0, 111.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 159.0, 178.0, 124.0, 155.0, 150.0, 118.0, 174.0, 118.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 164.0, 162.0, 162.0, 167.0, 169.0, 173.0, 171.0, 164.0], "value": 166.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 74.0, 177.0, 74.0, 77.0, 75.0, 90.0, 96.0, 74.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 127.0, 79.0, 80.0, 82.0, 141.0, 86.0, 78.0, 130.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 123.0, 114.0, 116.0, 118.0, 114.0, 114.0, 117.0, 114.0, 119.0], "value": 116.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 150.0, 151.0, 149.0, 151.0, 150.0, 153.0, 150.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 94.0, 94.0, 98.0, 99.0, 95.0, 94.0, 96.0, 99.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 55.0, 56.0, 58.0, 53.0, 56.0, 53.0, 56.0, 54.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 62.0, 53.0, 54.0, 54.0, 59.0, 54.0, 54.0, 58.0, 59.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 78.0, 78.0, 82.0, 78.0, 76.0, 77.0, 77.0], "value": 77.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 135.0, 133.0, 125.0, 121.0, 132.0, 136.0, 129.0, 125.0, 176.0], "value": 133.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 174.0, 173.0, 183.0, 178.0, 176.0, 176.0, 194.0, 183.0, 184.0], "value": 180.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 70.0, 76.0, 70.0, 69.0, 66.0, 69.0, 66.0, 74.0], "value": 70.4, "unit": "score"}]}]} 11:08:11 INFO - Return code: 0 11:08:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:08:11 INFO - # TBPL SUCCESS # 11:08:11 INFO - Running post-action listener: _package_coverage_data 11:08:11 INFO - Running post-action listener: _resource_record_post_action 11:08:11 INFO - [mozharness: 2017-08-11 18:08:11.239979Z] Finished run-tests step (success) 11:08:11 INFO - Running post-run listener: _resource_record_post_run 11:08:11 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 338243584; Read time: 16; Write time: 185440 11:08:11 INFO - TinderboxPrint: CPU usage
13.2% 11:08:11 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 11:08:11 INFO - TinderboxPrint: I/O write bytes / time
338,243,584 / 185,440 11:08:11 INFO - TinderboxPrint: CPU idle
8,272.5 (86.8%) 11:08:11 INFO - TinderboxPrint: CPU user
1,204.4 (12.6%) 11:08:11 INFO - TinderboxPrint: Swap in / out
0 / 0 11:08:11 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 15675392; Read time: 0; Write time: 3340 11:08:11 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:08:11 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 322568192; Read time: 0; Write time: 182100 11:08:11 INFO - Running post-run listener: _upload_blobber_files 11:08:11 INFO - Blob upload gear active. 11:08:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:08:11 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 11:08:11 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'] 11:08:11 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 11:08:12 INFO - (blobuploader) - INFO - Open directory for files ... 11:08:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:08:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:08:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:13 INFO - (blobuploader) - INFO - Done attempting. 11:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:08:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:13 INFO - (blobuploader) - INFO - Done attempting. 11:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:08:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:17 INFO - (blobuploader) - INFO - Done attempting. 11:08:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:08:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:08:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:17 INFO - (blobuploader) - INFO - Done attempting. 11:08:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:08:17 INFO - Return code: 0 11:08:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:08:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:08:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9a3fbcfbb3568bb554819c82cf6a47581b35e650bb3ecaf9e7b56adb1f90c4dc29e2fe9e9844c2f1c62687f2905b734f6a1ee663dc0085637774339410d1d5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e94271e5bb36749cbcc654f5c4ec6a8ad38c626208ef648ce1055c4fcd6fa8e213f89d07313611931cf92f09a2229428ec19abbeea9e1bb263f04f8b7e4682f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1ea84ba73163b26d1918ee6df16b25f0d5be871a208f2dc0fcde077a9d4520803637ed7dbc8cafb5fc39358c2730127df2c04a3145696f253a646069d59c525", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/489106b74199a55036f26ff8fb37cc0b4d5f1be81dd97b26c04883ae90d89f3593b1df5178d2e800eb73369d4da01ccfda9852b6273e0311c130ec81a2597544"} 11:08:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:08:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:08:17 INFO - Contents: 11:08:17 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9a3fbcfbb3568bb554819c82cf6a47581b35e650bb3ecaf9e7b56adb1f90c4dc29e2fe9e9844c2f1c62687f2905b734f6a1ee663dc0085637774339410d1d5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e94271e5bb36749cbcc654f5c4ec6a8ad38c626208ef648ce1055c4fcd6fa8e213f89d07313611931cf92f09a2229428ec19abbeea9e1bb263f04f8b7e4682f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1ea84ba73163b26d1918ee6df16b25f0d5be871a208f2dc0fcde077a9d4520803637ed7dbc8cafb5fc39358c2730127df2c04a3145696f253a646069d59c525", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/489106b74199a55036f26ff8fb37cc0b4d5f1be81dd97b26c04883ae90d89f3593b1df5178d2e800eb73369d4da01ccfda9852b6273e0311c130ec81a2597544"} 11:08:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:08:17 INFO - Copying logs to upload dir... 11:08:17 INFO - mkdir: /builds/slave/test/build/upload/logs 11:08:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1317.589375 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2017-08-11 11:08:17.480995) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 11:08:17.488897) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9a3fbcfbb3568bb554819c82cf6a47581b35e650bb3ecaf9e7b56adb1f90c4dc29e2fe9e9844c2f1c62687f2905b734f6a1ee663dc0085637774339410d1d5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e94271e5bb36749cbcc654f5c4ec6a8ad38c626208ef648ce1055c4fcd6fa8e213f89d07313611931cf92f09a2229428ec19abbeea9e1bb263f04f8b7e4682f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1ea84ba73163b26d1918ee6df16b25f0d5be871a208f2dc0fcde077a9d4520803637ed7dbc8cafb5fc39358c2730127df2c04a3145696f253a646069d59c525", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/489106b74199a55036f26ff8fb37cc0b4d5f1be81dd97b26c04883ae90d89f3593b1df5178d2e800eb73369d4da01ccfda9852b6273e0311c130ec81a2597544"} build_url:https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011474 build_url: 'https://queue.taskcluster.net/v1/task/E4iAGlFXSzGuQDDtIxOgHQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9a3fbcfbb3568bb554819c82cf6a47581b35e650bb3ecaf9e7b56adb1f90c4dc29e2fe9e9844c2f1c62687f2905b734f6a1ee663dc0085637774339410d1d5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e94271e5bb36749cbcc654f5c4ec6a8ad38c626208ef648ce1055c4fcd6fa8e213f89d07313611931cf92f09a2229428ec19abbeea9e1bb263f04f8b7e4682f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1ea84ba73163b26d1918ee6df16b25f0d5be871a208f2dc0fcde077a9d4520803637ed7dbc8cafb5fc39358c2730127df2c04a3145696f253a646069d59c525", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/489106b74199a55036f26ff8fb37cc0b4d5f1be81dd97b26c04883ae90d89f3593b1df5178d2e800eb73369d4da01ccfda9852b6273e0311c130ec81a2597544"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 11:08:17.521845) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 11:08:17.522250) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dbb0bb31bf4a4c7db9ba1648000001b6-1502468924.482610-1015150725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004520 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 11:08:17.603396) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 11:08:17.606787) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 11:08:17.607104) ========= ========= Total master_lag: 0.17 =========