builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-084 starttime: 1495659588.85 results: success (0) revision: 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:48.853008) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:48.858036) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:48.858330) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:48.874365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:48.874653) ========= 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=ea65d483da93c827905c2090000001f5-1495658669.496680-727035852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-24 13:59:48-- 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% 37.7M=0s 2017-05-24 13:59:49 (37.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174979 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.062448) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.062765) ========= 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=ea65d483da93c827905c2090000001f5-1495658669.496680-727035852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014677 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.117920) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.118283) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 --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 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 --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=ea65d483da93c827905c2090000001f5-1495658669.496680-727035852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-24 13:59:49,140 truncating revision to first 12 chars 2017-05-24 13:59:49,140 Setting DEBUG logging. 2017-05-24 13:59:49,140 attempt 1/10 2017-05-24 13:59:49,140 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/934ff2cb74b0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 13:59:49,449 unpacking tar archive at: mozilla-central-934ff2cb74b0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.576253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.709976) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.710313) ========= script_repo_revision: 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.710748) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.711056) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 13:59:49.720987) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 55 secs) (at 2017-05-24 13:59:49.721312) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=ea65d483da93c827905c2090000001f5-1495658669.496680-727035852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:59:49 INFO - MultiFileLogger online at 20170524 13:59:49 in /builds/slave/test 13:59:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 13:59:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:59:49 INFO - {'append_to_log': False, 13:59:49 INFO - 'base_work_dir': '/builds/slave/test', 13:59:49 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 13:59:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:59:49 INFO - 'branch': 'Firefox-Non-PGO', 13:59:49 INFO - 'buildbot_json_path': 'buildprops.json', 13:59:49 INFO - 'config_files': ('talos/linux_config.py',), 13:59:49 INFO - 'default_actions': ('clobber', 13:59:49 INFO - 'read-buildbot-config', 13:59:49 INFO - 'download-and-extract', 13:59:49 INFO - 'populate-webroot', 13:59:49 INFO - 'create-virtualenv', 13:59:49 INFO - 'install', 13:59:49 INFO - 'run-tests'), 13:59:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:59:49 INFO - 'download_minidump_stackwalk': True, 13:59:49 INFO - 'download_symbols': 'ondemand', 13:59:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:59:49 INFO - 'tooltool.py': '/tools/tooltool.py', 13:59:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:59:49 INFO - '/tools/misc-python/virtualenv.py')}, 13:59:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:59:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:59:49 INFO - 'gecko_profile': False, 13:59:49 INFO - 'gecko_profile_interval': 0, 13:59:49 INFO - 'installer_path': 'installer.exe', 13:59:49 INFO - 'log_level': 'info', 13:59:49 INFO - 'log_name': 'talos', 13:59:49 INFO - 'log_to_console': True, 13:59:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:59:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:59:49 INFO - 'opt_config_files': (), 13:59:49 INFO - 'pip_index': False, 13:59:49 INFO - 'suite': 'dromaeojs-e10s', 13:59:49 INFO - 'system_bits': '32', 13:59:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:59:49 INFO - 'title': 'talos-linux64-ix-084', 13:59:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:59:49 INFO - 'use_talos_json': True, 13:59:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:59:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:59:49 INFO - 'work_dir': 'build'} 13:59:49 INFO - [mozharness: 2017-05-24 20:59:49.841910Z] Running clobber step. 13:59:49 INFO - Running pre-action listener: _resource_record_pre_action 13:59:49 INFO - Running main action method: clobber 13:59:49 INFO - rmtree: /builds/slave/test/build 13:59:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:59:51 INFO - Running post-action listener: _resource_record_post_action 13:59:51 INFO - [mozharness: 2017-05-24 20:59:51.370051Z] Finished clobber step (success) 13:59:51 INFO - [mozharness: 2017-05-24 20:59:51.370150Z] Running read-buildbot-config step. 13:59:51 INFO - Running pre-action listener: _resource_record_pre_action 13:59:51 INFO - Running main action method: read_buildbot_config 13:59:51 INFO - Using buildbot properties: 13:59:51 INFO - { 13:59:51 INFO - "project": "", 13:59:51 INFO - "product": "firefox", 13:59:51 INFO - "who": "ryanvm@gmail.com", 13:59:51 INFO - "installer_path": "public/build/target.tar.bz2", 13:59:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 13:59:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 13:59:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 13:59:51 INFO - "stage_platform": "linux64", 13:59:51 INFO - "basedir": "/builds/slave/test", 13:59:51 INFO - "buildnumber": 849, 13:59:51 INFO - "platform": "ubuntu64_hw", 13:59:51 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:59:51 INFO - "slavebuilddir": "test", 13:59:51 INFO - "taskId": "doKXF7kxTdaBeNV-XNMR2w", 13:59:51 INFO - "branch": "mozilla-central", 13:59:51 INFO - "script_repo_revision": "production", 13:59:51 INFO - "revision": "934ff2cb74b00bb92a7ab4f39aeaa014a9075a67", 13:59:51 INFO - "slavename": "talos-linux64-ix-084", 13:59:51 INFO - "repo_path": "mozilla-central" 13:59:51 INFO - } 13:59:51 INFO - Finding installer, test and symbols from parent task. 13:59:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/doKXF7kxTdaBeNV-XNMR2w'}, attempt #1 13:59:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/doKXF7kxTdaBeNV-XNMR2w'}, attempt #1 13:59:52 INFO - Task dependencies: VCP7xjcwSMKhl36s8DKKeQ 13:59:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ'}, attempt #1 13:59:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.tar.bz2 13:59:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/test_packages.json 13:59:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.crashreporter-symbols.zip 13:59:52 INFO - Running post-action listener: _resource_record_post_action 13:59:52 INFO - [mozharness: 2017-05-24 20:59:52.823593Z] Finished read-buildbot-config step (success) 13:59:52 INFO - [mozharness: 2017-05-24 20:59:52.823813Z] Running download-and-extract step. 13:59:52 INFO - Running pre-action listener: _resource_record_pre_action 13:59:52 INFO - Running main action method: download_and_extract 13:59:52 INFO - mkdir: /builds/slave/test/build/tests 13:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:52 INFO - trying https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.test_packages.json 13:59:52 INFO - Downloading https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:59:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:59:53 INFO - Downloaded 986 bytes. 13:59:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:59:53 INFO - Using the following test package requirements: 13:59:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:59:53 INFO - u'common': [u'target.common.tests.zip'], 13:59:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:59:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:59:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:59:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:59:53 INFO - u'mozbase': [u'target.common.tests.zip'], 13:59:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:59:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:59:53 INFO - u'web-platform': [u'target.common.tests.zip', 13:59:53 INFO - u'target.web-platform.tests.zip'], 13:59:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:59:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:59:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.common.tests.zip 13:59:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:59:53 INFO - Fetch https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.common.tests.zip into memory 13:59:55 INFO - Content-Length response header: 63523779 13:59:55 INFO - Bytes received: 63523779 14:00:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:00:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.common.tests.zip 14:00:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:00:06 INFO - Fetch https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.common.tests.zip into memory 14:00:07 INFO - Content-Length response header: 63523779 14:00:07 INFO - Bytes received: 63523779 14:00:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.talos.tests.zip 14:00:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:00:19 INFO - Fetch https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.talos.tests.zip into memory 14:00:20 INFO - Content-Length response header: 14411859 14:00:20 INFO - Bytes received: 14411859 14:00:20 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')]} 14:00:20 INFO - trying https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.tar.bz2 14:00:20 INFO - Downloading https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:00:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:00:22 INFO - Downloaded 54896066 bytes. 14:00:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.tar.bz2 14:00:22 INFO - mkdir: /builds/slave/test/properties 14:00:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:00:22 INFO - Writing to file /builds/slave/test/properties/build_url 14:00:22 INFO - Contents: 14:00:22 INFO - build_url:https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.tar.bz2 14:00:22 INFO - Running post-action listener: _resource_record_post_action 14:00:22 INFO - Running post-action listener: set_extra_try_arguments 14:00:22 INFO - [mozharness: 2017-05-24 21:00:22.296971Z] Finished download-and-extract step (success) 14:00:22 INFO - [mozharness: 2017-05-24 21:00:22.297133Z] Running populate-webroot step. 14:00:22 INFO - Running pre-action listener: _resource_record_pre_action 14:00:22 INFO - Running main action method: populate_webroot 14:00:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:00:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:00:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:00:22 INFO - '--tppagecycles', 14:00:22 INFO - '7'], 14:00:22 INFO - 'tests': ['tsvgm']}}, 14:00:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:00:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:00:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:00:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:00:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:00:22 INFO - 'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:00:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:00:22 INFO - 'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['damp', 'tps']}, 14:00:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:00:22 INFO - 'tests': ['damp', 'tps']}, 14:00:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['dromaeo_dom']}, 14:00:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:00:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:00:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:00:22 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:00:22 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:00:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['a11yr', 14:00:22 INFO - 'ts_paint', 14:00:22 INFO - 'tpaint', 14:00:22 INFO - 'sessionrestore', 14:00:22 INFO - 'sessionrestore_no_auto_restore', 14:00:22 INFO - 'tabpaint']}, 14:00:22 INFO - 'other-e10s': {'tests': ['a11yr', 14:00:22 INFO - 'ts_paint', 14:00:22 INFO - 'tpaint', 14:00:22 INFO - 'sessionrestore', 14:00:22 INFO - 'sessionrestore_no_auto_restore', 14:00:22 INFO - 'tabpaint']}, 14:00:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:00:22 INFO - 'ts_paint', 14:00:22 INFO - 'tpaint', 14:00:22 INFO - 'sessionrestore', 14:00:22 INFO - 'sessionrestore_no_auto_restore', 14:00:22 INFO - 'tabpaint']}, 14:00:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:00:22 INFO - 'ts_paint', 14:00:22 INFO - 'tpaint', 14:00:22 INFO - 'sessionrestore', 14:00:22 INFO - 'sessionrestore_no_auto_restore', 14:00:22 INFO - 'tabpaint']}, 14:00:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['a11yr', 14:00:22 INFO - 'ts_paint', 14:00:22 INFO - 'tpaint', 14:00:22 INFO - 'sessionrestore', 14:00:22 INFO - 'sessionrestore_no_auto_restore', 14:00:22 INFO - 'tabpaint']}, 14:00:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['a11yr', 14:00:22 INFO - 'ts_paint', 14:00:22 INFO - 'tpaint', 14:00:22 INFO - 'sessionrestore', 14:00:22 INFO - 'sessionrestore_no_auto_restore', 14:00:22 INFO - 'tabpaint']}, 14:00:22 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:00:22 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:00:22 INFO - 'bloom_basic_ref']}, 14:00:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['tsvgx', 14:00:22 INFO - 'tsvgr_opacity', 14:00:22 INFO - 'tart', 14:00:22 INFO - 'tscrollx', 14:00:22 INFO - 'cart', 14:00:22 INFO - 'tsvg_static']}, 14:00:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:00:22 INFO - 'tsvgr_opacity', 14:00:22 INFO - 'tart', 14:00:22 INFO - 'tscrollx', 14:00:22 INFO - 'cart', 14:00:22 INFO - 'tsvg_static']}, 14:00:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:00:22 INFO - 'talos_options': ['--disable-e10s'], 14:00:22 INFO - 'tests': ['tp5o']}, 14:00:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:00:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:00:22 INFO - 'talos_options': ['--disable-e10s', 14:00:22 INFO - '--xperf_path', 14:00:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:22 INFO - 'tests': ['tp5n']}, 14:00:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:00:22 INFO - 'talos_options': ['--xperf_path', 14:00:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:22 INFO - 'tests': ['tp5n']}}, 14:00:22 INFO - 'talos.zip': {'path': '', 14:00:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:00:22 INFO - Running post-action listener: _resource_record_post_action 14:00:22 INFO - [mozharness: 2017-05-24 21:00:22.316712Z] Finished populate-webroot step (success) 14:00:22 INFO - [mozharness: 2017-05-24 21:00:22.316801Z] Running create-virtualenv step. 14:00:22 INFO - Running pre-action listener: _resource_record_pre_action 14:00:22 INFO - Running main action method: create_virtualenv 14:00:22 INFO - Creating virtualenv /builds/slave/test/build/venv 14:00:22 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 14:00:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:00:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:00:22 INFO - Using real prefix '/usr' 14:00:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:00:22 INFO - Installing distribute.............................................................................................................................................................................................done. 14:00:25 INFO - Installing pip.................done. 14:00:25 INFO - Return code: 0 14:00:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:00:25 INFO - Reading from file tmpfile_stdout 14:00:25 INFO - Output received: 14:00:25 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) 14:00:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00: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')]} 14:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:25 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 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00:25 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 14:00:25 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 14:00:25 INFO - Using env: {'DISPLAY': ':0', 14:00:25 INFO - 'HOME': '/home/cltbld', 14:00:25 INFO - 'LANG': 'en_US.UTF-8', 14:00:25 INFO - 'LANGUAGE': 'en_US:en', 14:00:25 INFO - 'LOGNAME': 'cltbld', 14:00:25 INFO - 'MAIL': '/var/mail/cltbld', 14:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:25 INFO - 'MOZ_NO_REMOTE': '1', 14:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:25 INFO - 'NO_EM_RESTART': '1', 14:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:25 INFO - 'PWD': '/builds/slave/test', 14:00:25 INFO - 'SHELL': '/bin/bash', 14:00:25 INFO - 'SHLVL': '1', 14:00:25 INFO - 'TERM': 'linux', 14:00:25 INFO - 'TMOUT': '86400', 14:00:25 INFO - 'USER': 'cltbld', 14:00:25 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:25 INFO - '_': '/tools/buildbot/bin/python'} 14:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:25 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 14:00:25 INFO - Cleaning up... 14:00:25 INFO - Return code: 0 14:00:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00: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')]} 14:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:25 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 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00:25 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 14:00:25 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 14:00:25 INFO - Using env: {'DISPLAY': ':0', 14:00:25 INFO - 'HOME': '/home/cltbld', 14:00:25 INFO - 'LANG': 'en_US.UTF-8', 14:00:25 INFO - 'LANGUAGE': 'en_US:en', 14:00:25 INFO - 'LOGNAME': 'cltbld', 14:00:25 INFO - 'MAIL': '/var/mail/cltbld', 14:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:25 INFO - 'MOZ_NO_REMOTE': '1', 14:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:25 INFO - 'NO_EM_RESTART': '1', 14:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:25 INFO - 'PWD': '/builds/slave/test', 14:00:25 INFO - 'SHELL': '/bin/bash', 14:00:25 INFO - 'SHLVL': '1', 14:00:25 INFO - 'TERM': 'linux', 14:00:25 INFO - 'TMOUT': '86400', 14:00:25 INFO - 'USER': 'cltbld', 14:00:25 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:25 INFO - '_': '/tools/buildbot/bin/python'} 14:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:25 INFO - Downloading/unpacking psutil>=3.1.1 14:00:25 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 14:00:25 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 14:00:25 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 14:00:25 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 14:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:00:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:26 INFO - Installing collected packages: psutil 14:00:26 INFO - Running setup.py install for psutil 14:00:26 INFO - building 'psutil._psutil_linux' extension 14:00:26 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 14:00:27 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 14:00:27 INFO - building 'psutil._psutil_posix' extension 14:00:27 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 14:00:27 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 14:00:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:27 INFO - Successfully installed psutil 14:00:27 INFO - Cleaning up... 14:00:27 INFO - Return code: 0 14:00:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:00:27 INFO - Using env: {'DISPLAY': ':0', 14:00:27 INFO - 'HOME': '/home/cltbld', 14:00:27 INFO - 'LANG': 'en_US.UTF-8', 14:00:27 INFO - 'LANGUAGE': 'en_US:en', 14:00:27 INFO - 'LOGNAME': 'cltbld', 14:00:27 INFO - 'MAIL': '/var/mail/cltbld', 14:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:27 INFO - 'MOZ_NO_REMOTE': '1', 14:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:27 INFO - 'NO_EM_RESTART': '1', 14:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:27 INFO - 'PWD': '/builds/slave/test', 14:00:27 INFO - 'SHELL': '/bin/bash', 14:00:27 INFO - 'SHLVL': '1', 14:00:27 INFO - 'TERM': 'linux', 14:00:27 INFO - 'TMOUT': '86400', 14:00:27 INFO - 'USER': 'cltbld', 14:00:27 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:27 INFO - '_': '/tools/buildbot/bin/python'} 14:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:00:28 INFO - Installing collected packages: mozsystemmonitor 14:00:28 INFO - Running setup.py install for mozsystemmonitor 14:00:28 INFO - Successfully installed mozsystemmonitor 14:00:28 INFO - Cleaning up... 14:00:29 INFO - Return code: 0 14:00:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:00:29 INFO - Using env: {'DISPLAY': ':0', 14:00:29 INFO - 'HOME': '/home/cltbld', 14:00:29 INFO - 'LANG': 'en_US.UTF-8', 14:00:29 INFO - 'LANGUAGE': 'en_US:en', 14:00:29 INFO - 'LOGNAME': 'cltbld', 14:00:29 INFO - 'MAIL': '/var/mail/cltbld', 14:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:29 INFO - 'MOZ_NO_REMOTE': '1', 14:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:29 INFO - 'NO_EM_RESTART': '1', 14:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:29 INFO - 'PWD': '/builds/slave/test', 14:00:29 INFO - 'SHELL': '/bin/bash', 14:00:29 INFO - 'SHLVL': '1', 14:00:29 INFO - 'TERM': 'linux', 14:00:29 INFO - 'TMOUT': '86400', 14:00:29 INFO - 'USER': 'cltbld', 14:00:29 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:29 INFO - '_': '/tools/buildbot/bin/python'} 14:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:29 INFO - Downloading/unpacking jsonschema==2.5.1 14:00: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 14:00: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 14:00: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 14:00: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 14:00:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:00:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:30 INFO - Downloading functools32-3.2.3-2.tar.gz 14:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:00:30 INFO - warning: no files found matching '*.txt' 14:00:30 INFO - no previously-included directories found matching 'build' 14:00:30 INFO - no previously-included directories found matching 'dist' 14:00:30 INFO - no previously-included directories found matching '.git*' 14:00:30 INFO - Installing collected packages: jsonschema, functools32 14:00:30 INFO - Running setup.py install for functools32 14:00:30 INFO - warning: no files found matching '*.txt' 14:00:30 INFO - no previously-included directories found matching 'build' 14:00:30 INFO - no previously-included directories found matching 'dist' 14:00:30 INFO - no previously-included directories found matching '.git*' 14:00:30 INFO - Successfully installed jsonschema functools32 14:00:30 INFO - Cleaning up... 14:00:30 INFO - Return code: 0 14:00:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00: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')]} 14:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00: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', '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 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:00: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 functools32==3.2.3-2 14:00:30 INFO - Using env: {'DISPLAY': ':0', 14:00:30 INFO - 'HOME': '/home/cltbld', 14:00:30 INFO - 'LANG': 'en_US.UTF-8', 14:00:30 INFO - 'LANGUAGE': 'en_US:en', 14:00:30 INFO - 'LOGNAME': 'cltbld', 14:00:30 INFO - 'MAIL': '/var/mail/cltbld', 14:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:30 INFO - 'MOZ_NO_REMOTE': '1', 14:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:30 INFO - 'NO_EM_RESTART': '1', 14:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:30 INFO - 'PWD': '/builds/slave/test', 14:00:30 INFO - 'SHELL': '/bin/bash', 14:00:30 INFO - 'SHLVL': '1', 14:00:30 INFO - 'TERM': 'linux', 14:00:30 INFO - 'TMOUT': '86400', 14:00:30 INFO - 'USER': 'cltbld', 14:00:30 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:30 INFO - '_': '/tools/buildbot/bin/python'} 14:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:00:30 INFO - Cleaning up... 14:00:30 INFO - Return code: 0 14:00:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00: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')]} 14:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00: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', '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 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:00: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 blobuploader==1.2.4 14:00:30 INFO - Using env: {'DISPLAY': ':0', 14:00:30 INFO - 'HOME': '/home/cltbld', 14:00:30 INFO - 'LANG': 'en_US.UTF-8', 14:00:30 INFO - 'LANGUAGE': 'en_US:en', 14:00:30 INFO - 'LOGNAME': 'cltbld', 14:00:30 INFO - 'MAIL': '/var/mail/cltbld', 14:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:30 INFO - 'MOZ_NO_REMOTE': '1', 14:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:30 INFO - 'NO_EM_RESTART': '1', 14:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:30 INFO - 'PWD': '/builds/slave/test', 14:00:30 INFO - 'SHELL': '/bin/bash', 14:00:30 INFO - 'SHLVL': '1', 14:00:30 INFO - 'TERM': 'linux', 14:00:30 INFO - 'TMOUT': '86400', 14:00:30 INFO - 'USER': 'cltbld', 14:00:30 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:30 INFO - '_': '/tools/buildbot/bin/python'} 14:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:31 INFO - Downloading/unpacking blobuploader==1.2.4 14:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:32 INFO - Downloading blobuploader-1.2.4.tar.gz 14:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:00:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:00:32 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 14:00:32 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 14:00:32 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 14:00:32 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 14:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:00:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:00:32 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 14:00:32 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 14:00:32 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 14:00:32 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 14:00:32 INFO - Downloading docopt-0.6.1.tar.gz 14:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:00:32 INFO - Installing collected packages: blobuploader, requests, docopt 14:00:32 INFO - Running setup.py install for blobuploader 14:00:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:00:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:00:32 INFO - Running setup.py install for requests 14:00:33 INFO - Running setup.py install for docopt 14:00:33 INFO - Successfully installed blobuploader requests docopt 14:00:33 INFO - Cleaning up... 14:00:33 INFO - Return code: 0 14:00:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:33 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 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00:33 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 14:00:33 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 14:00:33 INFO - Using env: {'DISPLAY': ':0', 14:00:33 INFO - 'HOME': '/home/cltbld', 14:00:33 INFO - 'LANG': 'en_US.UTF-8', 14:00:33 INFO - 'LANGUAGE': 'en_US:en', 14:00:33 INFO - 'LOGNAME': 'cltbld', 14:00:33 INFO - 'MAIL': '/var/mail/cltbld', 14:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:33 INFO - 'MOZ_NO_REMOTE': '1', 14:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:33 INFO - 'NO_EM_RESTART': '1', 14:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:33 INFO - 'PWD': '/builds/slave/test', 14:00:33 INFO - 'SHELL': '/bin/bash', 14:00:33 INFO - 'SHLVL': '1', 14:00:33 INFO - 'TERM': 'linux', 14:00:33 INFO - 'TMOUT': '86400', 14:00:33 INFO - 'USER': 'cltbld', 14:00:33 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:33 INFO - '_': '/tools/buildbot/bin/python'} 14:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:33 INFO - Running setup.py (path:/tmp/pip-VlUim_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:33 INFO - Running setup.py (path:/tmp/pip-YhiOyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:33 INFO - Running setup.py (path:/tmp/pip-00JtwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:33 INFO - Running setup.py (path:/tmp/pip-0X0mmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:33 INFO - Running setup.py (path:/tmp/pip-oYjSQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:34 INFO - Running setup.py (path:/tmp/pip-es_now-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:34 INFO - Running setup.py (path:/tmp/pip-cPyT7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:34 INFO - Running setup.py (path:/tmp/pip-98nMj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:34 INFO - Running setup.py (path:/tmp/pip-PZF5gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:34 INFO - Running setup.py (path:/tmp/pip-Lf7els-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:34 INFO - Running setup.py (path:/tmp/pip-qpXaeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:34 INFO - Running setup.py (path:/tmp/pip-GnKPUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:34 INFO - Running setup.py (path:/tmp/pip-b_tqUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:34 INFO - Running setup.py (path:/tmp/pip-cehGWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:34 INFO - Running setup.py (path:/tmp/pip-MpQY1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:34 INFO - Running setup.py (path:/tmp/pip-xCFeOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:34 INFO - Running setup.py (path:/tmp/pip-8jIaDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:00:34 INFO - Running setup.py install for manifestparser 14:00:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:00:34 INFO - Running setup.py install for mozcrash 14:00:35 INFO - Running setup.py install for mozdebug 14:00:35 INFO - Running setup.py install for mozdevice 14:00:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:00:35 INFO - Running setup.py install for mozfile 14:00:35 INFO - Running setup.py install for mozhttpd 14:00:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:00:35 INFO - Running setup.py install for mozinfo 14:00:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:00:35 INFO - Running setup.py install for mozInstall 14:00:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:00:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:00:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:00:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:00:35 INFO - Running setup.py install for mozleak 14:00:35 INFO - Running setup.py install for mozlog 14:00:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:00:35 INFO - Running setup.py install for moznetwork 14:00:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:00:36 INFO - Running setup.py install for mozprocess 14:00:36 INFO - Running setup.py install for mozprofile 14:00:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:00:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:00:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:00:36 INFO - Running setup.py install for mozrunner 14:00:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:00:36 INFO - Running setup.py install for mozscreenshot 14:00:36 INFO - Running setup.py install for moztest 14:00:36 INFO - Running setup.py install for mozversion 14:00:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:00:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:00:36 INFO - Cleaning up... 14:00:36 INFO - Return code: 0 14:00:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:36 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')]} 14:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:36 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')]} 14:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:36 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 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00:36 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 14:00:36 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 14:00:36 INFO - Using env: {'DISPLAY': ':0', 14:00:36 INFO - 'HOME': '/home/cltbld', 14:00:36 INFO - 'LANG': 'en_US.UTF-8', 14:00:36 INFO - 'LANGUAGE': 'en_US:en', 14:00:36 INFO - 'LOGNAME': 'cltbld', 14:00:36 INFO - 'MAIL': '/var/mail/cltbld', 14:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:36 INFO - 'MOZ_NO_REMOTE': '1', 14:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:36 INFO - 'NO_EM_RESTART': '1', 14:00:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:36 INFO - 'PWD': '/builds/slave/test', 14:00:36 INFO - 'SHELL': '/bin/bash', 14:00:36 INFO - 'SHLVL': '1', 14:00:36 INFO - 'TERM': 'linux', 14:00:36 INFO - 'TMOUT': '86400', 14:00:36 INFO - 'USER': 'cltbld', 14:00:36 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:36 INFO - '_': '/tools/buildbot/bin/python'} 14:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:36 INFO - Running setup.py (path:/tmp/pip-4IIznu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:37 INFO - Running setup.py (path:/tmp/pip-91hzd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:37 INFO - Running setup.py (path:/tmp/pip-T_Gbtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:37 INFO - Running setup.py (path:/tmp/pip-bj2IHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:37 INFO - Running setup.py (path:/tmp/pip-zvgzQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:37 INFO - Running setup.py (path:/tmp/pip-Ebjw2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:37 INFO - Running setup.py (path:/tmp/pip-gnJLR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:37 INFO - Running setup.py (path:/tmp/pip-Zfw2vS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:37 INFO - Running setup.py (path:/tmp/pip-KKN9_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:37 INFO - Running setup.py (path:/tmp/pip-gkrgXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:37 INFO - Running setup.py (path:/tmp/pip-qKsaoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:37 INFO - Running setup.py (path:/tmp/pip-DkQk0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:37 INFO - Running setup.py (path:/tmp/pip-AOZPER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:37 INFO - Running setup.py (path:/tmp/pip-q42ofm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:37 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)) 14:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:37 INFO - Running setup.py (path:/tmp/pip-a6IBG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:38 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)) 14:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:38 INFO - Running setup.py (path:/tmp/pip-ztVA1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:38 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)) 14:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:38 INFO - Running setup.py (path:/tmp/pip-IEW0fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:38 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)) 14:00:38 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)) 14:00:38 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)) 14:00:38 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)) 14:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:00:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:00:38 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 14:00:38 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 14:00:38 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 14:00:38 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 14:00:39 INFO - Downloading blessings-1.6.tar.gz 14:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:00:39 INFO - Installing collected packages: blessings 14:00:39 INFO - Running setup.py install for blessings 14:00:39 INFO - Successfully installed blessings 14:00:39 INFO - Cleaning up... 14:00:39 INFO - Return code: 0 14:00:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:39 INFO - Reading from file tmpfile_stdout 14:00:39 INFO - Current package versions: 14:00:39 INFO - argparse == 1.2.1 14:00:39 INFO - blessings == 1.6 14:00:39 INFO - blobuploader == 1.2.4 14:00:39 INFO - docopt == 0.6.1 14:00:39 INFO - functools32 == 3.2.3-2 14:00:39 INFO - jsonschema == 2.5.1 14:00:39 INFO - manifestparser == 1.1 14:00:39 INFO - mozInstall == 1.12 14:00:39 INFO - mozcrash == 1.0 14:00:39 INFO - mozdebug == 0.1 14:00:39 INFO - mozdevice == 0.50 14:00:39 INFO - mozfile == 1.2 14:00:39 INFO - mozhttpd == 0.7 14:00:39 INFO - mozinfo == 0.9 14:00:39 INFO - mozleak == 0.1 14:00:39 INFO - mozlog == 3.4 14:00:39 INFO - moznetwork == 0.27 14:00:39 INFO - mozprocess == 0.25 14:00:39 INFO - mozprofile == 0.28 14:00:39 INFO - mozrunner == 6.13 14:00:39 INFO - mozscreenshot == 0.1 14:00:39 INFO - mozsystemmonitor == 0.3 14:00:39 INFO - moztest == 0.8 14:00:39 INFO - mozversion == 1.4 14:00:39 INFO - psutil == 3.1.1 14:00:39 INFO - requests == 1.2.3 14:00:39 INFO - wsgiref == 0.1.2 14:00:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:39 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 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00:39 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 14:00:39 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 14:00:39 INFO - Using env: {'DISPLAY': ':0', 14:00:39 INFO - 'HOME': '/home/cltbld', 14:00:39 INFO - 'LANG': 'en_US.UTF-8', 14:00:39 INFO - 'LANGUAGE': 'en_US:en', 14:00:39 INFO - 'LOGNAME': 'cltbld', 14:00:39 INFO - 'MAIL': '/var/mail/cltbld', 14:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:39 INFO - 'MOZ_NO_REMOTE': '1', 14:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:39 INFO - 'NO_EM_RESTART': '1', 14:00:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:39 INFO - 'PWD': '/builds/slave/test', 14:00:39 INFO - 'SHELL': '/bin/bash', 14:00:39 INFO - 'SHLVL': '1', 14:00:39 INFO - 'TERM': 'linux', 14:00:39 INFO - 'TMOUT': '86400', 14:00:39 INFO - 'USER': 'cltbld', 14:00:39 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:39 INFO - '_': '/tools/buildbot/bin/python'} 14:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:39 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)) 14:00:39 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)) 14:00:39 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)) 14:00:39 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)) 14:00:39 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)) 14:00:39 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)) 14:00:39 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)) 14:00:39 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)) 14:00:39 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)) 14:00:39 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:00:39 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 14:00:39 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 14:00:39 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 14:00:39 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 14:00:40 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:00:41 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)) 14:00:41 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)) 14:00:41 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)) 14:00:41 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)) 14:00:41 INFO - Installing collected packages: marionette-driver 14:00:41 INFO - Running setup.py install for marionette-driver 14:00:41 INFO - Successfully installed marionette-driver 14:00:41 INFO - Cleaning up... 14:00:41 INFO - Return code: 0 14:00:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:00:41 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')]} 14:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:41 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')]} 14:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:41 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 0x19921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d055c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, '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': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', '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 14:00:41 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 14:00:41 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 14:00:41 INFO - Using env: {'DISPLAY': ':0', 14:00:41 INFO - 'HOME': '/home/cltbld', 14:00:41 INFO - 'LANG': 'en_US.UTF-8', 14:00:41 INFO - 'LANGUAGE': 'en_US:en', 14:00:41 INFO - 'LOGNAME': 'cltbld', 14:00:41 INFO - 'MAIL': '/var/mail/cltbld', 14:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:41 INFO - 'MOZ_NO_REMOTE': '1', 14:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:41 INFO - 'NO_EM_RESTART': '1', 14:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:41 INFO - 'PWD': '/builds/slave/test', 14:00:41 INFO - 'SHELL': '/bin/bash', 14:00:41 INFO - 'SHLVL': '1', 14:00:41 INFO - 'TERM': 'linux', 14:00:41 INFO - 'TMOUT': '86400', 14:00:41 INFO - 'USER': 'cltbld', 14:00:41 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:41 INFO - '_': '/tools/buildbot/bin/python'} 14:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:00:41 INFO - Cleaning up... 14:00:41 INFO - Return code: 0 14:00:41 INFO - Running post-action listener: _resource_record_post_action 14:00:41 INFO - Running post-action listener: _start_resource_monitoring 14:00:41 INFO - Starting resource monitoring. 14:00:41 INFO - [mozharness: 2017-05-24 21:00:41.486213Z] Finished create-virtualenv step (success) 14:00:41 INFO - [mozharness: 2017-05-24 21:00:41.486717Z] Running install step. 14:00:41 INFO - Running pre-action listener: _resource_record_pre_action 14:00:41 INFO - Running main action method: install 14:00:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:41 INFO - Reading from file tmpfile_stdout 14:00:41 INFO - Detecting whether we're running mozinstall >=1.0... 14:00:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:00:41 INFO - Reading from file tmpfile_stdout 14:00:41 INFO - Output received: 14:00:41 INFO - Usage: mozinstall [options] installer 14:00:41 INFO - Options: 14:00:41 INFO - -h, --help show this help message and exit 14:00:41 INFO - -d DEST, --destination=DEST 14:00:41 INFO - Directory to install application into. [default: 14:00:41 INFO - "/builds/slave/test"] 14:00:41 INFO - --app=APP Application being installed. [default: firefox] 14:00:41 INFO - mkdir: /builds/slave/test/build/application 14:00:41 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'] 14:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:00:58 INFO - Reading from file tmpfile_stdout 14:00:58 INFO - Output received: 14:00:58 INFO - /builds/slave/test/build/application/firefox/firefox 14:00:58 INFO - Running post-action listener: _resource_record_post_action 14:00:58 INFO - [mozharness: 2017-05-24 21:00:58.273827Z] Finished install step (success) 14:00:58 INFO - [mozharness: 2017-05-24 21:00:58.274097Z] Running run-tests step. 14:00:58 INFO - Running pre-action listener: _resource_record_pre_action 14:00:58 INFO - Running main action method: run_tests 14:00:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:00:58 INFO - Python 2.7.3 14:00:58 INFO - Return code: 0 14:00:58 INFO - grabbing minidump binary from tooltool 14:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:58 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 0x1d027a0>, '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 0x1d036a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03b30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:00:58 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 14:00:58 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 14:00:58 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 14:00:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:00:58 INFO - Return code: 0 14:00:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:00:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:00:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:00:58 INFO - ENV: RUST_BACKTRACE is now 1 14:00:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:00:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:00:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--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 14:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-084 --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 14:00:58 INFO - Using env: {'DISPLAY': ':0', 14:00:58 INFO - 'HOME': '/home/cltbld', 14:00:58 INFO - 'LANG': 'en_US.UTF-8', 14:00:58 INFO - 'LANGUAGE': 'en_US:en', 14:00:58 INFO - 'LOGNAME': 'cltbld', 14:00:58 INFO - 'MAIL': '/var/mail/cltbld', 14:00:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:00:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:58 INFO - 'MOZ_NO_REMOTE': '1', 14:00:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:58 INFO - 'NO_EM_RESTART': '1', 14:00:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:58 INFO - 'PWD': '/builds/slave/test', 14:00:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:00:58 INFO - 'RUST_BACKTRACE': '1', 14:00:58 INFO - 'SHELL': '/bin/bash', 14:00:58 INFO - 'SHLVL': '1', 14:00:58 INFO - 'TERM': 'linux', 14:00:58 INFO - 'TMOUT': '86400', 14:00:58 INFO - 'USER': 'cltbld', 14:00:58 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1495658669.496680-727035852', 14:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:58 INFO - '_': '/tools/buildbot/bin/python'} 14:00:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--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 14:00:58 INFO - mozversion application_buildid: 20170524203251 14:00:58 INFO - mozversion application_changeset: 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 14:00:58 INFO - mozversion application_display_name: Nightly 14:00:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:00:58 INFO - mozversion application_name: Firefox 14:00:58 INFO - mozversion application_remotingname: firefox 14:00:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:00:58 INFO - mozversion application_vendor: Mozilla 14:00:58 INFO - mozversion application_version: 55.0a1 14:00:58 INFO - mozversion platform_buildid: 20170524203251 14:00:58 INFO - mozversion platform_changeset: 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 14:00:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:00:58 INFO - mozversion platform_version: 55.0a1 14:00:58 INFO - using testdate: 1495659658 14:00:58 INFO - actual date: 1495659658 14:00:58 INFO - starting webserver on 'localhost:59321' 14:00:58 INFO - SUITE-START | Running 2 tests 14:00:58 INFO - TEST-START | dromaeo_css 14:00:58 INFO - operating with platform_type : linux_ 14:00:58 INFO - Initialising browser for dromaeo_css test... 14:00:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59321/getInfo.html -profile /tmp/tmprN2ueU/profile 14:00:58 INFO - TEST-INFO | started process 10853 (/builds/slave/test/build/application/firefox/firefox http://localhost:59321/getInfo.html) 14:01:33 INFO - TEST-INFO | 10853: killed by SIGTERM 14:01:34 INFO - Browser initialized. 14:01:34 INFO - Running cycle 1/1 for dromaeo_css test... 14:01:34 INFO - TEST-INFO | started process 11051 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprN2ueU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:01:46 INFO - PID 11051 | RSS: Main: 172118016 14:01:46 INFO - PID 11051 | 14:04:49 INFO - PID 11051 | Cycle 1(1): loaded http://localhost:59321/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59321/tests/dromaeo/cssquery-ext.html) 14:04:51 INFO - PID 11051 | RSS: Main: 169787392 14:04:51 INFO - PID 11051 | 14:07:53 INFO - PID 11051 | Cycle 1(1): loaded http://localhost:59321/tests/dromaeo/cssquery-ext.html (next: http://localhost:59321/tests/dromaeo/cssquery-jquery.html) 14:07:54 INFO - PID 11051 | RSS: Main: 176238592 14:07:54 INFO - PID 11051 | 14:10:56 INFO - PID 11051 | Cycle 1(1): loaded http://localhost:59321/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59321/tests/dromaeo/cssquery-mootools.html) 14:10:56 INFO - PID 11051 | RSS: Main: 173649920 14:10:56 INFO - PID 11051 | 14:13:58 INFO - PID 11051 | Cycle 1(1): loaded http://localhost:59321/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59321/tests/dromaeo/cssquery-prototype.html) 14:13:58 INFO - PID 11051 | RSS: Main: 178241536 14:13:58 INFO - PID 11051 | 14:17:01 INFO - PID 11051 | Cycle 1(1): loaded http://localhost:59321/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59321/tests/dromaeo/cssquery-yui.html) 14:17:02 INFO - PID 11051 | RSS: Main: 177037312 14:17:02 INFO - PID 11051 | 14:20:03 INFO - PID 11051 | Cycle 1(1): loaded http://localhost:59321/tests/dromaeo/cssquery-yui.html (next: http://localhost:59321/tests/dromaeo/cssquery-dojo.html) 14:20:04 INFO - PID 11051 | RSS: Main: 174342144 14:20:04 INFO - PID 11051 | 14:20:04 INFO - PID 11051 | __start_tp_report 14:20:04 INFO - PID 11051 | _x_x_mozilla_page_load 14:20:04 INFO - PID 11051 | _x_x_mozilla_page_load_details 14:20:04 INFO - PID 11051 | |i|pagename|runs| 14:20:04 INFO - PID 11051 | |0;dojo.html;2701.081632245037;2708.133397312861;2723.373624066465;2736.2201772494836;2743.177046885935;5182.637215394924;5260.552853007494;5274.208868669697;5298.390685071218;5318.494742999414;5229.3201883755055;5241.056609810242;5267.86830329242;5327.041132596131;5356.1697936819755;4501.257292546742;4680.297955306714;4706.647001474895;4746.098241334153;4786.856394308159;4775.808967641307;4793;4794.520547945195;4809.7835597398025;4847;4679;4689.062187562501;4727.952720472786;4730.929036064462;4743.051389722035;4716.834910778106;4727;4745.667803253774;4750.121227572876;4750.382450281476;4654.488006319302;4667.113248482778;4671.392718946515;4679.134360143385;4721.5514526120305;5201;5226.268322434862;5259.526642602146;5260.710660913648;5300.337457817773;5660.377358490604;5676.631018983794;5685.033544297479;5699.202111704365;5745.569082318842;5784.074548072288;5786.508146807527;5798.188253644493;5801.680907550083;5841.795537156179;5339.839804805823;5355.812546560852;5356.0894647909945;5359.758810853443;5362.51737343641;5756.827295181151;5842.766289348378;5844;5885.234919460443;5907.4683278505145;5622.325320961429;5637.238972738628;5640.0693885508435;5679.261695979496;5680.4319568042865;5618.887622247532;5712.857178570486;5713.371529131792;5730.426957304237;5731.627444216113;5627.043402621564;5693.772249109989;5703.20155178267;5707.68607726579;5709.800156994485;5609.607327487037;5618.241537392481;5659.009673307155;5659.462351076641;5684.602077854511;4747.192977193886;4749.762511874393;4750.952490475051;4779.211430114062;4783.330333753277;4036.1322315701677;4046.959530404717;4051.6961227907436;4061.8578349757618;4095.2219078375015;4758.000819827806;4758.214894542431;4822.180229361017;4828;4845.6123510119105;5714.542836573148;5737.971310143423;5744;5747.281589801275;5767.22142510764;4785.282207668877;4790.616750659939;4792.376990991149;4793;4824.34871292378;4939.975300123477;4957.603391728653;5031.949680503149;5044.8234311798915;5050.696958182521;5203.19350500673;5215.55667768244;5216.530512253916;5266.262723218676;5283.418823929442;5763.049096898964;5769.07694768835;5796.362400136066;5802.94197058024;5819.098039803668;5256.868578285574;5263.578913686973;5266.289050978069;5274.762635681327;5281.234221038005;5454.154606036071;5616.466435688603;5674.205611214351;5676.7445464955;5686.886262274814;5643.181738647791;5655.4344565543115;5657.094864821609;5669.461401166883;5749.367569567428;5486.588505861996;5489.3687225969525;5497.312835895513;5500.669959802424;5557.194206840067;5410.48600382963;5491.697956612425;5514.91727624094;5516.227728118147;5531.115021596525;5335.733213339395;5376;5423.9694458052845;5426.267453893832;5440.809571664972;5493.807716729895;5550.47270509301;5552.222688823487;5563.527100196528;5584.246126772834;5410.188471729272;5411.485908838654;5413.160960051198;5415.6479828811;5452.482014208644 14:20:04 INFO - PID 11051 | |1;ext.html;638.4987784589099;700.9894851577227;718.4252597921663;1132.3432409202662;1176.2001838749657;34295.14262143445;35003.64996350036;35030.2993940121;35055;35056.82471587641;33135.171620709494;33404.49893251597;33428.49857252143;33526.664733352656;33538.8323058385;6204.16243807087;6548.607083575001;6749.7300107995625;6749.831254218635;6753.4259587935085;6689.33106689332;6753.088333075021;6754.696038678247;6766.729330826785;6773.796786096425;6746.224184218829;6776.661166941633;6790.830229244259;6795.456363490909;6808.2510923798345;6748.6625668716615;6766.357196066366;6791.728330866759;6805.502789386327;6810.897836532456;6696.296888826651;6735.259121496631;6741.59550426976;6745.426638735713;6756.189257289107;31375.686243137505;31797;31912.521312180335;32043.839780800947;32110.678893211003;3005.233665415305;3036.7722420818527;3055.4958431858718;3060.234941264684;3062.908112756621;24051.27846164618;24151.396215094763;24191.75808241913;24237;24289.635655465183;2958.837263950482;2981.433527629765;2989.4618968585646;3009.307859192398;3046.375493023947;140948;141764;142288;146860;151749;39549.406758898636;39567.80216098901;39759.60240397617;39814.40278395826;39977.400338994936;41019.38470922939;41038.38442423366;41042.79478602588;41061.38407923884;44053.33919991203;11872.10959178065;11981.700457488632;12090.335031573348;12159.756804863853;12163.722809105093;9640.566174522164;9674.75813104678;9678.419294842332;9715.319927605142;9721.659741909;12766.425510852034;12891.484340626457;12892.613221603367;12894.29081400532;12936.353182340845;5383.838484845461;5483.479069488472;5504.862378440571;5555.722213889289;5576.7211639417865;870.6386849457531;907.8281575553052;917.2111983694018;943.3820847344863;955.1117460761419;21021.89489052545;21036.739305407686;21298.467538311666;21370.358889233346;21835.344939651837;991.980160396788;999;999.9750006249902;1006.054308949585;1011.4588695048114;10868.67393978182;11388.886111138783;11514.596989105341;14106.858931410554;14733.631659208606;167332;168838;169060;169947;170032;45243.54756452459;45316.77341613271;45456.54543454523;45457.54542454599;45493;1207.3600991474532;1250.9311987840756;1252.9686757831128;1255.9309237991997;1259.212991880075;23298;23822.64266035976;24790.38024049413;25096;25159.24522264335;30230.84884575563;36238.45642315315;37152.44271335878;37199;37289.44065839069;167674;168269;168776;169299.15350423168;169459;29793;29987.4002519944;30064;30149.397012060075;30209.09372718822;35528;35791.46312805362;35875.82062089777;36131.81934090313;36162.638373615926;26980.73019269782;27689.58465622977;27727.168184954484;27841.721582783914;28511.714882850905;26702.732972670026;27468.313292167855;27847.607619619343;27855;28850.567241491815;30035;30093.548596771503;30285.545716813824;30420.695793041785;30674.846625767623;26423.735762642897;26874.865625671748;27858;28165.718342816308;28769.42461150807;21146.57706845824;22511;22768;22874.31377058691;22968.081276748744 14:20:04 INFO - PID 11051 | |2;jquery.html;400.11574420530627;408.60773657289;423.25295852819767;430.2728389022552;431.22379716510295;17203.655926881456;17545.298188072462;17899.19453624587;17958.82041179591;17987.64024719505;18384.172712227948;18542.536436589093;18642.720359194624;19131.043447827622;19310.613787724236;5320.122179840341;5529.585281103913;5690.630109042899;5706.857328566798;5736.1682556029355;5439.945600544003;5519.641223320491;5549.750261238254;5555.611107222496;5559.833205003836;5453.182022696607;5469.917951230735;5497.45025497451;5523.337199536061;5526.032944234763;5371.5165635092835;5502.5597952164135;5522.91715624266;5540.50135487804;5556.527695145917;4995.375578052744;4998.050370429643;5021.899562008776;5026.497350264981;5030.773615187325;21340.359789206348;21826.345209643736;21952.670709939364;22101.226457073924;22116;1634.1012443156335;1653.5618061213788;1666.2085509383066;1668.0825545949801;1673.1968655045646;14671.266436678123;14701;14816.481423150246;15098.018628789203;15116.622084447867;2038.0828627117855;2086.405374468269;2120.120150137691;2126.075157306577;2130.446084018151;80530;81109;81193.59403203006;81273;82480.58759706163;10954.890451095547;11015.504302306415;11344.546218151347;11422.657320280405;11472.369019703996;15710.764338534931;15720.449784257726;15787.447439339569;15812.76280855765;15836.445724399591;6662.933370666231;6778.593284402951;6912.235105957657;6914.515983881181;6986.615736134459;5962.880742385128;5981.222441082631;6114.266288045463;6131.110988906584;6145.692715364214;6871;6991;6996.930030699729;7124.6081465518855;7160.462965277625;2017.4452025692758;2029.2086086426305;2073.6371135051304;2098.748150221983;2114.6722258050027;864.6238886084448;906.3791302957495;907.0272158056201;909.949952752605;938.3009657804843;16915.40796072156;17056.658866822592;17533.736993945102;17914.193861276275;18045.368412105512;899.0874262623443;904.8099899021148;906.7778394293308;916.9174774270348;931.3434029009532;9150.222231110298;9436;9583.712488625353;9745.269104817025;9770.609175633037;352960;360093;361438;361868;365703;79623;80138.5993070031;80580.59709701531;81083.59458202671;81266.18733812704;1051.9105876000472;1057.645688694296;1073.559840465421;1091.6997825598023;1096.7532305231418;16137.273822678242;18765.90617046906;19145;19354.129064191864;19360.15160309378;40363.596364035984;40684.59315406808;40863;41380.1723965525;41852.581474184866;46927.76536117298;46994;47950.80122996953;48416;48680.51319486902;8185.508869467851;8197.95901020515;8282.917170828456;8638.308935285037;8655.91344086551;12268.570600028956;12930.288834114213;12946.41741121633;13552.728945421233;13671.86328136706;10411.375317480975;10430.478476076014;10455.320404173704;11369.033632141362;11578.015868651259;9609.56756945953;9986.400815951067;10027.247956403153;10156.593736250466;10473.895261047292;10369.9481502592;11013.160852084202;11379.203455758017;11460.426978651201;11509.021733152777;8548.829023419621;8611.82776344482;8624.765712857677;8641.740747777576;8662.480251184697;11193.608223712132;11224.382658953837;11280.548778048786;11331.093512518813;11425.942870285595 14:20:04 INFO - PID 11051 | |3;mootools.html;1293.3791779945625;1321.9867801321984;1327.6879933215698;1335.3256605414265;1351.7296540691864;10144.949275253623;10272.537735801889;10563.207759418035;10719.678409647713;10726.624568140107;10421.322614030101;10682.252242343038;10693.411862347568;10711.83932241017;10722;2178.8256939444846;2210.469487323047;2222.855514391559;2223.155201023617;2232.598132336179;2135.7437107547116;2178.9237376691817;2184.2682701295007;2184.3119417383555;2193.8903054847274;2119.703241546185;2150.612889679857;2150.956980860382;2160;2162.1675654872906;2049.3646969439524;2062.669972804352;2074.7406574178226;2080.2719048333115;2080.38628604562;1772.3530911217442;1835.0641173001732;2005.7793642699291;2055.989720051405;2069.7412823397076;10274.9486252569;10336.89663103367;10352.5341359639;10362.533685984074;10413.16694664425;5514.6139770216105;5759.884802303973;5772.855678608026;5775.682337471438;5791.102379131199;3089.490234111369;3209.823459709715;3270;3277.918052048718;3288.32589319189;987.3927534566191;1003.5684655598089;1013.4071568132651;1097.9231453798238;1130.2314426190187;14034.649133771636;14397.496087636988;14438.133711977314;14446.205458699767;14488.275586220647;4867.440244371872;4925.950740492621;4998.925016124761;5031.169856973632;5106.106431374495;12211.26732396059;12291.56979505713;12306.507739690313;12313.26120432777;12314.568990085483;5660.377358490563;5748.19525266463;5769.105788602773;5795.130730390475;5820.854478637983;4961.131801934647;5100.770465329069;5141.280220769096;5188.584913206934;5193.1171700810955;3668.614795446493;3797.715171362159;3810.314143454205;3818.6181381862148;3826.2730081284467;1050.9159267258754;1062.4156713807529;1075.5912753153907;1076.8546246256656;1090.3893819461127;686.2965460403127;712.4549719464613;717.1931576975902;726.280981827997;727.4943913979733;5367.060764366221;5422.457754224625;5438.700871452029;5444.482774136451;5480.698561579152;506.63522263969884;513.1302442360163;521.9086659834513;522.1488972974028;522.605432898167;3475.4091804393315;3494.7029502492064;3752.7560708553856;3806.809659517013;4082.4896887889013;12883.355832208352;13625.523106691218;13943.233122178377;13949.372278247503;13962.162270263816;13788.241646709528;13835.100718453268;13861.514846980308;13864.098833575987;13880.167189968633;7540.773776786705;7733.110692270348;7858.410619203582;8018.158093400226;8028.397870159762;6007.3992600739575;6280.152179455715;6332.778352757631;6372.936270637234;6422.908105621962;11899.226550274205;12103.576374826838;12168.2699038058;12173.87826121763;12227.510899563918;12169.69575760613;12283.815742763687;12324.81512777327;12430.37848107573;12471.625851224479;8600.182982616641;8681.175288347597;8691.087435819149;8727.389082764399;8748.606312715816;9795.85306220393;9921.25590580695;9951.303408761318;9961.551730172016;10000.749906261717;8283.130271321665;8505.82988340242;8564.52895090776;8659.913400865911;8680.826383472422;8239.340852731886;8270.131636178116;8372.581370931552;8464.746057618282;8627.654893804178;7394.704211831467;7526.059242594676;7536.5101268417375;7558.357539609195;7588.27911348421;5014.4484106747495;5097.541221289954;5138.254953031715;5139.126348520836;5151.330327057609;5331.466853314792;5378.892422151614;5395.217693434509;5511.972440137773;5562.638428502134 14:20:04 INFO - PID 11051 | |4;prototype.html;336.38945314254624;370.7404816628361;375.5624697227729;376.6440713525719;379.7911148868123;5412.5940554458475;5543.168524721284;5551.558034018909;5569.303837020378;5604.131359639257;5347.465253474651;5421.322334708161;5422.2951016367915;5427.565794736422;5440.727963601824;4092.6521245694153;4102.897427564305;4116.526599441057;4121.1345617420375;4129.566395528472;4025.939610905839;4087.734297270668;4106.178764247147;4122.773247471388;4131.277026520362;4086.754794712312;4095.221907837516;4124.628783409493;4126.649234815044;4127.463429754128;4090.897727556805;4093.692973027005;4098.4057311689785;4098.938515922264;4101.179764047188;3537.5047493350953;3557.4130268505664;3562.5724913010345;3585.8924232273075;3600.225951420431;5184.7148406837605;5310.893782124374;5347.197920311945;5353.243079813899;5381;4762.071256249765;4781.378420805273;4787;4807.61539076873;4821.7589120543835;5514.558835293167;5742;5753.137029445575;5776.8844623107725;5782.2483077199695;3980.960190398088;4007.2987227235117;4011.8796436106963;4014.6788256939376;4023.0948036691802;5846.561507886883;6059.030555111205;6081.60469569481;6086.908696369558;6142.170806941051;4073.205724883619;4085.3667681509414;4103.712740108163;4104.651104656137;4111.97944010278;4130.359794231899;4135.834566617302;4147.108371700038;4155.127423241153;4161.230172418052;4665.300204969281;4758.3100450434495;4772.570468657837;4789.377380940456;4802.183628783116;5732.828015159468;5761.596688231783;5768.538516918637;5780.421957804186;5801.274840644919;1404.4592831759846;1421.729871324445;1426.942922283118;1428.2287564715145;1430.306301443799;908.1871724806393;926.2173463423396;960.558143254111;964.238251781099;969.2439896880445;4933.728644924564;4942.135126352874;4947.925781113287;4949.7030178189425;4977.925331120036;5897.552515705934;5945.137954996501;5952.255968003999;5966.552508561788;5987.251593550806;4758.809647614056;4908.680935739166;4910.926336104962;4944.258361245842;4945.109880221523;3129.8435078246;3180.3162320101287;3180.411623849549;3180.538821870816;3191.5372271020574;5392.218128371364;5485.833095927086;5502.312210973629;5502.504774570228;5516.172574113834;6003.939960600338;6047.818565443044;6104.0467050544285;6116.663583502861;6164.229471316085;5368.282149712133;5436.510714035677;5453.291072160594;5457.672539647634;5472.398036216013;5744.770209191585;5924.7037648118285;5931.555133365101;5981.611195272293;6025.578209525291;5927.585069045125;5954.404559544011;5955.612885162451;5980;5984.491318237824;5281;5468.781248750005;5488.984537860509;5492.5331346835965;5513.5037846592395;4022.1955608877756;4022.4971878515184;4041.1109555897656;4054.5267386893383;4057;5821.854453638523;5985.563464768511;6011.489023433057;6047.213862198062;6053.152558641882;5751.223584816164;5803.47768700819;5810.593258471867;5832.212651292191;5850.5027072699295;5802.680852552981;5806.883862322815;5810.186573879745;5820.613548445117;5829.76680932758;5584.738181036687;5610.214569960291;5666.546676265806;5701.372848986525;5723.279868085778;5791.391903850204;5801.419858014334;5802.593818432669;5856.033754430471;5866.853328666647;5551.666899986014;5566.276384070045;5672.886542269049;5680.886382272249;5710.314762228559 14:20:04 INFO - PID 11051 | |5;yui.html;747.1594456236734;751.1174370115115;767.2595944913157;768.896199013133;769.0540635018926;13514.594562163125;13764.793528097074;14067.718645627081;14426.567202983913;14504.419823207072;15818.762718559203;15926.681466370695;16042.679146417095;16111.597210069755;16113.194340282967;904.2539904578716;907.9818403631923;913.3104506097886;914.2092090341839;918.6141820435399;906.2659246010719;906.5875026862794;907.8275127725744;910.2808781062952;913.8172365526888;902.8916530016407;922.478799478296;951.3435729346763;953.1993125774349;953.6042542344918;912.548288597142;915.8626206069076;917.4632839788732;922.0272612393956;922.3635691372965;669.5079116849112;673.0913267089439;674.5076094451078;674.6222115615222;677.190757045331;14049.157050576998;14113.08264962774;14161.22113283769;14187.716245675028;14192.716145677132;14863.554093377108;15580.61048473786;15603.921980390025;15746.448874289345;15750.897437179403;12135.150539462242;12441.502339906392;12518.81221781674;12535.059870509656;12586.307753073577;3053.190904410333;3057.8623961921767;3070.263136847163;3074.323648797261;3074.4158609864053;87170.5641471795;88023;88414.5579272106;88528;89049;12404.56584019555;12459.190152640049;12507;12519.186252893533;12607.432665530074;21456.785432145793;22104.778952210272;22244.332670019925;22314.88842555777;22342;6167.620989097702;6169.413905678954;6185.752569897244;6243.43809057187;6261.373862613702;3026.8183908965534;3100.069979006334;3115.813051216934;3121.0948824841;3123.640781310178;3877.689784817208;3907.785071821077;3940.5468371137113;3943.881683549498;3949.5063117110362;672.3444641474523;678.945684345261;682.4915437998624;682.8497730499282;685.9881674530028;586.8151532267323;596.2129988415337;605.1709158452948;615.387689249191;624.0389799708398;8908.554572271361;9421.528923553931;9775.315727898978;9964.750881227883;10266.435346055889;566.6600039975981;572.0760971031754;574.4915749561668;577.2582231832159;592.925884264467;4243.8090285937205;4293.656507479394;4301.78491075445;4309.762963037063;4360.778981885077;81575.18424815676;82023;82907.17092829115;83196.58401708074;84129.15870841335;71286;71959.64020179865;72551;73324.63337683277;73365.6331718338;58129.999999999156;61572.69213653903;62122.378776212565;62978.0553291701;63762.36237637657;9529.666461673809;10138.290319677551;10139.340942838691;10789.946050269698;11063.502142403771;28056.15831524976;28339.71660283371;28789.42421151607;28838;29174.7082529172;35196.296074077865;35400;35694.28611427705;36113.45829812502;36296.63703363038;14078.296085195494;14200.857991419953;14346.354414051602;14358.712825743636;14633.634159146106;15549.144797036271;16149.59626009359;16306.940048896784;16527.33890644361;16695.49913502597;11445.656630301104;11513.021393181338;11530.94234528822;11586.71033224176;11773.47019384147;13085.476580936656;13221.338933053117;13246.933765331498;13346.3326833656;13404.597862064154;13149.408276627384;13301.80047299272;13787.862121378657;14605.780913286097;14760;10336.431496267778;10433.53049112807;10571.312864663772;10775.515101820594;10893.618723344962;11851.288922664668;12366.505339786308;12503.687407814878;12602.306873122066;12640.115191936477 14:20:04 INFO - PID 11051 | __end_tp_report 14:20:04 INFO - PID 11051 | __start_cc_report 14:20:04 INFO - PID 11051 | _x_x_mozilla_cycle_collect,347 14:20:04 INFO - PID 11051 | __end_cc_report 14:20:04 INFO - PID 11051 | __startTimestamp1495660804116__endTimestamp 14:20:04 INFO - PID 11051 | 14:20:04 INFO - PID 11051 | ------- Summary: start ------- 14:20:04 INFO - PID 11051 | Number of tests: 6 14:20:04 INFO - PID 11051 | 14:20:04 INFO - PID 11051 | [#0] dojo.html Cycles:165 Average:5201.71 Median:5356.09 stddev:619.31 (11.6%) stddev-sans-first:589.33 14:20:04 INFO - PID 11051 | Values: 2701.1 2708.1 2723.4 2736.2 2743.2 5182.6 5260.6 5274.2 5298.4 5318.5 5229.3 5241.1 5267.9 5327.0 5356.2 4501.3 4680.3 4706.6 4746.1 4786.9 4775.8 4793.0 4794.5 4809.8 4847.0 4679.0 4689.1 4728.0 4730.9 4743.1 4716.8 4727.0 4745.7 4750.1 4750.4 4654.5 4667.1 4671.4 4679.1 4721.6 5201.0 5226.3 5259.5 5260.7 5300.3 5660.4 5676.6 5685.0 5699.2 5745.6 5784.1 5786.5 5798.2 5801.7 5841.8 5339.8 5355.8 5356.1 5359.8 5362.5 5756.8 5842.8 5844.0 5885.2 5907.5 5622.3 5637.2 5640.1 5679.3 5680.4 5618.9 5712.9 5713.4 5730.4 5731.6 5627.0 5693.8 5703.2 5707.7 5709.8 5609.6 5618.2 5659.0 5659.5 5684.6 4747.2 4749.8 4751.0 4779.2 4783.3 4036.1 4047.0 4051.7 4061.9 4095.2 4758.0 4758.2 4822.2 4828.0 4845.6 5714.5 5738.0 5744.0 5747.3 5767.2 4785.3 4790.6 4792.4 4793.0 4824.3 4940.0 4957.6 5031.9 5044.8 5050.7 5203.2 5215.6 5216.5 5266.3 5283.4 5763.0 5769.1 5796.4 5802.9 5819.1 5256.9 5263.6 5266.3 5274.8 5281.2 5454.2 5616.5 5674.2 5676.7 5686.9 5643.2 5655.4 5657.1 5669.5 5749.4 5486.6 5489.4 5497.3 5500.7 5557.2 5410.5 5491.7 5514.9 5516.2 5531.1 5335.7 5376.0 5424.0 5426.3 5440.8 5493.8 5550.5 5552.2 5563.5 5584.2 5410.2 5411.5 5413.2 5415.6 5452.5 14:20:04 INFO - PID 11051 | 14:20:04 INFO - PID 11051 | [#1] ext.html Cycles:180 Average:30919.53 Median:3050.94 stddev:41607.49 (1363.8%) stddev-sans-first:41662.08 14:20:04 INFO - PID 11051 | Values: 638.5 701.0 718.4 1132.3 1176.2 34295.1 35003.6 35030.3 35055.0 35056.8 33135.2 33404.5 33428.5 33526.7 33538.8 6204.2 6548.6 6749.7 6749.8 6753.4 6689.3 6753.1 6754.7 6766.7 6773.8 6746.2 6776.7 6790.8 6795.5 6808.3 6748.7 6766.4 6791.7 6805.5 6810.9 6696.3 6735.3 6741.6 6745.4 6756.2 31375.7 31797.0 31912.5 32043.8 32110.7 3005.2 3036.8 3055.5 3060.2 3062.9 24051.3 24151.4 24191.8 24237.0 24289.6 2958.8 2981.4 2989.5 3009.3 3046.4 140948.0 141764.0 142288.0 146860.0 151749.0 39549.4 39567.8 39759.6 39814.4 39977.4 41019.4 41038.4 41042.8 41061.4 44053.3 11872.1 11981.7 12090.3 12159.8 12163.7 9640.6 9674.8 9678.4 9715.3 9721.7 12766.4 12891.5 12892.6 12894.3 12936.4 5383.8 5483.5 5504.9 5555.7 5576.7 870.6 907.8 917.2 943.4 955.1 21021.9 21036.7 21298.5 21370.4 21835.3 992.0 999.0 1000.0 1006.1 1011.5 10868.7 11388.9 11514.6 14106.9 14733.6 167332.0 168838.0 169060.0 169947.0 170032.0 45243.5 45316.8 45456.5 45457.5 45493.0 1207.4 1250.9 1253.0 1255.9 1259.2 23298.0 23822.6 24790.4 25096.0 25159.2 30230.8 36238.5 37152.4 37199.0 37289.4 167674.0 168269.0 168776.0 169299.2 169459.0 29793.0 29987.4 30064.0 30149.4 30209.1 35528.0 35791.5 35875.8 36131.8 36162.6 26980.7 27689.6 27727.2 27841.7 28511.7 26702.7 27468.3 27847.6 27855.0 28850.6 30035.0 30093.5 30285.5 30420.7 30674.8 26423.7 26874.9 27858.0 28165.7 28769.4 21146.6 22511.0 22768.0 22874.3 22968.1 14:20:04 INFO - PID 11051 | 14:20:04 INFO - PID 11051 | [#2] jquery.html Cycles:180 Average:24627.53 Median:20546.60 stddev:59961.71 (291.8%) stddev-sans-first:60102.33 14:20:04 INFO - PID 11051 | Values: 400.1 408.6 423.3 430.3 431.2 17203.7 17545.3 17899.2 17958.8 17987.6 18384.2 18542.5 18642.7 19131.0 19310.6 5320.1 5529.6 5690.6 5706.9 5736.2 5439.9 5519.6 5549.8 5555.6 5559.8 5453.2 5469.9 5497.5 5523.3 5526.0 5371.5 5502.6 5522.9 5540.5 5556.5 4995.4 4998.1 5021.9 5026.5 5030.8 21340.4 21826.3 21952.7 22101.2 22116.0 1634.1 1653.6 1666.2 1668.1 1673.2 14671.3 14701.0 14816.5 15098.0 15116.6 2038.1 2086.4 2120.1 2126.1 2130.4 80530.0 81109.0 81193.6 81273.0 82480.6 10954.9 11015.5 11344.5 11422.7 11472.4 15710.8 15720.4 15787.4 15812.8 15836.4 6662.9 6778.6 6912.2 6914.5 6986.6 5962.9 5981.2 6114.3 6131.1 6145.7 6871.0 6991.0 6996.9 7124.6 7160.5 2017.4 2029.2 2073.6 2098.7 2114.7 864.6 906.4 907.0 909.9 938.3 16915.4 17056.7 17533.7 17914.2 18045.4 899.1 904.8 906.8 916.9 931.3 9150.2 9436.0 9583.7 9745.3 9770.6 352960.0 360093.0 361438.0 361868.0 365703.0 79623.0 80138.6 80580.6 81083.6 81266.2 1051.9 1057.6 1073.6 1091.7 1096.8 16137.3 18765.9 19145.0 19354.1 19360.2 40363.6 40684.6 40863.0 41380.2 41852.6 46927.8 46994.0 47950.8 48416.0 48680.5 8185.5 8198.0 8282.9 8638.3 8655.9 12268.6 12930.3 12946.4 13552.7 13671.9 10411.4 10430.5 10455.3 11369.0 11578.0 9609.6 9986.4 10027.2 10156.6 10473.9 10369.9 11013.2 11379.2 11460.4 11509.0 8548.8 8611.8 8624.8 8641.7 8662.5 11193.6 11224.4 11280.5 11331.1 11425.9 14:20:04 INFO - PID 11051 | 14:20:04 INFO - PID 11051 | [#3] mootools.html Cycles:180 Average:6411.38 Median:3485.06 stddev:4190.12 (120.2%) stddev-sans-first:4184.22 14:20:04 INFO - PID 11051 | Values: 1293.4 1322.0 1327.7 1335.3 1351.7 10144.9 10272.5 10563.2 10719.7 10726.6 10421.3 10682.3 10693.4 10711.8 10722.0 2178.8 2210.5 2222.9 2223.2 2232.6 2135.7 2178.9 2184.3 2184.3 2193.9 2119.7 2150.6 2151.0 2160.0 2162.2 2049.4 2062.7 2074.7 2080.3 2080.4 1772.4 1835.1 2005.8 2056.0 2069.7 10274.9 10336.9 10352.5 10362.5 10413.2 5514.6 5759.9 5772.9 5775.7 5791.1 3089.5 3209.8 3270.0 3277.9 3288.3 987.4 1003.6 1013.4 1097.9 1130.2 14034.6 14397.5 14438.1 14446.2 14488.3 4867.4 4926.0 4998.9 5031.2 5106.1 12211.3 12291.6 12306.5 12313.3 12314.6 5660.4 5748.2 5769.1 5795.1 5820.9 4961.1 5100.8 5141.3 5188.6 5193.1 3668.6 3797.7 3810.3 3818.6 3826.3 1050.9 1062.4 1075.6 1076.9 1090.4 686.3 712.5 717.2 726.3 727.5 5367.1 5422.5 5438.7 5444.5 5480.7 506.6 513.1 521.9 522.1 522.6 3475.4 3494.7 3752.8 3806.8 4082.5 12883.4 13625.5 13943.2 13949.4 13962.2 13788.2 13835.1 13861.5 13864.1 13880.2 7540.8 7733.1 7858.4 8018.2 8028.4 6007.4 6280.2 6332.8 6372.9 6422.9 11899.2 12103.6 12168.3 12173.9 12227.5 12169.7 12283.8 12324.8 12430.4 12471.6 8600.2 8681.2 8691.1 8727.4 8748.6 9795.9 9921.3 9951.3 9961.6 10000.7 8283.1 8505.8 8564.5 8659.9 8680.8 8239.3 8270.1 8372.6 8464.7 8627.7 7394.7 7526.1 7536.5 7558.4 7588.3 5014.4 5097.5 5138.3 5139.1 5151.3 5331.5 5378.9 5395.2 5512.0 5562.6 14:20:04 INFO - PID 11051 | 14:20:04 INFO - PID 11051 | [#4] prototype.html Cycles:180 Average:4732.64 Median:5421.81 stddev:1412.25 (26.0%) stddev-sans-first:1377.12 14:20:04 INFO - PID 11051 | Values: 336.4 370.7 375.6 376.6 379.8 5412.6 5543.2 5551.6 5569.3 5604.1 5347.5 5421.3 5422.3 5427.6 5440.7 4092.7 4102.9 4116.5 4121.1 4129.6 4025.9 4087.7 4106.2 4122.8 4131.3 4086.8 4095.2 4124.6 4126.6 4127.5 4090.9 4093.7 4098.4 4098.9 4101.2 3537.5 3557.4 3562.6 3585.9 3600.2 5184.7 5310.9 5347.2 5353.2 5381.0 4762.1 4781.4 4787.0 4807.6 4821.8 5514.6 5742.0 5753.1 5776.9 5782.2 3981.0 4007.3 4011.9 4014.7 4023.1 5846.6 6059.0 6081.6 6086.9 6142.2 4073.2 4085.4 4103.7 4104.7 4112.0 4130.4 4135.8 4147.1 4155.1 4161.2 4665.3 4758.3 4772.6 4789.4 4802.2 5732.8 5761.6 5768.5 5780.4 5801.3 1404.5 1421.7 1426.9 1428.2 1430.3 908.2 926.2 960.6 964.2 969.2 4933.7 4942.1 4947.9 4949.7 4977.9 5897.6 5945.1 5952.3 5966.6 5987.3 4758.8 4908.7 4910.9 4944.3 4945.1 3129.8 3180.3 3180.4 3180.5 3191.5 5392.2 5485.8 5502.3 5502.5 5516.2 6003.9 6047.8 6104.0 6116.7 6164.2 5368.3 5436.5 5453.3 5457.7 5472.4 5744.8 5924.7 5931.6 5981.6 6025.6 5927.6 5954.4 5955.6 5980.0 5984.5 5281.0 5468.8 5489.0 5492.5 5513.5 4022.2 4022.5 4041.1 4054.5 4057.0 5821.9 5985.6 6011.5 6047.2 6053.2 5751.2 5803.5 5810.6 5832.2 5850.5 5802.7 5806.9 5810.2 5820.6 5829.8 5584.7 5610.2 5666.5 5701.4 5723.3 5791.4 5801.4 5802.6 5856.0 5866.9 5551.7 5566.3 5672.9 5680.9 5710.3 14:20:04 INFO - PID 11051 | 14:20:04 INFO - PID 11051 | [#5] yui.html Cycles:180 Average:17117.22 Median:35298.15 stddev:22724.18 (64.4%) stddev-sans-first:22754.68 14:20:04 INFO - PID 11051 | Values: 747.2 751.1 767.3 768.9 769.1 13514.6 13764.8 14067.7 14426.6 14504.4 15818.8 15926.7 16042.7 16111.6 16113.2 904.3 908.0 913.3 914.2 918.6 906.3 906.6 907.8 910.3 913.8 902.9 922.5 951.3 953.2 953.6 912.5 915.9 917.5 922.0 922.4 669.5 673.1 674.5 674.6 677.2 14049.2 14113.1 14161.2 14187.7 14192.7 14863.6 15580.6 15603.9 15746.4 15750.9 12135.2 12441.5 12518.8 12535.1 12586.3 3053.2 3057.9 3070.3 3074.3 3074.4 87170.6 88023.0 88414.6 88528.0 89049.0 12404.6 12459.2 12507.0 12519.2 12607.4 21456.8 22104.8 22244.3 22314.9 22342.0 6167.6 6169.4 6185.8 6243.4 6261.4 3026.8 3100.1 3115.8 3121.1 3123.6 3877.7 3907.8 3940.5 3943.9 3949.5 672.3 678.9 682.5 682.8 686.0 586.8 596.2 605.2 615.4 624.0 8908.6 9421.5 9775.3 9964.8 10266.4 566.7 572.1 574.5 577.3 592.9 4243.8 4293.7 4301.8 4309.8 4360.8 81575.2 82023.0 82907.2 83196.6 84129.2 71286.0 71959.6 72551.0 73324.6 73365.6 58130.0 61572.7 62122.4 62978.1 63762.4 9529.7 10138.3 10139.3 10789.9 11063.5 28056.2 28339.7 28789.4 28838.0 29174.7 35196.3 35400.0 35694.3 36113.5 36296.6 14078.3 14200.9 14346.4 14358.7 14633.6 15549.1 16149.6 16306.9 16527.3 16695.5 11445.7 11513.0 11530.9 11586.7 11773.5 13085.5 13221.3 13246.9 13346.3 13404.6 13149.4 13301.8 13787.9 14605.8 14760.0 10336.4 10433.5 10571.3 10775.5 10893.6 11851.3 12366.5 12503.7 12602.3 12640.1 14:20:04 INFO - PID 11051 | -------- Summary: end -------- 14:20:04 INFO - PID 11051 | 14:20:04 INFO - TEST-INFO | 11051: exit 0 14:20:04 INFO - TEST-OK | dromaeo_css | took 1146312ms 14:20:04 INFO - TEST-START | kraken 14:20:04 INFO - operating with platform_type : linux_ 14:20:04 INFO - Initialising browser for kraken test... 14:20:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59321/getInfo.html -profile /tmp/tmpOlSQrF/profile 14:20:04 INFO - TEST-INFO | started process 11458 (/builds/slave/test/build/application/firefox/firefox http://localhost:59321/getInfo.html) 14:20:39 INFO - TEST-INFO | 11458: killed by SIGTERM 14:20:40 INFO - Browser initialized. 14:20:40 INFO - Running cycle 1/1 for kraken test... 14:20:40 INFO - TEST-INFO | started process 11653 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOlSQrF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:52 INFO - PID 11653 | RSS: Main: 172195840 14:20:52 INFO - PID 11653 | 14:20:54 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59321/tests/kraken/driver.html?testName=audio-beat-detection) 14:20:55 INFO - PID 11653 | RSS: Main: 177971200 14:20:55 INFO - PID 11653 | 14:20:58 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59321/tests/kraken/driver.html?testName=audio-dft) 14:20:58 INFO - PID 11653 | RSS: Main: 177299456 14:20:58 INFO - PID 11653 | 14:21:01 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59321/tests/kraken/driver.html?testName=audio-fft) 14:21:02 INFO - PID 11653 | RSS: Main: 174628864 14:21:02 INFO - PID 11653 | 14:21:04 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59321/tests/kraken/driver.html?testName=audio-oscillator) 14:21:05 INFO - PID 11653 | RSS: Main: 175013888 14:21:05 INFO - PID 11653 | 14:21:07 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59321/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:21:07 INFO - PID 11653 | RSS: Main: 175013888 14:21:07 INFO - PID 11653 | 14:21:11 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59321/tests/kraken/driver.html?testName=imaging-darkroom) 14:21:11 INFO - PID 11653 | RSS: Main: 176635904 14:21:11 INFO - PID 11653 | 14:21:15 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59321/tests/kraken/driver.html?testName=imaging-desaturate) 14:21:15 INFO - PID 11653 | RSS: Main: 175931392 14:21:15 INFO - PID 11653 | 14:21:18 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59321/tests/kraken/driver.html?testName=json-parse-financial) 14:21:19 INFO - PID 11653 | RSS: Main: 175853568 14:21:19 INFO - PID 11653 | 14:21:20 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59321/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:21:21 INFO - PID 11653 | RSS: Main: 175927296 14:21:21 INFO - PID 11653 | 14:21:23 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59321/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:21:24 INFO - PID 11653 | RSS: Main: 175775744 14:21:24 INFO - PID 11653 | 14:21:26 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59321/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:21:26 INFO - PID 11653 | RSS: Main: 176414720 14:21:26 INFO - PID 11653 | 14:21:29 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59321/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:21:30 INFO - PID 11653 | RSS: Main: 176046080 14:21:30 INFO - PID 11653 | 14:21:33 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59321/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:21:34 INFO - PID 11653 | RSS: Main: 176263168 14:21:34 INFO - PID 11653 | 14:21:35 INFO - PID 11653 | Cycle 1(1): loaded http://localhost:59321/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59321/tests/kraken/driver.html?testName=ai-astar) 14:21:36 INFO - PID 11653 | RSS: Main: 177893376 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | __start_tp_report 14:21:36 INFO - PID 11653 | _x_x_mozilla_page_load 14:21:36 INFO - PID 11653 | _x_x_mozilla_page_load_details 14:21:36 INFO - PID 11653 | |i|pagename|runs| 14:21:36 INFO - PID 11653 | |0;ai-astar;114;111;113;112;112;111;112;113;113;113 14:21:36 INFO - PID 11653 | |1;audio-beat-detection;153;129;125;176;124;124;123;121;151;128 14:21:36 INFO - PID 11653 | |2;audio-dft;164;165;166;166;167;169;169;164;163;163 14:21:36 INFO - PID 11653 | |3;audio-fft;74;76;74;109;79;75;74;75;96;74 14:21:36 INFO - PID 11653 | |4;audio-oscillator;84;83;113;86;86;85;98;84;84;103 14:21:36 INFO - PID 11653 | |5;imaging-gaussian-blur;114;115;113;114;114;114;114;114;116;114 14:21:36 INFO - PID 11653 | |6;imaging-darkroom;151;151;150;161;150;150;150;151;151;151 14:21:36 INFO - PID 11653 | |7;imaging-desaturate;94;95;95;94;94;94;95;95;95;95 14:21:36 INFO - PID 11653 | |8;json-parse-financial;55;57;55;57;56;56;56;56;57;55 14:21:36 INFO - PID 11653 | |9;json-stringify-tinderbox;56;57;57;56;56;57;56;57;56;57 14:21:36 INFO - PID 11653 | |10;stanford-crypto-aes;86;88;86;97;87;87;97;88;113;87 14:21:36 INFO - PID 11653 | |11;stanford-crypto-ccm;147;133;133;143;132;133;142;133;134;143 14:21:36 INFO - PID 11653 | |12;stanford-crypto-pbkdf2;190;187;203;196;193;196;196;200;203;196 14:21:36 INFO - PID 11653 | |13;stanford-crypto-sha256-iterative;77;75;73;72;74;74;76;77;71;72 14:21:36 INFO - PID 11653 | __end_tp_report 14:21:36 INFO - PID 11653 | __start_cc_report 14:21:36 INFO - PID 11653 | _x_x_mozilla_cycle_collect,904 14:21:36 INFO - PID 11653 | __end_cc_report 14:21:36 INFO - PID 11653 | __startTimestamp1495660896204__endTimestamp 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | ------- Summary: start ------- 14:21:36 INFO - PID 11653 | Number of tests: 14 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:0.97 (0.9%) stddev-sans-first:0.83 14:21:36 INFO - PID 11653 | Values: 114.0 111.0 113.0 112.0 112.0 111.0 112.0 113.0 113.0 113.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:128.50 stddev:18.34 (14.3%) stddev-sans-first:18.31 14:21:36 INFO - PID 11653 | Values: 153.0 129.0 125.0 176.0 124.0 124.0 123.0 121.0 151.0 128.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#2] audio-dft Cycles:10 Average:165.60 Median:166.00 stddev:2.22 (1.3%) stddev-sans-first:2.28 14:21:36 INFO - PID 11653 | Values: 164.0 165.0 166.0 166.0 167.0 169.0 169.0 164.0 163.0 163.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.00 stddev:12.04 (16.1%) stddev-sans-first:12.53 14:21:36 INFO - PID 11653 | Values: 74.0 76.0 74.0 109.0 79.0 75.0 74.0 75.0 96.0 74.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:84.50 stddev:10.39 (12.3%) stddev-sans-first:10.75 14:21:36 INFO - PID 11653 | Values: 84.0 83.0 113.0 86.0 86.0 85.0 98.0 84.0 84.0 103.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 14:21:36 INFO - PID 11653 | Values: 114.0 115.0 113.0 114.0 114.0 114.0 114.0 114.0 116.0 114.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:151.00 stddev:3.34 (2.2%) stddev-sans-first:3.54 14:21:36 INFO - PID 11653 | Values: 151.0 151.0 150.0 161.0 150.0 150.0 150.0 151.0 151.0 151.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 14:21:36 INFO - PID 11653 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.00 stddev:0.82 (1.5%) stddev-sans-first:0.78 14:21:36 INFO - PID 11653 | Values: 55.0 57.0 55.0 57.0 56.0 56.0 56.0 56.0 57.0 55.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#9] json-stringify-tinderbox Cycles:10 Average:56.50 Median:57.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 14:21:36 INFO - PID 11653 | Values: 56.0 57.0 57.0 56.0 56.0 57.0 56.0 57.0 56.0 57.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:87.50 stddev:8.62 (9.8%) stddev-sans-first:8.90 14:21:36 INFO - PID 11653 | Values: 86.0 88.0 86.0 97.0 87.0 87.0 97.0 88.0 113.0 87.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#11] stanford-crypto-ccm Cycles:10 Average:137.30 Median:138.00 stddev:5.72 (4.1%) stddev-sans-first:4.87 14:21:36 INFO - PID 11653 | Values: 147.0 133.0 133.0 143.0 132.0 133.0 142.0 133.0 134.0 143.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.00 Median:196.00 stddev:5.16 (2.6%) stddev-sans-first:5.00 14:21:36 INFO - PID 11653 | Values: 190.0 187.0 203.0 196.0 193.0 196.0 196.0 200.0 203.0 196.0 14:21:36 INFO - PID 11653 | 14:21:36 INFO - PID 11653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:74.50 stddev:2.13 (2.9%) stddev-sans-first:1.99 14:21:36 INFO - PID 11653 | Values: 77.0 75.0 73.0 72.0 74.0 74.0 76.0 77.0 71.0 72.0 14:21:36 INFO - PID 11653 | -------- Summary: end -------- 14:21:36 INFO - PID 11653 | 14:21:37 INFO - TEST-INFO | 11653: exit 0 14:21:37 INFO - TEST-OK | kraken | took 92338ms 14:21:37 INFO - SUITE-END | took 1238s 14:21:37 INFO - Completed test suite (00:20:38) 14:21:37 INFO - javascript benchmark 14:21:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6763.3476158079875, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2701.081632245037, 2708.133397312861, 2723.373624066465, 2736.2201772494836, 2743.177046885935, 5182.637215394924, 5260.552853007494, 5274.208868669697, 5298.390685071218, 5318.494742999414, 5229.3201883755055, 5241.056609810242, 5267.86830329242, 5327.041132596131, 5356.1697936819755, 4501.257292546742, 4680.297955306714, 4706.647001474895, 4746.098241334153, 4786.856394308159, 4775.808967641307, 4793.0, 4794.520547945195, 4809.7835597398025, 4847.0, 4679.0, 4689.062187562501, 4727.952720472786, 4730.929036064462, 4743.051389722035, 4716.834910778106, 4727.0, 4745.667803253774, 4750.121227572876, 4750.382450281476, 4654.488006319302, 4667.113248482778, 4671.392718946515, 4679.134360143385, 4721.5514526120305, 5201.0, 5226.268322434862, 5259.526642602146, 5260.710660913648, 5300.337457817773, 5660.377358490604, 5676.631018983794, 5685.033544297479, 5699.202111704365, 5745.569082318842, 5784.074548072288, 5786.508146807527, 5798.188253644493, 5801.680907550083, 5841.795537156179, 5339.839804805823, 5355.812546560852, 5356.0894647909945, 5359.758810853443, 5362.51737343641, 5756.827295181151, 5842.766289348378, 5844.0, 5885.234919460443, 5907.4683278505145, 5622.325320961429, 5637.238972738628, 5640.0693885508435, 5679.261695979496, 5680.4319568042865, 5618.887622247532, 5712.857178570486, 5713.371529131792, 5730.426957304237, 5731.627444216113, 5627.043402621564, 5693.772249109989, 5703.20155178267, 5707.68607726579, 5709.800156994485, 5609.607327487037, 5618.241537392481, 5659.009673307155, 5659.462351076641, 5684.602077854511, 4747.192977193886, 4749.762511874393, 4750.952490475051, 4779.211430114062, 4783.330333753277, 4036.1322315701677, 4046.959530404717, 4051.6961227907436, 4061.8578349757618, 4095.2219078375015, 4758.000819827806, 4758.214894542431, 4822.180229361017, 4828.0, 4845.6123510119105, 5714.542836573148, 5737.971310143423, 5744.0, 5747.281589801275, 5767.22142510764, 4785.282207668877, 4790.616750659939, 4792.376990991149, 4793.0, 4824.34871292378, 4939.975300123477, 4957.603391728653, 5031.949680503149, 5044.8234311798915, 5050.696958182521, 5203.19350500673, 5215.55667768244, 5216.530512253916, 5266.262723218676, 5283.418823929442, 5763.049096898964, 5769.07694768835, 5796.362400136066, 5802.94197058024, 5819.098039803668, 5256.868578285574, 5263.578913686973, 5266.289050978069, 5274.762635681327, 5281.234221038005, 5454.154606036071, 5616.466435688603, 5674.205611214351, 5676.7445464955, 5686.886262274814, 5643.181738647791, 5655.4344565543115, 5657.094864821609, 5669.461401166883, 5749.367569567428, 5486.588505861996, 5489.3687225969525, 5497.312835895513, 5500.669959802424, 5557.194206840067, 5410.48600382963, 5491.697956612425, 5514.91727624094, 5516.227728118147, 5531.115021596525, 5335.733213339395, 5376.0, 5423.9694458052845, 5426.267453893832, 5440.809571664972, 5493.807716729895, 5550.47270509301, 5552.222688823487, 5563.527100196528, 5584.246126772834, 5410.188471729272, 5411.485908838654, 5413.160960051198, 5415.6479828811, 5452.482014208644], "value": 5155.988190300413, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.4987784589099, 700.9894851577227, 718.4252597921663, 1132.3432409202662, 1176.2001838749657, 34295.14262143445, 35003.64996350036, 35030.2993940121, 35055.0, 35056.82471587641, 33135.171620709494, 33404.49893251597, 33428.49857252143, 33526.664733352656, 33538.8323058385, 6204.16243807087, 6548.607083575001, 6749.7300107995625, 6749.831254218635, 6753.4259587935085, 6689.33106689332, 6753.088333075021, 6754.696038678247, 6766.729330826785, 6773.796786096425, 6746.224184218829, 6776.661166941633, 6790.830229244259, 6795.456363490909, 6808.2510923798345, 6748.6625668716615, 6766.357196066366, 6791.728330866759, 6805.502789386327, 6810.897836532456, 6696.296888826651, 6735.259121496631, 6741.59550426976, 6745.426638735713, 6756.189257289107, 31375.686243137505, 31797.0, 31912.521312180335, 32043.839780800947, 32110.678893211003, 3005.233665415305, 3036.7722420818527, 3055.4958431858718, 3060.234941264684, 3062.908112756621, 24051.27846164618, 24151.396215094763, 24191.75808241913, 24237.0, 24289.635655465183, 2958.837263950482, 2981.433527629765, 2989.4618968585646, 3009.307859192398, 3046.375493023947, 140948.0, 141764.0, 142288.0, 146860.0, 151749.0, 39549.406758898636, 39567.80216098901, 39759.60240397617, 39814.40278395826, 39977.400338994936, 41019.38470922939, 41038.38442423366, 41042.79478602588, 41061.38407923884, 44053.33919991203, 11872.10959178065, 11981.700457488632, 12090.335031573348, 12159.756804863853, 12163.722809105093, 9640.566174522164, 9674.75813104678, 9678.419294842332, 9715.319927605142, 9721.659741909, 12766.425510852034, 12891.484340626457, 12892.613221603367, 12894.29081400532, 12936.353182340845, 5383.838484845461, 5483.479069488472, 5504.862378440571, 5555.722213889289, 5576.7211639417865, 870.6386849457531, 907.8281575553052, 917.2111983694018, 943.3820847344863, 955.1117460761419, 21021.89489052545, 21036.739305407686, 21298.467538311666, 21370.358889233346, 21835.344939651837, 991.980160396788, 999.0, 999.9750006249902, 1006.054308949585, 1011.4588695048114, 10868.67393978182, 11388.886111138783, 11514.596989105341, 14106.858931410554, 14733.631659208606, 167332.0, 168838.0, 169060.0, 169947.0, 170032.0, 45243.54756452459, 45316.77341613271, 45456.54543454523, 45457.54542454599, 45493.0, 1207.3600991474532, 1250.9311987840756, 1252.9686757831128, 1255.9309237991997, 1259.212991880075, 23298.0, 23822.64266035976, 24790.38024049413, 25096.0, 25159.24522264335, 30230.84884575563, 36238.45642315315, 37152.44271335878, 37199.0, 37289.44065839069, 167674.0, 168269.0, 168776.0, 169299.15350423168, 169459.0, 29793.0, 29987.4002519944, 30064.0, 30149.397012060075, 30209.09372718822, 35528.0, 35791.46312805362, 35875.82062089777, 36131.81934090313, 36162.638373615926, 26980.73019269782, 27689.58465622977, 27727.168184954484, 27841.721582783914, 28511.714882850905, 26702.732972670026, 27468.313292167855, 27847.607619619343, 27855.0, 28850.567241491815, 30035.0, 30093.548596771503, 30285.545716813824, 30420.695793041785, 30674.846625767623, 26423.735762642897, 26874.865625671748, 27858.0, 28165.718342816308, 28769.42461150807, 21146.57706845824, 22511.0, 22768.0, 22874.31377058691, 22968.081276748744], "value": 14700.135494352045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.11574420530627, 408.60773657289, 423.25295852819767, 430.2728389022552, 431.22379716510295, 17203.655926881456, 17545.298188072462, 17899.19453624587, 17958.82041179591, 17987.64024719505, 18384.172712227948, 18542.536436589093, 18642.720359194624, 19131.043447827622, 19310.613787724236, 5320.122179840341, 5529.585281103913, 5690.630109042899, 5706.857328566798, 5736.1682556029355, 5439.945600544003, 5519.641223320491, 5549.750261238254, 5555.611107222496, 5559.833205003836, 5453.182022696607, 5469.917951230735, 5497.45025497451, 5523.337199536061, 5526.032944234763, 5371.5165635092835, 5502.5597952164135, 5522.91715624266, 5540.50135487804, 5556.527695145917, 4995.375578052744, 4998.050370429643, 5021.899562008776, 5026.497350264981, 5030.773615187325, 21340.359789206348, 21826.345209643736, 21952.670709939364, 22101.226457073924, 22116.0, 1634.1012443156335, 1653.5618061213788, 1666.2085509383066, 1668.0825545949801, 1673.1968655045646, 14671.266436678123, 14701.0, 14816.481423150246, 15098.018628789203, 15116.622084447867, 2038.0828627117855, 2086.405374468269, 2120.120150137691, 2126.075157306577, 2130.446084018151, 80530.0, 81109.0, 81193.59403203006, 81273.0, 82480.58759706163, 10954.890451095547, 11015.504302306415, 11344.546218151347, 11422.657320280405, 11472.369019703996, 15710.764338534931, 15720.449784257726, 15787.447439339569, 15812.76280855765, 15836.445724399591, 6662.933370666231, 6778.593284402951, 6912.235105957657, 6914.515983881181, 6986.615736134459, 5962.880742385128, 5981.222441082631, 6114.266288045463, 6131.110988906584, 6145.692715364214, 6871.0, 6991.0, 6996.930030699729, 7124.6081465518855, 7160.462965277625, 2017.4452025692758, 2029.2086086426305, 2073.6371135051304, 2098.748150221983, 2114.6722258050027, 864.6238886084448, 906.3791302957495, 907.0272158056201, 909.949952752605, 938.3009657804843, 16915.40796072156, 17056.658866822592, 17533.736993945102, 17914.193861276275, 18045.368412105512, 899.0874262623443, 904.8099899021148, 906.7778394293308, 916.9174774270348, 931.3434029009532, 9150.222231110298, 9436.0, 9583.712488625353, 9745.269104817025, 9770.609175633037, 352960.0, 360093.0, 361438.0, 361868.0, 365703.0, 79623.0, 80138.5993070031, 80580.59709701531, 81083.59458202671, 81266.18733812704, 1051.9105876000472, 1057.645688694296, 1073.559840465421, 1091.6997825598023, 1096.7532305231418, 16137.273822678242, 18765.90617046906, 19145.0, 19354.129064191864, 19360.15160309378, 40363.596364035984, 40684.59315406808, 40863.0, 41380.1723965525, 41852.581474184866, 46927.76536117298, 46994.0, 47950.80122996953, 48416.0, 48680.51319486902, 8185.508869467851, 8197.95901020515, 8282.917170828456, 8638.308935285037, 8655.91344086551, 12268.570600028956, 12930.288834114213, 12946.41741121633, 13552.728945421233, 13671.86328136706, 10411.375317480975, 10430.478476076014, 10455.320404173704, 11369.033632141362, 11578.015868651259, 9609.56756945953, 9986.400815951067, 10027.247956403153, 10156.593736250466, 10473.895261047292, 10369.9481502592, 11013.160852084202, 11379.203455758017, 11460.426978651201, 11509.021733152777, 8548.829023419621, 8611.82776344482, 8624.765712857677, 8641.740747777576, 8662.480251184697, 11193.608223712132, 11224.382658953837, 11280.548778048786, 11331.093512518813, 11425.942870285595], "value": 8866.427296337179, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.3791779945625, 1321.9867801321984, 1327.6879933215698, 1335.3256605414265, 1351.7296540691864, 10144.949275253623, 10272.537735801889, 10563.207759418035, 10719.678409647713, 10726.624568140107, 10421.322614030101, 10682.252242343038, 10693.411862347568, 10711.83932241017, 10722.0, 2178.8256939444846, 2210.469487323047, 2222.855514391559, 2223.155201023617, 2232.598132336179, 2135.7437107547116, 2178.9237376691817, 2184.2682701295007, 2184.3119417383555, 2193.8903054847274, 2119.703241546185, 2150.612889679857, 2150.956980860382, 2160.0, 2162.1675654872906, 2049.3646969439524, 2062.669972804352, 2074.7406574178226, 2080.2719048333115, 2080.38628604562, 1772.3530911217442, 1835.0641173001732, 2005.7793642699291, 2055.989720051405, 2069.7412823397076, 10274.9486252569, 10336.89663103367, 10352.5341359639, 10362.533685984074, 10413.16694664425, 5514.6139770216105, 5759.884802303973, 5772.855678608026, 5775.682337471438, 5791.102379131199, 3089.490234111369, 3209.823459709715, 3270.0, 3277.918052048718, 3288.32589319189, 987.3927534566191, 1003.5684655598089, 1013.4071568132651, 1097.9231453798238, 1130.2314426190187, 14034.649133771636, 14397.496087636988, 14438.133711977314, 14446.205458699767, 14488.275586220647, 4867.440244371872, 4925.950740492621, 4998.925016124761, 5031.169856973632, 5106.106431374495, 12211.26732396059, 12291.56979505713, 12306.507739690313, 12313.26120432777, 12314.568990085483, 5660.377358490563, 5748.19525266463, 5769.105788602773, 5795.130730390475, 5820.854478637983, 4961.131801934647, 5100.770465329069, 5141.280220769096, 5188.584913206934, 5193.1171700810955, 3668.614795446493, 3797.715171362159, 3810.314143454205, 3818.6181381862148, 3826.2730081284467, 1050.9159267258754, 1062.4156713807529, 1075.5912753153907, 1076.8546246256656, 1090.3893819461127, 686.2965460403127, 712.4549719464613, 717.1931576975902, 726.280981827997, 727.4943913979733, 5367.060764366221, 5422.457754224625, 5438.700871452029, 5444.482774136451, 5480.698561579152, 506.63522263969884, 513.1302442360163, 521.9086659834513, 522.1488972974028, 522.605432898167, 3475.4091804393315, 3494.7029502492064, 3752.7560708553856, 3806.809659517013, 4082.4896887889013, 12883.355832208352, 13625.523106691218, 13943.233122178377, 13949.372278247503, 13962.162270263816, 13788.241646709528, 13835.100718453268, 13861.514846980308, 13864.098833575987, 13880.167189968633, 7540.773776786705, 7733.110692270348, 7858.410619203582, 8018.158093400226, 8028.397870159762, 6007.3992600739575, 6280.152179455715, 6332.778352757631, 6372.936270637234, 6422.908105621962, 11899.226550274205, 12103.576374826838, 12168.2699038058, 12173.87826121763, 12227.510899563918, 12169.69575760613, 12283.815742763687, 12324.81512777327, 12430.37848107573, 12471.625851224479, 8600.182982616641, 8681.175288347597, 8691.087435819149, 8727.389082764399, 8748.606312715816, 9795.85306220393, 9921.25590580695, 9951.303408761318, 9961.551730172016, 10000.749906261717, 8283.130271321665, 8505.82988340242, 8564.52895090776, 8659.913400865911, 8680.826383472422, 8239.340852731886, 8270.131636178116, 8372.581370931552, 8464.746057618282, 8627.654893804178, 7394.704211831467, 7526.059242594676, 7536.5101268417375, 7558.357539609195, 7588.27911348421, 5014.4484106747495, 5097.541221289954, 5138.254953031715, 5139.126348520836, 5151.330327057609, 5331.466853314792, 5378.892422151614, 5395.217693434509, 5511.972440137773, 5562.638428502134], "value": 4714.329760704713, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.38945314254624, 370.7404816628361, 375.5624697227729, 376.6440713525719, 379.7911148868123, 5412.5940554458475, 5543.168524721284, 5551.558034018909, 5569.303837020378, 5604.131359639257, 5347.465253474651, 5421.322334708161, 5422.2951016367915, 5427.565794736422, 5440.727963601824, 4092.6521245694153, 4102.897427564305, 4116.526599441057, 4121.1345617420375, 4129.566395528472, 4025.939610905839, 4087.734297270668, 4106.178764247147, 4122.773247471388, 4131.277026520362, 4086.754794712312, 4095.221907837516, 4124.628783409493, 4126.649234815044, 4127.463429754128, 4090.897727556805, 4093.692973027005, 4098.4057311689785, 4098.938515922264, 4101.179764047188, 3537.5047493350953, 3557.4130268505664, 3562.5724913010345, 3585.8924232273075, 3600.225951420431, 5184.7148406837605, 5310.893782124374, 5347.197920311945, 5353.243079813899, 5381.0, 4762.071256249765, 4781.378420805273, 4787.0, 4807.61539076873, 4821.7589120543835, 5514.558835293167, 5742.0, 5753.137029445575, 5776.8844623107725, 5782.2483077199695, 3980.960190398088, 4007.2987227235117, 4011.8796436106963, 4014.6788256939376, 4023.0948036691802, 5846.561507886883, 6059.030555111205, 6081.60469569481, 6086.908696369558, 6142.170806941051, 4073.205724883619, 4085.3667681509414, 4103.712740108163, 4104.651104656137, 4111.97944010278, 4130.359794231899, 4135.834566617302, 4147.108371700038, 4155.127423241153, 4161.230172418052, 4665.300204969281, 4758.3100450434495, 4772.570468657837, 4789.377380940456, 4802.183628783116, 5732.828015159468, 5761.596688231783, 5768.538516918637, 5780.421957804186, 5801.274840644919, 1404.4592831759846, 1421.729871324445, 1426.942922283118, 1428.2287564715145, 1430.306301443799, 908.1871724806393, 926.2173463423396, 960.558143254111, 964.238251781099, 969.2439896880445, 4933.728644924564, 4942.135126352874, 4947.925781113287, 4949.7030178189425, 4977.925331120036, 5897.552515705934, 5945.137954996501, 5952.255968003999, 5966.552508561788, 5987.251593550806, 4758.809647614056, 4908.680935739166, 4910.926336104962, 4944.258361245842, 4945.109880221523, 3129.8435078246, 3180.3162320101287, 3180.411623849549, 3180.538821870816, 3191.5372271020574, 5392.218128371364, 5485.833095927086, 5502.312210973629, 5502.504774570228, 5516.172574113834, 6003.939960600338, 6047.818565443044, 6104.0467050544285, 6116.663583502861, 6164.229471316085, 5368.282149712133, 5436.510714035677, 5453.291072160594, 5457.672539647634, 5472.398036216013, 5744.770209191585, 5924.7037648118285, 5931.555133365101, 5981.611195272293, 6025.578209525291, 5927.585069045125, 5954.404559544011, 5955.612885162451, 5980.0, 5984.491318237824, 5281.0, 5468.781248750005, 5488.984537860509, 5492.5331346835965, 5513.5037846592395, 4022.1955608877756, 4022.4971878515184, 4041.1109555897656, 4054.5267386893383, 4057.0, 5821.854453638523, 5985.563464768511, 6011.489023433057, 6047.213862198062, 6053.152558641882, 5751.223584816164, 5803.47768700819, 5810.593258471867, 5832.212651292191, 5850.5027072699295, 5802.680852552981, 5806.883862322815, 5810.186573879745, 5820.613548445117, 5829.76680932758, 5584.738181036687, 5610.214569960291, 5666.546676265806, 5701.372848986525, 5723.279868085778, 5791.391903850204, 5801.419858014334, 5802.593818432669, 5856.033754430471, 5866.853328666647, 5551.666899986014, 5566.276384070045, 5672.886542269049, 5680.886382272249, 5710.314762228559], "value": 4292.23253912611, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.1594456236734, 751.1174370115115, 767.2595944913157, 768.896199013133, 769.0540635018926, 13514.594562163125, 13764.793528097074, 14067.718645627081, 14426.567202983913, 14504.419823207072, 15818.762718559203, 15926.681466370695, 16042.679146417095, 16111.597210069755, 16113.194340282967, 904.2539904578716, 907.9818403631923, 913.3104506097886, 914.2092090341839, 918.6141820435399, 906.2659246010719, 906.5875026862794, 907.8275127725744, 910.2808781062952, 913.8172365526888, 902.8916530016407, 922.478799478296, 951.3435729346763, 953.1993125774349, 953.6042542344918, 912.548288597142, 915.8626206069076, 917.4632839788732, 922.0272612393956, 922.3635691372965, 669.5079116849112, 673.0913267089439, 674.5076094451078, 674.6222115615222, 677.190757045331, 14049.157050576998, 14113.08264962774, 14161.22113283769, 14187.716245675028, 14192.716145677132, 14863.554093377108, 15580.61048473786, 15603.921980390025, 15746.448874289345, 15750.897437179403, 12135.150539462242, 12441.502339906392, 12518.81221781674, 12535.059870509656, 12586.307753073577, 3053.190904410333, 3057.8623961921767, 3070.263136847163, 3074.323648797261, 3074.4158609864053, 87170.5641471795, 88023.0, 88414.5579272106, 88528.0, 89049.0, 12404.56584019555, 12459.190152640049, 12507.0, 12519.186252893533, 12607.432665530074, 21456.785432145793, 22104.778952210272, 22244.332670019925, 22314.88842555777, 22342.0, 6167.620989097702, 6169.413905678954, 6185.752569897244, 6243.43809057187, 6261.373862613702, 3026.8183908965534, 3100.069979006334, 3115.813051216934, 3121.0948824841, 3123.640781310178, 3877.689784817208, 3907.785071821077, 3940.5468371137113, 3943.881683549498, 3949.5063117110362, 672.3444641474523, 678.945684345261, 682.4915437998624, 682.8497730499282, 685.9881674530028, 586.8151532267323, 596.2129988415337, 605.1709158452948, 615.387689249191, 624.0389799708398, 8908.554572271361, 9421.528923553931, 9775.315727898978, 9964.750881227883, 10266.435346055889, 566.6600039975981, 572.0760971031754, 574.4915749561668, 577.2582231832159, 592.925884264467, 4243.8090285937205, 4293.656507479394, 4301.78491075445, 4309.762963037063, 4360.778981885077, 81575.18424815676, 82023.0, 82907.17092829115, 83196.58401708074, 84129.15870841335, 71286.0, 71959.64020179865, 72551.0, 73324.63337683277, 73365.6331718338, 58129.999999999156, 61572.69213653903, 62122.378776212565, 62978.0553291701, 63762.36237637657, 9529.666461673809, 10138.290319677551, 10139.340942838691, 10789.946050269698, 11063.502142403771, 28056.15831524976, 28339.71660283371, 28789.42421151607, 28838.0, 29174.7082529172, 35196.296074077865, 35400.0, 35694.28611427705, 36113.45829812502, 36296.63703363038, 14078.296085195494, 14200.857991419953, 14346.354414051602, 14358.712825743636, 14633.634159146106, 15549.144797036271, 16149.59626009359, 16306.940048896784, 16527.33890644361, 16695.49913502597, 11445.656630301104, 11513.021393181338, 11530.94234528822, 11586.71033224176, 11773.47019384147, 13085.476580936656, 13221.338933053117, 13246.933765331498, 13346.3326833656, 13404.597862064154, 13149.408276627384, 13301.80047299272, 13787.862121378657, 14605.780913286097, 14760.0, 10336.431496267778, 10433.53049112807, 10571.312864663772, 10775.515101820594, 10893.618723344962, 11851.288922664668, 12366.505339786308, 12503.687407814878, 12602.306873122066, 12640.115191936477], "value": 7038.055184275849, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 113.0, 112.0, 112.0, 111.0, 112.0, 113.0, 113.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 129.0, 125.0, 176.0, 124.0, 124.0, 123.0, 121.0, 151.0, 128.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 166.0, 166.0, 167.0, 169.0, 169.0, 164.0, 163.0, 163.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 109.0, 79.0, 75.0, 74.0, 75.0, 96.0, 74.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 113.0, 86.0, 86.0, 85.0, 98.0, 84.0, 84.0, 103.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 116.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 150.0, 161.0, 150.0, 150.0, 150.0, 151.0, 151.0, 151.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 55.0, 57.0, 56.0, 56.0, 56.0, 56.0, 57.0, 55.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 56.0, 56.0, 57.0, 56.0, 57.0, 56.0, 57.0], "value": 56.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 97.0, 87.0, 87.0, 97.0, 88.0, 113.0, 87.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 133.0, 133.0, 143.0, 132.0, 133.0, 142.0, 133.0, 134.0, 143.0], "value": 137.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 187.0, 203.0, 196.0, 193.0, 196.0, 196.0, 200.0, 203.0, 196.0], "value": 196.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 73.0, 72.0, 74.0, 74.0, 76.0, 77.0, 71.0, 72.0], "value": 74.1, "unit": "score"}]}]} 14:21:37 INFO - Return code: 0 14:21:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:21:37 INFO - # TBPL SUCCESS # 14:21:37 INFO - Running post-action listener: _resource_record_post_action 14:21:37 INFO - [mozharness: 2017-05-24 21:21:37.529965Z] Finished run-tests step (success) 14:21:37 INFO - Running post-run listener: _resource_record_post_run 14:21:37 INFO - Total resource usage - Wall time: 1255s; CPU: 13.0%; Read bytes: 12288; Write bytes: 403369984; Read time: 180; Write time: 277764 14:21:37 INFO - TinderboxPrint: CPU usage
12.8% 14:21:37 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 180 14:21:37 INFO - TinderboxPrint: I/O write bytes / time
403,369,984 / 277,764 14:21:37 INFO - TinderboxPrint: CPU idle
8,739.3 (87.2%) 14:21:37 INFO - TinderboxPrint: CPU user
1,223.6 (12.2%) 14:21:37 INFO - TinderboxPrint: Swap in / out
0 / 0 14:21:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 94494720; Read time: 84; Write time: 87392 14:21:37 INFO - run-tests - Wall time: 1239s; CPU: 13.0%; Read bytes: 8192; Write bytes: 308875264; Read time: 96; Write time: 190372 14:21:38 INFO - Running post-run listener: _upload_blobber_files 14:21:38 INFO - Blob upload gear active. 14:21:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:21:38 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 14:21:38 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:21:38 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:21:38 INFO - (blobuploader) - INFO - Open directory for files ... 14:21:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:21:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:21:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:39 INFO - (blobuploader) - INFO - Done attempting. 14:21:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:21:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:21:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:43 INFO - (blobuploader) - INFO - Done attempting. 14:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:21:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:44 INFO - (blobuploader) - INFO - Done attempting. 14:21:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:21:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:45 INFO - (blobuploader) - INFO - Done attempting. 14:21:45 INFO - (blobuploader) - INFO - Iteration through files over. 14:21:45 INFO - Return code: 0 14:21:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:21:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:21:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d52d08bf48ddc990907eff190db422872181082dc3804767c6aff087332dc72f9627a54994f56d782037bc3ff21865cb8c2556185e73d9191fe53c6008bf2933", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/86e7c12bb2109fac78ea5a0343c368c81196f55e1c3c7f41bde3ef81babc3e2141b87b2d37d9d0eb39af361e7d72ecd421ceab4e07ca4e89c9dd6b2946ed91dc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5fda610156d89e9cab928625d6a25f5eee62cc3e8ce3972a44a6dc13fb22ae808517620fd5ce30080bee74df2c25ae715b9d3933ae14e3631e9601268c9b13e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be4294df96094a39c56fe917183a9caccc34dd79bae83d69646a3d59c1359e23052f24c62ceafd22d67c617a8d4f8ad4e0ec5ea5ba816e0c9e210878f1d8e07a"} 14:21:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:21:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:21:45 INFO - Contents: 14:21:45 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d52d08bf48ddc990907eff190db422872181082dc3804767c6aff087332dc72f9627a54994f56d782037bc3ff21865cb8c2556185e73d9191fe53c6008bf2933", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/86e7c12bb2109fac78ea5a0343c368c81196f55e1c3c7f41bde3ef81babc3e2141b87b2d37d9d0eb39af361e7d72ecd421ceab4e07ca4e89c9dd6b2946ed91dc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5fda610156d89e9cab928625d6a25f5eee62cc3e8ce3972a44a6dc13fb22ae808517620fd5ce30080bee74df2c25ae715b9d3933ae14e3631e9601268c9b13e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be4294df96094a39c56fe917183a9caccc34dd79bae83d69646a3d59c1359e23052f24c62ceafd22d67c617a8d4f8ad4e0ec5ea5ba816e0c9e210878f1d8e07a"} 14:21:45 INFO - Running post-run listener: copy_logs_to_upload_dir 14:21:45 INFO - Copying logs to upload dir... 14:21:45 INFO - mkdir: /builds/slave/test/build/upload/logs 14:21:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1315.474491 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 55 secs) (at 2017-05-24 14:21:45.326030) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 14:21:45.333336) ========= 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=ea65d483da93c827905c2090000001f5-1495658669.496680-727035852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d52d08bf48ddc990907eff190db422872181082dc3804767c6aff087332dc72f9627a54994f56d782037bc3ff21865cb8c2556185e73d9191fe53c6008bf2933", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/86e7c12bb2109fac78ea5a0343c368c81196f55e1c3c7f41bde3ef81babc3e2141b87b2d37d9d0eb39af361e7d72ecd421ceab4e07ca4e89c9dd6b2946ed91dc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5fda610156d89e9cab928625d6a25f5eee62cc3e8ce3972a44a6dc13fb22ae808517620fd5ce30080bee74df2c25ae715b9d3933ae14e3631e9601268c9b13e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be4294df96094a39c56fe917183a9caccc34dd79bae83d69646a3d59c1359e23052f24c62ceafd22d67c617a8d4f8ad4e0ec5ea5ba816e0c9e210878f1d8e07a"} build_url:https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/VCP7xjcwSMKhl36s8DKKeQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d52d08bf48ddc990907eff190db422872181082dc3804767c6aff087332dc72f9627a54994f56d782037bc3ff21865cb8c2556185e73d9191fe53c6008bf2933", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/86e7c12bb2109fac78ea5a0343c368c81196f55e1c3c7f41bde3ef81babc3e2141b87b2d37d9d0eb39af361e7d72ecd421ceab4e07ca4e89c9dd6b2946ed91dc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5fda610156d89e9cab928625d6a25f5eee62cc3e8ce3972a44a6dc13fb22ae808517620fd5ce30080bee74df2c25ae715b9d3933ae14e3631e9601268c9b13e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be4294df96094a39c56fe917183a9caccc34dd79bae83d69646a3d59c1359e23052f24c62ceafd22d67c617a8d4f8ad4e0ec5ea5ba816e0c9e210878f1d8e07a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 14:21:45.358886) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 14:21:45.359237) ========= 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=ea65d483da93c827905c2090000001f5-1495658669.496680-727035852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 14:21:45.441708) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 14:21:45.445110) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 14:21:45.445454) ========= ========= Total master_lag: 0.29 =========