builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-076 starttime: 1500398290.62 results: success (0) revision: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.616667) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.617171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.617444) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.633804) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.634100) ========= 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=9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-18 10:18:11-- 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% 143M=0s 2017-07-18 10:18:11 (143 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109720 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.766183) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.766645) ========= 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=9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014738 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.827035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:10.827356) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 --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 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 --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=9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-18 10:18:11,548 truncating revision to first 12 chars 2017-07-18 10:18:11,548 Setting DEBUG logging. 2017-07-18 10:18:11,548 attempt 1/10 2017-07-18 10:18:11,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/56c6c81993e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-18 10:18:12,191 unpacking tar archive at: mozilla-central-56c6c81993e6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.960379 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:11.803556) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:11.803838) ========= script_repo_revision: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:11.804189) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:11.804455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 10:18:11.815691) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-07-18 10:18:11.818998) ========= /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=9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:18:12 INFO - MultiFileLogger online at 20170718 10:18:12 in /builds/slave/test 10:18:12 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:18:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:18:12 INFO - {'append_to_log': False, 10:18:12 INFO - 'base_work_dir': '/builds/slave/test', 10:18:12 INFO - 'blob_upload_branch': 'Firefox', 10:18:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:18:12 INFO - 'branch': 'Firefox', 10:18:12 INFO - 'buildbot_json_path': 'buildprops.json', 10:18:12 INFO - 'code_coverage': False, 10:18:12 INFO - 'config_files': ('talos/linux_config.py',), 10:18:12 INFO - 'default_actions': ('clobber', 10:18:12 INFO - 'read-buildbot-config', 10:18:12 INFO - 'download-and-extract', 10:18:12 INFO - 'populate-webroot', 10:18:12 INFO - 'create-virtualenv', 10:18:12 INFO - 'install', 10:18:12 INFO - 'setup-mitmproxy', 10:18:12 INFO - 'run-tests'), 10:18:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:18:12 INFO - 'disable_ccov_upload': False, 10:18:12 INFO - 'download_minidump_stackwalk': True, 10:18:12 INFO - 'download_symbols': 'ondemand', 10:18:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:18:12 INFO - 'tooltool.py': '/tools/tooltool.py', 10:18:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:18:12 INFO - '/tools/misc-python/virtualenv.py')}, 10:18:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:18:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:18:12 INFO - 'gecko_profile': False, 10:18:12 INFO - 'gecko_profile_interval': 0, 10:18:12 INFO - 'installer_path': 'installer.exe', 10:18:12 INFO - 'log_level': 'info', 10:18:12 INFO - 'log_name': 'talos', 10:18:12 INFO - 'log_to_console': True, 10:18:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:18:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:18:12 INFO - 'opt_config_files': (), 10:18:12 INFO - 'pip_index': False, 10:18:12 INFO - 'suite': 'dromaeojs-e10s', 10:18:12 INFO - 'system_bits': '32', 10:18:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:18:12 INFO - 'title': 'talos-linux64-ix-076', 10:18:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:18:12 INFO - 'use_talos_json': True, 10:18:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:18:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:18:12 INFO - 'work_dir': 'build'} 10:18:12 INFO - [mozharness: 2017-07-18 17:18:12.644810Z] Running clobber step. 10:18:12 INFO - Running pre-action listener: _resource_record_pre_action 10:18:12 INFO - Running main action method: clobber 10:18:12 INFO - rmtree: /builds/slave/test/build 10:18:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:18:13 INFO - Running post-action listener: _resource_record_post_action 10:18:13 INFO - [mozharness: 2017-07-18 17:18:13.681792Z] Finished clobber step (success) 10:18:13 INFO - [mozharness: 2017-07-18 17:18:13.681895Z] Running read-buildbot-config step. 10:18:13 INFO - Running pre-action listener: _resource_record_pre_action 10:18:13 INFO - Running main action method: read_buildbot_config 10:18:13 INFO - Using buildbot properties: 10:18:13 INFO - { 10:18:13 INFO - "project": "", 10:18:13 INFO - "product": "firefox", 10:18:13 INFO - "who": "ryanvm@gmail.com", 10:18:13 INFO - "installer_path": "public/build/target.tar.bz2", 10:18:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 10:18:13 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:18:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 10:18:13 INFO - "stage_platform": "linux64-pgo", 10:18:13 INFO - "basedir": "/builds/slave/test", 10:18:13 INFO - "buildnumber": 69, 10:18:13 INFO - "platform": "ubuntu64_hw", 10:18:13 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:18:13 INFO - "slavebuilddir": "test", 10:18:13 INFO - "taskId": "asUa8rI0R3mhAWg7oD0OAQ", 10:18:13 INFO - "branch": "mozilla-central", 10:18:13 INFO - "script_repo_revision": "production", 10:18:13 INFO - "revision": "56c6c81993e6b0f0e094e0c88b42ce3ec566ead9", 10:18:13 INFO - "slavename": "talos-linux64-ix-076", 10:18:13 INFO - "repo_path": "mozilla-central" 10:18:13 INFO - } 10:18:13 INFO - Finding installer, test and symbols from parent task. 10:18:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/asUa8rI0R3mhAWg7oD0OAQ'}, attempt #1 10:18:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/asUa8rI0R3mhAWg7oD0OAQ'}, attempt #1 10:18:14 INFO - Task dependencies: Yt7z9mouSkOiUBbDcWg8og 10:18:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og'}, attempt #1 10:18:15 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.tar.bz2 10:18:15 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/test_packages.json 10:18:15 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.crashreporter-symbols.zip 10:18:15 INFO - Running post-action listener: _resource_record_post_action 10:18:15 INFO - [mozharness: 2017-07-18 17:18:15.030598Z] Finished read-buildbot-config step (success) 10:18:15 INFO - [mozharness: 2017-07-18 17:18:15.030818Z] Running download-and-extract step. 10:18:15 INFO - Running pre-action listener: _resource_record_pre_action 10:18:15 INFO - Running main action method: download_and_extract 10:18:15 INFO - mkdir: /builds/slave/test/build/tests 10:18: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:18:15 INFO - trying https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.test_packages.json 10:18:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:18:15 INFO - Downloaded 989 bytes. 10:18:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:18:15 INFO - Using the following test package requirements: 10:18:15 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 10:18:15 INFO - u'common': [u'target.common.tests.zip'], 10:18:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:18:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:18:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:18:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:18:15 INFO - u'mozbase': [u'target.common.tests.zip'], 10:18:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:18:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:18:15 INFO - u'web-platform': [u'target.common.tests.zip', 10:18:15 INFO - u'target.web-platform.tests.tar.gz'], 10:18:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:18:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:18:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.common.tests.zip 10:18:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:18:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.common.tests.zip into memory 10:18:20 INFO - Content-Length response header: 73363488 10:18:20 INFO - Bytes received: 73363488 10:18:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:18:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.common.tests.zip 10:18:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:18:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.common.tests.zip into memory 10:18:33 INFO - Content-Length response header: 73363488 10:18:33 INFO - Bytes received: 73363488 10:18:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.talos.tests.zip 10:18:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:18:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.talos.tests.zip into memory 10:18:46 INFO - Content-Length response header: 14495033 10:18:46 INFO - Bytes received: 14495033 10:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:47 INFO - trying https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.tar.bz2 10:18:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:18:49 INFO - Downloaded 62695401 bytes. 10:18:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.tar.bz2 10:18:49 INFO - mkdir: /builds/slave/test/properties 10:18:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:18:49 INFO - Writing to file /builds/slave/test/properties/build_url 10:18:49 INFO - Contents: 10:18:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.tar.bz2 10:18:49 INFO - Running post-action listener: _resource_record_post_action 10:18:49 INFO - Running post-action listener: set_extra_try_arguments 10:18:49 INFO - [mozharness: 2017-07-18 17:18:49.096134Z] Finished download-and-extract step (success) 10:18:49 INFO - [mozharness: 2017-07-18 17:18:49.096231Z] Running populate-webroot step. 10:18:49 INFO - Running pre-action listener: _resource_record_pre_action 10:18:49 INFO - Running main action method: populate_webroot 10:18:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:18:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:18:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:18:49 INFO - '--tppagecycles', 10:18:49 INFO - '7'], 10:18:49 INFO - 'tests': ['tsvgm']}}, 10:18:49 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:18:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:18:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:18:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:18:49 INFO - 'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:18:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:18:49 INFO - 'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['damp', 'tps']}, 10:18:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:18:49 INFO - 'tests': ['damp', 'tps']}, 10:18:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['dromaeo_dom']}, 10:18:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:18:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:18:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:18:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:18:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:18:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['a11yr', 10:18:49 INFO - 'ts_paint', 10:18:49 INFO - 'tpaint', 10:18:49 INFO - 'sessionrestore', 10:18:49 INFO - 'sessionrestore_no_auto_restore', 10:18:49 INFO - 'tabpaint']}, 10:18:49 INFO - 'other-e10s': {'tests': ['a11yr', 10:18:49 INFO - 'ts_paint', 10:18:49 INFO - 'tpaint', 10:18:49 INFO - 'sessionrestore', 10:18:49 INFO - 'sessionrestore_many_windows', 10:18:49 INFO - 'sessionrestore_no_auto_restore', 10:18:49 INFO - 'tabpaint']}, 10:18:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:18:49 INFO - 'ts_paint', 10:18:49 INFO - 'tpaint', 10:18:49 INFO - 'sessionrestore', 10:18:49 INFO - 'sessionrestore_many_windows', 10:18:49 INFO - 'sessionrestore_no_auto_restore', 10:18:49 INFO - 'tabpaint']}, 10:18:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:18:49 INFO - 'ts_paint', 10:18:49 INFO - 'tpaint', 10:18:49 INFO - 'sessionrestore', 10:18:49 INFO - 'sessionrestore_many_windows', 10:18:49 INFO - 'sessionrestore_no_auto_restore', 10:18:49 INFO - 'tabpaint']}, 10:18:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['a11yr', 10:18:49 INFO - 'ts_paint', 10:18:49 INFO - 'tpaint', 10:18:49 INFO - 'sessionrestore', 10:18:49 INFO - 'sessionrestore_no_auto_restore', 10:18:49 INFO - 'tabpaint']}, 10:18:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['a11yr', 10:18:49 INFO - 'ts_paint', 10:18:49 INFO - 'tpaint', 10:18:49 INFO - 'sessionrestore', 10:18:49 INFO - 'sessionrestore_no_auto_restore', 10:18:49 INFO - 'tabpaint']}, 10:18:49 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 10:18:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 10:18:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 10:18:49 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:18:49 INFO - 'talos_options': ['--mitmproxy', 10:18:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:18:49 INFO - '--firstNonBlankPaint'], 10:18:49 INFO - 'tests': ['quantum_pageload_google', 10:18:49 INFO - 'quantum_pageload_youtube', 10:18:49 INFO - 'quantum_pageload_amazon', 10:18:49 INFO - 'quantum_pageload_facebook']}, 10:18:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['tsvgx', 10:18:49 INFO - 'tsvgr_opacity', 10:18:49 INFO - 'tart', 10:18:49 INFO - 'tscrollx', 10:18:49 INFO - 'cart', 10:18:49 INFO - 'tsvg_static']}, 10:18:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:18:49 INFO - 'tsvgr_opacity', 10:18:49 INFO - 'tart', 10:18:49 INFO - 'tscrollx', 10:18:49 INFO - 'cart', 10:18:49 INFO - 'tsvg_static']}, 10:18:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:18:49 INFO - 'talos_options': ['--disable-e10s'], 10:18:49 INFO - 'tests': ['tp5o']}, 10:18:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:18:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:18:49 INFO - 'talos_options': ['--disable-e10s', 10:18:49 INFO - '--xperf_path', 10:18:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:49 INFO - 'tests': ['tp5n']}, 10:18:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:18:49 INFO - 'talos_options': ['--xperf_path', 10:18:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:49 INFO - 'tests': ['tp5n']}}, 10:18:49 INFO - 'talos.zip': {'path': '', 10:18:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:18:49 INFO - Running post-action listener: _resource_record_post_action 10:18:49 INFO - [mozharness: 2017-07-18 17:18:49.111669Z] Finished populate-webroot step (success) 10:18:49 INFO - [mozharness: 2017-07-18 17:18:49.111761Z] Running create-virtualenv step. 10:18:49 INFO - Running pre-action listener: _resource_record_pre_action 10:18:49 INFO - Running main action method: create_virtualenv 10:18:49 INFO - Creating virtualenv /builds/slave/test/build/venv 10:18:49 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:18:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:18:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:18:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:18:49 INFO - Using real prefix '/usr' 10:18:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:18:49 INFO - Installing distribute.............................................................................................................................................................................................done. 10:18:51 INFO - Installing pip.................done. 10:18:51 INFO - Return code: 0 10:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:18:51 INFO - Reading from file tmpfile_stdout 10:18:51 INFO - Output received: 10:18:51 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:18:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:18: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:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18: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:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:18:52 INFO - Using env: {'DISPLAY': ':0', 10:18:52 INFO - 'HOME': '/home/cltbld', 10:18:52 INFO - 'LANG': 'en_US.UTF-8', 10:18:52 INFO - 'LANGUAGE': 'en_US:en', 10:18:52 INFO - 'LOGNAME': 'cltbld', 10:18:52 INFO - 'MAIL': '/var/mail/cltbld', 10:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:52 INFO - 'MOZ_NO_REMOTE': '1', 10:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:52 INFO - 'NO_EM_RESTART': '1', 10:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:52 INFO - 'PWD': '/builds/slave/test', 10:18:52 INFO - 'SHELL': '/bin/bash', 10:18:52 INFO - 'SHLVL': '1', 10:18:52 INFO - 'TERM': 'linux', 10:18:52 INFO - 'TMOUT': '86400', 10:18:52 INFO - 'USER': 'cltbld', 10:18:52 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:52 INFO - '_': '/tools/buildbot/bin/python'} 10:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:52 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:18:52 INFO - Cleaning up... 10:18:52 INFO - Return code: 0 10:18:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:18:52 INFO - Using env: {'DISPLAY': ':0', 10:18:52 INFO - 'HOME': '/home/cltbld', 10:18:52 INFO - 'LANG': 'en_US.UTF-8', 10:18:52 INFO - 'LANGUAGE': 'en_US:en', 10:18:52 INFO - 'LOGNAME': 'cltbld', 10:18:52 INFO - 'MAIL': '/var/mail/cltbld', 10:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:52 INFO - 'MOZ_NO_REMOTE': '1', 10:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:52 INFO - 'NO_EM_RESTART': '1', 10:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:52 INFO - 'PWD': '/builds/slave/test', 10:18:52 INFO - 'SHELL': '/bin/bash', 10:18:52 INFO - 'SHLVL': '1', 10:18:52 INFO - 'TERM': 'linux', 10:18:52 INFO - 'TMOUT': '86400', 10:18:52 INFO - 'USER': 'cltbld', 10:18:52 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:52 INFO - '_': '/tools/buildbot/bin/python'} 10:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:52 INFO - Downloading/unpacking psutil>=3.1.1 10:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:18:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:54 INFO - Installing collected packages: psutil 10:18:54 INFO - Running setup.py install for psutil 10:18:54 INFO - building 'psutil._psutil_linux' extension 10:18:54 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:18:54 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:18:54 INFO - building 'psutil._psutil_posix' extension 10:18:54 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:18:54 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:18:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:54 INFO - Successfully installed psutil 10:18:54 INFO - Cleaning up... 10:18:54 INFO - Return code: 0 10:18:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:18:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:18:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:18:54 INFO - Using env: {'DISPLAY': ':0', 10:18:54 INFO - 'HOME': '/home/cltbld', 10:18:54 INFO - 'LANG': 'en_US.UTF-8', 10:18:54 INFO - 'LANGUAGE': 'en_US:en', 10:18:54 INFO - 'LOGNAME': 'cltbld', 10:18:54 INFO - 'MAIL': '/var/mail/cltbld', 10:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:54 INFO - 'MOZ_NO_REMOTE': '1', 10:18:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:54 INFO - 'NO_EM_RESTART': '1', 10:18:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:54 INFO - 'PWD': '/builds/slave/test', 10:18:54 INFO - 'SHELL': '/bin/bash', 10:18:54 INFO - 'SHLVL': '1', 10:18:54 INFO - 'TERM': 'linux', 10:18:54 INFO - 'TMOUT': '86400', 10:18:54 INFO - 'USER': 'cltbld', 10:18:54 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:54 INFO - '_': '/tools/buildbot/bin/python'} 10:18:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:18:56 INFO - Installing collected packages: mozsystemmonitor 10:18:56 INFO - Running setup.py install for mozsystemmonitor 10:18:56 INFO - Successfully installed mozsystemmonitor 10:18:56 INFO - Cleaning up... 10:18:56 INFO - Return code: 0 10:18:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:18:56 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:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:56 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:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:56 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 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:18:56 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:18:56 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:18:56 INFO - Using env: {'DISPLAY': ':0', 10:18:56 INFO - 'HOME': '/home/cltbld', 10:18:56 INFO - 'LANG': 'en_US.UTF-8', 10:18:56 INFO - 'LANGUAGE': 'en_US:en', 10:18:56 INFO - 'LOGNAME': 'cltbld', 10:18:56 INFO - 'MAIL': '/var/mail/cltbld', 10:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:56 INFO - 'MOZ_NO_REMOTE': '1', 10:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:56 INFO - 'NO_EM_RESTART': '1', 10:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:56 INFO - 'PWD': '/builds/slave/test', 10:18:56 INFO - 'SHELL': '/bin/bash', 10:18:56 INFO - 'SHLVL': '1', 10:18:56 INFO - 'TERM': 'linux', 10:18:56 INFO - 'TMOUT': '86400', 10:18:56 INFO - 'USER': 'cltbld', 10:18:56 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:56 INFO - '_': '/tools/buildbot/bin/python'} 10:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:57 INFO - Downloading/unpacking jsonschema==2.5.1 10:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:18:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:18: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:18: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:18: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:18: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:18:58 INFO - Downloading functools32-3.2.3-2.tar.gz 10:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:18:58 INFO - warning: no files found matching '*.txt' 10:18:58 INFO - no previously-included directories found matching 'build' 10:18:58 INFO - no previously-included directories found matching 'dist' 10:18:58 INFO - no previously-included directories found matching '.git*' 10:18:58 INFO - Installing collected packages: jsonschema, functools32 10:18:58 INFO - Running setup.py install for functools32 10:18:58 INFO - warning: no files found matching '*.txt' 10:18:58 INFO - no previously-included directories found matching 'build' 10:18:58 INFO - no previously-included directories found matching 'dist' 10:18:58 INFO - no previously-included directories found matching '.git*' 10:18:58 INFO - Successfully installed jsonschema functools32 10:18:58 INFO - Cleaning up... 10:18:58 INFO - Return code: 0 10:18:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:18: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:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18: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:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:59 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 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:18:59 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:18:59 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:18:59 INFO - Using env: {'DISPLAY': ':0', 10:18:59 INFO - 'HOME': '/home/cltbld', 10:18:59 INFO - 'LANG': 'en_US.UTF-8', 10:18:59 INFO - 'LANGUAGE': 'en_US:en', 10:18:59 INFO - 'LOGNAME': 'cltbld', 10:18:59 INFO - 'MAIL': '/var/mail/cltbld', 10:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:59 INFO - 'MOZ_NO_REMOTE': '1', 10:18:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:59 INFO - 'NO_EM_RESTART': '1', 10:18:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:59 INFO - 'PWD': '/builds/slave/test', 10:18:59 INFO - 'SHELL': '/bin/bash', 10:18:59 INFO - 'SHLVL': '1', 10:18:59 INFO - 'TERM': 'linux', 10:18:59 INFO - 'TMOUT': '86400', 10:18:59 INFO - 'USER': 'cltbld', 10:18:59 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:59 INFO - '_': '/tools/buildbot/bin/python'} 10:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:18:59 INFO - Cleaning up... 10:18:59 INFO - Return code: 0 10:18:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:18:59 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:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:59 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:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:59 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 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:18:59 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:18:59 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:18:59 INFO - Using env: {'DISPLAY': ':0', 10:18:59 INFO - 'HOME': '/home/cltbld', 10:18:59 INFO - 'LANG': 'en_US.UTF-8', 10:18:59 INFO - 'LANGUAGE': 'en_US:en', 10:18:59 INFO - 'LOGNAME': 'cltbld', 10:18:59 INFO - 'MAIL': '/var/mail/cltbld', 10:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:59 INFO - 'MOZ_NO_REMOTE': '1', 10:18:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:59 INFO - 'NO_EM_RESTART': '1', 10:18:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:59 INFO - 'PWD': '/builds/slave/test', 10:18:59 INFO - 'SHELL': '/bin/bash', 10:18:59 INFO - 'SHLVL': '1', 10:18:59 INFO - 'TERM': 'linux', 10:18:59 INFO - 'TMOUT': '86400', 10:18:59 INFO - 'USER': 'cltbld', 10:18:59 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:59 INFO - '_': '/tools/buildbot/bin/python'} 10:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:59 INFO - Downloading/unpacking blobuploader==1.2.4 10:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:01 INFO - Downloading blobuploader-1.2.4.tar.gz 10:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:19:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:19:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:01 INFO - Downloading docopt-0.6.1.tar.gz 10:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:19:01 INFO - Installing collected packages: blobuploader, requests, docopt 10:19:01 INFO - Running setup.py install for blobuploader 10:19:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:19:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:19:01 INFO - Running setup.py install for requests 10:19:02 INFO - Running setup.py install for docopt 10:19:02 INFO - Successfully installed blobuploader requests docopt 10:19:02 INFO - Cleaning up... 10:19:02 INFO - Return code: 0 10:19:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19: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:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19: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:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:02 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 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:19:02 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:19:02 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:19:02 INFO - Using env: {'DISPLAY': ':0', 10:19:02 INFO - 'HOME': '/home/cltbld', 10:19:02 INFO - 'LANG': 'en_US.UTF-8', 10:19:02 INFO - 'LANGUAGE': 'en_US:en', 10:19:02 INFO - 'LOGNAME': 'cltbld', 10:19:02 INFO - 'MAIL': '/var/mail/cltbld', 10:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:02 INFO - 'MOZ_NO_REMOTE': '1', 10:19:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:02 INFO - 'NO_EM_RESTART': '1', 10:19:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:02 INFO - 'PWD': '/builds/slave/test', 10:19:02 INFO - 'SHELL': '/bin/bash', 10:19:02 INFO - 'SHLVL': '1', 10:19:02 INFO - 'TERM': 'linux', 10:19:02 INFO - 'TMOUT': '86400', 10:19:02 INFO - 'USER': 'cltbld', 10:19:02 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:02 INFO - '_': '/tools/buildbot/bin/python'} 10:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:02 INFO - Running setup.py (path:/tmp/pip-kOBzPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:02 INFO - Running setup.py (path:/tmp/pip-NUT0xJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:02 INFO - Running setup.py (path:/tmp/pip-OwcLEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:03 INFO - Running setup.py (path:/tmp/pip-6nb75L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:03 INFO - Running setup.py (path:/tmp/pip-wtfMdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:03 INFO - Running setup.py (path:/tmp/pip-vSRgfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:03 INFO - Running setup.py (path:/tmp/pip-FHOGlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:19:03 INFO - Running setup.py (path:/tmp/pip-yuIK0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:19:03 INFO - Running setup.py (path:/tmp/pip-na_VCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:19:03 INFO - Running setup.py (path:/tmp/pip-dqoQWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:19:03 INFO - Running setup.py (path:/tmp/pip-cDTqNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:19:03 INFO - Running setup.py (path:/tmp/pip-3_5nx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:19:03 INFO - Running setup.py (path:/tmp/pip-IDyRZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:19:03 INFO - Running setup.py (path:/tmp/pip-RgcE8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:03 INFO - Running setup.py (path:/tmp/pip-bsSlXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:19:03 INFO - Running setup.py (path:/tmp/pip-VSi_rL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:19:03 INFO - Running setup.py (path:/tmp/pip-V4QepE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:19:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:19:04 INFO - Running setup.py install for manifestparser 10:19:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Running setup.py install for mozcrash 10:19:04 INFO - Running setup.py install for mozdebug 10:19:04 INFO - Running setup.py install for mozdevice 10:19:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Running setup.py install for mozfile 10:19:04 INFO - Running setup.py install for mozhttpd 10:19:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Running setup.py install for mozinfo 10:19:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Running setup.py install for mozInstall 10:19:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Running setup.py install for mozleak 10:19:04 INFO - Running setup.py install for mozlog 10:19:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:19:05 INFO - Running setup.py install for moznetwork 10:19:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:19:05 INFO - Running setup.py install for mozprocess 10:19:05 INFO - Running setup.py install for mozprofile 10:19:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:19:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:19:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:19:05 INFO - Running setup.py install for mozrunner 10:19:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:19:05 INFO - Running setup.py install for mozscreenshot 10:19:05 INFO - Running setup.py install for moztest 10:19:05 INFO - Running setup.py install for mozversion 10:19:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:19:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:19:05 INFO - Cleaning up... 10:19:05 INFO - Return code: 0 10:19:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:05 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:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:05 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:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:06 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 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:19:06 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:19:06 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:19:06 INFO - Using env: {'DISPLAY': ':0', 10:19:06 INFO - 'HOME': '/home/cltbld', 10:19:06 INFO - 'LANG': 'en_US.UTF-8', 10:19:06 INFO - 'LANGUAGE': 'en_US:en', 10:19:06 INFO - 'LOGNAME': 'cltbld', 10:19:06 INFO - 'MAIL': '/var/mail/cltbld', 10:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:06 INFO - 'MOZ_NO_REMOTE': '1', 10:19:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:06 INFO - 'NO_EM_RESTART': '1', 10:19:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:06 INFO - 'PWD': '/builds/slave/test', 10:19:06 INFO - 'SHELL': '/bin/bash', 10:19:06 INFO - 'SHLVL': '1', 10:19:06 INFO - 'TERM': 'linux', 10:19:06 INFO - 'TMOUT': '86400', 10:19:06 INFO - 'USER': 'cltbld', 10:19:06 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:06 INFO - '_': '/tools/buildbot/bin/python'} 10:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:06 INFO - Running setup.py (path:/tmp/pip-Psuk0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:06 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:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:06 INFO - Running setup.py (path:/tmp/pip-HcdSIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:06 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:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:06 INFO - Running setup.py (path:/tmp/pip-UuOhT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:06 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:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:06 INFO - Running setup.py (path:/tmp/pip-_FGaHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:06 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:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:06 INFO - Running setup.py (path:/tmp/pip-aqKhlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:06 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:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:06 INFO - Running setup.py (path:/tmp/pip-t3yXh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:06 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:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:06 INFO - Running setup.py (path:/tmp/pip-PBstTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:19:06 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:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:19:06 INFO - Running setup.py (path:/tmp/pip-mEEYwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:19:06 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:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:19:06 INFO - Running setup.py (path:/tmp/pip-YtTFT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:19:07 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:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:19:07 INFO - Running setup.py (path:/tmp/pip-OXDshz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:19:07 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:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:19:07 INFO - Running setup.py (path:/tmp/pip-1YgHF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:19:07 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:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:19:07 INFO - Running setup.py (path:/tmp/pip-rvEKBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:19:07 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:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:19:07 INFO - Running setup.py (path:/tmp/pip-ZsmmBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:19:07 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:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:19:07 INFO - Running setup.py (path:/tmp/pip-pmQZ_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:19:07 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:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:07 INFO - Running setup.py (path:/tmp/pip-hxHI1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:07 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:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:19:07 INFO - Running setup.py (path:/tmp/pip-WavmBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:19:07 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:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:19:07 INFO - Running setup.py (path:/tmp/pip-5A5zrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:19:07 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:19:07 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:19:07 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:19:07 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:19:07 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:19:07 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:19:07 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:19:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:19:07 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:19:07 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:19:07 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:19:07 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:19:08 INFO - Downloading blessings-1.6.tar.gz 10:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:19:08 INFO - Installing collected packages: blessings 10:19:08 INFO - Running setup.py install for blessings 10:19:09 INFO - Successfully installed blessings 10:19:09 INFO - Cleaning up... 10:19:09 INFO - Return code: 0 10:19:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:19:09 INFO - Reading from file tmpfile_stdout 10:19:09 INFO - Current package versions: 10:19:09 INFO - argparse == 1.2.1 10:19:09 INFO - blessings == 1.6 10:19:09 INFO - blobuploader == 1.2.4 10:19:09 INFO - docopt == 0.6.1 10:19:09 INFO - functools32 == 3.2.3-2 10:19:09 INFO - jsonschema == 2.5.1 10:19:09 INFO - manifestparser == 1.1 10:19:09 INFO - mozInstall == 1.13 10:19:09 INFO - mozcrash == 1.0 10:19:09 INFO - mozdebug == 0.1 10:19:09 INFO - mozdevice == 0.51 10:19:09 INFO - mozfile == 1.2 10:19:09 INFO - mozhttpd == 0.7 10:19:09 INFO - mozinfo == 0.10 10:19:09 INFO - mozleak == 0.1 10:19:09 INFO - mozlog == 3.5 10:19:09 INFO - moznetwork == 0.27 10:19:09 INFO - mozprocess == 0.25 10:19:09 INFO - mozprofile == 0.28 10:19:09 INFO - mozrunner == 6.13 10:19:09 INFO - mozscreenshot == 0.1 10:19:09 INFO - mozsystemmonitor == 0.3 10:19:09 INFO - moztest == 0.8 10:19:09 INFO - mozversion == 1.4 10:19:09 INFO - psutil == 3.1.1 10:19:09 INFO - requests == 1.2.3 10:19:09 INFO - wsgiref == 0.1.2 10:19:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19: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:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19: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:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:09 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 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:19:09 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:19:09 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:19:09 INFO - Using env: {'DISPLAY': ':0', 10:19:09 INFO - 'HOME': '/home/cltbld', 10:19:09 INFO - 'LANG': 'en_US.UTF-8', 10:19:09 INFO - 'LANGUAGE': 'en_US:en', 10:19:09 INFO - 'LOGNAME': 'cltbld', 10:19:09 INFO - 'MAIL': '/var/mail/cltbld', 10:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:09 INFO - 'MOZ_NO_REMOTE': '1', 10:19:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:09 INFO - 'NO_EM_RESTART': '1', 10:19:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:09 INFO - 'PWD': '/builds/slave/test', 10:19:09 INFO - 'SHELL': '/bin/bash', 10:19:09 INFO - 'SHLVL': '1', 10:19:09 INFO - 'TERM': 'linux', 10:19:09 INFO - 'TMOUT': '86400', 10:19:09 INFO - 'USER': 'cltbld', 10:19:09 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:09 INFO - '_': '/tools/buildbot/bin/python'} 10:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:09 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:19:09 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:19:09 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:19:09 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:19:09 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:19:09 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:19:09 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:19:09 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:19:09 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:19:09 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:19:09 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:19:09 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:19:09 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:19:09 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:19:10 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:19:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 10:19:10 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:19:10 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:19:10 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:19:10 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:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 10:19:11 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:19:11 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:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:19:11 INFO - Installing collected packages: marionette-driver, simplejson 10:19:11 INFO - Running setup.py install for marionette-driver 10:19:11 INFO - Running setup.py install for simplejson 10:19:11 INFO - building 'simplejson._speedups' extension 10:19:11 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:19:12 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:19:12 INFO - Successfully installed marionette-driver simplejson 10:19:12 INFO - Cleaning up... 10:19:12 INFO - Return code: 0 10:19:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:19: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:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19: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:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:12 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 0x12041f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1575b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, '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': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', '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:19:12 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:19:12 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:19:12 INFO - Using env: {'DISPLAY': ':0', 10:19:12 INFO - 'HOME': '/home/cltbld', 10:19:12 INFO - 'LANG': 'en_US.UTF-8', 10:19:12 INFO - 'LANGUAGE': 'en_US:en', 10:19:12 INFO - 'LOGNAME': 'cltbld', 10:19:12 INFO - 'MAIL': '/var/mail/cltbld', 10:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:12 INFO - 'MOZ_NO_REMOTE': '1', 10:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:12 INFO - 'NO_EM_RESTART': '1', 10:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:12 INFO - 'PWD': '/builds/slave/test', 10:19:12 INFO - 'SHELL': '/bin/bash', 10:19:12 INFO - 'SHLVL': '1', 10:19:12 INFO - 'TERM': 'linux', 10:19:12 INFO - 'TMOUT': '86400', 10:19:12 INFO - 'USER': 'cltbld', 10:19:12 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:12 INFO - '_': '/tools/buildbot/bin/python'} 10:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:19:12 INFO - Cleaning up... 10:19:12 INFO - Return code: 0 10:19:12 INFO - Running post-action listener: _resource_record_post_action 10:19:12 INFO - Running post-action listener: _start_resource_monitoring 10:19:12 INFO - Starting resource monitoring. 10:19:12 INFO - [mozharness: 2017-07-18 17:19:12.567051Z] Finished create-virtualenv step (success) 10:19:12 INFO - [mozharness: 2017-07-18 17:19:12.567639Z] Running install step. 10:19:12 INFO - Running pre-action listener: _resource_record_pre_action 10:19:12 INFO - Running main action method: install 10:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:19:12 INFO - Reading from file tmpfile_stdout 10:19:12 INFO - Detecting whether we're running mozinstall >=1.0... 10:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:19:12 INFO - Reading from file tmpfile_stdout 10:19:12 INFO - Output received: 10:19:12 INFO - Usage: mozinstall [options] installer 10:19:12 INFO - Options: 10:19:12 INFO - -h, --help show this help message and exit 10:19:12 INFO - -d DEST, --destination=DEST 10:19:12 INFO - Directory to install application into. [default: 10:19:12 INFO - "/builds/slave/test"] 10:19:12 INFO - --app=APP Application being installed. [default: firefox] 10:19:12 INFO - mkdir: /builds/slave/test/build/application 10:19:12 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:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:19:30 INFO - Reading from file tmpfile_stdout 10:19:30 INFO - Output received: 10:19:30 INFO - /builds/slave/test/build/application/firefox/firefox 10:19:30 INFO - Running post-action listener: _resource_record_post_action 10:19:30 INFO - [mozharness: 2017-07-18 17:19:30.626541Z] Finished install step (success) 10:19:30 INFO - [mozharness: 2017-07-18 17:19:30.626688Z] Running setup-mitmproxy step. 10:19:30 INFO - Running pre-action listener: _resource_record_pre_action 10:19:30 INFO - Running main action method: setup_mitmproxy 10:19:30 INFO - Skipping: mitmproxy is not required 10:19:30 INFO - Running post-action listener: _resource_record_post_action 10:19:30 INFO - [mozharness: 2017-07-18 17:19:30.627685Z] Finished setup-mitmproxy step (success) 10:19:30 INFO - [mozharness: 2017-07-18 17:19:30.627923Z] Running run-tests step. 10:19:30 INFO - Running pre-action listener: _resource_record_pre_action 10:19:30 INFO - Running pre-action listener: _set_gcov_prefix 10:19:30 INFO - Running main action method: run_tests 10:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:19:30 INFO - Python 2.7.3 10:19:30 INFO - Return code: 0 10:19:30 INFO - grabbing minidump binary from tooltool 10:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/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 0x1572d00>, '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 0x1573c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1574090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:19:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:19:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:19:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:19:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:19:30 INFO - Return code: 0 10:19:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:19:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:19:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:19:30 INFO - ENV: RUST_BACKTRACE is now 1 10:19:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:19:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:19:30 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/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--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:19:30 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/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-076 --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:19:30 INFO - Using env: {'DISPLAY': ':0', 10:19:30 INFO - 'HOME': '/home/cltbld', 10:19:30 INFO - 'LANG': 'en_US.UTF-8', 10:19:30 INFO - 'LANGUAGE': 'en_US:en', 10:19:30 INFO - 'LOGNAME': 'cltbld', 10:19:30 INFO - 'MAIL': '/var/mail/cltbld', 10:19:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:19:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:30 INFO - 'MOZ_NO_REMOTE': '1', 10:19:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:19:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:30 INFO - 'NO_EM_RESTART': '1', 10:19:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:30 INFO - 'PWD': '/builds/slave/test', 10:19:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:19:30 INFO - 'RUST_BACKTRACE': '1', 10:19:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:19:30 INFO - 'SHELL': '/bin/bash', 10:19:30 INFO - 'SHLVL': '1', 10:19:30 INFO - 'TERM': 'linux', 10:19:30 INFO - 'TMOUT': '86400', 10:19:30 INFO - 'USER': 'cltbld', 10:19:30 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110', 10:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:30 INFO - '_': '/tools/buildbot/bin/python'} 10:19:30 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/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--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:19:30 INFO - mozversion application_buildid: 20170718161501 10:19:30 INFO - mozversion application_changeset: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 10:19:30 INFO - mozversion application_display_name: Nightly 10:19:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:19:30 INFO - mozversion application_name: Firefox 10:19:30 INFO - mozversion application_remotingname: firefox 10:19:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:19:30 INFO - mozversion application_vendor: Mozilla 10:19:30 INFO - mozversion application_version: 56.0a1 10:19:30 INFO - mozversion platform_buildid: 20170718161501 10:19:30 INFO - mozversion platform_changeset: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 10:19:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:19:30 INFO - mozversion platform_version: 56.0a1 10:19:30 INFO - using testdate: 1500398370 10:19:30 INFO - actual date: 1500398370 10:19:30 INFO - starting webserver on 'localhost:55635' 10:19:30 INFO - SUITE-START | Running 2 tests 10:19:30 INFO - TEST-START | dromaeo_css 10:19:30 INFO - Initialising browser for dromaeo_css test... 10:19:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55635/getInfo.html -profile /tmp/tmpAnJBfr/profile 10:19:31 INFO - TEST-INFO | started process 8728 (/builds/slave/test/build/application/firefox/firefox http://localhost:55635/getInfo.html) 10:19:37 INFO - TEST-INFO | 8728: exit 0 10:19:37 INFO - Browser initialized. 10:19:37 INFO - Running cycle 1/1 for dromaeo_css test... 10:19:37 INFO - TEST-INFO | started process 8980 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAnJBfr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:48 INFO - PID 8980 | RSS: Main: 166469632 10:19:48 INFO - PID 8980 | 10:22:46 INFO - PID 8980 | Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55635/tests/dromaeo/cssquery-ext.html) 10:22:48 INFO - PID 8980 | RSS: Main: 172384256 10:22:48 INFO - PID 8980 | 10:25:49 INFO - PID 8980 | Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-ext.html (next: http://localhost:55635/tests/dromaeo/cssquery-jquery.html) 10:25:50 INFO - PID 8980 | RSS: Main: 169824256 10:25:50 INFO - PID 8980 | 10:28:51 INFO - PID 8980 | Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55635/tests/dromaeo/cssquery-mootools.html) 10:28:52 INFO - PID 8980 | RSS: Main: 171208704 10:28:52 INFO - PID 8980 | 10:31:53 INFO - PID 8980 | Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55635/tests/dromaeo/cssquery-prototype.html) 10:31:54 INFO - PID 8980 | RSS: Main: 170270720 10:31:54 INFO - PID 8980 | 10:34:57 INFO - PID 8980 | Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55635/tests/dromaeo/cssquery-yui.html) 10:34:57 INFO - PID 8980 | RSS: Main: 171401216 10:34:57 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | Cycle 1(1): loaded http://localhost:55635/tests/dromaeo/cssquery-yui.html (next: http://localhost:55635/tests/dromaeo/cssquery-dojo.html) 10:37:59 INFO - PID 8980 | RSS: Main: 173944832 10:37:59 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | __start_tp_report 10:37:59 INFO - PID 8980 | _x_x_mozilla_page_load 10:37:59 INFO - PID 8980 | _x_x_mozilla_page_load_details 10:37:59 INFO - PID 8980 | |i|pagename|runs| 10:37:59 INFO - PID 8980 | |0;dojo.html;3374.4263475209214;3417.487376893466;3428.982855085724;3449.775764575302;3453.844576994035;6321.6523091229965;6351.428371446569;6370.885095108349;6391.137196478479;6413.518986076039;6180.75276988921;6247.969085100953;6299.622022678643;6305.2433707955;6306.495480361572;5499.560035197174;5527.170924361348;5544.140658197965;5579.330480342344;5580.91628625571;5545.473180047889;5561.5272701820395;5579.888402231953;5607.271054762875;5638.097904335307;5598.888022239553;5601.243832082678;5602.38373778886;5611.102223644218;5641.266635337401;5572.693501857396;5574.470425309589;5575.860603484925;5588.608797384185;5596.356419011805;5516.834494965157;5552.7223638818305;5560.666360018412;5568.6658800472105;5666.631668941505;6225.190725205694;6251.531135164835;6269.59247648906;6280.811575652738;6290;6706.597604143767;6771.72913083476;6804.489663275274;6894.931050689529;6905.965470172617;6844.247132815386;6860.828479288008;6910.343517365842;6913.066735990627;6922.5154239203275;6349.619022858597;6367.299597044322;6371.8407039823915;6450.935490645081;6470.547061705683;7060.858782824417;7085.89371159433;7088.007678924954;7172.031775710317;7189.856202876018;6754.054432379471;6763.72945082201;6826.522143449911;6833.043373927654;6835.965820170867;6773.45812335012;6854.246032936373;6858.005589189639;6859.039734437183;6868;6727.798166055026;6799.1840979081835;6818.624975626289;6829.760958366433;6832.726690932307;6791.456683465311;6793.082933803873;6801.4898882584;6885.965570172117;6893.345132212432;5062.392512898476;5106.336176297058;5122.154844450699;5157.484251574888;5160.535551800356;4489.506154323022;4543.091381723669;4552.089582083597;4567.36056952027;4582.564656357641;6269.404406581368;6295.842603934938;6297.1813664223355;6317.305096439388;6321.1782468278825;6765.898511522331;6829;6845.281245469254;6846.212685541226;6855.4172895304455;6222.12890195373;6276.466500347522;6281.434670879643;6292.182016337847;6298.496120310456;5554.805581804618;5669.40471250521;5673.716314184357;5691.3170419549915;5720.799772007959;7043.295670433018;7057.541259818096;7057.576545407292;7059.647017649097;7094;6785.898211526733;6819.931800682029;6821.147356580328;6872;6925.930740692528;7055.153381594037;7064.646767661699;7140.107486564179;7211.963940180475;7226.313500217681;6595.406413422815;6733.595984240961;6796.558223715443;6830.077939478306;6874.346937040973;6847;6863.039174515671;6880.346367095118;6884.965575172092;6888.862222755617;5696.943030569641;6611.041398997215;6700.966495167493;6702.028205910213;6734.9326506734305;6617.933820661732;6627.271000189879;6665.66671666405;6711.429528490133;6739.764108256188;6565.376289252514;6600.1089852869245;6694.765683201064;6704.363085506869;6717.764878229238;6524.119243902012;6541.542092053701;6585.341465853377;6653.467722582085;6656.933430665825;6379.904301435389;6440.162778838721;6441.935580644134;6452.257990331172;6465.61206327603 10:37:59 INFO - PID 8980 | |1;ext.html;867.6276299962534;920.199426498946;948.924086073114;969.5200875566595;972.1736523954644;42759.35860962088;43264.35103473451;43276.567234327646;43670.12659746803;43696.56303436968;40523.59476405235;40735.59264407355;40842.591574084254;40936.18127637445;41001.58998410015;6790.049393084984;6890.138732658418;6892.379685828299;6892.448604111684;6899.103116594835;6726.697298621574;6733.225679046924;6766.25571187169;6814.080099186596;6827.931720682779;6748.595084294933;6749.865002699943;6772.18733751948;6784.59292442455;6800.591964482147;6723.764668236613;6725.46196304297;6745.359190876883;6782.796516104525;6785.72857085716;6225.128482012522;6290.150829638032;6300;6324.557280990332;6335.176427064452;36174.63825361765;36241.63758362409;36272.63727362746;36359.63640363589;36395;3347.3807345641135;3348.1796959744775;3361.226917808893;3364.34395292918;3494.5107684924133;32646.347073058645;32658.673413265802;32707;32729;32802;5031.622628302855;5051.116054690415;5067.594592432597;5097.617678674077;5168.560672342818;203002;203436;203733;204974.9751251249;205594;47577.76211118922;52549.73725131426;52751;52860.735696322045;53658.19512707312;55688.16467752987;55719;55842.441575583725;56005.43994560084;56019.439805601425;17199.742003869953;17423.564410889576;17426;17430.302787888595;17470.47588572345;14259.714805703828;14285.714285714228;14290.785638215435;14311.57065288043;14319.642008949651;15071.4724984625;15255.694886102216;15306.770398444032;15321.693566128615;15408.614784630474;5902;5926.911096333559;5945.970270148708;6019.939800602026;6065.302490213594;1092.1044743310567;1097.4457898761075;1098.0940723902813;1104.5968221599219;1126.622581435212;24702.629460558106;24926.37684057913;25115.246542603752;25198.748012520005;25251.242462726146;1214.678110300771;1219.3354621731178;1229.96925076872;1231.7721221574038;1235.7528494301175;13278.933605331911;13292.136011159437;13392.531261405804;13444.260565668787;13446;273967;274785;276493;277405;278688;56508.71745641328;56624.150637740466;56821;56981.14528282079;57076.143857842166;1507.4422463688286;1563.5543869996995;1576.4167258114342;1583.4774524407148;1586.4843925724185;24944.781474559328;25051.498970020133;25216.62175067339;25362.365940851625;25397.11110111137;40437.59562404338;40536.59463405328;40541.39187912243;40586.594134059465;40603.1879362417;220286;222276;222718;222745;222760;31674.524882127247;35848.64151358453;36060;36114.27771444504;36305.45541816822;43048;44998.55001449944;45804.541954580025;45877.31184032175;46358.53641463542;32870.506942396365;33000;33524.48566357582;33757.49363759496;33878.66121338755;28923;30515.2371190722;30817.845910770302;31947.562359694333;32220.516692249166;42339.5766042348;43150.568494314655;43436.13127737364;43488.56511434845;43917.341239882066;33543.32913341768;34843;35330.29339413146;35423.11442213965;35831;26878.32804179911;27059;27313;27329.86335068312;27667.72332276732 10:37:59 INFO - PID 8980 | |2;jquery.html;562.151151760841;562.2493970549314;574.531756618356;575.5970820425703;585.2041223935448;22501.774982250194;23360.76639233607;23507.29478115655;23521.176758813403;23559.175428859973;25952.221433356983;27082.187534374;27774.16677499673;28368.290792730142;28418;6218.62688238706;6285.9057114143325;6346.143270658448;6361.141245931786;6364.713587888533;6093.878122437571;6158.353372895848;6158.538109641772;6166.568340216187;6172.290186628527;5870.853228669296;5911.9408805912035;6110.694465276741;6121.510279177655;6145.477634401082;6117.204763380754;6210.751569937174;6211.720472578745;6221.844453888666;6237.376262373772;5798.043322851724;5806.941930580682;5818.621789583706;5827.854303642401;5832.766689332464;28579.857100714573;31631.84184079065;31837.840810796028;31986.520202196985;32090.518642220384;2186.2457452178974;2204.1954686539457;2217.268301460514;2219.755826859044;2223.499712564676;19996.80003199964;20561.177552898014;20563.48591285215;20568.074436650386;20607.5878482431;2404.987975060131;2428.7449817769057;2480.218731099698;2486.751324867517;2490.0288887333772;147481;152378;152706.23646881807;153355;157134.21432892763;15011.24943752808;15463.768043479356;15780.447684330993;16489.6702065958;16499.505014849572;24806;24871;24921.875390622932;24962.126325578516;25042.624360634603;10837.783244335069;10906.890931090746;10958.835617465744;10971.341719496697;11167.553297868157;10228.07947284748;10302.742431439125;10305.690829275134;10324.896751032544;10357.860635330107;9559.753457937379;9686.564104615309;9747.56135973883;9775.755606109904;9951.502424878872;2637.353848307177;2737.986310068437;2741.4928238275984;2747.9450410991667;2812.072016234638;1020.4948550467566;1029.7425643589104;1033.9379637221791;1049.8162821506205;1076.1713480619878;21242.681359779617;21610.56778864414;21727.456813579473;22163.335099947028;22416.55166896653;1081.956721731122;1087.9619213327653;1104.5195340027115;1105.723569107723;1130.4460814201148;11991.580294689644;12197.573084941985;12273.447694853752;12274;12353.752924941451;557682;581353;592181;593547;617180;131221;132222;133677;133996;134201;1247.9064070194954;1269.447790211261;1276.3363051213316;1291.2252648411065;1296.3129541343103;19154.904225478782;19289.8071019288;19289.807101929364;19373.612527749647;19618.313359032367;58627.70686146542;58687;59627;60748;62141.68929155325;73644.63177684078;74017.62991185009;74543;75012.62493687715;76178;14802.185879776285;14944.626384340478;15573.922130389275;15674.98112622676;15782.763258550902;23545;25002.249932502054;25651.486970260863;25658.743412565636;26100.477990440464;20078.49803754918;20355.185792568132;20675.586488269848;20693.689594655792;20855.165793368094;16783.496495105166;16880.493585192464;17087.401940932006;17180.39868604593;17914.5521361967;20948.581028379653;21077.47306317293;21139.15443382309;21155;21163;15283.541493755205;15345.156016418758;15398.846011540189;15432.074075555503;15563.066216027075;19995.50011249672;20199.59600807946;20450.69323960112;20513.58972820565;20563.897180514003 10:37:59 INFO - PID 8980 | |3;mootools.html;1389.735950169468;1450.6808502129534;1451.281615600278;1455.3378212913126;1455.767077267636;14814.333354999024;15437.150956697376;15438.84561154388;15451.381944722225;15452.381904723812;14809.629759256024;15156.469523566677;15685.137317447565;15779.60550986226;15782.84217157828;2616.7775739062204;2644.4975454663554;2658.056389981558;2660.0157941561647;2666.2801043718187;2630.513355029326;2684.2081585932197;2688.301041729145;2714.8371097734102;2717.2935036890453;2642.577187649976;2666.533356662586;2694.986525067372;2702.513547561448;2708.2281549758322;2648.1128821844704;2724.087430710714;2733.384988377619;2734.480448714738;2736.904208352698;2746.7939904507043;2772.861356932145;2773.2928103333725;2773.486904922596;2780.207640822376;15268.770968435483;15424;15445.459408920633;15453.845461545354;15553.22233888301;8292.295154911839;8543.316534677273;8559.614817333235;8576.44253123545;8608.956955215246;5181.248718935771;5383.138697808332;5565.972170139124;5569.610127291091;5652.06740887753;1861.3206179744432;1929.9807001929942;1938.7770406403163;1960.784313725489;1983.0580474274605;26183.607245891326;26411.33971650705;26436.074737384293;26498.205053848415;26551.07071252497;6885.242623311432;6888.758893438806;7030.402415794612;7035.050268213726;7045.154581450259;17738.733918991227;17834.375796847307;17848.55378615519;17908.820911790714;17912.820871791115;8232.217939295757;8486.702965396182;8495.532745699045;8495.787605309917;8503.319734421231;7774.650140743567;7863.252990965849;7899.407544434191;7935.722249721348;7936.246056624612;5357.705326207096;5479.972600137054;5531.778728850801;5593.412691667398;5685.260916080883;1549.3957356630922;1554.875609951201;1558.0807323679085;1565.9138747369004;1566.0681894272889;899.964001439935;904.2585080234277;930.7812664023949;944.419182202954;955.603424578806;7718.421118416141;8695.173958473935;8749.781255468664;8821.779455513535;9062.728118156334;705.8235441139715;741.8850078237882;742.8365759532894;749.4528993834531;773.1649818196335;4885.6335774817035;5507.889842203133;5576.079946808731;5631;5638.633488823213;20121.49696257576;21039.579208415747;21192.682109768364;21319.8934005329;21329;19944.401667949984;20701.171953122008;20777.688334674993;20862.687059694115;20898.895505522374;11925.821112683143;12028.939855300667;12113.576024839265;12117.39413029345;12138.239955206445;8906.910930890608;9159.313051521029;9388;9552.95223523878;9614.18279446227;18174.27302907869;18895.811041889407;18945.526361841064;19093.522661933563;19916.800831991495;19028.619427611648;19776.901115494908;20068.69896951576;20114.798852011878;20195.192192312144;12399.132060756021;12413.689657758629;12561.309127998047;12623.93688031554;12706.428210730724;13952.37214325378;14351.856481435052;14354.354054067384;14363.497277595234;14375.065620734618;11178;11303.208775385645;11548.191626586058;11655.30068195911;11877.22798018126;10594.735131621772;10839.403832789272;10845.295055821347;10991.175661825555;11219;10535.525901334306;10716.571337146426;10726.624568140078;10749.62376316825;10754.623588174376;7330.706771729071;7759.495632784076;7766.844663106819;7989.960050199712;8066.354691624539;8143.59282035884;8305.724936430835;8312.70905518328;8361.456505327134;8383.371247156365 10:37:59 INFO - PID 8980 | |4;prototype.html;494.24133954380034;500.94990500949933;528.9709066001369;529.0582762682427;531.1342511705918;5994.670293133876;6038.728257228424;6141.447269745722;6148.784792532262;6228.501719862412;6037.426444487777;6043.697815109249;6046.425589568984;6060.848478788022;6108.847278818021;4691.765411729417;4693.78877950493;4749.313993532298;4759.23852183649;4777.784999675015;4736.147493451177;4766.642501812361;4776.570108690217;4778.283257511366;4799.7360145192;4628.652851036186;4712;4720.386349774524;4776.617870570346;4792.418501894384;4684.5315468453055;4705.3647757552635;4739.170645137087;4740.857774266778;4756.357891684613;4261.701680882339;4280.844172073523;4293.957060429387;4302.161078589676;4305.138972205571;5929.703514824241;5945.227120474354;5950.613210141327;5962.403759624003;5981.611195272293;5654.578979717253;5676.460736230093;5691.743871525791;5697.800576979786;5701.315842098933;6534.901976470309;6553.901691474632;6598.60408375499;6601.900971485432;6630.635315057718;4859.73271470069;4862.683925544864;4903.166461701484;4913.70517768935;4913.778879950411;6569.638669873155;6802.251752307292;6804.591724496497;6844.828879278008;6877.759278425231;4933.851984440473;4955.72743499111;4958.851234462972;4981.726005069683;5035.471275516018;5039;5050.84847454577;5071.721055341918;5076.289319495274;5086.847394578169;5639.182318563785;5671.120976248606;5690.260266165372;5694.971525142348;5707.686077265707;6389.488840892717;6443.420092191725;6525.2169739631545;6564.310747371553;6575.901361479581;1724.094850203653;1764.1003088424739;1767.098779622402;1768.8142745011846;1769.5841477252836;1189.898858597029;1240.503798480615;1240.528599132324;1242.0746543824907;1252.0359323321175;6080.483158931452;6093.177421048102;6119.632822030692;6123.142760013601;6133.264008318941;6585.703643336062;6667.466602671775;6738.663066846638;6754.966225168941;6823.181218253742;5986.719495560608;6102.877942441126;6125.632462052291;6128.448439640365;6149.200603921551;3578.570571531433;3585.4263317869536;3602.9819850900576;3607.5670919489303;3615.295017471568;6965.407940325129;6993.405560527311;7019.403350715144;7054.823629409306;7063.5761854287875;6902.654867256617;6908.378245957788;6917.654117294115;6934.410575101072;6950.930490695129;6934.895976560356;6961.37347638715;6983.406410454965;6991;7036.296370363025;6247.812565623039;6550.443212327005;6642.675063727656;6664.7000884959325;6676.465882729467;6554.180727409074;6603.438707709898;6605.47156227491;6606.471482281502;6624.648249871227;6791.675623253418;6792.76225332111;6893.310668933066;6893.689783959834;6900.930990690029;4948.455669876239;5028.371453568304;5036.546710795899;5061.9493805061475;5071.416787069535;6410.8076757697345;6661.533692641468;6690;6711.161104861892;6815.727370905307;6338.809835704937;6434.356564343529;6435.717783930323;6458.515611329075;6566.80299591013;6415.679216039086;6442.387973142544;6478.70845811949;6480.837979050561;6497.18785151856;6366.936330636634;6404.598275646633;6413.775517856852;6436.558774445618;6531.869362612626;6365.204349456318;6475.5716606272645;6476.352364763675;6491.837704057248;6551.868962620816;6273.717682704198;6346.936530634634;6355.428011478989;6365.140706004815;6387.137736405712 10:37:59 INFO - PID 8980 | |5;yui.html;931.5807886451099;932.8927173375064;936.8969413364531;951.8714973478578;960.7934294126761;18968;19343.322983695554;19557.511062223453;19693.704594431096;19809.405717828453;18524.536886577844;18621.627567448642;18665;18833.81166188341;22367.55264894705;1060.862087928572;1077.272840832439;1080.6001779341655;1081.4592703648175;1082.631905152248;1100.5652767156969;1104.34843442369;1104.5802595013884;1105.8949399807007;1107.2360071550604;1156.8900954409319;1157.3056166300194;1160.9477573509212;1163.9418029098554;1165.4930105404135;1030.3611760708334;1098.9450527473675;1102.0522350778324;1142.206166714134;1151.4645689754263;806.6329819931917;812.2486699802658;813.3208770676492;814.7474282972238;816.8815521749373;19156.32952846644;19537.218511259533;19802.10890509916;19841.30555430553;19886.502837429034;21298.5740285195;21694.783052169434;21748.565028699497;21866.562668746537;21937;16021.91989040059;16185.676286474205;16463.506094817174;16501.91749041259;16514.58713532159;3648.3068217038676;3648.416253399443;3676.7977761223124;3682.558093028827;3702.500162478058;131204.3439782795;134430;142074;143872.63179017286;144054.2797286007;16631.501054968372;16866.987980721195;17062.658746825244;17281.13594320279;17719.911400442914;32040.83979580087;32209.677903221138;32530;32688.346233075205;32873.50689739656;8824.117588241124;9023.909760902437;9050.592723327467;9137.771555711028;9175.495347755937;4361.193179261847;4380.759058251763;4393.384926110347;4412.161689279027;4435.844745433894;5357.8392648220615;5410.83767486976;5522.5581953443625;5534.1145416733125;5537.1140617501815;1153.8499995000764;1157.4444266752007;1159.1943599198644;1168.2289688805347;1173.8943495085311;708.0830324729487;714.921358650548;716.8387112899555;729.6461216310086;749.0187853911393;11775.411229438494;12292.201006934522;13115.606531804062;13150.473981040843;13214.801777973338;713.9357457828717;721.0626185958338;731.0751898847961;737.1986451484338;740.8703476891523;5330.92003619946;5593.608447408642;5627.588960423184;5629.746661400247;5685.289338832646;122269.38865305617;123457;127525;127590.36204818917;129120.35439822929;101818;105995;106164.46917765362;106696;109113.45443272733;94375.52812236032;95444.52277738567;98273.01726982639;98444.99999999857;99038.50480747694;12776.488940442277;13467.777816660533;13605.251711155981;13663.795043074164;13773.173609583457;44750.10499790051;44812.32781508215;44938;45512.772436138934;46939.295910562054;56863.431365685814;58085.70957145356;75041;80793;82915.17084829074;18086.276548937894;18592.907035464737;18645.90677046606;18718.157682904573;18944;18229.54426139357;18334.81665183331;18646.81353186505;18984.620307594047;21443.785562144178;17246.65506689834;17902.55243618868;18306.26774928988;18516;18559.721604175676;19751.111199995747;19770;19941;20205.09077091505;20279.188832446536;16653.916730416757;16730.163491825602;16747.246373913447;16748.665026699153;16877.156142193086;12016.279023258283;12075.75848483043;12628.810567841307;12819.294938778457;12913.741725165632;13726;14487.710245795235;14520.782188266972;14521.346539405964;14577.708445831237 10:37:59 INFO - PID 8980 | __end_tp_report 10:37:59 INFO - PID 8980 | __start_cc_report 10:37:59 INFO - PID 8980 | _x_x_mozilla_cycle_collect,343 10:37:59 INFO - PID 8980 | __end_cc_report 10:37:59 INFO - PID 8980 | __startTimestamp1500399479519__endTimestamp 10:37:59 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | ------- Summary: start ------- 10:37:59 INFO - PID 8980 | Number of tests: 6 10:37:59 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | [#0] dojo.html Cycles:165 Average:6272.67 Median:6541.54 stddev:802.60 (12.3%) stddev-sans-first:772.19 10:37:59 INFO - PID 8980 | Values: 3374.4 3417.5 3429.0 3449.8 3453.8 6321.7 6351.4 6370.9 6391.1 6413.5 6180.8 6248.0 6299.6 6305.2 6306.5 5499.6 5527.2 5544.1 5579.3 5580.9 5545.5 5561.5 5579.9 5607.3 5638.1 5598.9 5601.2 5602.4 5611.1 5641.3 5572.7 5574.5 5575.9 5588.6 5596.4 5516.8 5552.7 5560.7 5568.7 5666.6 6225.2 6251.5 6269.6 6280.8 6290.0 6706.6 6771.7 6804.5 6894.9 6906.0 6844.2 6860.8 6910.3 6913.1 6922.5 6349.6 6367.3 6371.8 6450.9 6470.5 7060.9 7085.9 7088.0 7172.0 7189.9 6754.1 6763.7 6826.5 6833.0 6836.0 6773.5 6854.2 6858.0 6859.0 6868.0 6727.8 6799.2 6818.6 6829.8 6832.7 6791.5 6793.1 6801.5 6886.0 6893.3 5062.4 5106.3 5122.2 5157.5 5160.5 4489.5 4543.1 4552.1 4567.4 4582.6 6269.4 6295.8 6297.2 6317.3 6321.2 6765.9 6829.0 6845.3 6846.2 6855.4 6222.1 6276.5 6281.4 6292.2 6298.5 5554.8 5669.4 5673.7 5691.3 5720.8 7043.3 7057.5 7057.6 7059.6 7094.0 6785.9 6819.9 6821.1 6872.0 6925.9 7055.2 7064.6 7140.1 7212.0 7226.3 6595.4 6733.6 6796.6 6830.1 6874.3 6847.0 6863.0 6880.3 6885.0 6888.9 5696.9 6611.0 6701.0 6702.0 6734.9 6617.9 6627.3 6665.7 6711.4 6739.8 6565.4 6600.1 6694.8 6704.4 6717.8 6524.1 6541.5 6585.3 6653.5 6656.9 6379.9 6440.2 6441.9 6452.3 6465.6 10:37:59 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | [#1] ext.html Cycles:180 Average:40952.82 Median:35839.82 stddev:61472.04 (171.5%) stddev-sans-first:61570.80 10:37:59 INFO - PID 8980 | Values: 867.6 920.2 948.9 969.5 972.2 42759.4 43264.4 43276.6 43670.1 43696.6 40523.6 40735.6 40842.6 40936.2 41001.6 6790.0 6890.1 6892.4 6892.4 6899.1 6726.7 6733.2 6766.3 6814.1 6827.9 6748.6 6749.9 6772.2 6784.6 6800.6 6723.8 6725.5 6745.4 6782.8 6785.7 6225.1 6290.2 6300.0 6324.6 6335.2 36174.6 36241.6 36272.6 36359.6 36395.0 3347.4 3348.2 3361.2 3364.3 3494.5 32646.3 32658.7 32707.0 32729.0 32802.0 5031.6 5051.1 5067.6 5097.6 5168.6 203002.0 203436.0 203733.0 204975.0 205594.0 47577.8 52549.7 52751.0 52860.7 53658.2 55688.2 55719.0 55842.4 56005.4 56019.4 17199.7 17423.6 17426.0 17430.3 17470.5 14259.7 14285.7 14290.8 14311.6 14319.6 15071.5 15255.7 15306.8 15321.7 15408.6 5902.0 5926.9 5946.0 6019.9 6065.3 1092.1 1097.4 1098.1 1104.6 1126.6 24702.6 24926.4 25115.2 25198.7 25251.2 1214.7 1219.3 1230.0 1231.8 1235.8 13278.9 13292.1 13392.5 13444.3 13446.0 273967.0 274785.0 276493.0 277405.0 278688.0 56508.7 56624.2 56821.0 56981.1 57076.1 1507.4 1563.6 1576.4 1583.5 1586.5 24944.8 25051.5 25216.6 25362.4 25397.1 40437.6 40536.6 40541.4 40586.6 40603.2 220286.0 222276.0 222718.0 222745.0 222760.0 31674.5 35848.6 36060.0 36114.3 36305.5 43048.0 44998.6 45804.5 45877.3 46358.5 32870.5 33000.0 33524.5 33757.5 33878.7 28923.0 30515.2 30817.8 31947.6 32220.5 42339.6 43150.6 43436.1 43488.6 43917.3 33543.3 34843.0 35330.3 35423.1 35831.0 26878.3 27059.0 27313.0 27329.9 27667.7 10:37:59 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | [#2] jquery.html Cycles:180 Average:39260.77 Median:12190.30 stddev:98741.96 (810.0%) stddev-sans-first:98976.20 10:37:59 INFO - PID 8980 | Values: 562.2 562.2 574.5 575.6 585.2 22501.8 23360.8 23507.3 23521.2 23559.2 25952.2 27082.2 27774.2 28368.3 28418.0 6218.6 6285.9 6346.1 6361.1 6364.7 6093.9 6158.4 6158.5 6166.6 6172.3 5870.9 5911.9 6110.7 6121.5 6145.5 6117.2 6210.8 6211.7 6221.8 6237.4 5798.0 5806.9 5818.6 5827.9 5832.8 28579.9 31631.8 31837.8 31986.5 32090.5 2186.2 2204.2 2217.3 2219.8 2223.5 19996.8 20561.2 20563.5 20568.1 20607.6 2405.0 2428.7 2480.2 2486.8 2490.0 147481.0 152378.0 152706.2 153355.0 157134.2 15011.2 15463.8 15780.4 16489.7 16499.5 24806.0 24871.0 24921.9 24962.1 25042.6 10837.8 10906.9 10958.8 10971.3 11167.6 10228.1 10302.7 10305.7 10324.9 10357.9 9559.8 9686.6 9747.6 9775.8 9951.5 2637.4 2738.0 2741.5 2747.9 2812.1 1020.5 1029.7 1033.9 1049.8 1076.2 21242.7 21610.6 21727.5 22163.3 22416.6 1082.0 1088.0 1104.5 1105.7 1130.4 11991.6 12197.6 12273.4 12274.0 12353.8 557682.0 581353.0 592181.0 593547.0 617180.0 131221.0 132222.0 133677.0 133996.0 134201.0 1247.9 1269.4 1276.3 1291.2 1296.3 19154.9 19289.8 19289.8 19373.6 19618.3 58627.7 58687.0 59627.0 60748.0 62141.7 73644.6 74017.6 74543.0 75012.6 76178.0 14802.2 14944.6 15573.9 15675.0 15782.8 23545.0 25002.2 25651.5 25658.7 26100.5 20078.5 20355.2 20675.6 20693.7 20855.2 16783.5 16880.5 17087.4 17180.4 17914.6 20948.6 21077.5 21139.2 21155.0 21163.0 15283.5 15345.2 15398.8 15432.1 15563.1 19995.5 20199.6 20450.7 20513.6 20563.9 10:37:59 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | [#3] mootools.html Cycles:180 Average:9592.17 Median:14540.29 stddev:6669.59 (45.9%) stddev-sans-first:6659.83 10:37:59 INFO - PID 8980 | Values: 1389.7 1450.7 1451.3 1455.3 1455.8 14814.3 15437.2 15438.8 15451.4 15452.4 14809.6 15156.5 15685.1 15779.6 15782.8 2616.8 2644.5 2658.1 2660.0 2666.3 2630.5 2684.2 2688.3 2714.8 2717.3 2642.6 2666.5 2695.0 2702.5 2708.2 2648.1 2724.1 2733.4 2734.5 2736.9 2746.8 2772.9 2773.3 2773.5 2780.2 15268.8 15424.0 15445.5 15453.8 15553.2 8292.3 8543.3 8559.6 8576.4 8609.0 5181.2 5383.1 5566.0 5569.6 5652.1 1861.3 1930.0 1938.8 1960.8 1983.1 26183.6 26411.3 26436.1 26498.2 26551.1 6885.2 6888.8 7030.4 7035.1 7045.2 17738.7 17834.4 17848.6 17908.8 17912.8 8232.2 8486.7 8495.5 8495.8 8503.3 7774.7 7863.3 7899.4 7935.7 7936.2 5357.7 5480.0 5531.8 5593.4 5685.3 1549.4 1554.9 1558.1 1565.9 1566.1 900.0 904.3 930.8 944.4 955.6 7718.4 8695.2 8749.8 8821.8 9062.7 705.8 741.9 742.8 749.5 773.2 4885.6 5507.9 5576.1 5631.0 5638.6 20121.5 21039.6 21192.7 21319.9 21329.0 19944.4 20701.2 20777.7 20862.7 20898.9 11925.8 12028.9 12113.6 12117.4 12138.2 8906.9 9159.3 9388.0 9553.0 9614.2 18174.3 18895.8 18945.5 19093.5 19916.8 19028.6 19776.9 20068.7 20114.8 20195.2 12399.1 12413.7 12561.3 12623.9 12706.4 13952.4 14351.9 14354.4 14363.5 14375.1 11178.0 11303.2 11548.2 11655.3 11877.2 10594.7 10839.4 10845.3 10991.2 11219.0 10535.5 10716.6 10726.6 10749.6 10754.6 7330.7 7759.5 7766.8 7990.0 8066.4 8143.6 8305.7 8312.7 8361.5 8383.4 10:37:59 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | [#4] prototype.html Cycles:180 Average:5509.30 Median:6114.24 stddev:1576.34 (25.8%) stddev-sans-first:1535.16 10:37:59 INFO - PID 8980 | Values: 494.2 500.9 529.0 529.1 531.1 5994.7 6038.7 6141.4 6148.8 6228.5 6037.4 6043.7 6046.4 6060.8 6108.8 4691.8 4693.8 4749.3 4759.2 4777.8 4736.1 4766.6 4776.6 4778.3 4799.7 4628.7 4712.0 4720.4 4776.6 4792.4 4684.5 4705.4 4739.2 4740.9 4756.4 4261.7 4280.8 4294.0 4302.2 4305.1 5929.7 5945.2 5950.6 5962.4 5981.6 5654.6 5676.5 5691.7 5697.8 5701.3 6534.9 6553.9 6598.6 6601.9 6630.6 4859.7 4862.7 4903.2 4913.7 4913.8 6569.6 6802.3 6804.6 6844.8 6877.8 4933.9 4955.7 4958.9 4981.7 5035.5 5039.0 5050.8 5071.7 5076.3 5086.8 5639.2 5671.1 5690.3 5695.0 5707.7 6389.5 6443.4 6525.2 6564.3 6575.9 1724.1 1764.1 1767.1 1768.8 1769.6 1189.9 1240.5 1240.5 1242.1 1252.0 6080.5 6093.2 6119.6 6123.1 6133.3 6585.7 6667.5 6738.7 6755.0 6823.2 5986.7 6102.9 6125.6 6128.4 6149.2 3578.6 3585.4 3603.0 3607.6 3615.3 6965.4 6993.4 7019.4 7054.8 7063.6 6902.7 6908.4 6917.7 6934.4 6950.9 6934.9 6961.4 6983.4 6991.0 7036.3 6247.8 6550.4 6642.7 6664.7 6676.5 6554.2 6603.4 6605.5 6606.5 6624.6 6791.7 6792.8 6893.3 6893.7 6900.9 4948.5 5028.4 5036.5 5061.9 5071.4 6410.8 6661.5 6690.0 6711.2 6815.7 6338.8 6434.4 6435.7 6458.5 6566.8 6415.7 6442.4 6478.7 6480.8 6497.2 6366.9 6404.6 6413.8 6436.6 6531.9 6365.2 6475.6 6476.4 6491.8 6551.9 6273.7 6346.9 6355.4 6365.1 6387.1 10:37:59 INFO - PID 8980 | 10:37:59 INFO - PID 8980 | [#5] yui.html Cycles:180 Average:25362.29 Median:18888.91 stddev:35770.25 (189.4%) stddev-sans-first:35823.56 10:37:59 INFO - PID 8980 | Values: 931.6 932.9 936.9 951.9 960.8 18968.0 19343.3 19557.5 19693.7 19809.4 18524.5 18621.6 18665.0 18833.8 22367.6 1060.9 1077.3 1080.6 1081.5 1082.6 1100.6 1104.3 1104.6 1105.9 1107.2 1156.9 1157.3 1160.9 1163.9 1165.5 1030.4 1098.9 1102.1 1142.2 1151.5 806.6 812.2 813.3 814.7 816.9 19156.3 19537.2 19802.1 19841.3 19886.5 21298.6 21694.8 21748.6 21866.6 21937.0 16021.9 16185.7 16463.5 16501.9 16514.6 3648.3 3648.4 3676.8 3682.6 3702.5 131204.3 134430.0 142074.0 143872.6 144054.3 16631.5 16867.0 17062.7 17281.1 17719.9 32040.8 32209.7 32530.0 32688.3 32873.5 8824.1 9023.9 9050.6 9137.8 9175.5 4361.2 4380.8 4393.4 4412.2 4435.8 5357.8 5410.8 5522.6 5534.1 5537.1 1153.8 1157.4 1159.2 1168.2 1173.9 708.1 714.9 716.8 729.6 749.0 11775.4 12292.2 13115.6 13150.5 13214.8 713.9 721.1 731.1 737.2 740.9 5330.9 5593.6 5627.6 5629.7 5685.3 122269.4 123457.0 127525.0 127590.4 129120.4 101818.0 105995.0 106164.5 106696.0 109113.5 94375.5 95444.5 98273.0 98445.0 99038.5 12776.5 13467.8 13605.3 13663.8 13773.2 44750.1 44812.3 44938.0 45512.8 46939.3 56863.4 58085.7 75041.0 80793.0 82915.2 18086.3 18592.9 18645.9 18718.2 18944.0 18229.5 18334.8 18646.8 18984.6 21443.8 17246.7 17902.6 18306.3 18516.0 18559.7 19751.1 19770.0 19941.0 20205.1 20279.2 16653.9 16730.2 16747.2 16748.7 16877.2 12016.3 12075.8 12628.8 12819.3 12913.7 13726.0 14487.7 14520.8 14521.3 14577.7 10:37:59 INFO - PID 8980 | -------- Summary: end -------- 10:37:59 INFO - PID 8980 | 10:38:00 INFO - TEST-INFO | 8980: exit 0 10:38:00 INFO - TEST-OK | dromaeo_css | took 1109226ms 10:38:00 INFO - TEST-START | kraken 10:38:00 INFO - Initialising browser for kraken test... 10:38:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55635/getInfo.html -profile /tmp/tmpx51Qaz/profile 10:38:00 INFO - TEST-INFO | started process 9383 (/builds/slave/test/build/application/firefox/firefox http://localhost:55635/getInfo.html) 10:38:06 INFO - TEST-INFO | 9383: exit 0 10:38:06 INFO - Browser initialized. 10:38:06 INFO - Running cycle 1/1 for kraken test... 10:38:06 INFO - TEST-INFO | started process 9636 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx51Qaz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:38:17 INFO - PID 9636 | RSS: Main: 165777408 10:38:17 INFO - PID 9636 | 10:38:19 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55635/tests/kraken/driver.html?testName=audio-beat-detection) 10:38:20 INFO - PID 9636 | RSS: Main: 170893312 10:38:20 INFO - PID 9636 | 10:38:23 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55635/tests/kraken/driver.html?testName=audio-dft) 10:38:23 INFO - PID 9636 | RSS: Main: 171184128 10:38:23 INFO - PID 9636 | 10:38:26 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55635/tests/kraken/driver.html?testName=audio-fft) 10:38:27 INFO - PID 9636 | RSS: Main: 164163584 10:38:27 INFO - PID 9636 | 10:38:29 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55635/tests/kraken/driver.html?testName=audio-oscillator) 10:38:30 INFO - PID 9636 | RSS: Main: 165289984 10:38:30 INFO - PID 9636 | 10:38:32 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55635/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:38:32 INFO - PID 9636 | RSS: Main: 165994496 10:38:32 INFO - PID 9636 | 10:38:35 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55635/tests/kraken/driver.html?testName=imaging-darkroom) 10:38:36 INFO - PID 9636 | RSS: Main: 165683200 10:38:36 INFO - PID 9636 | 10:38:39 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55635/tests/kraken/driver.html?testName=imaging-desaturate) 10:38:40 INFO - PID 9636 | RSS: Main: 168308736 10:38:40 INFO - PID 9636 | 10:38:42 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55635/tests/kraken/driver.html?testName=json-parse-financial) 10:38:43 INFO - PID 9636 | RSS: Main: 169201664 10:38:43 INFO - PID 9636 | 10:38:44 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55635/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:38:45 INFO - PID 9636 | RSS: Main: 168927232 10:38:45 INFO - PID 9636 | 10:38:47 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:38:47 INFO - PID 9636 | RSS: Main: 168927232 10:38:47 INFO - PID 9636 | 10:38:49 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:38:50 INFO - PID 9636 | RSS: Main: 168886272 10:38:50 INFO - PID 9636 | 10:38:52 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:38:53 INFO - PID 9636 | RSS: Main: 169201664 10:38:53 INFO - PID 9636 | 10:38:56 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:38:57 INFO - PID 9636 | RSS: Main: 169537536 10:38:57 INFO - PID 9636 | 10:38:58 INFO - PID 9636 | Cycle 1(1): loaded http://localhost:55635/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55635/tests/kraken/driver.html?testName=ai-astar) 10:38:59 INFO - PID 9636 | RSS: Main: 170901504 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | __start_tp_report 10:38:59 INFO - PID 9636 | _x_x_mozilla_page_load 10:38:59 INFO - PID 9636 | _x_x_mozilla_page_load_details 10:38:59 INFO - PID 9636 | |i|pagename|runs| 10:38:59 INFO - PID 9636 | |0;ai-astar;102;108;95;106;107;118;109;106;106;106 10:38:59 INFO - PID 9636 | |1;audio-beat-detection;151;112;145;169;114;137;140;112;162;113 10:38:59 INFO - PID 9636 | |2;audio-dft;162;164;163;162;162;168;162;163;161;186 10:38:59 INFO - PID 9636 | |3;audio-fft;74;75;75;171;75;76;74;74;98;76 10:38:59 INFO - PID 9636 | |4;audio-oscillator;80;79;126;79;79;79;123;80;80;129 10:38:59 INFO - PID 9636 | |5;imaging-gaussian-blur;113;113;114;114;115;113;113;113;114;113 10:38:59 INFO - PID 9636 | |6;imaging-darkroom;148;147;148;148;149;148;148;150;147;147 10:38:59 INFO - PID 9636 | |7;imaging-desaturate;94;93;93;94;95;94;93;94;94;93 10:38:59 INFO - PID 9636 | |8;json-parse-financial;47;47;47;51;47;48;49;48;47;50 10:38:59 INFO - PID 9636 | |9;json-stringify-tinderbox;53;54;54;54;54;53;54;54;54;53 10:38:59 INFO - PID 9636 | |10;stanford-crypto-aes;75;75;75;75;85;76;74;73;75;76 10:38:59 INFO - PID 9636 | |11;stanford-crypto-ccm;125;122;133;119;118;120;119;121;123;146 10:38:59 INFO - PID 9636 | |12;stanford-crypto-pbkdf2;171;192;176;177;177;173;181;181;173;168 10:38:59 INFO - PID 9636 | |13;stanford-crypto-sha256-iterative;65;63;62;67;61;63;63;63;63;62 10:38:59 INFO - PID 9636 | __end_tp_report 10:38:59 INFO - PID 9636 | __start_cc_report 10:38:59 INFO - PID 9636 | _x_x_mozilla_cycle_collect,842 10:38:59 INFO - PID 9636 | __end_cc_report 10:38:59 INFO - PID 9636 | __startTimestamp1500399539116__endTimestamp 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | ------- Summary: start ------- 10:38:59 INFO - PID 9636 | Number of tests: 14 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#0] ai-astar Cycles:10 Average:106.30 Median:107.50 stddev:5.72 (5.3%) stddev-sans-first:5.85 10:38:59 INFO - PID 9636 | Values: 102.0 108.0 95.0 106.0 107.0 118.0 109.0 106.0 106.0 106.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:142.50 stddev:21.73 (15.3%) stddev-sans-first:22.31 10:38:59 INFO - PID 9636 | Values: 151.0 112.0 145.0 169.0 114.0 137.0 140.0 112.0 162.0 113.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#2] audio-dft Cycles:10 Average:165.30 Median:163.00 stddev:7.53 (4.6%) stddev-sans-first:7.89 10:38:59 INFO - PID 9636 | Values: 162.0 164.0 163.0 162.0 162.0 168.0 162.0 163.0 161.0 186.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#3] audio-fft Cycles:10 Average:86.80 Median:75.00 stddev:30.47 (40.6%) stddev-sans-first:31.97 10:38:59 INFO - PID 9636 | Values: 74.0 75.0 75.0 171.0 75.0 76.0 74.0 74.0 98.0 76.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:79.00 stddev:22.54 (28.5%) stddev-sans-first:23.39 10:38:59 INFO - PID 9636 | Values: 80.0 79.0 126.0 79.0 79.0 79.0 123.0 80.0 80.0 129.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:113.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 10:38:59 INFO - PID 9636 | Values: 113.0 113.0 114.0 114.0 115.0 113.0 113.0 113.0 114.0 113.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 10:38:59 INFO - PID 9636 | Values: 148.0 147.0 148.0 148.0 149.0 148.0 148.0 150.0 147.0 147.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 10:38:59 INFO - PID 9636 | Values: 94.0 93.0 93.0 94.0 95.0 94.0 93.0 94.0 94.0 93.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#8] json-parse-financial Cycles:10 Average:48.10 Median:48.00 stddev:1.45 (3.0%) stddev-sans-first:1.48 10:38:59 INFO - PID 9636 | Values: 47.0 47.0 47.0 51.0 47.0 48.0 49.0 48.0 47.0 50.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#9] json-stringify-tinderbox Cycles:10 Average:53.70 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.44 10:38:59 INFO - PID 9636 | Values: 53.0 54.0 54.0 54.0 54.0 53.0 54.0 54.0 54.0 53.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:75.00 stddev:3.31 (4.4%) stddev-sans-first:3.50 10:38:59 INFO - PID 9636 | Values: 75.0 75.0 75.0 75.0 85.0 76.0 74.0 73.0 75.0 76.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#11] stanford-crypto-ccm Cycles:10 Average:124.60 Median:122.50 stddev:8.68 (7.1%) stddev-sans-first:9.21 10:38:59 INFO - PID 9636 | Values: 125.0 122.0 133.0 119.0 118.0 120.0 119.0 121.0 123.0 146.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:177.00 stddev:6.72 (3.8%) stddev-sans-first:6.78 10:38:59 INFO - PID 9636 | Values: 171.0 192.0 176.0 177.0 177.0 173.0 181.0 181.0 173.0 168.0 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.69 (2.7%) stddev-sans-first:1.66 10:38:59 INFO - PID 9636 | Values: 65.0 63.0 62.0 67.0 61.0 63.0 63.0 63.0 63.0 62.0 10:38:59 INFO - PID 9636 | -------- Summary: end -------- 10:38:59 INFO - PID 9636 | 10:38:59 INFO - PID 9636 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:38:59 INFO - TEST-INFO | 9636: exit 0 10:39:00 INFO - TEST-OK | kraken | took 59828ms 10:39:00 INFO - SUITE-END | took 1169s 10:39:00 INFO - Completed test suite (00:19:29) 10:39:00 INFO - javascript benchmark 10:39:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8788.912551778234, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3374.4263475209214, 3417.487376893466, 3428.982855085724, 3449.775764575302, 3453.844576994035, 6321.6523091229965, 6351.428371446569, 6370.885095108349, 6391.137196478479, 6413.518986076039, 6180.75276988921, 6247.969085100953, 6299.622022678643, 6305.2433707955, 6306.495480361572, 5499.560035197174, 5527.170924361348, 5544.140658197965, 5579.330480342344, 5580.91628625571, 5545.473180047889, 5561.5272701820395, 5579.888402231953, 5607.271054762875, 5638.097904335307, 5598.888022239553, 5601.243832082678, 5602.38373778886, 5611.102223644218, 5641.266635337401, 5572.693501857396, 5574.470425309589, 5575.860603484925, 5588.608797384185, 5596.356419011805, 5516.834494965157, 5552.7223638818305, 5560.666360018412, 5568.6658800472105, 5666.631668941505, 6225.190725205694, 6251.531135164835, 6269.59247648906, 6280.811575652738, 6290.0, 6706.597604143767, 6771.72913083476, 6804.489663275274, 6894.931050689529, 6905.965470172617, 6844.247132815386, 6860.828479288008, 6910.343517365842, 6913.066735990627, 6922.5154239203275, 6349.619022858597, 6367.299597044322, 6371.8407039823915, 6450.935490645081, 6470.547061705683, 7060.858782824417, 7085.89371159433, 7088.007678924954, 7172.031775710317, 7189.856202876018, 6754.054432379471, 6763.72945082201, 6826.522143449911, 6833.043373927654, 6835.965820170867, 6773.45812335012, 6854.246032936373, 6858.005589189639, 6859.039734437183, 6868.0, 6727.798166055026, 6799.1840979081835, 6818.624975626289, 6829.760958366433, 6832.726690932307, 6791.456683465311, 6793.082933803873, 6801.4898882584, 6885.965570172117, 6893.345132212432, 5062.392512898476, 5106.336176297058, 5122.154844450699, 5157.484251574888, 5160.535551800356, 4489.506154323022, 4543.091381723669, 4552.089582083597, 4567.36056952027, 4582.564656357641, 6269.404406581368, 6295.842603934938, 6297.1813664223355, 6317.305096439388, 6321.1782468278825, 6765.898511522331, 6829.0, 6845.281245469254, 6846.212685541226, 6855.4172895304455, 6222.12890195373, 6276.466500347522, 6281.434670879643, 6292.182016337847, 6298.496120310456, 5554.805581804618, 5669.40471250521, 5673.716314184357, 5691.3170419549915, 5720.799772007959, 7043.295670433018, 7057.541259818096, 7057.576545407292, 7059.647017649097, 7094.0, 6785.898211526733, 6819.931800682029, 6821.147356580328, 6872.0, 6925.930740692528, 7055.153381594037, 7064.646767661699, 7140.107486564179, 7211.963940180475, 7226.313500217681, 6595.406413422815, 6733.595984240961, 6796.558223715443, 6830.077939478306, 6874.346937040973, 6847.0, 6863.039174515671, 6880.346367095118, 6884.965575172092, 6888.862222755617, 5696.943030569641, 6611.041398997215, 6700.966495167493, 6702.028205910213, 6734.9326506734305, 6617.933820661732, 6627.271000189879, 6665.66671666405, 6711.429528490133, 6739.764108256188, 6565.376289252514, 6600.1089852869245, 6694.765683201064, 6704.363085506869, 6717.764878229238, 6524.119243902012, 6541.542092053701, 6585.341465853377, 6653.467722582085, 6656.933430665825, 6379.904301435389, 6440.162778838721, 6441.935580644134, 6452.257990331172, 6465.61206327603], "value": 6211.562648794678, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [867.6276299962534, 920.199426498946, 948.924086073114, 969.5200875566595, 972.1736523954644, 42759.35860962088, 43264.35103473451, 43276.567234327646, 43670.12659746803, 43696.56303436968, 40523.59476405235, 40735.59264407355, 40842.591574084254, 40936.18127637445, 41001.58998410015, 6790.049393084984, 6890.138732658418, 6892.379685828299, 6892.448604111684, 6899.103116594835, 6726.697298621574, 6733.225679046924, 6766.25571187169, 6814.080099186596, 6827.931720682779, 6748.595084294933, 6749.865002699943, 6772.18733751948, 6784.59292442455, 6800.591964482147, 6723.764668236613, 6725.46196304297, 6745.359190876883, 6782.796516104525, 6785.72857085716, 6225.128482012522, 6290.150829638032, 6300.0, 6324.557280990332, 6335.176427064452, 36174.63825361765, 36241.63758362409, 36272.63727362746, 36359.63640363589, 36395.0, 3347.3807345641135, 3348.1796959744775, 3361.226917808893, 3364.34395292918, 3494.5107684924133, 32646.347073058645, 32658.673413265802, 32707.0, 32729.0, 32802.0, 5031.622628302855, 5051.116054690415, 5067.594592432597, 5097.617678674077, 5168.560672342818, 203002.0, 203436.0, 203733.0, 204974.9751251249, 205594.0, 47577.76211118922, 52549.73725131426, 52751.0, 52860.735696322045, 53658.19512707312, 55688.16467752987, 55719.0, 55842.441575583725, 56005.43994560084, 56019.439805601425, 17199.742003869953, 17423.564410889576, 17426.0, 17430.302787888595, 17470.47588572345, 14259.714805703828, 14285.714285714228, 14290.785638215435, 14311.57065288043, 14319.642008949651, 15071.4724984625, 15255.694886102216, 15306.770398444032, 15321.693566128615, 15408.614784630474, 5902.0, 5926.911096333559, 5945.970270148708, 6019.939800602026, 6065.302490213594, 1092.1044743310567, 1097.4457898761075, 1098.0940723902813, 1104.5968221599219, 1126.622581435212, 24702.629460558106, 24926.37684057913, 25115.246542603752, 25198.748012520005, 25251.242462726146, 1214.678110300771, 1219.3354621731178, 1229.96925076872, 1231.7721221574038, 1235.7528494301175, 13278.933605331911, 13292.136011159437, 13392.531261405804, 13444.260565668787, 13446.0, 273967.0, 274785.0, 276493.0, 277405.0, 278688.0, 56508.71745641328, 56624.150637740466, 56821.0, 56981.14528282079, 57076.143857842166, 1507.4422463688286, 1563.5543869996995, 1576.4167258114342, 1583.4774524407148, 1586.4843925724185, 24944.781474559328, 25051.498970020133, 25216.62175067339, 25362.365940851625, 25397.11110111137, 40437.59562404338, 40536.59463405328, 40541.39187912243, 40586.594134059465, 40603.1879362417, 220286.0, 222276.0, 222718.0, 222745.0, 222760.0, 31674.524882127247, 35848.64151358453, 36060.0, 36114.27771444504, 36305.45541816822, 43048.0, 44998.55001449944, 45804.541954580025, 45877.31184032175, 46358.53641463542, 32870.506942396365, 33000.0, 33524.48566357582, 33757.49363759496, 33878.66121338755, 28923.0, 30515.2371190722, 30817.845910770302, 31947.562359694333, 32220.516692249166, 42339.5766042348, 43150.568494314655, 43436.13127737364, 43488.56511434845, 43917.341239882066, 33543.32913341768, 34843.0, 35330.29339413146, 35423.11442213965, 35831.0, 26878.32804179911, 27059.0, 27313.0, 27329.86335068312, 27667.72332276732], "value": 17802.758396962654, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.151151760841, 562.2493970549314, 574.531756618356, 575.5970820425703, 585.2041223935448, 22501.774982250194, 23360.76639233607, 23507.29478115655, 23521.176758813403, 23559.175428859973, 25952.221433356983, 27082.187534374, 27774.16677499673, 28368.290792730142, 28418.0, 6218.62688238706, 6285.9057114143325, 6346.143270658448, 6361.141245931786, 6364.713587888533, 6093.878122437571, 6158.353372895848, 6158.538109641772, 6166.568340216187, 6172.290186628527, 5870.853228669296, 5911.9408805912035, 6110.694465276741, 6121.510279177655, 6145.477634401082, 6117.204763380754, 6210.751569937174, 6211.720472578745, 6221.844453888666, 6237.376262373772, 5798.043322851724, 5806.941930580682, 5818.621789583706, 5827.854303642401, 5832.766689332464, 28579.857100714573, 31631.84184079065, 31837.840810796028, 31986.520202196985, 32090.518642220384, 2186.2457452178974, 2204.1954686539457, 2217.268301460514, 2219.755826859044, 2223.499712564676, 19996.80003199964, 20561.177552898014, 20563.48591285215, 20568.074436650386, 20607.5878482431, 2404.987975060131, 2428.7449817769057, 2480.218731099698, 2486.751324867517, 2490.0288887333772, 147481.0, 152378.0, 152706.23646881807, 153355.0, 157134.21432892763, 15011.24943752808, 15463.768043479356, 15780.447684330993, 16489.6702065958, 16499.505014849572, 24806.0, 24871.0, 24921.875390622932, 24962.126325578516, 25042.624360634603, 10837.783244335069, 10906.890931090746, 10958.835617465744, 10971.341719496697, 11167.553297868157, 10228.07947284748, 10302.742431439125, 10305.690829275134, 10324.896751032544, 10357.860635330107, 9559.753457937379, 9686.564104615309, 9747.56135973883, 9775.755606109904, 9951.502424878872, 2637.353848307177, 2737.986310068437, 2741.4928238275984, 2747.9450410991667, 2812.072016234638, 1020.4948550467566, 1029.7425643589104, 1033.9379637221791, 1049.8162821506205, 1076.1713480619878, 21242.681359779617, 21610.56778864414, 21727.456813579473, 22163.335099947028, 22416.55166896653, 1081.956721731122, 1087.9619213327653, 1104.5195340027115, 1105.723569107723, 1130.4460814201148, 11991.580294689644, 12197.573084941985, 12273.447694853752, 12274.0, 12353.752924941451, 557682.0, 581353.0, 592181.0, 593547.0, 617180.0, 131221.0, 132222.0, 133677.0, 133996.0, 134201.0, 1247.9064070194954, 1269.447790211261, 1276.3363051213316, 1291.2252648411065, 1296.3129541343103, 19154.904225478782, 19289.8071019288, 19289.807101929364, 19373.612527749647, 19618.313359032367, 58627.70686146542, 58687.0, 59627.0, 60748.0, 62141.68929155325, 73644.63177684078, 74017.62991185009, 74543.0, 75012.62493687715, 76178.0, 14802.185879776285, 14944.626384340478, 15573.922130389275, 15674.98112622676, 15782.763258550902, 23545.0, 25002.249932502054, 25651.486970260863, 25658.743412565636, 26100.477990440464, 20078.49803754918, 20355.185792568132, 20675.586488269848, 20693.689594655792, 20855.165793368094, 16783.496495105166, 16880.493585192464, 17087.401940932006, 17180.39868604593, 17914.5521361967, 20948.581028379653, 21077.47306317293, 21139.15443382309, 21155.0, 21163.0, 15283.541493755205, 15345.156016418758, 15398.846011540189, 15432.074075555503, 15563.066216027075, 19995.50011249672, 20199.59600807946, 20450.69323960112, 20513.58972820565, 20563.897180514003], "value": 12646.611682320978, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.735950169468, 1450.6808502129534, 1451.281615600278, 1455.3378212913126, 1455.767077267636, 14814.333354999024, 15437.150956697376, 15438.84561154388, 15451.381944722225, 15452.381904723812, 14809.629759256024, 15156.469523566677, 15685.137317447565, 15779.60550986226, 15782.84217157828, 2616.7775739062204, 2644.4975454663554, 2658.056389981558, 2660.0157941561647, 2666.2801043718187, 2630.513355029326, 2684.2081585932197, 2688.301041729145, 2714.8371097734102, 2717.2935036890453, 2642.577187649976, 2666.533356662586, 2694.986525067372, 2702.513547561448, 2708.2281549758322, 2648.1128821844704, 2724.087430710714, 2733.384988377619, 2734.480448714738, 2736.904208352698, 2746.7939904507043, 2772.861356932145, 2773.2928103333725, 2773.486904922596, 2780.207640822376, 15268.770968435483, 15424.0, 15445.459408920633, 15453.845461545354, 15553.22233888301, 8292.295154911839, 8543.316534677273, 8559.614817333235, 8576.44253123545, 8608.956955215246, 5181.248718935771, 5383.138697808332, 5565.972170139124, 5569.610127291091, 5652.06740887753, 1861.3206179744432, 1929.9807001929942, 1938.7770406403163, 1960.784313725489, 1983.0580474274605, 26183.607245891326, 26411.33971650705, 26436.074737384293, 26498.205053848415, 26551.07071252497, 6885.242623311432, 6888.758893438806, 7030.402415794612, 7035.050268213726, 7045.154581450259, 17738.733918991227, 17834.375796847307, 17848.55378615519, 17908.820911790714, 17912.820871791115, 8232.217939295757, 8486.702965396182, 8495.532745699045, 8495.787605309917, 8503.319734421231, 7774.650140743567, 7863.252990965849, 7899.407544434191, 7935.722249721348, 7936.246056624612, 5357.705326207096, 5479.972600137054, 5531.778728850801, 5593.412691667398, 5685.260916080883, 1549.3957356630922, 1554.875609951201, 1558.0807323679085, 1565.9138747369004, 1566.0681894272889, 899.964001439935, 904.2585080234277, 930.7812664023949, 944.419182202954, 955.603424578806, 7718.421118416141, 8695.173958473935, 8749.781255468664, 8821.779455513535, 9062.728118156334, 705.8235441139715, 741.8850078237882, 742.8365759532894, 749.4528993834531, 773.1649818196335, 4885.6335774817035, 5507.889842203133, 5576.079946808731, 5631.0, 5638.633488823213, 20121.49696257576, 21039.579208415747, 21192.682109768364, 21319.8934005329, 21329.0, 19944.401667949984, 20701.171953122008, 20777.688334674993, 20862.687059694115, 20898.895505522374, 11925.821112683143, 12028.939855300667, 12113.576024839265, 12117.39413029345, 12138.239955206445, 8906.910930890608, 9159.313051521029, 9388.0, 9552.95223523878, 9614.18279446227, 18174.27302907869, 18895.811041889407, 18945.526361841064, 19093.522661933563, 19916.800831991495, 19028.619427611648, 19776.901115494908, 20068.69896951576, 20114.798852011878, 20195.192192312144, 12399.132060756021, 12413.689657758629, 12561.309127998047, 12623.93688031554, 12706.428210730724, 13952.37214325378, 14351.856481435052, 14354.354054067384, 14363.497277595234, 14375.065620734618, 11178.0, 11303.208775385645, 11548.191626586058, 11655.30068195911, 11877.22798018126, 10594.735131621772, 10839.403832789272, 10845.295055821347, 10991.175661825555, 11219.0, 10535.525901334306, 10716.571337146426, 10726.624568140078, 10749.62376316825, 10754.623588174376, 7330.706771729071, 7759.495632784076, 7766.844663106819, 7989.960050199712, 8066.354691624539, 8143.59282035884, 8305.724936430835, 8312.70905518328, 8361.456505327134, 8383.371247156365], "value": 6845.518082481822, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.24133954380034, 500.94990500949933, 528.9709066001369, 529.0582762682427, 531.1342511705918, 5994.670293133876, 6038.728257228424, 6141.447269745722, 6148.784792532262, 6228.501719862412, 6037.426444487777, 6043.697815109249, 6046.425589568984, 6060.848478788022, 6108.847278818021, 4691.765411729417, 4693.78877950493, 4749.313993532298, 4759.23852183649, 4777.784999675015, 4736.147493451177, 4766.642501812361, 4776.570108690217, 4778.283257511366, 4799.7360145192, 4628.652851036186, 4712.0, 4720.386349774524, 4776.617870570346, 4792.418501894384, 4684.5315468453055, 4705.3647757552635, 4739.170645137087, 4740.857774266778, 4756.357891684613, 4261.701680882339, 4280.844172073523, 4293.957060429387, 4302.161078589676, 4305.138972205571, 5929.703514824241, 5945.227120474354, 5950.613210141327, 5962.403759624003, 5981.611195272293, 5654.578979717253, 5676.460736230093, 5691.743871525791, 5697.800576979786, 5701.315842098933, 6534.901976470309, 6553.901691474632, 6598.60408375499, 6601.900971485432, 6630.635315057718, 4859.73271470069, 4862.683925544864, 4903.166461701484, 4913.70517768935, 4913.778879950411, 6569.638669873155, 6802.251752307292, 6804.591724496497, 6844.828879278008, 6877.759278425231, 4933.851984440473, 4955.72743499111, 4958.851234462972, 4981.726005069683, 5035.471275516018, 5039.0, 5050.84847454577, 5071.721055341918, 5076.289319495274, 5086.847394578169, 5639.182318563785, 5671.120976248606, 5690.260266165372, 5694.971525142348, 5707.686077265707, 6389.488840892717, 6443.420092191725, 6525.2169739631545, 6564.310747371553, 6575.901361479581, 1724.094850203653, 1764.1003088424739, 1767.098779622402, 1768.8142745011846, 1769.5841477252836, 1189.898858597029, 1240.503798480615, 1240.528599132324, 1242.0746543824907, 1252.0359323321175, 6080.483158931452, 6093.177421048102, 6119.632822030692, 6123.142760013601, 6133.264008318941, 6585.703643336062, 6667.466602671775, 6738.663066846638, 6754.966225168941, 6823.181218253742, 5986.719495560608, 6102.877942441126, 6125.632462052291, 6128.448439640365, 6149.200603921551, 3578.570571531433, 3585.4263317869536, 3602.9819850900576, 3607.5670919489303, 3615.295017471568, 6965.407940325129, 6993.405560527311, 7019.403350715144, 7054.823629409306, 7063.5761854287875, 6902.654867256617, 6908.378245957788, 6917.654117294115, 6934.410575101072, 6950.930490695129, 6934.895976560356, 6961.37347638715, 6983.406410454965, 6991.0, 7036.296370363025, 6247.812565623039, 6550.443212327005, 6642.675063727656, 6664.7000884959325, 6676.465882729467, 6554.180727409074, 6603.438707709898, 6605.47156227491, 6606.471482281502, 6624.648249871227, 6791.675623253418, 6792.76225332111, 6893.310668933066, 6893.689783959834, 6900.930990690029, 4948.455669876239, 5028.371453568304, 5036.546710795899, 5061.9493805061475, 5071.416787069535, 6410.8076757697345, 6661.533692641468, 6690.0, 6711.161104861892, 6815.727370905307, 6338.809835704937, 6434.356564343529, 6435.717783930323, 6458.515611329075, 6566.80299591013, 6415.679216039086, 6442.387973142544, 6478.70845811949, 6480.837979050561, 6497.18785151856, 6366.936330636634, 6404.598275646633, 6413.775517856852, 6436.558774445618, 6531.869362612626, 6365.204349456318, 6475.5716606272645, 6476.352364763675, 6491.837704057248, 6551.868962620816, 6273.717682704198, 6346.936530634634, 6355.428011478989, 6365.140706004815, 6387.137736405712], "value": 5048.695634678466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [931.5807886451099, 932.8927173375064, 936.8969413364531, 951.8714973478578, 960.7934294126761, 18968.0, 19343.322983695554, 19557.511062223453, 19693.704594431096, 19809.405717828453, 18524.536886577844, 18621.627567448642, 18665.0, 18833.81166188341, 22367.55264894705, 1060.862087928572, 1077.272840832439, 1080.6001779341655, 1081.4592703648175, 1082.631905152248, 1100.5652767156969, 1104.34843442369, 1104.5802595013884, 1105.8949399807007, 1107.2360071550604, 1156.8900954409319, 1157.3056166300194, 1160.9477573509212, 1163.9418029098554, 1165.4930105404135, 1030.3611760708334, 1098.9450527473675, 1102.0522350778324, 1142.206166714134, 1151.4645689754263, 806.6329819931917, 812.2486699802658, 813.3208770676492, 814.7474282972238, 816.8815521749373, 19156.32952846644, 19537.218511259533, 19802.10890509916, 19841.30555430553, 19886.502837429034, 21298.5740285195, 21694.783052169434, 21748.565028699497, 21866.562668746537, 21937.0, 16021.91989040059, 16185.676286474205, 16463.506094817174, 16501.91749041259, 16514.58713532159, 3648.3068217038676, 3648.416253399443, 3676.7977761223124, 3682.558093028827, 3702.500162478058, 131204.3439782795, 134430.0, 142074.0, 143872.63179017286, 144054.2797286007, 16631.501054968372, 16866.987980721195, 17062.658746825244, 17281.13594320279, 17719.911400442914, 32040.83979580087, 32209.677903221138, 32530.0, 32688.346233075205, 32873.50689739656, 8824.117588241124, 9023.909760902437, 9050.592723327467, 9137.771555711028, 9175.495347755937, 4361.193179261847, 4380.759058251763, 4393.384926110347, 4412.161689279027, 4435.844745433894, 5357.8392648220615, 5410.83767486976, 5522.5581953443625, 5534.1145416733125, 5537.1140617501815, 1153.8499995000764, 1157.4444266752007, 1159.1943599198644, 1168.2289688805347, 1173.8943495085311, 708.0830324729487, 714.921358650548, 716.8387112899555, 729.6461216310086, 749.0187853911393, 11775.411229438494, 12292.201006934522, 13115.606531804062, 13150.473981040843, 13214.801777973338, 713.9357457828717, 721.0626185958338, 731.0751898847961, 737.1986451484338, 740.8703476891523, 5330.92003619946, 5593.608447408642, 5627.588960423184, 5629.746661400247, 5685.289338832646, 122269.38865305617, 123457.0, 127525.0, 127590.36204818917, 129120.35439822929, 101818.0, 105995.0, 106164.46917765362, 106696.0, 109113.45443272733, 94375.52812236032, 95444.52277738567, 98273.01726982639, 98444.99999999857, 99038.50480747694, 12776.488940442277, 13467.777816660533, 13605.251711155981, 13663.795043074164, 13773.173609583457, 44750.10499790051, 44812.32781508215, 44938.0, 45512.772436138934, 46939.295910562054, 56863.431365685814, 58085.70957145356, 75041.0, 80793.0, 82915.17084829074, 18086.276548937894, 18592.907035464737, 18645.90677046606, 18718.157682904573, 18944.0, 18229.54426139357, 18334.81665183331, 18646.81353186505, 18984.620307594047, 21443.785562144178, 17246.65506689834, 17902.55243618868, 18306.26774928988, 18516.0, 18559.721604175676, 19751.111199995747, 19770.0, 19941.0, 20205.09077091505, 20279.188832446536, 16653.916730416757, 16730.163491825602, 16747.246373913447, 16748.665026699153, 16877.156142193086, 12016.279023258283, 12075.75848483043, 12628.810567841307, 12819.294938778457, 12913.741725165632, 13726.0, 14487.710245795235, 14520.782188266972, 14521.346539405964, 14577.708445831237], "value": 9535.332133175152, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 108.0, 95.0, 106.0, 107.0, 118.0, 109.0, 106.0, 106.0, 106.0], "value": 106.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 112.0, 145.0, 169.0, 114.0, 137.0, 140.0, 112.0, 162.0, 113.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 163.0, 162.0, 162.0, 168.0, 162.0, 163.0, 161.0, 186.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 171.0, 75.0, 76.0, 74.0, 74.0, 98.0, 76.0], "value": 86.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 126.0, 79.0, 79.0, 79.0, 123.0, 80.0, 80.0, 129.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 115.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 148.0, 149.0, 148.0, 148.0, 150.0, 147.0, 147.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 47.0, 47.0, 51.0, 47.0, 48.0, 49.0, 48.0, 47.0, 50.0], "value": 48.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 75.0, 85.0, 76.0, 74.0, 73.0, 75.0, 76.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 122.0, 133.0, 119.0, 118.0, 120.0, 119.0, 121.0, 123.0, 146.0], "value": 124.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 192.0, 176.0, 177.0, 177.0, 173.0, 181.0, 181.0, 173.0, 168.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 62.0, 67.0, 61.0, 63.0, 63.0, 63.0, 63.0, 62.0], "value": 63.2, "unit": "score"}]}]} 10:39:00 INFO - Return code: 0 10:39:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:39:00 INFO - # TBPL SUCCESS # 10:39:00 INFO - Running post-action listener: _package_coverage_data 10:39:00 INFO - Running post-action listener: _resource_record_post_action 10:39:00 INFO - [mozharness: 2017-07-18 17:39:00.396446Z] Finished run-tests step (success) 10:39:00 INFO - Running post-run listener: _resource_record_post_run 10:39:00 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 435118080; Read time: 8; Write time: 352088 10:39:00 INFO - TinderboxPrint: CPU usage
13.0% 10:39:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 10:39:00 INFO - TinderboxPrint: I/O write bytes / time
435,118,080 / 352,088 10:39:00 INFO - TinderboxPrint: CPU idle
8,231.9 (86.9%) 10:39:00 INFO - TinderboxPrint: CPU user
1,187.4 (12.5%) 10:39:00 INFO - TinderboxPrint: Swap in / out
0 / 0 10:39:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 109678592; Read time: 0; Write time: 110660 10:39:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:39:00 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 324915200; Read time: 0; Write time: 241380 10:39:01 INFO - Running post-run listener: _upload_blobber_files 10:39:01 INFO - Blob upload gear active. 10:39:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:39:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:39:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:39:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:39:01 INFO - Traceback (most recent call last): 10:39:01 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 10:39:01 INFO - main() 10:39:01 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 10:39:01 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 10:39:01 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 10:39:01 INFO - return set(response.json().get('whitelist', [])) 10:39:01 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 10:39:01 INFO - return json.loads(self.text or self.content, **kwargs) 10:39:01 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 10:39:01 INFO - return _default_decoder.decode(s) 10:39:01 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 10:39:01 INFO - obj, end = self.raw_decode(s) 10:39:01 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 10:39:01 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 10:39:01 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 10:39:01 ERROR - Return code: 1 10:39:01 INFO - Setting buildbot property blobber_files to {} 10:39:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:39:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:39:01 INFO - Contents: 10:39:01 INFO - blobber_files:{} 10:39:01 INFO - Running post-run listener: copy_logs_to_upload_dir 10:39:01 INFO - Copying logs to upload dir... 10:39:01 INFO - mkdir: /builds/slave/test/build/upload/logs 10:39:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.098453 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-07-18 10:39:00.969828) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-18 10:39:00.974251) ========= 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=9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011417 build_url: 'https://queue.taskcluster.net/v1/task/Yt7z9mouSkOiUBbDcWg8og/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.12 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-18 10:39:01.104689) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:39:01.105014) ========= 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=9bfb7e13875ed353a705960e000001c0-1500397825.889964-1297977110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004552 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:39:01.141752) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 10:39:01.142052) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 10:39:01.152247) ========= ========= Total master_lag: 0.29 =========