builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-067 starttime: 1500339989.75 results: success (0) revision: efc0b1525edbd357818dc7195537364e76f709e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:29.746264) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:29.746655) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:29.746964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:29.763210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:29.763465) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-17 18:06:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 87.1M=0s 2017-07-17 18:06:31 (87.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106257 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:29.886398) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:29.886765) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021218 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:29.937425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-17 18:06:29.937762) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev efc0b1525edbd357818dc7195537364e76f709e7 --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 efc0b1525edbd357818dc7195537364e76f709e7 --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=e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-17 18:06:32,029 truncating revision to first 12 chars 2017-07-17 18:06:32,029 Setting DEBUG logging. 2017-07-17 18:06:32,029 attempt 1/10 2017-07-17 18:06:32,029 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/efc0b1525edb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-17 18:06:32,949 unpacking tar archive at: mozilla-central-efc0b1525edb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.245743 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-17 18:06:31.196413) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:31.196771) ========= script_repo_revision: efc0b1525edbd357818dc7195537364e76f709e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:31.197192) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:31.197462) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 18:06:31.205404) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-07-17 18:06:31.205682) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:06:33 INFO - MultiFileLogger online at 20170717 18:06:33 in /builds/slave/test 18:06:33 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 18:06:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:06:33 INFO - {'append_to_log': False, 18:06:33 INFO - 'base_work_dir': '/builds/slave/test', 18:06:33 INFO - 'blob_upload_branch': 'Firefox', 18:06:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:06:33 INFO - 'branch': 'Firefox', 18:06:33 INFO - 'buildbot_json_path': 'buildprops.json', 18:06:33 INFO - 'code_coverage': False, 18:06:33 INFO - 'config_files': ('talos/linux_config.py',), 18:06:33 INFO - 'default_actions': ('clobber', 18:06:33 INFO - 'read-buildbot-config', 18:06:33 INFO - 'download-and-extract', 18:06:33 INFO - 'populate-webroot', 18:06:33 INFO - 'create-virtualenv', 18:06:33 INFO - 'install', 18:06:33 INFO - 'setup-mitmproxy', 18:06:33 INFO - 'run-tests'), 18:06:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:06:33 INFO - 'disable_ccov_upload': False, 18:06:33 INFO - 'download_minidump_stackwalk': True, 18:06:33 INFO - 'download_symbols': 'ondemand', 18:06:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:06:33 INFO - 'tooltool.py': '/tools/tooltool.py', 18:06:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:06:33 INFO - '/tools/misc-python/virtualenv.py')}, 18:06:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:06:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:06:33 INFO - 'gecko_profile': False, 18:06:33 INFO - 'gecko_profile_interval': 0, 18:06:33 INFO - 'installer_path': 'installer.exe', 18:06:33 INFO - 'log_level': 'info', 18:06:33 INFO - 'log_name': 'talos', 18:06:33 INFO - 'log_to_console': True, 18:06:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:06:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:06:33 INFO - 'opt_config_files': (), 18:06:33 INFO - 'pip_index': False, 18:06:33 INFO - 'suite': 'dromaeojs-e10s', 18:06:33 INFO - 'system_bits': '32', 18:06:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:06:33 INFO - 'title': 'talos-linux64-ix-067', 18:06:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:06:33 INFO - 'use_talos_json': True, 18:06:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:06:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:06:33 INFO - 'work_dir': 'build'} 18:06:33 INFO - [mozharness: 2017-07-18 01:06:33.411019Z] Running clobber step. 18:06:33 INFO - Running pre-action listener: _resource_record_pre_action 18:06:33 INFO - Running main action method: clobber 18:06:33 INFO - rmtree: /builds/slave/test/build 18:06:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:06:34 INFO - Running post-action listener: _resource_record_post_action 18:06:34 INFO - [mozharness: 2017-07-18 01:06:34.453165Z] Finished clobber step (success) 18:06:34 INFO - [mozharness: 2017-07-18 01:06:34.453267Z] Running read-buildbot-config step. 18:06:34 INFO - Running pre-action listener: _resource_record_pre_action 18:06:34 INFO - Running main action method: read_buildbot_config 18:06:34 INFO - Using buildbot properties: 18:06:34 INFO - { 18:06:34 INFO - "project": "", 18:06:34 INFO - "product": "firefox", 18:06:34 INFO - "who": "kwierso@gmail.com", 18:06:34 INFO - "installer_path": "public/build/target.tar.bz2", 18:06:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:06:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:06:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:06:34 INFO - "stage_platform": "linux64-pgo", 18:06:34 INFO - "basedir": "/builds/slave/test", 18:06:34 INFO - "buildnumber": 74, 18:06:34 INFO - "platform": "ubuntu64_hw", 18:06:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:06:34 INFO - "slavebuilddir": "test", 18:06:34 INFO - "taskId": "XWU9aaYTQMCt05DRwucImw", 18:06:34 INFO - "branch": "mozilla-central", 18:06:34 INFO - "script_repo_revision": "production", 18:06:34 INFO - "revision": "efc0b1525edbd357818dc7195537364e76f709e7", 18:06:34 INFO - "slavename": "talos-linux64-ix-067", 18:06:34 INFO - "repo_path": "mozilla-central" 18:06:34 INFO - } 18:06:34 INFO - Finding installer, test and symbols from parent task. 18:06:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XWU9aaYTQMCt05DRwucImw'}, attempt #1 18:06:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XWU9aaYTQMCt05DRwucImw'}, attempt #1 18:06:35 INFO - Task dependencies: Ki-5sk4FRaafhiv8Ygow_w 18:06:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w'}, attempt #1 18:06:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.tar.bz2 18:06:35 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/test_packages.json 18:06:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.crashreporter-symbols.zip 18:06:35 INFO - Running post-action listener: _resource_record_post_action 18:06:35 INFO - [mozharness: 2017-07-18 01:06:35.828708Z] Finished read-buildbot-config step (success) 18:06:35 INFO - [mozharness: 2017-07-18 01:06:35.828930Z] Running download-and-extract step. 18:06:35 INFO - Running pre-action listener: _resource_record_pre_action 18:06:35 INFO - Running main action method: download_and_extract 18:06:35 INFO - mkdir: /builds/slave/test/build/tests 18:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:35 INFO - trying https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.test_packages.json 18:06:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:06:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:06:36 INFO - Downloaded 989 bytes. 18:06:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:06:36 INFO - Using the following test package requirements: 18:06:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:06:36 INFO - u'common': [u'target.common.tests.zip'], 18:06:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:06:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:06:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:06:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:06:36 INFO - u'mozbase': [u'target.common.tests.zip'], 18:06:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:06:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:06:36 INFO - u'web-platform': [u'target.common.tests.zip', 18:06:36 INFO - u'target.web-platform.tests.tar.gz'], 18:06:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:06:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:06:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.common.tests.zip 18:06:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:06:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.common.tests.zip into memory 18:06:38 INFO - Content-Length response header: 73393159 18:06:38 INFO - Bytes received: 73393159 18:06:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:06:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.common.tests.zip 18:06:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:06:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.common.tests.zip into memory 18:06:51 INFO - Content-Length response header: 73393159 18:06:51 INFO - Bytes received: 73393159 18:07:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.talos.tests.zip 18:07:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:07:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.talos.tests.zip into memory 18:07:05 INFO - Content-Length response header: 14495033 18:07:05 INFO - Bytes received: 14495033 18:07: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')]} 18:07:05 INFO - trying https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.tar.bz2 18:07:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:07:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:07:10 INFO - Downloaded 62664714 bytes. 18:07:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.tar.bz2 18:07:10 INFO - mkdir: /builds/slave/test/properties 18:07:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:07:10 INFO - Writing to file /builds/slave/test/properties/build_url 18:07:10 INFO - Contents: 18:07:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.tar.bz2 18:07:10 INFO - Running post-action listener: _resource_record_post_action 18:07:10 INFO - Running post-action listener: set_extra_try_arguments 18:07:10 INFO - [mozharness: 2017-07-18 01:07:10.949028Z] Finished download-and-extract step (success) 18:07:10 INFO - [mozharness: 2017-07-18 01:07:10.949258Z] Running populate-webroot step. 18:07:10 INFO - Running pre-action listener: _resource_record_pre_action 18:07:10 INFO - Running main action method: populate_webroot 18:07:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:07:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:07:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:07:10 INFO - '--tppagecycles', 18:07:10 INFO - '7'], 18:07:10 INFO - 'tests': ['tsvgm']}}, 18:07:10 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:07:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:07:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:07:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:07:10 INFO - 'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:07:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:07:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:07:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:07:10 INFO - 'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['damp', 'tps']}, 18:07:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:07:10 INFO - 'tests': ['damp', 'tps']}, 18:07:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['dromaeo_dom']}, 18:07:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:07:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:07:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:07:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:07:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:07:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['a11yr', 18:07:10 INFO - 'ts_paint', 18:07:10 INFO - 'tpaint', 18:07:10 INFO - 'sessionrestore', 18:07:10 INFO - 'sessionrestore_no_auto_restore', 18:07:10 INFO - 'tabpaint']}, 18:07:10 INFO - 'other-e10s': {'tests': ['a11yr', 18:07:10 INFO - 'ts_paint', 18:07:10 INFO - 'tpaint', 18:07:10 INFO - 'sessionrestore', 18:07:10 INFO - 'sessionrestore_many_windows', 18:07:10 INFO - 'sessionrestore_no_auto_restore', 18:07:10 INFO - 'tabpaint']}, 18:07:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:07:10 INFO - 'ts_paint', 18:07:10 INFO - 'tpaint', 18:07:10 INFO - 'sessionrestore', 18:07:10 INFO - 'sessionrestore_many_windows', 18:07:10 INFO - 'sessionrestore_no_auto_restore', 18:07:10 INFO - 'tabpaint']}, 18:07:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:07:10 INFO - 'ts_paint', 18:07:10 INFO - 'tpaint', 18:07:10 INFO - 'sessionrestore', 18:07:10 INFO - 'sessionrestore_many_windows', 18:07:10 INFO - 'sessionrestore_no_auto_restore', 18:07:10 INFO - 'tabpaint']}, 18:07:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['a11yr', 18:07:10 INFO - 'ts_paint', 18:07:10 INFO - 'tpaint', 18:07:10 INFO - 'sessionrestore', 18:07:10 INFO - 'sessionrestore_no_auto_restore', 18:07:10 INFO - 'tabpaint']}, 18:07:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['a11yr', 18:07:10 INFO - 'ts_paint', 18:07:10 INFO - 'tpaint', 18:07:10 INFO - 'sessionrestore', 18:07:10 INFO - 'sessionrestore_no_auto_restore', 18:07:10 INFO - 'tabpaint']}, 18:07:10 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 18:07:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:07:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:07:10 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:07:10 INFO - 'talos_options': ['--mitmproxy', 18:07:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:07:10 INFO - '--firstNonBlankPaint'], 18:07:10 INFO - 'tests': ['quantum_pageload_google', 18:07:10 INFO - 'quantum_pageload_youtube', 18:07:10 INFO - 'quantum_pageload_amazon', 18:07:10 INFO - 'quantum_pageload_facebook']}, 18:07:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['tsvgx', 18:07:10 INFO - 'tsvgr_opacity', 18:07:10 INFO - 'tart', 18:07:10 INFO - 'tscrollx', 18:07:10 INFO - 'cart', 18:07:10 INFO - 'tsvg_static']}, 18:07:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:07:10 INFO - 'tsvgr_opacity', 18:07:10 INFO - 'tart', 18:07:10 INFO - 'tscrollx', 18:07:10 INFO - 'cart', 18:07:10 INFO - 'tsvg_static']}, 18:07:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:07:10 INFO - 'talos_options': ['--disable-e10s'], 18:07:10 INFO - 'tests': ['tp5o']}, 18:07:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:07:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:07:10 INFO - 'talos_options': ['--disable-e10s', 18:07:10 INFO - '--xperf_path', 18:07:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:07:10 INFO - 'tests': ['tp5n']}, 18:07:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:07:10 INFO - 'talos_options': ['--xperf_path', 18:07:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:07:10 INFO - 'tests': ['tp5n']}}, 18:07:10 INFO - 'talos.zip': {'path': '', 18:07:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:07:10 INFO - Running post-action listener: _resource_record_post_action 18:07:10 INFO - [mozharness: 2017-07-18 01:07:10.970352Z] Finished populate-webroot step (success) 18:07:10 INFO - [mozharness: 2017-07-18 01:07:10.970449Z] Running create-virtualenv step. 18:07:10 INFO - Running pre-action listener: _resource_record_pre_action 18:07:10 INFO - Running main action method: create_virtualenv 18:07:10 INFO - Creating virtualenv /builds/slave/test/build/venv 18:07:10 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 18:07:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:07:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:07:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:07:11 INFO - Using real prefix '/usr' 18:07:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:07:11 INFO - Installing distribute.............................................................................................................................................................................................done. 18:07:13 INFO - Installing pip.................done. 18:07:13 INFO - Return code: 0 18:07:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:07:13 INFO - Reading from file tmpfile_stdout 18:07:13 INFO - Output received: 18:07:13 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) 18:07:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:07:13 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')]} 18:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:13 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')]} 18:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:13 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:13 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 18:07:13 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 18:07:13 INFO - Using env: {'DISPLAY': ':0', 18:07:13 INFO - 'HOME': '/home/cltbld', 18:07:13 INFO - 'LANG': 'en_US.UTF-8', 18:07:13 INFO - 'LANGUAGE': 'en_US:en', 18:07:13 INFO - 'LOGNAME': 'cltbld', 18:07:13 INFO - 'MAIL': '/var/mail/cltbld', 18:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:13 INFO - 'MOZ_NO_REMOTE': '1', 18:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:13 INFO - 'NO_EM_RESTART': '1', 18:07:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:13 INFO - 'PWD': '/builds/slave/test', 18:07:13 INFO - 'SHELL': '/bin/bash', 18:07:13 INFO - 'SHLVL': '1', 18:07:13 INFO - 'TERM': 'linux', 18:07:13 INFO - 'TMOUT': '86400', 18:07:13 INFO - 'USER': 'cltbld', 18:07:13 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:13 INFO - '_': '/tools/buildbot/bin/python'} 18:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:13 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 18:07:13 INFO - Cleaning up... 18:07:13 INFO - Return code: 0 18:07:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:07:13 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')]} 18:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:13 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')]} 18:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:13 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:13 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 18:07:13 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 18:07:13 INFO - Using env: {'DISPLAY': ':0', 18:07:13 INFO - 'HOME': '/home/cltbld', 18:07:13 INFO - 'LANG': 'en_US.UTF-8', 18:07:13 INFO - 'LANGUAGE': 'en_US:en', 18:07:13 INFO - 'LOGNAME': 'cltbld', 18:07:13 INFO - 'MAIL': '/var/mail/cltbld', 18:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:13 INFO - 'MOZ_NO_REMOTE': '1', 18:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:13 INFO - 'NO_EM_RESTART': '1', 18:07:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:13 INFO - 'PWD': '/builds/slave/test', 18:07:13 INFO - 'SHELL': '/bin/bash', 18:07:13 INFO - 'SHLVL': '1', 18:07:13 INFO - 'TERM': 'linux', 18:07:13 INFO - 'TMOUT': '86400', 18:07:13 INFO - 'USER': 'cltbld', 18:07:13 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:13 INFO - '_': '/tools/buildbot/bin/python'} 18:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:14 INFO - Downloading/unpacking psutil>=3.1.1 18:07:14 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 18:07:14 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 18:07:14 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 18:07:14 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 18:07:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:07:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:07:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:07:15 INFO - Installing collected packages: psutil 18:07:15 INFO - Running setup.py install for psutil 18:07:15 INFO - building 'psutil._psutil_linux' extension 18:07:15 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 18:07:15 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 18:07:15 INFO - building 'psutil._psutil_posix' extension 18:07:15 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 18:07:15 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 18:07:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:07:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:07:15 INFO - Successfully installed psutil 18:07:15 INFO - Cleaning up... 18:07:15 INFO - Return code: 0 18:07:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:07: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')]} 18:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07: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')]} 18:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:07:15 INFO - Using env: {'DISPLAY': ':0', 18:07:15 INFO - 'HOME': '/home/cltbld', 18:07:15 INFO - 'LANG': 'en_US.UTF-8', 18:07:15 INFO - 'LANGUAGE': 'en_US:en', 18:07:15 INFO - 'LOGNAME': 'cltbld', 18:07:15 INFO - 'MAIL': '/var/mail/cltbld', 18:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:15 INFO - 'MOZ_NO_REMOTE': '1', 18:07:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:15 INFO - 'NO_EM_RESTART': '1', 18:07:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:15 INFO - 'PWD': '/builds/slave/test', 18:07:15 INFO - 'SHELL': '/bin/bash', 18:07:15 INFO - 'SHLVL': '1', 18:07:15 INFO - 'TERM': 'linux', 18:07:15 INFO - 'TMOUT': '86400', 18:07:15 INFO - 'USER': 'cltbld', 18:07:15 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:15 INFO - '_': '/tools/buildbot/bin/python'} 18:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:07:16 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 18:07:16 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 18:07:16 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 18:07:16 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 18:07:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:07:17 INFO - Installing collected packages: mozsystemmonitor 18:07:17 INFO - Running setup.py install for mozsystemmonitor 18:07:17 INFO - Successfully installed mozsystemmonitor 18:07:17 INFO - Cleaning up... 18:07:17 INFO - Return code: 0 18:07:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:07:17 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')]} 18:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:17 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')]} 18:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:17 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:17 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 18:07:17 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 18:07:17 INFO - Using env: {'DISPLAY': ':0', 18:07:17 INFO - 'HOME': '/home/cltbld', 18:07:17 INFO - 'LANG': 'en_US.UTF-8', 18:07:17 INFO - 'LANGUAGE': 'en_US:en', 18:07:17 INFO - 'LOGNAME': 'cltbld', 18:07:17 INFO - 'MAIL': '/var/mail/cltbld', 18:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:17 INFO - 'MOZ_NO_REMOTE': '1', 18:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:17 INFO - 'NO_EM_RESTART': '1', 18:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:17 INFO - 'PWD': '/builds/slave/test', 18:07:17 INFO - 'SHELL': '/bin/bash', 18:07:17 INFO - 'SHLVL': '1', 18:07:17 INFO - 'TERM': 'linux', 18:07:17 INFO - 'TMOUT': '86400', 18:07:17 INFO - 'USER': 'cltbld', 18:07:17 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:17 INFO - '_': '/tools/buildbot/bin/python'} 18:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:17 INFO - Downloading/unpacking jsonschema==2.5.1 18:07:17 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 18:07:17 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 18:07:17 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 18:07:17 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 18:07:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:07:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:07:18 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 18:07:18 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 18:07:18 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 18:07:18 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 18:07:18 INFO - Downloading functools32-3.2.3-2.tar.gz 18:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:07:18 INFO - warning: no files found matching '*.txt' 18:07:18 INFO - no previously-included directories found matching 'build' 18:07:18 INFO - no previously-included directories found matching 'dist' 18:07:18 INFO - no previously-included directories found matching '.git*' 18:07:18 INFO - Installing collected packages: jsonschema, functools32 18:07:18 INFO - Running setup.py install for functools32 18:07:19 INFO - warning: no files found matching '*.txt' 18:07:19 INFO - no previously-included directories found matching 'build' 18:07:19 INFO - no previously-included directories found matching 'dist' 18:07:19 INFO - no previously-included directories found matching '.git*' 18:07:19 INFO - Successfully installed jsonschema functools32 18:07:19 INFO - Cleaning up... 18:07:19 INFO - Return code: 0 18:07:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:19 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:19 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 18:07:19 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 18:07:19 INFO - Using env: {'DISPLAY': ':0', 18:07:19 INFO - 'HOME': '/home/cltbld', 18:07:19 INFO - 'LANG': 'en_US.UTF-8', 18:07:19 INFO - 'LANGUAGE': 'en_US:en', 18:07:19 INFO - 'LOGNAME': 'cltbld', 18:07:19 INFO - 'MAIL': '/var/mail/cltbld', 18:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:19 INFO - 'MOZ_NO_REMOTE': '1', 18:07:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:19 INFO - 'NO_EM_RESTART': '1', 18:07:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:19 INFO - 'PWD': '/builds/slave/test', 18:07:19 INFO - 'SHELL': '/bin/bash', 18:07:19 INFO - 'SHLVL': '1', 18:07:19 INFO - 'TERM': 'linux', 18:07:19 INFO - 'TMOUT': '86400', 18:07:19 INFO - 'USER': 'cltbld', 18:07:19 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:19 INFO - '_': '/tools/buildbot/bin/python'} 18:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:07:19 INFO - Cleaning up... 18:07:19 INFO - Return code: 0 18:07:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:19 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:19 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 18:07:19 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 18:07:19 INFO - Using env: {'DISPLAY': ':0', 18:07:19 INFO - 'HOME': '/home/cltbld', 18:07:19 INFO - 'LANG': 'en_US.UTF-8', 18:07:19 INFO - 'LANGUAGE': 'en_US:en', 18:07:19 INFO - 'LOGNAME': 'cltbld', 18:07:19 INFO - 'MAIL': '/var/mail/cltbld', 18:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:19 INFO - 'MOZ_NO_REMOTE': '1', 18:07:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:19 INFO - 'NO_EM_RESTART': '1', 18:07:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:19 INFO - 'PWD': '/builds/slave/test', 18:07:19 INFO - 'SHELL': '/bin/bash', 18:07:19 INFO - 'SHLVL': '1', 18:07:19 INFO - 'TERM': 'linux', 18:07:19 INFO - 'TMOUT': '86400', 18:07:19 INFO - 'USER': 'cltbld', 18:07:19 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:19 INFO - '_': '/tools/buildbot/bin/python'} 18:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:19 INFO - Downloading/unpacking blobuploader==1.2.4 18:07:19 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 18:07:19 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 18:07:19 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 18:07:19 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 18:07:20 INFO - Downloading blobuploader-1.2.4.tar.gz 18:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:07:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:07:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:07:21 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 18:07:21 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 18:07:21 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 18:07:21 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 18:07:21 INFO - Downloading docopt-0.6.1.tar.gz 18:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:07:21 INFO - Installing collected packages: blobuploader, requests, docopt 18:07:21 INFO - Running setup.py install for blobuploader 18:07:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:07:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:07:21 INFO - Running setup.py install for requests 18:07:21 INFO - Running setup.py install for docopt 18:07:21 INFO - Successfully installed blobuploader requests docopt 18:07:21 INFO - Cleaning up... 18:07:21 INFO - Return code: 0 18:07:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:21 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:21 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 18:07:21 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 18:07:21 INFO - Using env: {'DISPLAY': ':0', 18:07:21 INFO - 'HOME': '/home/cltbld', 18:07:21 INFO - 'LANG': 'en_US.UTF-8', 18:07:21 INFO - 'LANGUAGE': 'en_US:en', 18:07:21 INFO - 'LOGNAME': 'cltbld', 18:07:21 INFO - 'MAIL': '/var/mail/cltbld', 18:07:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:21 INFO - 'MOZ_NO_REMOTE': '1', 18:07:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:21 INFO - 'NO_EM_RESTART': '1', 18:07:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:21 INFO - 'PWD': '/builds/slave/test', 18:07:21 INFO - 'SHELL': '/bin/bash', 18:07:21 INFO - 'SHLVL': '1', 18:07:21 INFO - 'TERM': 'linux', 18:07:21 INFO - 'TMOUT': '86400', 18:07:21 INFO - 'USER': 'cltbld', 18:07:21 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:21 INFO - '_': '/tools/buildbot/bin/python'} 18:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:07:22 INFO - Running setup.py (path:/tmp/pip-OqL84t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:07:22 INFO - Running setup.py (path:/tmp/pip-8irUlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:07:22 INFO - Running setup.py (path:/tmp/pip-bMn3lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:07:22 INFO - Running setup.py (path:/tmp/pip-XL5lqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:07:22 INFO - Running setup.py (path:/tmp/pip-J7ADTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:07:22 INFO - Running setup.py (path:/tmp/pip-jFkWKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:07:22 INFO - Running setup.py (path:/tmp/pip-mZmJxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:07:22 INFO - Running setup.py (path:/tmp/pip-oKXIZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:07:22 INFO - Running setup.py (path:/tmp/pip-3N3QjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:07:22 INFO - Running setup.py (path:/tmp/pip-_kHfSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:07:22 INFO - Running setup.py (path:/tmp/pip-6Isjva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:07:22 INFO - Running setup.py (path:/tmp/pip-iI1z_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:07:22 INFO - Running setup.py (path:/tmp/pip-mB5VYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:07:22 INFO - Running setup.py (path:/tmp/pip-rIRmtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:23 INFO - Running setup.py (path:/tmp/pip-au7nli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:07:23 INFO - Running setup.py (path:/tmp/pip-vyeEeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:07:23 INFO - Running setup.py (path:/tmp/pip-ZJJZuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:07:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:07:23 INFO - Running setup.py install for manifestparser 18:07:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:07:23 INFO - Running setup.py install for mozcrash 18:07:23 INFO - Running setup.py install for mozdebug 18:07:23 INFO - Running setup.py install for mozdevice 18:07:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:07:23 INFO - Running setup.py install for mozfile 18:07:23 INFO - Running setup.py install for mozhttpd 18:07:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:07:23 INFO - Running setup.py install for mozinfo 18:07:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:07:23 INFO - Running setup.py install for mozInstall 18:07:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Running setup.py install for mozleak 18:07:24 INFO - Running setup.py install for mozlog 18:07:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Running setup.py install for moznetwork 18:07:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Running setup.py install for mozprocess 18:07:24 INFO - Running setup.py install for mozprofile 18:07:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Running setup.py install for mozrunner 18:07:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:07:24 INFO - Running setup.py install for mozscreenshot 18:07:24 INFO - Running setup.py install for moztest 18:07:24 INFO - Running setup.py install for mozversion 18:07:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:07:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:07:25 INFO - Cleaning up... 18:07:25 INFO - Return code: 0 18:07:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:25 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:25 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 18:07:25 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 18:07:25 INFO - Using env: {'DISPLAY': ':0', 18:07:25 INFO - 'HOME': '/home/cltbld', 18:07:25 INFO - 'LANG': 'en_US.UTF-8', 18:07:25 INFO - 'LANGUAGE': 'en_US:en', 18:07:25 INFO - 'LOGNAME': 'cltbld', 18:07:25 INFO - 'MAIL': '/var/mail/cltbld', 18:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:25 INFO - 'MOZ_NO_REMOTE': '1', 18:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:25 INFO - 'NO_EM_RESTART': '1', 18:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:25 INFO - 'PWD': '/builds/slave/test', 18:07:25 INFO - 'SHELL': '/bin/bash', 18:07:25 INFO - 'SHLVL': '1', 18:07:25 INFO - 'TERM': 'linux', 18:07:25 INFO - 'TMOUT': '86400', 18:07:25 INFO - 'USER': 'cltbld', 18:07:25 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:25 INFO - '_': '/tools/buildbot/bin/python'} 18:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:07:25 INFO - Running setup.py (path:/tmp/pip-nb13vU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:07:25 INFO - Running setup.py (path:/tmp/pip-sdXczf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:07:25 INFO - Running setup.py (path:/tmp/pip-EhB5iP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:07:25 INFO - Running setup.py (path:/tmp/pip-iSf8qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:07:25 INFO - Running setup.py (path:/tmp/pip-DC9HFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:07:25 INFO - Running setup.py (path:/tmp/pip-KuQQM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:07:25 INFO - Running setup.py (path:/tmp/pip-482Prd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:07:25 INFO - Running setup.py (path:/tmp/pip-Cul56s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:07:25 INFO - Running setup.py (path:/tmp/pip-R3V6IM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:07:25 INFO - Running setup.py (path:/tmp/pip-PH7dSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:07:25 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)) 18:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:07:25 INFO - Running setup.py (path:/tmp/pip-WnPXGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:07:26 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)) 18:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:07:26 INFO - Running setup.py (path:/tmp/pip-qXt4Eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:07:26 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)) 18:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:07:26 INFO - Running setup.py (path:/tmp/pip-sG_Mo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:07:26 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)) 18:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:07:26 INFO - Running setup.py (path:/tmp/pip-QGg8ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:07:26 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)) 18:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:26 INFO - Running setup.py (path:/tmp/pip-utxBBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:26 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)) 18:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:07:26 INFO - Running setup.py (path:/tmp/pip-oTBP_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:07:26 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)) 18:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:07:26 INFO - Running setup.py (path:/tmp/pip-pH6Mq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:07:26 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)) 18:07:26 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)) 18:07:26 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)) 18:07:26 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)) 18:07:26 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)) 18:07:26 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)) 18:07:26 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)) 18:07:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:07:26 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 18:07:26 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 18:07:26 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 18:07:26 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 18:07:27 INFO - Downloading blessings-1.6.tar.gz 18:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:07:27 INFO - Installing collected packages: blessings 18:07:27 INFO - Running setup.py install for blessings 18:07:27 INFO - Successfully installed blessings 18:07:27 INFO - Cleaning up... 18:07:27 INFO - Return code: 0 18:07:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:07:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:07:28 INFO - Reading from file tmpfile_stdout 18:07:28 INFO - Current package versions: 18:07:28 INFO - argparse == 1.2.1 18:07:28 INFO - blessings == 1.6 18:07:28 INFO - blobuploader == 1.2.4 18:07:28 INFO - docopt == 0.6.1 18:07:28 INFO - functools32 == 3.2.3-2 18:07:28 INFO - jsonschema == 2.5.1 18:07:28 INFO - manifestparser == 1.1 18:07:28 INFO - mozInstall == 1.13 18:07:28 INFO - mozcrash == 1.0 18:07:28 INFO - mozdebug == 0.1 18:07:28 INFO - mozdevice == 0.51 18:07:28 INFO - mozfile == 1.2 18:07:28 INFO - mozhttpd == 0.7 18:07:28 INFO - mozinfo == 0.10 18:07:28 INFO - mozleak == 0.1 18:07:28 INFO - mozlog == 3.5 18:07:28 INFO - moznetwork == 0.27 18:07:28 INFO - mozprocess == 0.25 18:07:28 INFO - mozprofile == 0.28 18:07:28 INFO - mozrunner == 6.13 18:07:28 INFO - mozscreenshot == 0.1 18:07:28 INFO - mozsystemmonitor == 0.3 18:07:28 INFO - moztest == 0.8 18:07:28 INFO - mozversion == 1.4 18:07:28 INFO - psutil == 3.1.1 18:07:28 INFO - requests == 1.2.3 18:07:28 INFO - wsgiref == 0.1.2 18:07:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:28 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')]} 18:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:28 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')]} 18:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:28 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:28 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 18:07:28 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 18:07:28 INFO - Using env: {'DISPLAY': ':0', 18:07:28 INFO - 'HOME': '/home/cltbld', 18:07:28 INFO - 'LANG': 'en_US.UTF-8', 18:07:28 INFO - 'LANGUAGE': 'en_US:en', 18:07:28 INFO - 'LOGNAME': 'cltbld', 18:07:28 INFO - 'MAIL': '/var/mail/cltbld', 18:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:28 INFO - 'MOZ_NO_REMOTE': '1', 18:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:28 INFO - 'NO_EM_RESTART': '1', 18:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:28 INFO - 'PWD': '/builds/slave/test', 18:07:28 INFO - 'SHELL': '/bin/bash', 18:07:28 INFO - 'SHLVL': '1', 18:07:28 INFO - 'TERM': 'linux', 18:07:28 INFO - 'TMOUT': '86400', 18:07:28 INFO - 'USER': 'cltbld', 18:07:28 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:28 INFO - '_': '/tools/buildbot/bin/python'} 18:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:28 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)) 18:07:28 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)) 18:07:28 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)) 18:07:28 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)) 18:07:28 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)) 18:07:28 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)) 18:07:28 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)) 18:07:28 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)) 18:07:28 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)) 18:07:28 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:07:28 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 18:07:28 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 18:07:28 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 18:07:28 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 18:07:29 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:07:29 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 18:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:07:29 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)) 18:07:29 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)) 18:07:29 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)) 18:07:29 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)) 18:07:29 INFO - Installing collected packages: marionette-driver, simplejson 18:07:29 INFO - Running setup.py install for marionette-driver 18:07:29 INFO - Running setup.py install for simplejson 18:07:29 INFO - building 'simplejson._speedups' extension 18:07:29 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 18:07:30 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 18:07:30 INFO - Successfully installed marionette-driver simplejson 18:07:30 INFO - Cleaning up... 18:07:30 INFO - Return code: 0 18:07:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:07: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')]} 18:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07: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')]} 18:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:30 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 0x275f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad0b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', '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 18:07:30 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 18:07:30 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 18:07:30 INFO - Using env: {'DISPLAY': ':0', 18:07:30 INFO - 'HOME': '/home/cltbld', 18:07:30 INFO - 'LANG': 'en_US.UTF-8', 18:07:30 INFO - 'LANGUAGE': 'en_US:en', 18:07:30 INFO - 'LOGNAME': 'cltbld', 18:07:30 INFO - 'MAIL': '/var/mail/cltbld', 18:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:30 INFO - 'MOZ_NO_REMOTE': '1', 18:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:30 INFO - 'NO_EM_RESTART': '1', 18:07:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:30 INFO - 'PWD': '/builds/slave/test', 18:07:30 INFO - 'SHELL': '/bin/bash', 18:07:30 INFO - 'SHLVL': '1', 18:07:30 INFO - 'TERM': 'linux', 18:07:30 INFO - 'TMOUT': '86400', 18:07:30 INFO - 'USER': 'cltbld', 18:07:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:30 INFO - '_': '/tools/buildbot/bin/python'} 18:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:07:31 INFO - Cleaning up... 18:07:31 INFO - Return code: 0 18:07:31 INFO - Running post-action listener: _resource_record_post_action 18:07:31 INFO - Running post-action listener: _start_resource_monitoring 18:07:31 INFO - Starting resource monitoring. 18:07:31 INFO - [mozharness: 2017-07-18 01:07:31.046630Z] Finished create-virtualenv step (success) 18:07:31 INFO - [mozharness: 2017-07-18 01:07:31.047137Z] Running install step. 18:07:31 INFO - Running pre-action listener: _resource_record_pre_action 18:07:31 INFO - Running main action method: install 18:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:07:31 INFO - Reading from file tmpfile_stdout 18:07:31 INFO - Detecting whether we're running mozinstall >=1.0... 18:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:07:31 INFO - Reading from file tmpfile_stdout 18:07:31 INFO - Output received: 18:07:31 INFO - Usage: mozinstall [options] installer 18:07:31 INFO - Options: 18:07:31 INFO - -h, --help show this help message and exit 18:07:31 INFO - -d DEST, --destination=DEST 18:07:31 INFO - Directory to install application into. [default: 18:07:31 INFO - "/builds/slave/test"] 18:07:31 INFO - --app=APP Application being installed. [default: firefox] 18:07:31 INFO - mkdir: /builds/slave/test/build/application 18:07:31 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'] 18:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:07:49 INFO - Reading from file tmpfile_stdout 18:07:49 INFO - Output received: 18:07:49 INFO - /builds/slave/test/build/application/firefox/firefox 18:07:49 INFO - Running post-action listener: _resource_record_post_action 18:07:49 INFO - [mozharness: 2017-07-18 01:07:49.167766Z] Finished install step (success) 18:07:49 INFO - [mozharness: 2017-07-18 01:07:49.168022Z] Running setup-mitmproxy step. 18:07:49 INFO - Running pre-action listener: _resource_record_pre_action 18:07:49 INFO - Running main action method: setup_mitmproxy 18:07:49 INFO - Skipping: mitmproxy is not required 18:07:49 INFO - Running post-action listener: _resource_record_post_action 18:07:49 INFO - [mozharness: 2017-07-18 01:07:49.169153Z] Finished setup-mitmproxy step (success) 18:07:49 INFO - [mozharness: 2017-07-18 01:07:49.169391Z] Running run-tests step. 18:07:49 INFO - Running pre-action listener: _resource_record_pre_action 18:07:49 INFO - Running pre-action listener: _set_gcov_prefix 18:07:49 INFO - Running main action method: run_tests 18:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:07:49 INFO - Python 2.7.3 18:07:49 INFO - Return code: 0 18:07:49 INFO - grabbing minidump binary from tooltool 18:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:49 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 0x2acdd00>, '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 0x2acec00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2acf090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:07:49 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 18:07:49 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 18:07:49 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 18:07:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:07:49 INFO - Return code: 0 18:07:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:07:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:07:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:07:49 INFO - ENV: RUST_BACKTRACE is now 1 18:07:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:07:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:07:49 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/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 18:07:49 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/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 18:07:49 INFO - Using env: {'DISPLAY': ':0', 18:07:49 INFO - 'HOME': '/home/cltbld', 18:07:49 INFO - 'LANG': 'en_US.UTF-8', 18:07:49 INFO - 'LANGUAGE': 'en_US:en', 18:07:49 INFO - 'LOGNAME': 'cltbld', 18:07:49 INFO - 'MAIL': '/var/mail/cltbld', 18:07:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:07:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:49 INFO - 'MOZ_NO_REMOTE': '1', 18:07:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:07:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:49 INFO - 'NO_EM_RESTART': '1', 18:07:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:49 INFO - 'PWD': '/builds/slave/test', 18:07:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:07:49 INFO - 'RUST_BACKTRACE': '1', 18:07:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:07:49 INFO - 'SHELL': '/bin/bash', 18:07:49 INFO - 'SHLVL': '1', 18:07:49 INFO - 'TERM': 'linux', 18:07:49 INFO - 'TMOUT': '86400', 18:07:49 INFO - 'USER': 'cltbld', 18:07:49 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157', 18:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:49 INFO - '_': '/tools/buildbot/bin/python'} 18:07:49 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/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 18:07:49 INFO - mozversion application_buildid: 20170718000101 18:07:49 INFO - mozversion application_changeset: efc0b1525edbd357818dc7195537364e76f709e7 18:07:49 INFO - mozversion application_display_name: Nightly 18:07:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:07:49 INFO - mozversion application_name: Firefox 18:07:49 INFO - mozversion application_remotingname: firefox 18:07:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:07:49 INFO - mozversion application_vendor: Mozilla 18:07:49 INFO - mozversion application_version: 56.0a1 18:07:49 INFO - mozversion platform_buildid: 20170718000101 18:07:49 INFO - mozversion platform_changeset: efc0b1525edbd357818dc7195537364e76f709e7 18:07:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:07:49 INFO - mozversion platform_version: 56.0a1 18:07:49 INFO - using testdate: 1500340069 18:07:49 INFO - actual date: 1500340069 18:07:49 INFO - starting webserver on 'localhost:40691' 18:07:49 INFO - SUITE-START | Running 2 tests 18:07:49 INFO - TEST-START | dromaeo_css 18:07:49 INFO - Initialising browser for dromaeo_css test... 18:07:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40691/getInfo.html -profile /tmp/tmpp7Kpfr/profile 18:07:49 INFO - TEST-INFO | started process 924 (/builds/slave/test/build/application/firefox/firefox http://localhost:40691/getInfo.html) 18:07:54 INFO - TEST-INFO | 924: exit 0 18:07:54 INFO - Browser initialized. 18:07:54 INFO - Running cycle 1/1 for dromaeo_css test... 18:07:54 INFO - TEST-INFO | started process 1146 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp7Kpfr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:08:06 INFO - PID 1146 | RSS: Main: 160235520 18:08:06 INFO - PID 1146 | 18:11:03 INFO - PID 1146 | Cycle 1(1): loaded http://localhost:40691/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40691/tests/dromaeo/cssquery-ext.html) 18:11:04 INFO - PID 1146 | RSS: Main: 168611840 18:11:04 INFO - PID 1146 | 18:14:06 INFO - PID 1146 | Cycle 1(1): loaded http://localhost:40691/tests/dromaeo/cssquery-ext.html (next: http://localhost:40691/tests/dromaeo/cssquery-jquery.html) 18:14:07 INFO - PID 1146 | RSS: Main: 167370752 18:14:07 INFO - PID 1146 | 18:17:08 INFO - PID 1146 | Cycle 1(1): loaded http://localhost:40691/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40691/tests/dromaeo/cssquery-mootools.html) 18:17:09 INFO - PID 1146 | RSS: Main: 169693184 18:17:09 INFO - PID 1146 | 18:20:10 INFO - PID 1146 | Cycle 1(1): loaded http://localhost:40691/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40691/tests/dromaeo/cssquery-prototype.html) 18:20:11 INFO - PID 1146 | RSS: Main: 168058880 18:20:11 INFO - PID 1146 | 18:23:14 INFO - PID 1146 | Cycle 1(1): loaded http://localhost:40691/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40691/tests/dromaeo/cssquery-yui.html) 18:23:14 INFO - PID 1146 | RSS: Main: 167428096 18:23:14 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | Cycle 1(1): loaded http://localhost:40691/tests/dromaeo/cssquery-yui.html (next: http://localhost:40691/tests/dromaeo/cssquery-dojo.html) 18:26:16 INFO - PID 1146 | RSS: Main: 171233280 18:26:16 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | __start_tp_report 18:26:16 INFO - PID 1146 | _x_x_mozilla_page_load 18:26:16 INFO - PID 1146 | _x_x_mozilla_page_load_details 18:26:16 INFO - PID 1146 | |i|pagename|runs| 18:26:16 INFO - PID 1146 | |0;dojo.html;3284.244623736542;3407.9488807667885;3436.158141255393;3445.190380260639;3474.148833535784;5977.432143946318;5990.880182396355;6011.519078473722;6051.12258722485;6064.393560643934;6007.609505382147;6053.031514957607;6087.665178415196;6098.4511393974535;6115.113308570255;5352.063388906945;5368.32895888014;5370.489803468674;5376.435474275204;5389.83830485086;5426.810061647843;5429.158480435539;5431.2396264522995;5431.321084864392;5451.945480545203;5428.348598168225;5431.266778984826;5432.511074003339;5435.565154787608;5435.9943410468995;5420.051490989061;5430.864228394283;5433.320834895638;5437.646552974044;5442.102053161204;5344.812931547377;5386.703731294777;5469.15228139639;5478.506934375925;5578.693171875545;6268.780592679234;6277.372262773732;6279.529035322324;6318.557700960934;6364.745410183587;6343.8731225375695;6752;6774.390304872536;6836.1113055302985;6875.209350924658;6575.539712220147;6586.538942274043;6589.901151482732;6600.339966003409;6601.801945941629;6040.244969378828;6050.971334873038;6069.635821850703;6090.056041313604;6102.72537735803;6703.329667033355;6724.025016372599;6941.514094013371;6943.826404339831;7039.92960070403;6602.669866506655;6662.83342916431;6666.600003999775;6753.729850805913;6763.154605674291;6791.762288319984;6798.864022719518;6806.081179040865;6887;6896.482763792736;6456.451201647913;6491.480681545465;6508.088867558546;6550.934490655128;6574.5397822152945;5629.4652008059165;6558.213014438298;6687.331266873273;6766.28953959837;6782.423494002966;5015.247712843103;5066.037452884014;5071.96024814912;5074.67014644047;5075.441701412916;4328.199283132631;4400.471943366752;4403.251447253974;4406.537313582027;4409.426774519292;6085.6348619083;6093.542984276109;6095.603785753912;6099.146119543268;6108.694565271719;6488.772892948724;6490.967545162244;6494.902576461357;6505.024246362988;6516.446102081376;6004.519638428916;6053.45518903292;6053.4551890330085;6065.787697430569;6066.878662426727;5442.210879422461;5520.227168196456;5522;5551.111822108524;5603.355614104348;6124.601900876429;6701.262861085277;7150.070490836158;7248.057752492142;7249.202587715347;6814.420774234147;6841.863162736618;6843.00776387411;6924.515283930076;6930.3762661361725;7139.107611548557;7169.318914703095;7171.677274522554;7236.96381518089;7240.348368646847;6370.649614271074;6490.3185165558825;6504;6519.5436319457185;6835.043093966749;5699.437851342911;6537.607743535403;6808.82977925536;6836.794896153124;6925.89611155823;6579.901301480386;6588.736450541925;6640.800775976729;6661.067450557023;6662.56693314933;6498.545101842826;6566.212054553484;6641.169853768279;6657.4674026078765;6671.232808227116;6327.082573026977;6395.840103997437;6399.168108145911;6404.039394090737;6406.167198264196;6302.873942521032;6359.3640635936035;6380.393862583047;6404.455621272058;6411.294757576569;5285.947140528546;6411.230652321751;6454.870902582016;6486.481081513562;6540.411362977355 18:26:16 INFO - PID 1146 | |1;ext.html;776.5884081436841;816.8938038055053;831.7546323834468;839.4711331860926;1165.7027457998208;31866.840665796666;32550.837245813796;32641.836790816073;40985;42147;41406;41587;41660.166796664045;41792;41871.162576748444;7208.387287080604;7261;7341.302576255247;7348.3753880920185;7389;6844.9657751711175;6876.553024053421;6893.276205998373;6929.896051559231;6944.236134025253;6906.378425941664;6931.861362772766;7226.56640601563;7385.29839665231;7472.17806041335;7327.633618319062;7395.445341599348;7396.297351751575;7414.740484083031;7468.813279667997;7196.748113816044;7281.344678978918;7309.744158954464;7311.049563556757;7355.595442250674;38723.8063809682;38950.61049389498;38956.220875582614;39334.60665393367;39342;3000;3044.9543256851166;3046.3450358172877;3055.8319292438905;3058.5565093061605;31019.689803101905;31030;31070;31211;31239.375212495852;4793.640476964241;4802.4957379475;4836.806527738886;4863.17326054568;4930.778114984834;204193;204822;249226;251326;254131;49813;50079.49920500749;50235.24647130296;50335;50430.47847607605;51818;51950.22074668883;52111.739441302554;52161.47838521566;52354.47645523572;16639;16642.251098700344;16688.74966875498;16763.91618041902;16785.916070419815;13498.460061597623;13605.795913061313;13632.113912595649;13661.521846735317;13732.45070197201;14496.275186240646;14564.781528277084;14609.415623374945;14760.630984225265;14835.777463338058;5826.242588463472;5853.765849365977;5902.439268269508;5982.970085149546;6011.819645410645;1008.6318493749728;1008.7326858382535;1027.5992362978445;1032.4270030133198;1034.1933292032227;22718.659220111713;22998.310050698507;23246.883765581064;23639.645405318934;23646.527069458516;1098.609993452313;1103.7957977774138;1123.168855047259;1133.8696049954196;1135.7103938495632;12149.878501214875;12486.687832804071;12501.624951251477;12665.683357915941;12696;259971;262214;262427;263792;264044;52856.47143528515;53683.731581342625;53750.731246343516;53767.462325377026;54024.729876350364;1368.7604669182854;1370.4244217428704;1376.800363947222;1378.0835744230176;1521.4522771802142;27181.86409068021;29129;29591.704082959757;29925;30017.5497367544;51743.741281293354;51745;52312.476875232285;52432;52677.7366113167;238288;241134;241345;241858;241873;35161;35202.47196292109;35236;35432;35436.291274173855;40828.38757418701;42971.78514107409;43110.78444607757;43119.13761724685;44046;31209.8439507801;32487.83756081299;32493.18767030843;32680;32692.83653581717;31710.04869853908;32205;32398.35203295968;32701.182470437478;32751.836240818644;36542.63457365392;36811.63188368189;37342.8132859334;37673.246535069695;37729;30432.32635585094;30511.926990244447;31243.062708118792;31641.525377119822;31845.681543184273;23485.295441136794;24324.27027189187;24817.1314004009;25189.370265743502;25597.23208303754 18:26:16 INFO - PID 1146 | |2;jquery.html;556.7443334918368;565.5758181363977;568.7128000359818;569.4305694305694;585.8301092683122;22024.449388765268;22899.31302060941;23072.42318942027;23123.07507699692;23178.536429271448;25838.35404114898;27736;27778;27885.302867428272;28160.718392816067;6040.063790112528;6132.938670613281;6173.845653858644;6183.907241391383;6198.2252218472695;4180.435641188446;5911.320198177222;5953.493953013999;5983.042713165894;6011.338752737195;4353.956460435386;5801.390853960315;6151.261848578177;6186.443220110189;6198.938010619881;6148.508119350441;6179.227596550431;6179.907301390483;6200.627962322275;6202.379762023762;5776.624519406225;5788.392218817048;5792.131180322943;5802.100674395476;5803.187553742479;29169;30749;31222.37555248905;31238.21904452384;31472.213194670316;2082.6251274770534;2094.4030951178843;2094.5915546468445;2097.916083356664;2102.653062244743;19202.615947681108;19500.122494487783;19518.707219391577;19593.706094408597;19711.408657740292;2233.6761169630477;2266.331432227497;2267.489814791675;2284.680144779732;2367.940801479977;140335.29832350876;145955.81066284014;147268;147286.26356868146;147704;15036;15299.770503442458;15357.6160595986;15724.921375393049;15742.92128539373;24138.758612414003;24221.394465138514;24229.63655545168;24235.151769687975;24310.270691879272;10499.370037797757;10675.092617127631;10927.398993055458;10965.50655220517;11084.168687348481;10054.843692975255;10197.694069177936;10224.744381390376;10233.516140159636;10268.64059757905;9134.771630709285;9319.627214911328;9554.378965367368;9803.362781419184;9910.405375677483;2759.9724002759717;2790.2745286225727;2794.1058861164233;2848.5584734366207;2890.7542858856814;932.911373419524;937.5874615169304;945.5650400815701;981.3326937682444;1005.9044390782864;20482.79517204809;21155.47111322229;21377.03803328854;21377.893110534347;21397.78602213958;1031.7523794289466;1048.9895101048892;1065.6909496246158;1069.534752382716;1075.930064545802;11080.612178573872;11233.66299011031;11266.43667816606;11490.023348015346;11681.240719353214;581309;584961;594998;604808;607606;134920;138133;143537;144672.27663861614;144954;1310.7640624687458;1314.4676406055562;1331.4407948661585;1347.4677502386699;1360.177092858826;22679;22860.31419057431;23768.643470348303;23897.641535376635;24076.157334493208;62000;62648.373516266074;63415.365846340945;63701;64685.67657161684;75102.62448687722;76309;76412;76472.61763691145;76752.23247767451;15343.539693809204;15411.845881541041;15684.529464116094;15706.97904636195;15857.920710396374;24897;24960.126395576066;25064.498710026062;25377.87311063433;25624;19972.70040949416;20047.799522004592;20155.69766453475;20321.695174572687;20346.08442620056;17163.656726865644;17347.479575612753;17355.2190151446;17528.91235543814;18583.34958276454;17961.910190449486;18696.53258668544;18887;18928.43214703561;19064.714029289848;15557.688846223238;15649.452269170524;15657.686846262783;15660.216989150726;15755.606109847346;19176.520586985436;20389.49026274355;20536.28123015747;20545.48636284105;20587.07358168856 18:26:16 INFO - PID 1146 | |3;mootools.html;1474.7861560073788;1531.5022617649267;1537;1539.8537138971801;1555.5333399980004;15009.924950375247;15678.372865085412;15711.45009924653;15720.528384148494;15737.84262157378;15007.6998460031;15609.609759756011;16260.918695406506;16263.918680406581;16339.18304084797;2550.4516528946274;2610.229982155259;2610.477904419114;2615.6338112664243;2620.489004644095;2596.740325967407;2654.2302732207636;2657.6677915260593;2659.9468010639775;2662.5473669476237;2570.421655127591;2585.276122685651;2594.403287243935;2625.4749050189944;2681.7184195659465;2651.4829608226405;2657.7608015278524;2669.292637451077;2681.1286331942197;2682.8122031457806;2448.2165706973774;2448.6571879936823;2463.4457247119435;2466.3710753757855;2467.0625162438337;14970.62573435662;15161.317740701696;15176.468823591122;15311.693766124727;15339.769903451457;8045.517268963881;8351.039630442456;8380.036295825998;8394.45436046655;8434.198751118634;5207.151461231247;5334.91997620036;5475.0966090595;5516.696581688006;5518.2826232589505;1702.2169801891146;1731.411320151142;1740.8868423552556;1767.1959258537336;1777.2180240694054;26986.730132698813;27260;27385.31536711578;27464.03875864335;27494.312642183904;6800.31996800316;6864.347886950732;6866.828329291808;7004.509684322048;7017.824554386181;18123;18191.63616727658;18204.362847300545;18241.45275641733;18250.81749182518;8064.395170362246;8305.584720763938;8309;8312.54281014538;8323.875141872877;7405.7778266652085;7587.810304742316;7635.274648908345;7662.272084151997;7776.844463110706;5241.50205730455;5446.428125046892;5461.918071228934;5471.343438787371;5473.28847249855;1515.371120984801;1516.082769924202;1527.4119464006214;1539.2073082362592;1541.9691606168037;868.3617541107282;883.8409086364516;886.0873300500494;898.5237823953315;916.1388295002677;9454.48000359973;9741.318107732392;9905.702828915144;9949.377043957844;10064.899351006396;964.1274646444979;985.2167526816198;998.7003898830467;1011.9848202276971;1012.4886932099242;5529.806456774074;5723.6851973141875;5839.737211825478;5866.823995280149;6002.639841609517;21350.679739803916;21421;21911.233106841184;22162.445938851335;22251.44371390696;21089.78910210909;21728.34814955554;22555.548889022455;22660.206892758673;22900;11377.943110284561;11740.354280514483;11826.704332391759;11853.940730296294;11944;8895.555222238732;8961.059088795582;8990.100989900957;9094.454332740057;9190.356675032683;18337.908310458362;18591;18922.621547568695;19465.124069416626;19593.70609440831;18815.59544452384;19516.31692890742;19762;20383.694244586048;20524.179032838518;11485.19603627738;11755.47100380502;11865.466054027762;11877.168598198388;11911.40442977865;13891.51379701746;14290.214038227998;14357.784633230718;14454.638634033812;14658.267086645412;10854.18593605467;11199.552017919192;11301.378424186749;11457.484413201593;11764.764704705687;11219.495122719334;11629.592964246549;11681.06551475897;11771.11716621274;12159.878401216229;9976.62664562414;10232.079112879877;10260.230482713976;10429.26995110335;10488.842667360148;7535.510191837513;7956.84086318282;7993.72021979228;8078.474899131537;8117.8376432472205;7420.220876807857;7465.427351900149;7499.700011999459;7516.887246691194;7548.169701332739 18:26:16 INFO - PID 1146 | |4;prototype.html;493.66184163847765;503.5644167794856;526.373615397677;535.5046581911731;537.7606964900621;5991.490723288521;6075.210222671052;6089.391060893903;6135.018397056472;6153.077038444236;5915.822525324236;5988.490978266853;5998.670073145975;6003.429674180957;6011.158437818698;4744.240921452569;4784.1149387363275;4810.807567697297;4817.7831997560015;4839.467658557556;4798.448178459469;4843.128236917353;4849.345338379327;4870.245112007627;4877.658563900528;4641.303804429345;4703.129920964615;4743.264793956925;4775.928361074587;4805.567498925096;4690.1557719610455;4704.364910737041;4710.505396933324;4802.375691160162;4814.277858321245;4336.349547567858;4367.3448982652535;4368.4102646143;4374.300111982067;4388.29787234041;5974.462298393166;6016.669083200422;6061.848453788646;6064.332923378425;6072.119542666289;5735.942640573624;5772.8845423091725;5774.538036957033;5774.566907481955;5818.301803783573;6536.901946470807;6548.148740663731;6564.606123632598;6619.172603424572;6658.800235992928;4894.681845680019;4899.608031357483;4905.705657660516;4959.231319145538;4976.502349765031;6794.558353706993;6848.6918088686125;6874.8625027499675;6877.140357455318;6879.933610290413;5087.4149472811105;5091.847244582668;5097.502223910332;5107.897842043139;5128.461511541309;5091.389033315952;5095.796168153306;5138.460461651547;5143.868348963222;5155.7679904404395;5662.839117980804;5672.602917795714;5691.430856914359;5768.567357448208;5809;6529.54293199481;6546.443552298013;6553.41019308255;6599.406053455116;6610.140681711347;1766.381766381756;1782.2336395349864;1801.9819801801814;1803.504036389977;1807.8553715702976;1146.48408216303;1173.7828501727208;1242.67069226655;1244.328062846053;1268.8033354830015;6002.759889604367;6085.482733967574;6126.908096378558;6184.443400094013;6198.318184999646;6564.8358791030605;6635.834104147435;6677.833054173588;6682.699278532477;6717.261101278856;6189.3076511717545;6202.844928876814;6204.81385558434;6240.032794916704;6245.437910588069;3461.8442170102408;3470.965290347064;3502.9124271892897;3535.6994655454573;3538.292341531704;6923.584584924921;6955.582665040114;7090.858182836314;7096.964515177391;7111;6786.151731033621;6834.163822013908;6898.413634840995;6921.861562768717;6953;6994.6852391643515;7025.754098606524;7038.683259253346;7122.964385178145;7140.750073747394;6604.768833090819;6664.500162487736;6685.6322902240845;6686.933130668631;6700.229473610597;6649.966750166218;6654.900176497454;6669.399754022162;6681.262871653308;6740.135442437043;7032.613206273704;7067.57594544329;7089.397401220747;7096.53872498286;7120.572765634079;5526.751296191601;5532.557395408438;5551.722413879209;5559.775330308492;5561.694106824163;6695;6719.932800672126;6811.523193376416;6817.7272909083085;6840.965795170992;6481.319461456479;6504.349565043458;6513.8697226056165;6538.607683538813;6546.116274302908;6446.580972236789;6483.935160648333;6522.902156467562;6555.967220163869;6633.734650613921;6453.838654033687;6463.7737679181;6504.934950650433;6557.147570815763;6560.212774467094;6533.771318004037;6613.14029176204;6613.735450581923;6644.169478815148;6648.90026649591;6175.4750846176785;6335.873282534416;6409.166808314889;6446.8388290293115;6472.967635161794 18:26:16 INFO - PID 1146 | |5;yui.html;873.4584557574304;878.218385636783;891.4918496457018;896.3053633434088;896.8206358728256;18346.90826545867;18546.53633659159;18641.254349826024;18653.720194197096;18676.53308667284;19995.60008799823;21633.56732865342;21746.456338591543;21884.562308753815;21918.23286184984;1043.7808060307325;1082.182951871336;1087.429099722648;1087.526925787281;1088.7985722641338;811.6753298680545;1068.3910171202417;1072.0458791675414;1073.1522097542932;1073.9194560407961;913.7258822353301;922.4373132389237;1097.3470784882982;1140.5437824870035;1140.766142940695;1137.795196864564;1139.0659659079558;1140.8573928258968;1142.8114361130445;1144.7481554058095;827.3546633625722;830.7383174300077;834.6244190114472;835.5989125219868;836.0552575589605;18596.163172657263;19527.80472195288;20251.29120480791;20376.083076261457;20554.177832886668;19927;20193;20491.59016819655;20805.479863003548;20837.16651333945;14703.779443308358;14796.594323539353;14825.629359266104;14944.252787360589;15000.924995374953;3056.9541456878164;3062.846857657108;3070.5547695584237;3079.2455848317304;3092.690730926912;126388;130145;131598.34200828936;133024.33487832596;135040.32479837636;14178.291085445686;14918.925405372904;15322.540323790305;15365.078095314317;15435.15106669144;29919.443043285042;30756.384872302428;30782.53826192609;30865.8456707715;31124.221894452367;8212.260896519341;8305.501669899826;8347.123552027071;8380.16198380157;8439.493630382196;3854.5759966403675;3862.9806850965565;3874.748141370802;3910.687145028391;3980.800959951991;5204.4535323791215;5231.947680523146;5349.866253343697;5408.296921400193;5465.016297066566;957.262907561174;997.536145692242;999.2006394884064;1004.3572113847143;1010.8786945566579;699.7166147710185;713.7894321175265;716.469812338876;724.4132252875188;729.4674887332277;12981;13265.734685306239;13341.332933353293;13826.446942122404;14110.788338174936;672.9091572637632;697.8046147078826;700.4921431961787;701.312713540733;704.661762354073;6075.787347442818;6161.106639537242;6265.4361107499635;6342.334054924258;6395;125909.37045314898;126208;130133;132174;133494;114208;114779.42610286895;116004.41997789957;117265.41367293108;120702;94630.5268473667;95817;97419;97719.51140244395;99594;12110;13106.868931310946;13857.930710346383;13878;14054.227017514135;44277.77861110674;46631.766841165576;46821.53178468172;48484.81818318114;49242;74807;77202.6139869297;78700.60649696714;81277;83377.58311208404;19424.320148794723;19488.22047118156;19516;19538.706919396504;19590.60818783645;22170.556588868454;23367.88316058409;23473.882630586737;23477.76522234824;23576.410589735395;20664;20899.68650470214;21252.14991400327;21609.891950540146;21642.026108825456;19584.510387240432;20815.687764683844;20963.161473540888;21126.366209013755;21349;20350;20555.8972205138;20989.055492502568;21020.47448813792;21061.684074738583;13590.728185436434;14485.203313817841;14489.70434262344;14557.199354035629;14793.186374749062;15318.387264509294;15424.768628470358;15573.299201535732;16244.51266462008;16291.511254662379 18:26:16 INFO - PID 1146 | __end_tp_report 18:26:16 INFO - PID 1146 | __start_cc_report 18:26:16 INFO - PID 1146 | _x_x_mozilla_cycle_collect,306 18:26:16 INFO - PID 1146 | __end_cc_report 18:26:16 INFO - PID 1146 | __startTimestamp1500341176587__endTimestamp 18:26:16 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | ------- Summary: start ------- 18:26:16 INFO - PID 1146 | Number of tests: 6 18:26:16 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | [#0] dojo.html Cycles:165 Average:6109.49 Median:6364.75 stddev:799.25 (12.6%) stddev-sans-first:770.36 18:26:16 INFO - PID 1146 | Values: 3284.2 3407.9 3436.2 3445.2 3474.1 5977.4 5990.9 6011.5 6051.1 6064.4 6007.6 6053.0 6087.7 6098.5 6115.1 5352.1 5368.3 5370.5 5376.4 5389.8 5426.8 5429.2 5431.2 5431.3 5451.9 5428.3 5431.3 5432.5 5435.6 5436.0 5420.1 5430.9 5433.3 5437.6 5442.1 5344.8 5386.7 5469.2 5478.5 5578.7 6268.8 6277.4 6279.5 6318.6 6364.7 6343.9 6752.0 6774.4 6836.1 6875.2 6575.5 6586.5 6589.9 6600.3 6601.8 6040.2 6051.0 6069.6 6090.1 6102.7 6703.3 6724.0 6941.5 6943.8 7039.9 6602.7 6662.8 6666.6 6753.7 6763.2 6791.8 6798.9 6806.1 6887.0 6896.5 6456.5 6491.5 6508.1 6550.9 6574.5 5629.5 6558.2 6687.3 6766.3 6782.4 5015.2 5066.0 5072.0 5074.7 5075.4 4328.2 4400.5 4403.3 4406.5 4409.4 6085.6 6093.5 6095.6 6099.1 6108.7 6488.8 6491.0 6494.9 6505.0 6516.4 6004.5 6053.5 6053.5 6065.8 6066.9 5442.2 5520.2 5522.0 5551.1 5603.4 6124.6 6701.3 7150.1 7248.1 7249.2 6814.4 6841.9 6843.0 6924.5 6930.4 7139.1 7169.3 7171.7 7237.0 7240.3 6370.6 6490.3 6504.0 6519.5 6835.0 5699.4 6537.6 6808.8 6836.8 6925.9 6579.9 6588.7 6640.8 6661.1 6662.6 6498.5 6566.2 6641.2 6657.5 6671.2 6327.1 6395.8 6399.2 6404.0 6406.2 6302.9 6359.4 6380.4 6404.5 6411.3 5285.9 6411.2 6454.9 6486.5 6540.4 18:26:16 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | [#1] ext.html Cycles:180 Average:41447.03 Median:32686.42 stddev:64051.18 (196.0%) stddev-sans-first:64158.06 18:26:16 INFO - PID 1146 | Values: 776.6 816.9 831.8 839.5 1165.7 31866.8 32550.8 32641.8 40985.0 42147.0 41406.0 41587.0 41660.2 41792.0 41871.2 7208.4 7261.0 7341.3 7348.4 7389.0 6845.0 6876.6 6893.3 6929.9 6944.2 6906.4 6931.9 7226.6 7385.3 7472.2 7327.6 7395.4 7396.3 7414.7 7468.8 7196.7 7281.3 7309.7 7311.0 7355.6 38723.8 38950.6 38956.2 39334.6 39342.0 3000.0 3045.0 3046.3 3055.8 3058.6 31019.7 31030.0 31070.0 31211.0 31239.4 4793.6 4802.5 4836.8 4863.2 4930.8 204193.0 204822.0 249226.0 251326.0 254131.0 49813.0 50079.5 50235.2 50335.0 50430.5 51818.0 51950.2 52111.7 52161.5 52354.5 16639.0 16642.3 16688.7 16763.9 16785.9 13498.5 13605.8 13632.1 13661.5 13732.5 14496.3 14564.8 14609.4 14760.6 14835.8 5826.2 5853.8 5902.4 5983.0 6011.8 1008.6 1008.7 1027.6 1032.4 1034.2 22718.7 22998.3 23246.9 23639.6 23646.5 1098.6 1103.8 1123.2 1133.9 1135.7 12149.9 12486.7 12501.6 12665.7 12696.0 259971.0 262214.0 262427.0 263792.0 264044.0 52856.5 53683.7 53750.7 53767.5 54024.7 1368.8 1370.4 1376.8 1378.1 1521.5 27181.9 29129.0 29591.7 29925.0 30017.5 51743.7 51745.0 52312.5 52432.0 52677.7 238288.0 241134.0 241345.0 241858.0 241873.0 35161.0 35202.5 35236.0 35432.0 35436.3 40828.4 42971.8 43110.8 43119.1 44046.0 31209.8 32487.8 32493.2 32680.0 32692.8 31710.0 32205.0 32398.4 32701.2 32751.8 36542.6 36811.6 37342.8 37673.2 37729.0 30432.3 30511.9 31243.1 31641.5 31845.7 23485.3 24324.3 24817.1 25189.4 25597.2 18:26:16 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | [#2] jquery.html Cycles:180 Average:39492.86 Median:12481.84 stddev:99770.50 (799.3%) stddev-sans-first:100007.55 18:26:16 INFO - PID 1146 | Values: 556.7 565.6 568.7 569.4 585.8 22024.4 22899.3 23072.4 23123.1 23178.5 25838.4 27736.0 27778.0 27885.3 28160.7 6040.1 6132.9 6173.8 6183.9 6198.2 4180.4 5911.3 5953.5 5983.0 6011.3 4354.0 5801.4 6151.3 6186.4 6198.9 6148.5 6179.2 6179.9 6200.6 6202.4 5776.6 5788.4 5792.1 5802.1 5803.2 29169.0 30749.0 31222.4 31238.2 31472.2 2082.6 2094.4 2094.6 2097.9 2102.7 19202.6 19500.1 19518.7 19593.7 19711.4 2233.7 2266.3 2267.5 2284.7 2367.9 140335.3 145955.8 147268.0 147286.3 147704.0 15036.0 15299.8 15357.6 15724.9 15742.9 24138.8 24221.4 24229.6 24235.2 24310.3 10499.4 10675.1 10927.4 10965.5 11084.2 10054.8 10197.7 10224.7 10233.5 10268.6 9134.8 9319.6 9554.4 9803.4 9910.4 2760.0 2790.3 2794.1 2848.6 2890.8 932.9 937.6 945.6 981.3 1005.9 20482.8 21155.5 21377.0 21377.9 21397.8 1031.8 1049.0 1065.7 1069.5 1075.9 11080.6 11233.7 11266.4 11490.0 11681.2 581309.0 584961.0 594998.0 604808.0 607606.0 134920.0 138133.0 143537.0 144672.3 144954.0 1310.8 1314.5 1331.4 1347.5 1360.2 22679.0 22860.3 23768.6 23897.6 24076.2 62000.0 62648.4 63415.4 63701.0 64685.7 75102.6 76309.0 76412.0 76472.6 76752.2 15343.5 15411.8 15684.5 15707.0 15857.9 24897.0 24960.1 25064.5 25377.9 25624.0 19972.7 20047.8 20155.7 20321.7 20346.1 17163.7 17347.5 17355.2 17528.9 18583.3 17961.9 18696.5 18887.0 18928.4 19064.7 15557.7 15649.5 15657.7 15660.2 15755.6 19176.5 20389.5 20536.3 20545.5 20587.1 18:26:16 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | [#3] mootools.html Cycles:180 Average:9660.81 Median:2466.72 stddev:6880.37 (278.9%) stddev-sans-first:6872.18 18:26:16 INFO - PID 1146 | Values: 1474.8 1531.5 1537.0 1539.9 1555.5 15009.9 15678.4 15711.5 15720.5 15737.8 15007.7 15609.6 16260.9 16263.9 16339.2 2550.5 2610.2 2610.5 2615.6 2620.5 2596.7 2654.2 2657.7 2659.9 2662.5 2570.4 2585.3 2594.4 2625.5 2681.7 2651.5 2657.8 2669.3 2681.1 2682.8 2448.2 2448.7 2463.4 2466.4 2467.1 14970.6 15161.3 15176.5 15311.7 15339.8 8045.5 8351.0 8380.0 8394.5 8434.2 5207.2 5334.9 5475.1 5516.7 5518.3 1702.2 1731.4 1740.9 1767.2 1777.2 26986.7 27260.0 27385.3 27464.0 27494.3 6800.3 6864.3 6866.8 7004.5 7017.8 18123.0 18191.6 18204.4 18241.5 18250.8 8064.4 8305.6 8309.0 8312.5 8323.9 7405.8 7587.8 7635.3 7662.3 7776.8 5241.5 5446.4 5461.9 5471.3 5473.3 1515.4 1516.1 1527.4 1539.2 1542.0 868.4 883.8 886.1 898.5 916.1 9454.5 9741.3 9905.7 9949.4 10064.9 964.1 985.2 998.7 1012.0 1012.5 5529.8 5723.7 5839.7 5866.8 6002.6 21350.7 21421.0 21911.2 22162.4 22251.4 21089.8 21728.3 22555.5 22660.2 22900.0 11377.9 11740.4 11826.7 11853.9 11944.0 8895.6 8961.1 8990.1 9094.5 9190.4 18337.9 18591.0 18922.6 19465.1 19593.7 18815.6 19516.3 19762.0 20383.7 20524.2 11485.2 11755.5 11865.5 11877.2 11911.4 13891.5 14290.2 14357.8 14454.6 14658.3 10854.2 11199.6 11301.4 11457.5 11764.8 11219.5 11629.6 11681.1 11771.1 12159.9 9976.6 10232.1 10260.2 10429.3 10488.8 7535.5 7956.8 7993.7 8078.5 8117.8 7420.2 7465.4 7499.7 7516.9 7548.2 18:26:16 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | [#4] prototype.html Cycles:180 Average:5570.72 Median:6130.96 stddev:1593.09 (26.0%) stddev-sans-first:1551.32 18:26:16 INFO - PID 1146 | Values: 493.7 503.6 526.4 535.5 537.8 5991.5 6075.2 6089.4 6135.0 6153.1 5915.8 5988.5 5998.7 6003.4 6011.2 4744.2 4784.1 4810.8 4817.8 4839.5 4798.4 4843.1 4849.3 4870.2 4877.7 4641.3 4703.1 4743.3 4775.9 4805.6 4690.2 4704.4 4710.5 4802.4 4814.3 4336.3 4367.3 4368.4 4374.3 4388.3 5974.5 6016.7 6061.8 6064.3 6072.1 5735.9 5772.9 5774.5 5774.6 5818.3 6536.9 6548.1 6564.6 6619.2 6658.8 4894.7 4899.6 4905.7 4959.2 4976.5 6794.6 6848.7 6874.9 6877.1 6879.9 5087.4 5091.8 5097.5 5107.9 5128.5 5091.4 5095.8 5138.5 5143.9 5155.8 5662.8 5672.6 5691.4 5768.6 5809.0 6529.5 6546.4 6553.4 6599.4 6610.1 1766.4 1782.2 1802.0 1803.5 1807.9 1146.5 1173.8 1242.7 1244.3 1268.8 6002.8 6085.5 6126.9 6184.4 6198.3 6564.8 6635.8 6677.8 6682.7 6717.3 6189.3 6202.8 6204.8 6240.0 6245.4 3461.8 3471.0 3502.9 3535.7 3538.3 6923.6 6955.6 7090.9 7097.0 7111.0 6786.2 6834.2 6898.4 6921.9 6953.0 6994.7 7025.8 7038.7 7123.0 7140.8 6604.8 6664.5 6685.6 6686.9 6700.2 6650.0 6654.9 6669.4 6681.3 6740.1 7032.6 7067.6 7089.4 7096.5 7120.6 5526.8 5532.6 5551.7 5559.8 5561.7 6695.0 6719.9 6811.5 6817.7 6841.0 6481.3 6504.3 6513.9 6538.6 6546.1 6446.6 6483.9 6522.9 6556.0 6633.7 6453.8 6463.8 6504.9 6557.1 6560.2 6533.8 6613.1 6613.7 6644.2 6648.9 6175.5 6335.9 6409.2 6446.8 6473.0 18:26:16 INFO - PID 1146 | 18:26:16 INFO - PID 1146 | [#5] yui.html Cycles:180 Average:26093.88 Median:20931.42 stddev:36330.13 (173.6%) stddev-sans-first:36382.68 18:26:16 INFO - PID 1146 | Values: 873.5 878.2 891.5 896.3 896.8 18346.9 18546.5 18641.3 18653.7 18676.5 19995.6 21633.6 21746.5 21884.6 21918.2 1043.8 1082.2 1087.4 1087.5 1088.8 811.7 1068.4 1072.0 1073.2 1073.9 913.7 922.4 1097.3 1140.5 1140.8 1137.8 1139.1 1140.9 1142.8 1144.7 827.4 830.7 834.6 835.6 836.1 18596.2 19527.8 20251.3 20376.1 20554.2 19927.0 20193.0 20491.6 20805.5 20837.2 14703.8 14796.6 14825.6 14944.3 15000.9 3057.0 3062.8 3070.6 3079.2 3092.7 126388.0 130145.0 131598.3 133024.3 135040.3 14178.3 14918.9 15322.5 15365.1 15435.2 29919.4 30756.4 30782.5 30865.8 31124.2 8212.3 8305.5 8347.1 8380.2 8439.5 3854.6 3863.0 3874.7 3910.7 3980.8 5204.5 5231.9 5349.9 5408.3 5465.0 957.3 997.5 999.2 1004.4 1010.9 699.7 713.8 716.5 724.4 729.5 12981.0 13265.7 13341.3 13826.4 14110.8 672.9 697.8 700.5 701.3 704.7 6075.8 6161.1 6265.4 6342.3 6395.0 125909.4 126208.0 130133.0 132174.0 133494.0 114208.0 114779.4 116004.4 117265.4 120702.0 94630.5 95817.0 97419.0 97719.5 99594.0 12110.0 13106.9 13857.9 13878.0 14054.2 44277.8 46631.8 46821.5 48484.8 49242.0 74807.0 77202.6 78700.6 81277.0 83377.6 19424.3 19488.2 19516.0 19538.7 19590.6 22170.6 23367.9 23473.9 23477.8 23576.4 20664.0 20899.7 21252.1 21609.9 21642.0 19584.5 20815.7 20963.2 21126.4 21349.0 20350.0 20555.9 20989.1 21020.5 21061.7 13590.7 14485.2 14489.7 14557.2 14793.2 15318.4 15424.8 15573.3 16244.5 16291.5 18:26:16 INFO - PID 1146 | -------- Summary: end -------- 18:26:16 INFO - PID 1146 | 18:26:17 INFO - TEST-INFO | 1146: exit 0 18:26:17 INFO - TEST-OK | dromaeo_css | took 1107756ms 18:26:17 INFO - TEST-START | kraken 18:26:17 INFO - Initialising browser for kraken test... 18:26:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40691/getInfo.html -profile /tmp/tmp3QEyXY/profile 18:26:17 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/firefox/firefox http://localhost:40691/getInfo.html) 18:26:22 INFO - TEST-INFO | 1631: exit 0 18:26:22 INFO - Browser initialized. 18:26:22 INFO - Running cycle 1/1 for kraken test... 18:26:22 INFO - TEST-INFO | started process 1840 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3QEyXY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:33 INFO - PID 1840 | RSS: Main: 160022528 18:26:33 INFO - PID 1840 | 18:26:36 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40691/tests/kraken/driver.html?testName=audio-beat-detection) 18:26:37 INFO - PID 1840 | RSS: Main: 166916096 18:26:37 INFO - PID 1840 | 18:26:39 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40691/tests/kraken/driver.html?testName=audio-dft) 18:26:40 INFO - PID 1840 | RSS: Main: 166854656 18:26:40 INFO - PID 1840 | 18:26:43 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40691/tests/kraken/driver.html?testName=audio-fft) 18:26:44 INFO - PID 1840 | RSS: Main: 161263616 18:26:44 INFO - PID 1840 | 18:26:46 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40691/tests/kraken/driver.html?testName=audio-oscillator) 18:26:46 INFO - PID 1840 | RSS: Main: 162721792 18:26:46 INFO - PID 1840 | 18:26:48 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40691/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:26:49 INFO - PID 1840 | RSS: Main: 163184640 18:26:49 INFO - PID 1840 | 18:26:52 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40691/tests/kraken/driver.html?testName=imaging-darkroom) 18:26:52 INFO - PID 1840 | RSS: Main: 163160064 18:26:52 INFO - PID 1840 | 18:26:56 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40691/tests/kraken/driver.html?testName=imaging-desaturate) 18:26:56 INFO - PID 1840 | RSS: Main: 165761024 18:26:56 INFO - PID 1840 | 18:26:59 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40691/tests/kraken/driver.html?testName=json-parse-financial) 18:27:00 INFO - PID 1840 | RSS: Main: 166735872 18:27:00 INFO - PID 1840 | 18:27:01 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40691/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:27:02 INFO - PID 1840 | RSS: Main: 166141952 18:27:02 INFO - PID 1840 | 18:27:04 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40691/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:27:04 INFO - PID 1840 | RSS: Main: 165896192 18:27:04 INFO - PID 1840 | 18:27:06 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40691/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:27:07 INFO - PID 1840 | RSS: Main: 166514688 18:27:07 INFO - PID 1840 | 18:27:09 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40691/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:27:10 INFO - PID 1840 | RSS: Main: 166658048 18:27:10 INFO - PID 1840 | 18:27:13 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40691/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:27:13 INFO - PID 1840 | RSS: Main: 166666240 18:27:13 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | Cycle 1(1): loaded http://localhost:40691/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40691/tests/kraken/driver.html?testName=ai-astar) 18:27:15 INFO - PID 1840 | RSS: Main: 168136704 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | __start_tp_report 18:27:15 INFO - PID 1840 | _x_x_mozilla_page_load 18:27:15 INFO - PID 1840 | _x_x_mozilla_page_load_details 18:27:15 INFO - PID 1840 | |i|pagename|runs| 18:27:15 INFO - PID 1840 | |0;ai-astar;106;98;106;106;106;114;106;108;105;109 18:27:15 INFO - PID 1840 | |1;audio-beat-detection;139;114;150;182;115;154;149;116;167;117 18:27:15 INFO - PID 1840 | |2;audio-dft;163;164;163;162;163;161;163;162;175;172 18:27:15 INFO - PID 1840 | |3;audio-fft;75;73;75;184;75;77;76;75;148;76 18:27:15 INFO - PID 1840 | |4;audio-oscillator;81;81;138;82;81;81;127;81;80;124 18:27:15 INFO - PID 1840 | |5;imaging-gaussian-blur;113;113;114;113;114;114;114;112;113;113 18:27:15 INFO - PID 1840 | |6;imaging-darkroom;154;156;154;155;159;155;157;155;156;155 18:27:15 INFO - PID 1840 | |7;imaging-desaturate;94;95;95;94;94;94;94;95;95;95 18:27:15 INFO - PID 1840 | |8;json-parse-financial;47;51;47;47;47;49;50;49;46;51 18:27:15 INFO - PID 1840 | |9;json-stringify-tinderbox;54;55;55;55;55;55;56;55;55;55 18:27:15 INFO - PID 1840 | |10;stanford-crypto-aes;76;77;75;74;74;75;74;74;74;75 18:27:15 INFO - PID 1840 | |11;stanford-crypto-ccm;121;123;134;123;126;121;120;122;123;137 18:27:15 INFO - PID 1840 | |12;stanford-crypto-pbkdf2;174;170;177;178;171;170;173;175;168;167 18:27:15 INFO - PID 1840 | |13;stanford-crypto-sha256-iterative;62;62;68;62;64;60;67;63;62;61 18:27:15 INFO - PID 1840 | __end_tp_report 18:27:15 INFO - PID 1840 | __start_cc_report 18:27:15 INFO - PID 1840 | _x_x_mozilla_cycle_collect,840 18:27:15 INFO - PID 1840 | __end_cc_report 18:27:15 INFO - PID 1840 | __startTimestamp1500341235866__endTimestamp 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | ------- Summary: start ------- 18:27:15 INFO - PID 1840 | Number of tests: 14 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#0] ai-astar Cycles:10 Average:106.40 Median:107.00 stddev:3.95 (3.7%) stddev-sans-first:4.19 18:27:15 INFO - PID 1840 | Values: 106.0 98.0 106.0 106.0 106.0 114.0 106.0 108.0 105.0 109.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:149.50 stddev:24.21 (16.2%) stddev-sans-first:25.68 18:27:15 INFO - PID 1840 | Values: 139.0 114.0 150.0 182.0 115.0 154.0 149.0 116.0 167.0 117.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#2] audio-dft Cycles:10 Average:164.80 Median:163.00 stddev:4.71 (2.9%) stddev-sans-first:4.95 18:27:15 INFO - PID 1840 | Values: 163.0 164.0 163.0 162.0 163.0 161.0 163.0 162.0 175.0 172.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#3] audio-fft Cycles:10 Average:93.40 Median:75.00 stddev:39.21 (52.3%) stddev-sans-first:41.02 18:27:15 INFO - PID 1840 | Values: 75.0 73.0 75.0 184.0 75.0 77.0 76.0 75.0 148.0 76.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:81.00 stddev:23.77 (29.3%) stddev-sans-first:24.62 18:27:15 INFO - PID 1840 | Values: 81.0 81.0 138.0 82.0 81.0 81.0 127.0 81.0 80.0 124.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 18:27:15 INFO - PID 1840 | Values: 113.0 113.0 114.0 113.0 114.0 114.0 114.0 112.0 113.0 113.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#6] imaging-darkroom Cycles:10 Average:155.60 Median:155.50 stddev:1.51 (1.0%) stddev-sans-first:1.48 18:27:15 INFO - PID 1840 | Values: 154.0 156.0 154.0 155.0 159.0 155.0 157.0 155.0 156.0 155.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 18:27:15 INFO - PID 1840 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 95.0 95.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#8] json-parse-financial Cycles:10 Average:48.40 Median:49.00 stddev:1.84 (3.8%) stddev-sans-first:1.88 18:27:15 INFO - PID 1840 | Values: 47.0 51.0 47.0 47.0 47.0 49.0 50.0 49.0 46.0 51.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#9] json-stringify-tinderbox Cycles:10 Average:55.00 Median:55.00 stddev:0.47 (0.9%) stddev-sans-first:0.33 18:27:15 INFO - PID 1840 | Values: 54.0 55.0 55.0 55.0 55.0 55.0 56.0 55.0 55.0 55.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:75.00 stddev:1.03 (1.4%) stddev-sans-first:1.00 18:27:15 INFO - PID 1840 | Values: 76.0 77.0 75.0 74.0 74.0 75.0 74.0 74.0 74.0 75.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:123.00 stddev:5.81 (4.7%) stddev-sans-first:5.98 18:27:15 INFO - PID 1840 | Values: 121.0 123.0 134.0 123.0 126.0 121.0 120.0 122.0 123.0 137.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:173.50 stddev:3.71 (2.1%) stddev-sans-first:3.89 18:27:15 INFO - PID 1840 | Values: 174.0 170.0 177.0 178.0 171.0 170.0 173.0 175.0 168.0 167.0 18:27:15 INFO - PID 1840 | 18:27:15 INFO - PID 1840 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:62.50 stddev:2.56 (4.1%) stddev-sans-first:2.68 18:27:15 INFO - PID 1840 | Values: 62.0 62.0 68.0 62.0 64.0 60.0 67.0 63.0 62.0 61.0 18:27:15 INFO - PID 1840 | -------- Summary: end -------- 18:27:15 INFO - PID 1840 | 18:27:16 INFO - PID 1840 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:27:16 INFO - TEST-INFO | 1840: exit 0 18:27:16 INFO - TEST-OK | kraken | took 59361ms 18:27:16 INFO - SUITE-END | took 1167s 18:27:17 INFO - Completed test suite (00:19:27) 18:27:17 INFO - javascript benchmark 18:27:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8736.558597781124, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.244623736542, 3407.9488807667885, 3436.158141255393, 3445.190380260639, 3474.148833535784, 5977.432143946318, 5990.880182396355, 6011.519078473722, 6051.12258722485, 6064.393560643934, 6007.609505382147, 6053.031514957607, 6087.665178415196, 6098.4511393974535, 6115.113308570255, 5352.063388906945, 5368.32895888014, 5370.489803468674, 5376.435474275204, 5389.83830485086, 5426.810061647843, 5429.158480435539, 5431.2396264522995, 5431.321084864392, 5451.945480545203, 5428.348598168225, 5431.266778984826, 5432.511074003339, 5435.565154787608, 5435.9943410468995, 5420.051490989061, 5430.864228394283, 5433.320834895638, 5437.646552974044, 5442.102053161204, 5344.812931547377, 5386.703731294777, 5469.15228139639, 5478.506934375925, 5578.693171875545, 6268.780592679234, 6277.372262773732, 6279.529035322324, 6318.557700960934, 6364.745410183587, 6343.8731225375695, 6752.0, 6774.390304872536, 6836.1113055302985, 6875.209350924658, 6575.539712220147, 6586.538942274043, 6589.901151482732, 6600.339966003409, 6601.801945941629, 6040.244969378828, 6050.971334873038, 6069.635821850703, 6090.056041313604, 6102.72537735803, 6703.329667033355, 6724.025016372599, 6941.514094013371, 6943.826404339831, 7039.92960070403, 6602.669866506655, 6662.83342916431, 6666.600003999775, 6753.729850805913, 6763.154605674291, 6791.762288319984, 6798.864022719518, 6806.081179040865, 6887.0, 6896.482763792736, 6456.451201647913, 6491.480681545465, 6508.088867558546, 6550.934490655128, 6574.5397822152945, 5629.4652008059165, 6558.213014438298, 6687.331266873273, 6766.28953959837, 6782.423494002966, 5015.247712843103, 5066.037452884014, 5071.96024814912, 5074.67014644047, 5075.441701412916, 4328.199283132631, 4400.471943366752, 4403.251447253974, 4406.537313582027, 4409.426774519292, 6085.6348619083, 6093.542984276109, 6095.603785753912, 6099.146119543268, 6108.694565271719, 6488.772892948724, 6490.967545162244, 6494.902576461357, 6505.024246362988, 6516.446102081376, 6004.519638428916, 6053.45518903292, 6053.4551890330085, 6065.787697430569, 6066.878662426727, 5442.210879422461, 5520.227168196456, 5522.0, 5551.111822108524, 5603.355614104348, 6124.601900876429, 6701.262861085277, 7150.070490836158, 7248.057752492142, 7249.202587715347, 6814.420774234147, 6841.863162736618, 6843.00776387411, 6924.515283930076, 6930.3762661361725, 7139.107611548557, 7169.318914703095, 7171.677274522554, 7236.96381518089, 7240.348368646847, 6370.649614271074, 6490.3185165558825, 6504.0, 6519.5436319457185, 6835.043093966749, 5699.437851342911, 6537.607743535403, 6808.82977925536, 6836.794896153124, 6925.89611155823, 6579.901301480386, 6588.736450541925, 6640.800775976729, 6661.067450557023, 6662.56693314933, 6498.545101842826, 6566.212054553484, 6641.169853768279, 6657.4674026078765, 6671.232808227116, 6327.082573026977, 6395.840103997437, 6399.168108145911, 6404.039394090737, 6406.167198264196, 6302.873942521032, 6359.3640635936035, 6380.393862583047, 6404.455621272058, 6411.294757576569, 5285.947140528546, 6411.230652321751, 6454.870902582016, 6486.481081513562, 6540.411362977355], "value": 6050.43465711661, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.5884081436841, 816.8938038055053, 831.7546323834468, 839.4711331860926, 1165.7027457998208, 31866.840665796666, 32550.837245813796, 32641.836790816073, 40985.0, 42147.0, 41406.0, 41587.0, 41660.166796664045, 41792.0, 41871.162576748444, 7208.387287080604, 7261.0, 7341.302576255247, 7348.3753880920185, 7389.0, 6844.9657751711175, 6876.553024053421, 6893.276205998373, 6929.896051559231, 6944.236134025253, 6906.378425941664, 6931.861362772766, 7226.56640601563, 7385.29839665231, 7472.17806041335, 7327.633618319062, 7395.445341599348, 7396.297351751575, 7414.740484083031, 7468.813279667997, 7196.748113816044, 7281.344678978918, 7309.744158954464, 7311.049563556757, 7355.595442250674, 38723.8063809682, 38950.61049389498, 38956.220875582614, 39334.60665393367, 39342.0, 3000.0, 3044.9543256851166, 3046.3450358172877, 3055.8319292438905, 3058.5565093061605, 31019.689803101905, 31030.0, 31070.0, 31211.0, 31239.375212495852, 4793.640476964241, 4802.4957379475, 4836.806527738886, 4863.17326054568, 4930.778114984834, 204193.0, 204822.0, 249226.0, 251326.0, 254131.0, 49813.0, 50079.49920500749, 50235.24647130296, 50335.0, 50430.47847607605, 51818.0, 51950.22074668883, 52111.739441302554, 52161.47838521566, 52354.47645523572, 16639.0, 16642.251098700344, 16688.74966875498, 16763.91618041902, 16785.916070419815, 13498.460061597623, 13605.795913061313, 13632.113912595649, 13661.521846735317, 13732.45070197201, 14496.275186240646, 14564.781528277084, 14609.415623374945, 14760.630984225265, 14835.777463338058, 5826.242588463472, 5853.765849365977, 5902.439268269508, 5982.970085149546, 6011.819645410645, 1008.6318493749728, 1008.7326858382535, 1027.5992362978445, 1032.4270030133198, 1034.1933292032227, 22718.659220111713, 22998.310050698507, 23246.883765581064, 23639.645405318934, 23646.527069458516, 1098.609993452313, 1103.7957977774138, 1123.168855047259, 1133.8696049954196, 1135.7103938495632, 12149.878501214875, 12486.687832804071, 12501.624951251477, 12665.683357915941, 12696.0, 259971.0, 262214.0, 262427.0, 263792.0, 264044.0, 52856.47143528515, 53683.731581342625, 53750.731246343516, 53767.462325377026, 54024.729876350364, 1368.7604669182854, 1370.4244217428704, 1376.800363947222, 1378.0835744230176, 1521.4522771802142, 27181.86409068021, 29129.0, 29591.704082959757, 29925.0, 30017.5497367544, 51743.741281293354, 51745.0, 52312.476875232285, 52432.0, 52677.7366113167, 238288.0, 241134.0, 241345.0, 241858.0, 241873.0, 35161.0, 35202.47196292109, 35236.0, 35432.0, 35436.291274173855, 40828.38757418701, 42971.78514107409, 43110.78444607757, 43119.13761724685, 44046.0, 31209.8439507801, 32487.83756081299, 32493.18767030843, 32680.0, 32692.83653581717, 31710.04869853908, 32205.0, 32398.35203295968, 32701.182470437478, 32751.836240818644, 36542.63457365392, 36811.63188368189, 37342.8132859334, 37673.246535069695, 37729.0, 30432.32635585094, 30511.926990244447, 31243.062708118792, 31641.525377119822, 31845.681543184273, 23485.295441136794, 24324.27027189187, 24817.1314004009, 25189.370265743502, 25597.23208303754], "value": 17540.462276257324, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.7443334918368, 565.5758181363977, 568.7128000359818, 569.4305694305694, 585.8301092683122, 22024.449388765268, 22899.31302060941, 23072.42318942027, 23123.07507699692, 23178.536429271448, 25838.35404114898, 27736.0, 27778.0, 27885.302867428272, 28160.718392816067, 6040.063790112528, 6132.938670613281, 6173.845653858644, 6183.907241391383, 6198.2252218472695, 4180.435641188446, 5911.320198177222, 5953.493953013999, 5983.042713165894, 6011.338752737195, 4353.956460435386, 5801.390853960315, 6151.261848578177, 6186.443220110189, 6198.938010619881, 6148.508119350441, 6179.227596550431, 6179.907301390483, 6200.627962322275, 6202.379762023762, 5776.624519406225, 5788.392218817048, 5792.131180322943, 5802.100674395476, 5803.187553742479, 29169.0, 30749.0, 31222.37555248905, 31238.21904452384, 31472.213194670316, 2082.6251274770534, 2094.4030951178843, 2094.5915546468445, 2097.916083356664, 2102.653062244743, 19202.615947681108, 19500.122494487783, 19518.707219391577, 19593.706094408597, 19711.408657740292, 2233.6761169630477, 2266.331432227497, 2267.489814791675, 2284.680144779732, 2367.940801479977, 140335.29832350876, 145955.81066284014, 147268.0, 147286.26356868146, 147704.0, 15036.0, 15299.770503442458, 15357.6160595986, 15724.921375393049, 15742.92128539373, 24138.758612414003, 24221.394465138514, 24229.63655545168, 24235.151769687975, 24310.270691879272, 10499.370037797757, 10675.092617127631, 10927.398993055458, 10965.50655220517, 11084.168687348481, 10054.843692975255, 10197.694069177936, 10224.744381390376, 10233.516140159636, 10268.64059757905, 9134.771630709285, 9319.627214911328, 9554.378965367368, 9803.362781419184, 9910.405375677483, 2759.9724002759717, 2790.2745286225727, 2794.1058861164233, 2848.5584734366207, 2890.7542858856814, 932.911373419524, 937.5874615169304, 945.5650400815701, 981.3326937682444, 1005.9044390782864, 20482.79517204809, 21155.47111322229, 21377.03803328854, 21377.893110534347, 21397.78602213958, 1031.7523794289466, 1048.9895101048892, 1065.6909496246158, 1069.534752382716, 1075.930064545802, 11080.612178573872, 11233.66299011031, 11266.43667816606, 11490.023348015346, 11681.240719353214, 581309.0, 584961.0, 594998.0, 604808.0, 607606.0, 134920.0, 138133.0, 143537.0, 144672.27663861614, 144954.0, 1310.7640624687458, 1314.4676406055562, 1331.4407948661585, 1347.4677502386699, 1360.177092858826, 22679.0, 22860.31419057431, 23768.643470348303, 23897.641535376635, 24076.157334493208, 62000.0, 62648.373516266074, 63415.365846340945, 63701.0, 64685.67657161684, 75102.62448687722, 76309.0, 76412.0, 76472.61763691145, 76752.23247767451, 15343.539693809204, 15411.845881541041, 15684.529464116094, 15706.97904636195, 15857.920710396374, 24897.0, 24960.126395576066, 25064.498710026062, 25377.87311063433, 25624.0, 19972.70040949416, 20047.799522004592, 20155.69766453475, 20321.695174572687, 20346.08442620056, 17163.656726865644, 17347.479575612753, 17355.2190151446, 17528.91235543814, 18583.34958276454, 17961.910190449486, 18696.53258668544, 18887.0, 18928.43214703561, 19064.714029289848, 15557.688846223238, 15649.452269170524, 15657.686846262783, 15660.216989150726, 15755.606109847346, 19176.520586985436, 20389.49026274355, 20536.28123015747, 20545.48636284105, 20587.07358168856], "value": 12493.276312452801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.7861560073788, 1531.5022617649267, 1537.0, 1539.8537138971801, 1555.5333399980004, 15009.924950375247, 15678.372865085412, 15711.45009924653, 15720.528384148494, 15737.84262157378, 15007.6998460031, 15609.609759756011, 16260.918695406506, 16263.918680406581, 16339.18304084797, 2550.4516528946274, 2610.229982155259, 2610.477904419114, 2615.6338112664243, 2620.489004644095, 2596.740325967407, 2654.2302732207636, 2657.6677915260593, 2659.9468010639775, 2662.5473669476237, 2570.421655127591, 2585.276122685651, 2594.403287243935, 2625.4749050189944, 2681.7184195659465, 2651.4829608226405, 2657.7608015278524, 2669.292637451077, 2681.1286331942197, 2682.8122031457806, 2448.2165706973774, 2448.6571879936823, 2463.4457247119435, 2466.3710753757855, 2467.0625162438337, 14970.62573435662, 15161.317740701696, 15176.468823591122, 15311.693766124727, 15339.769903451457, 8045.517268963881, 8351.039630442456, 8380.036295825998, 8394.45436046655, 8434.198751118634, 5207.151461231247, 5334.91997620036, 5475.0966090595, 5516.696581688006, 5518.2826232589505, 1702.2169801891146, 1731.411320151142, 1740.8868423552556, 1767.1959258537336, 1777.2180240694054, 26986.730132698813, 27260.0, 27385.31536711578, 27464.03875864335, 27494.312642183904, 6800.31996800316, 6864.347886950732, 6866.828329291808, 7004.509684322048, 7017.824554386181, 18123.0, 18191.63616727658, 18204.362847300545, 18241.45275641733, 18250.81749182518, 8064.395170362246, 8305.584720763938, 8309.0, 8312.54281014538, 8323.875141872877, 7405.7778266652085, 7587.810304742316, 7635.274648908345, 7662.272084151997, 7776.844463110706, 5241.50205730455, 5446.428125046892, 5461.918071228934, 5471.343438787371, 5473.28847249855, 1515.371120984801, 1516.082769924202, 1527.4119464006214, 1539.2073082362592, 1541.9691606168037, 868.3617541107282, 883.8409086364516, 886.0873300500494, 898.5237823953315, 916.1388295002677, 9454.48000359973, 9741.318107732392, 9905.702828915144, 9949.377043957844, 10064.899351006396, 964.1274646444979, 985.2167526816198, 998.7003898830467, 1011.9848202276971, 1012.4886932099242, 5529.806456774074, 5723.6851973141875, 5839.737211825478, 5866.823995280149, 6002.639841609517, 21350.679739803916, 21421.0, 21911.233106841184, 22162.445938851335, 22251.44371390696, 21089.78910210909, 21728.34814955554, 22555.548889022455, 22660.206892758673, 22900.0, 11377.943110284561, 11740.354280514483, 11826.704332391759, 11853.940730296294, 11944.0, 8895.555222238732, 8961.059088795582, 8990.100989900957, 9094.454332740057, 9190.356675032683, 18337.908310458362, 18591.0, 18922.621547568695, 19465.124069416626, 19593.70609440831, 18815.59544452384, 19516.31692890742, 19762.0, 20383.694244586048, 20524.179032838518, 11485.19603627738, 11755.47100380502, 11865.466054027762, 11877.168598198388, 11911.40442977865, 13891.51379701746, 14290.214038227998, 14357.784633230718, 14454.638634033812, 14658.267086645412, 10854.18593605467, 11199.552017919192, 11301.378424186749, 11457.484413201593, 11764.764704705687, 11219.495122719334, 11629.592964246549, 11681.06551475897, 11771.11716621274, 12159.878401216229, 9976.62664562414, 10232.079112879877, 10260.230482713976, 10429.26995110335, 10488.842667360148, 7535.510191837513, 7956.84086318282, 7993.72021979228, 8078.474899131537, 8117.8376432472205, 7420.220876807857, 7465.427351900149, 7499.700011999459, 7516.887246691194, 7548.169701332739], "value": 6857.9313681257, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.66184163847765, 503.5644167794856, 526.373615397677, 535.5046581911731, 537.7606964900621, 5991.490723288521, 6075.210222671052, 6089.391060893903, 6135.018397056472, 6153.077038444236, 5915.822525324236, 5988.490978266853, 5998.670073145975, 6003.429674180957, 6011.158437818698, 4744.240921452569, 4784.1149387363275, 4810.807567697297, 4817.7831997560015, 4839.467658557556, 4798.448178459469, 4843.128236917353, 4849.345338379327, 4870.245112007627, 4877.658563900528, 4641.303804429345, 4703.129920964615, 4743.264793956925, 4775.928361074587, 4805.567498925096, 4690.1557719610455, 4704.364910737041, 4710.505396933324, 4802.375691160162, 4814.277858321245, 4336.349547567858, 4367.3448982652535, 4368.4102646143, 4374.300111982067, 4388.29787234041, 5974.462298393166, 6016.669083200422, 6061.848453788646, 6064.332923378425, 6072.119542666289, 5735.942640573624, 5772.8845423091725, 5774.538036957033, 5774.566907481955, 5818.301803783573, 6536.901946470807, 6548.148740663731, 6564.606123632598, 6619.172603424572, 6658.800235992928, 4894.681845680019, 4899.608031357483, 4905.705657660516, 4959.231319145538, 4976.502349765031, 6794.558353706993, 6848.6918088686125, 6874.8625027499675, 6877.140357455318, 6879.933610290413, 5087.4149472811105, 5091.847244582668, 5097.502223910332, 5107.897842043139, 5128.461511541309, 5091.389033315952, 5095.796168153306, 5138.460461651547, 5143.868348963222, 5155.7679904404395, 5662.839117980804, 5672.602917795714, 5691.430856914359, 5768.567357448208, 5809.0, 6529.54293199481, 6546.443552298013, 6553.41019308255, 6599.406053455116, 6610.140681711347, 1766.381766381756, 1782.2336395349864, 1801.9819801801814, 1803.504036389977, 1807.8553715702976, 1146.48408216303, 1173.7828501727208, 1242.67069226655, 1244.328062846053, 1268.8033354830015, 6002.759889604367, 6085.482733967574, 6126.908096378558, 6184.443400094013, 6198.318184999646, 6564.8358791030605, 6635.834104147435, 6677.833054173588, 6682.699278532477, 6717.261101278856, 6189.3076511717545, 6202.844928876814, 6204.81385558434, 6240.032794916704, 6245.437910588069, 3461.8442170102408, 3470.965290347064, 3502.9124271892897, 3535.6994655454573, 3538.292341531704, 6923.584584924921, 6955.582665040114, 7090.858182836314, 7096.964515177391, 7111.0, 6786.151731033621, 6834.163822013908, 6898.413634840995, 6921.861562768717, 6953.0, 6994.6852391643515, 7025.754098606524, 7038.683259253346, 7122.964385178145, 7140.750073747394, 6604.768833090819, 6664.500162487736, 6685.6322902240845, 6686.933130668631, 6700.229473610597, 6649.966750166218, 6654.900176497454, 6669.399754022162, 6681.262871653308, 6740.135442437043, 7032.613206273704, 7067.57594544329, 7089.397401220747, 7096.53872498286, 7120.572765634079, 5526.751296191601, 5532.557395408438, 5551.722413879209, 5559.775330308492, 5561.694106824163, 6695.0, 6719.932800672126, 6811.523193376416, 6817.7272909083085, 6840.965795170992, 6481.319461456479, 6504.349565043458, 6513.8697226056165, 6538.607683538813, 6546.116274302908, 6446.580972236789, 6483.935160648333, 6522.902156467562, 6555.967220163869, 6633.734650613921, 6453.838654033687, 6463.7737679181, 6504.934950650433, 6557.147570815763, 6560.212774467094, 6533.771318004037, 6613.14029176204, 6613.735450581923, 6644.169478815148, 6648.90026649591, 6175.4750846176785, 6335.873282534416, 6409.166808314889, 6446.8388290293115, 6472.967635161794], "value": 5101.899005631882, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [873.4584557574304, 878.218385636783, 891.4918496457018, 896.3053633434088, 896.8206358728256, 18346.90826545867, 18546.53633659159, 18641.254349826024, 18653.720194197096, 18676.53308667284, 19995.60008799823, 21633.56732865342, 21746.456338591543, 21884.562308753815, 21918.23286184984, 1043.7808060307325, 1082.182951871336, 1087.429099722648, 1087.526925787281, 1088.7985722641338, 811.6753298680545, 1068.3910171202417, 1072.0458791675414, 1073.1522097542932, 1073.9194560407961, 913.7258822353301, 922.4373132389237, 1097.3470784882982, 1140.5437824870035, 1140.766142940695, 1137.795196864564, 1139.0659659079558, 1140.8573928258968, 1142.8114361130445, 1144.7481554058095, 827.3546633625722, 830.7383174300077, 834.6244190114472, 835.5989125219868, 836.0552575589605, 18596.163172657263, 19527.80472195288, 20251.29120480791, 20376.083076261457, 20554.177832886668, 19927.0, 20193.0, 20491.59016819655, 20805.479863003548, 20837.16651333945, 14703.779443308358, 14796.594323539353, 14825.629359266104, 14944.252787360589, 15000.924995374953, 3056.9541456878164, 3062.846857657108, 3070.5547695584237, 3079.2455848317304, 3092.690730926912, 126388.0, 130145.0, 131598.34200828936, 133024.33487832596, 135040.32479837636, 14178.291085445686, 14918.925405372904, 15322.540323790305, 15365.078095314317, 15435.15106669144, 29919.443043285042, 30756.384872302428, 30782.53826192609, 30865.8456707715, 31124.221894452367, 8212.260896519341, 8305.501669899826, 8347.123552027071, 8380.16198380157, 8439.493630382196, 3854.5759966403675, 3862.9806850965565, 3874.748141370802, 3910.687145028391, 3980.800959951991, 5204.4535323791215, 5231.947680523146, 5349.866253343697, 5408.296921400193, 5465.016297066566, 957.262907561174, 997.536145692242, 999.2006394884064, 1004.3572113847143, 1010.8786945566579, 699.7166147710185, 713.7894321175265, 716.469812338876, 724.4132252875188, 729.4674887332277, 12981.0, 13265.734685306239, 13341.332933353293, 13826.446942122404, 14110.788338174936, 672.9091572637632, 697.8046147078826, 700.4921431961787, 701.312713540733, 704.661762354073, 6075.787347442818, 6161.106639537242, 6265.4361107499635, 6342.334054924258, 6395.0, 125909.37045314898, 126208.0, 130133.0, 132174.0, 133494.0, 114208.0, 114779.42610286895, 116004.41997789957, 117265.41367293108, 120702.0, 94630.5268473667, 95817.0, 97419.0, 97719.51140244395, 99594.0, 12110.0, 13106.868931310946, 13857.930710346383, 13878.0, 14054.227017514135, 44277.77861110674, 46631.766841165576, 46821.53178468172, 48484.81818318114, 49242.0, 74807.0, 77202.6139869297, 78700.60649696714, 81277.0, 83377.58311208404, 19424.320148794723, 19488.22047118156, 19516.0, 19538.706919396504, 19590.60818783645, 22170.556588868454, 23367.88316058409, 23473.882630586737, 23477.76522234824, 23576.410589735395, 20664.0, 20899.68650470214, 21252.14991400327, 21609.891950540146, 21642.026108825456, 19584.510387240432, 20815.687764683844, 20963.161473540888, 21126.366209013755, 21349.0, 20350.0, 20555.8972205138, 20989.055492502568, 21020.47448813792, 21061.684074738583, 13590.728185436434, 14485.203313817841, 14489.70434262344, 14557.199354035629, 14793.186374749062, 15318.387264509294, 15424.768628470358, 15573.299201535732, 16244.51266462008, 16291.511254662379], "value": 9584.878708151677, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1502.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 98.0, 106.0, 106.0, 106.0, 114.0, 106.0, 108.0, 105.0, 109.0], "value": 106.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 114.0, 150.0, 182.0, 115.0, 154.0, 149.0, 116.0, 167.0, 117.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 162.0, 163.0, 161.0, 163.0, 162.0, 175.0, 172.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 75.0, 184.0, 75.0, 77.0, 76.0, 75.0, 148.0, 76.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 138.0, 82.0, 81.0, 81.0, 127.0, 81.0, 80.0, 124.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 114.0, 112.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 154.0, 155.0, 159.0, 155.0, 157.0, 155.0, 156.0, 155.0], "value": 155.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 51.0, 47.0, 47.0, 47.0, 49.0, 50.0, 49.0, 46.0, 51.0], "value": 48.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0, 55.0, 55.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 74.0, 74.0, 75.0, 74.0, 74.0, 74.0, 75.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 123.0, 134.0, 123.0, 126.0, 121.0, 120.0, 122.0, 123.0, 137.0], "value": 125.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 177.0, 178.0, 171.0, 170.0, 173.0, 175.0, 168.0, 167.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 68.0, 62.0, 64.0, 60.0, 67.0, 63.0, 62.0, 61.0], "value": 63.1, "unit": "score"}]}]} 18:27:17 INFO - Return code: 0 18:27:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:27:17 INFO - # TBPL SUCCESS # 18:27:17 INFO - Running post-action listener: _package_coverage_data 18:27:17 INFO - Running post-action listener: _resource_record_post_action 18:27:17 INFO - [mozharness: 2017-07-18 01:27:17.157038Z] Finished run-tests step (success) 18:27:17 INFO - Running post-run listener: _resource_record_post_run 18:27:17 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 432820224; Read time: 0; Write time: 271500 18:27:17 INFO - TinderboxPrint: CPU usage
13.0% 18:27:17 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:27:17 INFO - TinderboxPrint: I/O write bytes / time
432,820,224 / 271,500 18:27:17 INFO - TinderboxPrint: CPU idle
8,226.6 (87.0%) 18:27:17 INFO - TinderboxPrint: CPU user
1,187.4 (12.6%) 18:27:17 INFO - TinderboxPrint: Swap in / out
0 / 0 18:27:17 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 109490176; Read time: 0; Write time: 62156 18:27:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:27:17 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 0; Write bytes: 323330048; Read time: 0; Write time: 209344 18:27:17 INFO - Running post-run listener: _upload_blobber_files 18:27:17 INFO - Blob upload gear active. 18:27:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:27:17 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 18:27:17 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'] 18:27:17 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 18:27:18 INFO - Traceback (most recent call last): 18:27:18 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 18:27:18 INFO - main() 18:27:18 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 18:27:18 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 18:27:18 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 18:27:18 INFO - return set(response.json().get('whitelist', [])) 18:27:18 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 18:27:18 INFO - return json.loads(self.text or self.content, **kwargs) 18:27:18 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 18:27:18 INFO - return _default_decoder.decode(s) 18:27:18 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 18:27:18 INFO - obj, end = self.raw_decode(s) 18:27:18 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 18:27:18 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 18:27:18 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 18:27:18 ERROR - Return code: 1 18:27:18 INFO - Setting buildbot property blobber_files to {} 18:27:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:27:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:27:18 INFO - Contents: 18:27:18 INFO - blobber_files:{} 18:27:18 INFO - Running post-run listener: copy_logs_to_upload_dir 18:27:18 INFO - Copying logs to upload dir... 18:27:18 INFO - mkdir: /builds/slave/test/build/upload/logs 18:27:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.008158 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-07-17 18:27:16.216807) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 18:27:16.219994) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011343 build_url: 'https://queue.taskcluster.net/v1/task/Ki-5sk4FRaafhiv8Ygow_w/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 18:27:16.271054) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:27:16.271394) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1500339906.675437-82714157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004431 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:27:16.321624) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 18:27:16.321943) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 18:27:16.322259) ========= ========= Total master_lag: 0.15 =========