builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-015 starttime: 1504519628.4 results: success (0) revision: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:08.399651) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:08.400064) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:08.400348) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:08.413386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:08.413638) ========= 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=20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 03:07:08-- 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% 91.0M=0s 2017-09-04 03:07:08 (91.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.224102 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:08.653302) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:08.653606) ========= 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=20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013298 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:08.702353) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-04 03:07:08.702658) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 --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 cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 --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=20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 03:07:08,834 truncating revision to first 12 chars 2017-09-04 03:07:08,834 Setting DEBUG logging. 2017-09-04 03:07:08,834 attempt 1/10 2017-09-04 03:07:08,834 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cef1935ebd9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 03:07:09,532 unpacking tar archive at: mozilla-central-cef1935ebd9b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.068093 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-04 03:07:09.792935) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:09.793257) ========= script_repo_revision: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:09.794229) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:09.794498) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:07:09.807751) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-09-04 03:07:09.808097) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:07:10 INFO - MultiFileLogger online at 20170904 03:07:10 in /builds/slave/test 03:07:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:07:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:07:10 INFO - {'append_to_log': False, 03:07:10 INFO - 'base_work_dir': '/builds/slave/test', 03:07:10 INFO - 'blob_upload_branch': 'Firefox', 03:07:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:07:10 INFO - 'branch': 'Firefox', 03:07:10 INFO - 'buildbot_json_path': 'buildprops.json', 03:07:10 INFO - 'code_coverage': False, 03:07:10 INFO - 'config_files': ('talos/linux_config.py',), 03:07:10 INFO - 'default_actions': ('clobber', 03:07:10 INFO - 'read-buildbot-config', 03:07:10 INFO - 'download-and-extract', 03:07:10 INFO - 'populate-webroot', 03:07:10 INFO - 'create-virtualenv', 03:07:10 INFO - 'install', 03:07:10 INFO - 'setup-mitmproxy', 03:07:10 INFO - 'run-tests'), 03:07:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:07:10 INFO - 'disable_ccov_upload': False, 03:07:10 INFO - 'disable_stylo': False, 03:07:10 INFO - 'download_minidump_stackwalk': True, 03:07:10 INFO - 'download_symbols': 'ondemand', 03:07:10 INFO - 'enable_stylo': False, 03:07:10 INFO - 'enable_webrender': False, 03:07:10 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:07:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:07:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:07:10 INFO - 'gecko_profile': False, 03:07:10 INFO - 'gecko_profile_interval': 0, 03:07:10 INFO - 'installer_path': 'installer.exe', 03:07:10 INFO - 'log_level': 'info', 03:07:10 INFO - 'log_name': 'talos', 03:07:10 INFO - 'log_to_console': True, 03:07:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:07:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:07:10 INFO - 'opt_config_files': (), 03:07:10 INFO - 'pip_index': False, 03:07:10 INFO - 'suite': 'dromaeojs-e10s', 03:07:10 INFO - 'system_bits': '32', 03:07:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:07:10 INFO - 'title': 'talos-linux64-ix-015', 03:07:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:07:10 INFO - 'use_talos_json': True, 03:07:10 INFO - 'verify': 'False', 03:07:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:07:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:07:10 INFO - 'work_dir': 'build'} 03:07:10 INFO - [mozharness: 2017-09-04 10:07:10.152687Z] Running clobber step. 03:07:10 INFO - Running pre-action listener: _resource_record_pre_action 03:07:10 INFO - Running main action method: clobber 03:07:10 INFO - rmtree: /builds/slave/test/build 03:07:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:07:11 INFO - Running post-action listener: _resource_record_post_action 03:07:11 INFO - [mozharness: 2017-09-04 10:07:11.143644Z] Finished clobber step (success) 03:07:11 INFO - [mozharness: 2017-09-04 10:07:11.143741Z] Running read-buildbot-config step. 03:07:11 INFO - Running pre-action listener: _resource_record_pre_action 03:07:11 INFO - Running main action method: read_buildbot_config 03:07:11 INFO - Using buildbot properties: 03:07:11 INFO - { 03:07:11 INFO - "project": "", 03:07:11 INFO - "product": "firefox", 03:07:11 INFO - "who": "archaeopteryx@coole-files.de", 03:07:11 INFO - "installer_path": "public/build/target.tar.bz2", 03:07:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 03:07:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:07:11 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 03:07:11 INFO - "stage_platform": "linux64-qr", 03:07:11 INFO - "basedir": "/builds/slave/test", 03:07:11 INFO - "buildnumber": 33, 03:07:11 INFO - "platform": "ubuntu64_hw_qr", 03:07:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:07:11 INFO - "slavebuilddir": "test", 03:07:11 INFO - "taskId": "Yk55DQHfQ9-NcZTPTB6DXQ", 03:07:11 INFO - "branch": "mozilla-central", 03:07:11 INFO - "script_repo_revision": "production", 03:07:11 INFO - "revision": "cef1935ebd9b328fc1ab5f5171cc7cdb6844a855", 03:07:11 INFO - "slavename": "talos-linux64-ix-015", 03:07:11 INFO - "repo_path": "mozilla-central" 03:07:11 INFO - } 03:07:11 INFO - Finding installer, test and symbols from parent task. 03:07:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk55DQHfQ9-NcZTPTB6DXQ'}, attempt #1 03:07:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk55DQHfQ9-NcZTPTB6DXQ'}, attempt #1 03:07:12 INFO - Task dependencies: e7aTuZz-Q-mFnC0rW_6dHw 03:07:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw'}, attempt #1 03:07:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 03:07:12 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.test_packages.json 03:07:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.crashreporter-symbols.zip 03:07:12 INFO - Running post-action listener: _resource_record_post_action 03:07:12 INFO - [mozharness: 2017-09-04 10:07:12.532234Z] Finished read-buildbot-config step (success) 03:07:12 INFO - [mozharness: 2017-09-04 10:07:12.532454Z] Running download-and-extract step. 03:07:12 INFO - Running pre-action listener: _resource_record_pre_action 03:07:12 INFO - Running main action method: download_and_extract 03:07:12 INFO - mkdir: /builds/slave/test/build/tests 03:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:12 INFO - trying https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.test_packages.json 03:07:12 INFO - Downloading https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:07:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:07:13 INFO - Downloaded 989 bytes. 03:07:13 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:07:13 INFO - Using the following test package requirements: 03:07:13 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:07:13 INFO - u'common': [u'target.common.tests.zip'], 03:07:13 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:07:13 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:07:13 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:07:13 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:07:13 INFO - u'mozbase': [u'target.common.tests.zip'], 03:07:13 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:07:13 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:07:13 INFO - u'web-platform': [u'target.common.tests.zip', 03:07:13 INFO - u'target.web-platform.tests.tar.gz'], 03:07:13 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:07:13 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:07:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip 03:07:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:07:13 INFO - Fetch https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip into memory 03:07:14 INFO - Content-Length response header: 55022364 03:07:14 INFO - Bytes received: 55022364 03:07:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip 03:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip into memory 03:07:26 INFO - Content-Length response header: 55022364 03:07:26 INFO - Bytes received: 55022364 03:07:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.talos.tests.zip 03:07:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:07:38 INFO - Fetch https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.talos.tests.zip into memory 03:07:39 INFO - Content-Length response header: 14496650 03:07:39 INFO - Bytes received: 14496650 03:07: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')]} 03:07:39 INFO - trying https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 03:07:39 INFO - Downloading https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:07:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:07:40 INFO - Downloaded 62705404 bytes. 03:07:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 03:07:40 INFO - mkdir: /builds/slave/test/properties 03:07:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:07:40 INFO - Writing to file /builds/slave/test/properties/build_url 03:07:40 INFO - Contents: 03:07:40 INFO - build_url:https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 03:07:40 INFO - Running post-action listener: _resource_record_post_action 03:07:40 INFO - Running post-action listener: find_tests_for_verification 03:07:40 INFO - Running post-action listener: set_extra_try_arguments 03:07:40 INFO - [mozharness: 2017-09-04 10:07:40.677375Z] Finished download-and-extract step (success) 03:07:40 INFO - [mozharness: 2017-09-04 10:07:40.677473Z] Running populate-webroot step. 03:07:40 INFO - Running pre-action listener: _resource_record_pre_action 03:07:40 INFO - Running main action method: populate_webroot 03:07:40 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:07:40 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:07:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:07:40 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:07:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:07:40 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:07:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'tests': ['damp', 'tps']}, 03:07:40 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['damp', 'tps']}, 03:07:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:07:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:07:40 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:07:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:07:40 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:07:40 INFO - 'other-e10s': {'tests': ['a11yr', 03:07:40 INFO - 'ts_paint', 03:07:40 INFO - 'tpaint', 03:07:40 INFO - 'sessionrestore', 03:07:40 INFO - 'sessionrestore_many_windows', 03:07:40 INFO - 'sessionrestore_no_auto_restore', 03:07:40 INFO - 'tabpaint']}, 03:07:40 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['a11yr', 03:07:40 INFO - 'ts_paint', 03:07:40 INFO - 'tpaint', 03:07:40 INFO - 'sessionrestore', 03:07:40 INFO - 'sessionrestore_many_windows', 03:07:40 INFO - 'sessionrestore_no_auto_restore', 03:07:40 INFO - 'tabpaint']}, 03:07:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:07:40 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:07:40 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['bloom_basic_singleton']}, 03:07:40 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['bloom_basic']}, 03:07:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:07:40 INFO - 'tsvgr_opacity', 03:07:40 INFO - 'tart', 03:07:40 INFO - 'tscrollx', 03:07:40 INFO - 'tsvg_static']}, 03:07:40 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['tsvgx', 03:07:40 INFO - 'tsvgr_opacity', 03:07:40 INFO - 'tart', 03:07:40 INFO - 'tscrollx', 03:07:40 INFO - 'tsvg_static']}, 03:07:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:07:40 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'talos_options': ['--enable-stylo'], 03:07:40 INFO - 'tests': ['tp5o']}, 03:07:40 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:07:40 INFO - 'talos_options': ['--mitmproxy', 03:07:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:07:40 INFO - '--firstNonBlankPaint'], 03:07:40 INFO - 'tests': ['tp6_google', 03:07:40 INFO - 'tp6_youtube', 03:07:40 INFO - 'tp6_amazon', 03:07:40 INFO - 'tp6_facebook']}, 03:07:40 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:07:40 INFO - 'talos_options': ['--enable-stylo', 03:07:40 INFO - '--mitmproxy', 03:07:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:07:40 INFO - '--firstNonBlankPaint'], 03:07:40 INFO - 'tests': ['tp6_google', 03:07:40 INFO - 'tp6_youtube', 03:07:40 INFO - 'tp6_amazon', 03:07:40 INFO - 'tp6_facebook']}, 03:07:40 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:07:40 INFO - 'talos_options': ['--enable-stylo', 03:07:40 INFO - '--stylo-threads=1', 03:07:40 INFO - '--mitmproxy', 03:07:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:07:40 INFO - '--firstNonBlankPaint'], 03:07:40 INFO - 'tests': ['tp6_google', 03:07:40 INFO - 'tp6_youtube', 03:07:40 INFO - 'tp6_amazon', 03:07:40 INFO - 'tp6_facebook']}, 03:07:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'talos_options': ['--xperf_path', 03:07:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:07:40 INFO - 'tests': ['tp5n']}, 03:07:40 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:07:40 INFO - 'talos_options': ['--enable-stylo', 03:07:40 INFO - '--xperf_path', 03:07:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:07:40 INFO - 'tests': ['tp5n']}}} 03:07:40 INFO - Running post-action listener: _resource_record_post_action 03:07:40 INFO - [mozharness: 2017-09-04 10:07:40.692446Z] Finished populate-webroot step (success) 03:07:40 INFO - [mozharness: 2017-09-04 10:07:40.692537Z] Running create-virtualenv step. 03:07:40 INFO - Running pre-action listener: _resource_record_pre_action 03:07:40 INFO - Running main action method: create_virtualenv 03:07:40 INFO - Creating virtualenv /builds/slave/test/build/venv 03:07:40 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:07:40 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:07:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:07:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:07:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:07:43 INFO - Installing distribute.............................................................................................................................................................................................done. 03:07:43 INFO - Installing pip...............done. 03:07:43 INFO - Return code: 0 03:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:07:43 INFO - Reading from file tmpfile_stdout 03:07:43 INFO - Output received: 03:07:43 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:07:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:07:43 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')]} 03:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:43 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')]} 03:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:43 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:07:43 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 03:07:43 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 03:07:43 INFO - Using env: {'DISPLAY': ':0', 03:07:43 INFO - 'HOME': '/home/cltbld', 03:07:43 INFO - 'LANG': 'en_US.UTF-8', 03:07:43 INFO - 'LANGUAGE': 'en_US:en', 03:07:43 INFO - 'LOGNAME': 'cltbld', 03:07:43 INFO - 'MAIL': '/var/mail/cltbld', 03:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:43 INFO - 'MOZ_NO_REMOTE': '1', 03:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:43 INFO - 'NO_EM_RESTART': '1', 03:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:43 INFO - 'PWD': '/builds/slave/test', 03:07:43 INFO - 'SHELL': '/bin/bash', 03:07:43 INFO - 'SHLVL': '1', 03:07:43 INFO - 'TERM': 'linux', 03:07:43 INFO - 'TMOUT': '86400', 03:07:43 INFO - 'USER': 'cltbld', 03:07:43 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:43 INFO - '_': '/tools/buildbot/bin/python'} 03:07:44 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:07:44 INFO - Downloading/unpacking pip>=1.5 03:07:44 INFO - Running setup.py egg_info for package pip 03:07:44 INFO - warning: no files found matching 'pip/cacert.pem' 03:07:44 INFO - warning: no files found matching '*.html' under directory 'docs' 03:07:44 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:07:44 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:07:44 INFO - Installing collected packages: pip 03:07:44 INFO - Found existing installation: pip 1.1 03:07:44 INFO - Uninstalling pip: 03:07:44 INFO - Successfully uninstalled pip 03:07:44 INFO - Running setup.py install for pip 03:07:44 INFO - warning: no files found matching 'pip/cacert.pem' 03:07:44 INFO - warning: no files found matching '*.html' under directory 'docs' 03:07:44 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:07:44 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:07:44 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:07:44 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:07:44 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:07:44 INFO - Successfully installed pip 03:07:44 INFO - Cleaning up... 03:07:44 INFO - Return code: 0 03:07:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:07:44 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')]} 03:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:44 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')]} 03:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:44 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:07:44 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 03:07:44 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 03:07:44 INFO - Using env: {'DISPLAY': ':0', 03:07:44 INFO - 'HOME': '/home/cltbld', 03:07:44 INFO - 'LANG': 'en_US.UTF-8', 03:07:44 INFO - 'LANGUAGE': 'en_US:en', 03:07:44 INFO - 'LOGNAME': 'cltbld', 03:07:44 INFO - 'MAIL': '/var/mail/cltbld', 03:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:44 INFO - 'MOZ_NO_REMOTE': '1', 03:07:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:44 INFO - 'NO_EM_RESTART': '1', 03:07:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:44 INFO - 'PWD': '/builds/slave/test', 03:07:44 INFO - 'SHELL': '/bin/bash', 03:07:44 INFO - 'SHLVL': '1', 03:07:44 INFO - 'TERM': 'linux', 03:07:44 INFO - 'TMOUT': '86400', 03:07:44 INFO - 'USER': 'cltbld', 03:07:44 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:44 INFO - '_': '/tools/buildbot/bin/python'} 03:07:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:45 INFO - Downloading/unpacking psutil>=3.1.1 03:07:45 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 03:07:45 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 03:07:45 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 03:07:45 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 03:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:07:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:07:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:07:46 INFO - Installing collected packages: psutil 03:07:46 INFO - Running setup.py install for psutil 03:07:46 INFO - building 'psutil._psutil_linux' extension 03:07:46 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 03:07:47 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 03:07:47 INFO - building 'psutil._psutil_posix' extension 03:07:47 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 03:07:47 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 03:07:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:07:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:07:47 INFO - Successfully installed psutil 03:07:47 INFO - Cleaning up... 03:07:47 INFO - Return code: 0 03:07:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:47 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:07:47 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 03:07:47 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 03:07:47 INFO - Using env: {'DISPLAY': ':0', 03:07:47 INFO - 'HOME': '/home/cltbld', 03:07:47 INFO - 'LANG': 'en_US.UTF-8', 03:07:47 INFO - 'LANGUAGE': 'en_US:en', 03:07:47 INFO - 'LOGNAME': 'cltbld', 03:07:47 INFO - 'MAIL': '/var/mail/cltbld', 03:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:47 INFO - 'MOZ_NO_REMOTE': '1', 03:07:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:47 INFO - 'NO_EM_RESTART': '1', 03:07:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:47 INFO - 'PWD': '/builds/slave/test', 03:07:47 INFO - 'SHELL': '/bin/bash', 03:07:47 INFO - 'SHLVL': '1', 03:07:47 INFO - 'TERM': 'linux', 03:07:47 INFO - 'TMOUT': '86400', 03:07:47 INFO - 'USER': 'cltbld', 03:07:47 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:47 INFO - '_': '/tools/buildbot/bin/python'} 03:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:07:48 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 03:07:48 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 03:07:48 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 03:07:48 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 03:07:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:07:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:07:49 INFO - Installing collected packages: mozsystemmonitor 03:07:49 INFO - Running setup.py install for mozsystemmonitor 03:07:49 INFO - Successfully installed mozsystemmonitor 03:07:49 INFO - Cleaning up... 03:07:49 INFO - Return code: 0 03:07:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:49 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:07:49 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 03:07:49 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 03:07:49 INFO - Using env: {'DISPLAY': ':0', 03:07:49 INFO - 'HOME': '/home/cltbld', 03:07:49 INFO - 'LANG': 'en_US.UTF-8', 03:07:49 INFO - 'LANGUAGE': 'en_US:en', 03:07:49 INFO - 'LOGNAME': 'cltbld', 03:07:49 INFO - 'MAIL': '/var/mail/cltbld', 03:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:49 INFO - 'MOZ_NO_REMOTE': '1', 03:07:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:49 INFO - 'NO_EM_RESTART': '1', 03:07:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:49 INFO - 'PWD': '/builds/slave/test', 03:07:49 INFO - 'SHELL': '/bin/bash', 03:07:49 INFO - 'SHLVL': '1', 03:07:49 INFO - 'TERM': 'linux', 03:07:49 INFO - 'TMOUT': '86400', 03:07:49 INFO - 'USER': 'cltbld', 03:07:49 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:49 INFO - '_': '/tools/buildbot/bin/python'} 03:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:49 INFO - Downloading/unpacking jsonschema==2.5.1 03:07:49 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 03:07:49 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 03:07:49 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 03:07:49 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 03:07:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:07:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:50 INFO - Downloading functools32-3.2.3-2.tar.gz 03:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:07:50 INFO - warning: no files found matching '*.txt' 03:07:50 INFO - no previously-included directories found matching 'build' 03:07:50 INFO - no previously-included directories found matching 'dist' 03:07:50 INFO - no previously-included directories found matching '.git*' 03:07:50 INFO - Installing collected packages: jsonschema, functools32 03:07:50 INFO - Running setup.py install for functools32 03:07:51 INFO - warning: no files found matching '*.txt' 03:07:51 INFO - no previously-included directories found matching 'build' 03:07:51 INFO - no previously-included directories found matching 'dist' 03:07:51 INFO - no previously-included directories found matching '.git*' 03:07:51 INFO - Successfully installed jsonschema functools32 03:07:51 INFO - Cleaning up... 03:07:51 INFO - Return code: 0 03:07:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:51 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:07:51 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 03:07:51 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 03:07:51 INFO - Using env: {'DISPLAY': ':0', 03:07:51 INFO - 'HOME': '/home/cltbld', 03:07:51 INFO - 'LANG': 'en_US.UTF-8', 03:07:51 INFO - 'LANGUAGE': 'en_US:en', 03:07:51 INFO - 'LOGNAME': 'cltbld', 03:07:51 INFO - 'MAIL': '/var/mail/cltbld', 03:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:51 INFO - 'MOZ_NO_REMOTE': '1', 03:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:51 INFO - 'NO_EM_RESTART': '1', 03:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:51 INFO - 'PWD': '/builds/slave/test', 03:07:51 INFO - 'SHELL': '/bin/bash', 03:07:51 INFO - 'SHLVL': '1', 03:07:51 INFO - 'TERM': 'linux', 03:07:51 INFO - 'TMOUT': '86400', 03:07:51 INFO - 'USER': 'cltbld', 03:07:51 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:51 INFO - '_': '/tools/buildbot/bin/python'} 03:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:07:51 INFO - Cleaning up... 03:07:51 INFO - Return code: 0 03:07:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:51 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:07:51 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 03:07:51 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 03:07:51 INFO - Using env: {'DISPLAY': ':0', 03:07:51 INFO - 'HOME': '/home/cltbld', 03:07:51 INFO - 'LANG': 'en_US.UTF-8', 03:07:51 INFO - 'LANGUAGE': 'en_US:en', 03:07:51 INFO - 'LOGNAME': 'cltbld', 03:07:51 INFO - 'MAIL': '/var/mail/cltbld', 03:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:51 INFO - 'MOZ_NO_REMOTE': '1', 03:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:51 INFO - 'NO_EM_RESTART': '1', 03:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:51 INFO - 'PWD': '/builds/slave/test', 03:07:51 INFO - 'SHELL': '/bin/bash', 03:07:51 INFO - 'SHLVL': '1', 03:07:51 INFO - 'TERM': 'linux', 03:07:51 INFO - 'TMOUT': '86400', 03:07:51 INFO - 'USER': 'cltbld', 03:07:51 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:51 INFO - '_': '/tools/buildbot/bin/python'} 03:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:51 INFO - Downloading/unpacking blobuploader==1.2.4 03:07:51 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 03:07:51 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 03:07:51 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 03:07:51 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 03:07:52 INFO - Downloading blobuploader-1.2.4.tar.gz 03:07:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:07:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:07:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:07:53 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 03:07:53 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 03:07:53 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 03:07:53 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 03:07:53 INFO - Downloading docopt-0.6.1.tar.gz 03:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:07:53 INFO - Installing collected packages: blobuploader, requests, docopt 03:07:53 INFO - Running setup.py install for blobuploader 03:07:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:07:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:07:53 INFO - Running setup.py install for requests 03:07:53 INFO - Running setup.py install for docopt 03:07:53 INFO - Successfully installed blobuploader requests docopt 03:07:53 INFO - Cleaning up... 03:07:53 INFO - Return code: 0 03:07:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:07:53 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')]} 03:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:53 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')]} 03:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:53 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:07:53 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 03:07:53 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 03:07:53 INFO - Using env: {'DISPLAY': ':0', 03:07:53 INFO - 'HOME': '/home/cltbld', 03:07:53 INFO - 'LANG': 'en_US.UTF-8', 03:07:53 INFO - 'LANGUAGE': 'en_US:en', 03:07:53 INFO - 'LOGNAME': 'cltbld', 03:07:53 INFO - 'MAIL': '/var/mail/cltbld', 03:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:53 INFO - 'MOZ_NO_REMOTE': '1', 03:07:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:53 INFO - 'NO_EM_RESTART': '1', 03:07:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:53 INFO - 'PWD': '/builds/slave/test', 03:07:53 INFO - 'SHELL': '/bin/bash', 03:07:53 INFO - 'SHLVL': '1', 03:07:53 INFO - 'TERM': 'linux', 03:07:53 INFO - 'TMOUT': '86400', 03:07:53 INFO - 'USER': 'cltbld', 03:07:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:53 INFO - '_': '/tools/buildbot/bin/python'} 03:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:07:54 INFO - Running setup.py (path:/tmp/pip-sS17s4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:07:54 INFO - Running setup.py (path:/tmp/pip-MnA4wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:07:54 INFO - Running setup.py (path:/tmp/pip-g3hbyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:07:54 INFO - Running setup.py (path:/tmp/pip-kL1qSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:07:54 INFO - Running setup.py (path:/tmp/pip-bgqu3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:07:54 INFO - Running setup.py (path:/tmp/pip-ypotk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:07:54 INFO - Running setup.py (path:/tmp/pip-slk__q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:07:54 INFO - Running setup.py (path:/tmp/pip-BFr9sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:07:54 INFO - Running setup.py (path:/tmp/pip-EXWXst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:07:54 INFO - Running setup.py (path:/tmp/pip-s9ak5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:07:54 INFO - Running setup.py (path:/tmp/pip-PrvDy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:07:54 INFO - Running setup.py (path:/tmp/pip-nYCksp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:07:54 INFO - Running setup.py (path:/tmp/pip-68toiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:07:54 INFO - Running setup.py (path:/tmp/pip-Lvaw_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:55 INFO - Running setup.py (path:/tmp/pip-Wqssuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:07:55 INFO - Running setup.py (path:/tmp/pip-7uSBY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:07:55 INFO - Running setup.py (path:/tmp/pip-gHf1ON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:07:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:07:55 INFO - Running setup.py install for manifestparser 03:07:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:07:55 INFO - Running setup.py install for mozcrash 03:07:55 INFO - Running setup.py install for mozdebug 03:07:55 INFO - Running setup.py install for mozdevice 03:07:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:07:55 INFO - Running setup.py install for mozfile 03:07:55 INFO - Running setup.py install for mozhttpd 03:07:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:07:55 INFO - Running setup.py install for mozinfo 03:07:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:07:55 INFO - Running setup.py install for mozInstall 03:07:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:07:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:07:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:07:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:07:56 INFO - Running setup.py install for mozleak 03:07:56 INFO - Running setup.py install for mozlog 03:07:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:07:56 INFO - Running setup.py install for moznetwork 03:07:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:07:56 INFO - Running setup.py install for mozprocess 03:07:56 INFO - Running setup.py install for mozprofile 03:07:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:07:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:07:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:07:56 INFO - Running setup.py install for mozrunner 03:07:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:07:56 INFO - Running setup.py install for mozscreenshot 03:07:56 INFO - Running setup.py install for moztest 03:07:56 INFO - Running setup.py install for mozversion 03:07:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:07:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:07:56 INFO - Cleaning up... 03:07:56 INFO - Return code: 0 03:07:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:57 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:07:57 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 03:07:57 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 03:07:57 INFO - Using env: {'DISPLAY': ':0', 03:07:57 INFO - 'HOME': '/home/cltbld', 03:07:57 INFO - 'LANG': 'en_US.UTF-8', 03:07:57 INFO - 'LANGUAGE': 'en_US:en', 03:07:57 INFO - 'LOGNAME': 'cltbld', 03:07:57 INFO - 'MAIL': '/var/mail/cltbld', 03:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:57 INFO - 'MOZ_NO_REMOTE': '1', 03:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:57 INFO - 'NO_EM_RESTART': '1', 03:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:57 INFO - 'PWD': '/builds/slave/test', 03:07:57 INFO - 'SHELL': '/bin/bash', 03:07:57 INFO - 'SHLVL': '1', 03:07:57 INFO - 'TERM': 'linux', 03:07:57 INFO - 'TMOUT': '86400', 03:07:57 INFO - 'USER': 'cltbld', 03:07:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:57 INFO - '_': '/tools/buildbot/bin/python'} 03:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:07:57 INFO - Running setup.py (path:/tmp/pip-epVdAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:07:57 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)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:07:57 INFO - Running setup.py (path:/tmp/pip-dztTxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:07:57 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)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:07:57 INFO - Running setup.py (path:/tmp/pip-zNM4eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:07:57 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)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:07:57 INFO - Running setup.py (path:/tmp/pip-70HvqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:07:57 INFO - Running setup.py (path:/tmp/pip-lKVV16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:07:57 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)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:07:57 INFO - Running setup.py (path:/tmp/pip-PcVVVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:07:57 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)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:07:57 INFO - Running setup.py (path:/tmp/pip-vFZJil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:07:57 INFO - Running setup.py (path:/tmp/pip-ljyitv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:07:57 INFO - Running setup.py (path:/tmp/pip-W7b2Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:07:57 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)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:07:57 INFO - Running setup.py (path:/tmp/pip-2Ky5DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:07:57 INFO - Running setup.py (path:/tmp/pip-W_Qsie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:07:57 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)) 03:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:07:58 INFO - Running setup.py (path:/tmp/pip-gppWeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:07:58 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)) 03:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:07:58 INFO - Running setup.py (path:/tmp/pip-E3ydXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:07:58 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)) 03:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:07:58 INFO - Running setup.py (path:/tmp/pip-NrjLC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:07:58 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)) 03:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:58 INFO - Running setup.py (path:/tmp/pip-0s96tG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:58 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)) 03:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:07:58 INFO - Running setup.py (path:/tmp/pip-wy7vYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:07:58 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)) 03:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:07:58 INFO - Running setup.py (path:/tmp/pip-WgoGHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:07:58 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)) 03:07:58 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)) 03:07:58 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)) 03:07:58 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)) 03:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:07:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:00 INFO - Downloading blessings-1.6.tar.gz 03:08:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:08:00 INFO - Installing collected packages: blessings 03:08:00 INFO - Running setup.py install for blessings 03:08:00 INFO - Successfully installed blessings 03:08:00 INFO - Cleaning up... 03:08:00 INFO - Return code: 0 03:08:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:08:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:08:00 INFO - Reading from file tmpfile_stdout 03:08:00 INFO - Current package versions: 03:08:00 INFO - argparse == 1.2.1 03:08:00 INFO - blessings == 1.6 03:08:00 INFO - blobuploader == 1.2.4 03:08:00 INFO - docopt == 0.6.1 03:08:00 INFO - functools32 == 3.2.3-2 03:08:00 INFO - jsonschema == 2.5.1 03:08:00 INFO - manifestparser == 1.1 03:08:00 INFO - mozInstall == 1.13 03:08:00 INFO - mozcrash == 1.0 03:08:00 INFO - mozdebug == 0.1 03:08:00 INFO - mozdevice == 0.51 03:08:00 INFO - mozfile == 1.2 03:08:00 INFO - mozhttpd == 0.7 03:08:00 INFO - mozinfo == 0.10 03:08:00 INFO - mozleak == 0.1 03:08:00 INFO - mozlog == 3.5 03:08:00 INFO - moznetwork == 0.27 03:08:00 INFO - mozprocess == 0.25 03:08:00 INFO - mozprofile == 0.28 03:08:00 INFO - mozrunner == 6.13 03:08:00 INFO - mozscreenshot == 0.1 03:08:00 INFO - mozsystemmonitor == 0.3 03:08:00 INFO - moztest == 0.8 03:08:00 INFO - mozversion == 1.4 03:08:00 INFO - psutil == 3.1.1 03:08:00 INFO - requests == 1.2.3 03:08:00 INFO - wsgiref == 0.1.2 03:08:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:08:01 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')]} 03:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:01 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')]} 03:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:01 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 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:08:01 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 03:08:01 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 03:08:01 INFO - Using env: {'DISPLAY': ':0', 03:08:01 INFO - 'HOME': '/home/cltbld', 03:08:01 INFO - 'LANG': 'en_US.UTF-8', 03:08:01 INFO - 'LANGUAGE': 'en_US:en', 03:08:01 INFO - 'LOGNAME': 'cltbld', 03:08:01 INFO - 'MAIL': '/var/mail/cltbld', 03:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:01 INFO - 'MOZ_NO_REMOTE': '1', 03:08:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:01 INFO - 'NO_EM_RESTART': '1', 03:08:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:01 INFO - 'PWD': '/builds/slave/test', 03:08:01 INFO - 'SHELL': '/bin/bash', 03:08:01 INFO - 'SHLVL': '1', 03:08:01 INFO - 'TERM': 'linux', 03:08:01 INFO - 'TMOUT': '86400', 03:08:01 INFO - 'USER': 'cltbld', 03:08:01 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:01 INFO - '_': '/tools/buildbot/bin/python'} 03:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:01 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)) 03:08:01 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)) 03:08:01 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)) 03:08:01 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)) 03:08:01 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)) 03:08:01 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)) 03:08:01 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)) 03:08:01 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)) 03:08:01 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)) 03:08:01 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:08:02 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)) 03:08:02 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)) 03:08:02 INFO - Installing collected packages: simplejson 03:08:02 INFO - Running setup.py install for simplejson 03:08:02 INFO - building 'simplejson._speedups' extension 03:08:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:08:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:08:03 INFO - Successfully installed simplejson 03:08:03 INFO - Cleaning up... 03:08:03 INFO - Return code: 0 03:08:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1877a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', '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 03:08:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:08:03 INFO - Using env: {'DISPLAY': ':0', 03:08:03 INFO - 'HOME': '/home/cltbld', 03:08:03 INFO - 'LANG': 'en_US.UTF-8', 03:08:03 INFO - 'LANGUAGE': 'en_US:en', 03:08:03 INFO - 'LOGNAME': 'cltbld', 03:08:03 INFO - 'MAIL': '/var/mail/cltbld', 03:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:03 INFO - 'MOZ_NO_REMOTE': '1', 03:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:03 INFO - 'NO_EM_RESTART': '1', 03:08:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:03 INFO - 'PWD': '/builds/slave/test', 03:08:03 INFO - 'SHELL': '/bin/bash', 03:08:03 INFO - 'SHLVL': '1', 03:08:03 INFO - 'TERM': 'linux', 03:08:03 INFO - 'TMOUT': '86400', 03:08:03 INFO - 'USER': 'cltbld', 03:08:03 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:03 INFO - '_': '/tools/buildbot/bin/python'} 03:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:08:03 INFO - Cleaning up... 03:08:03 INFO - Return code: 0 03:08:03 INFO - Running post-action listener: _resource_record_post_action 03:08:03 INFO - Running post-action listener: _start_resource_monitoring 03:08:03 INFO - Starting resource monitoring. 03:08:03 INFO - [mozharness: 2017-09-04 10:08:03.714186Z] Finished create-virtualenv step (success) 03:08:03 INFO - [mozharness: 2017-09-04 10:08:03.715206Z] Running install step. 03:08:03 INFO - Running pre-action listener: _resource_record_pre_action 03:08:03 INFO - Running main action method: install 03:08:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:08:03 INFO - Reading from file tmpfile_stdout 03:08:03 INFO - Detecting whether we're running mozinstall >=1.0... 03:08:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:08:04 INFO - Reading from file tmpfile_stdout 03:08:04 INFO - Output received: 03:08:04 INFO - Usage: mozinstall [options] installer 03:08:04 INFO - Options: 03:08:04 INFO - -h, --help show this help message and exit 03:08:04 INFO - -d DEST, --destination=DEST 03:08:04 INFO - Directory to install application into. [default: 03:08:04 INFO - "/builds/slave/test"] 03:08:04 INFO - --app=APP Application being installed. [default: firefox] 03:08:04 INFO - mkdir: /builds/slave/test/build/application 03:08:04 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'] 03:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:08:20 INFO - Reading from file tmpfile_stdout 03:08:20 INFO - Output received: 03:08:20 INFO - /builds/slave/test/build/application/firefox/firefox 03:08:20 INFO - Running post-action listener: _resource_record_post_action 03:08:20 INFO - [mozharness: 2017-09-04 10:08:20.583911Z] Finished install step (success) 03:08:20 INFO - [mozharness: 2017-09-04 10:08:20.584164Z] Running setup-mitmproxy step. 03:08:20 INFO - Running pre-action listener: _resource_record_pre_action 03:08:20 INFO - Running main action method: setup_mitmproxy 03:08:20 INFO - Skipping: mitmproxy is not required 03:08:20 INFO - Running post-action listener: _resource_record_post_action 03:08:20 INFO - [mozharness: 2017-09-04 10:08:20.585285Z] Finished setup-mitmproxy step (success) 03:08:20 INFO - [mozharness: 2017-09-04 10:08:20.585511Z] Running run-tests step. 03:08:20 INFO - Running pre-action listener: _resource_record_pre_action 03:08:20 INFO - Running pre-action listener: _set_gcov_prefix 03:08:20 INFO - Running main action method: run_tests 03:08:20 WARNING - Try message not found. 03:08:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:08:20 INFO - Python 2.7.3 03:08:20 INFO - Return code: 0 03:08:20 INFO - grabbing minidump binary from tooltool 03:08: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')]} 03:08:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x1a1e090>, '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 0x1a1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a18f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:08:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 03:08:20 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:08:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 03:08:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:08:20 INFO - Return code: 0 03:08:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:08:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:08:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:08:20 INFO - ENV: RUST_BACKTRACE is now full 03:08:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:08:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:08:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 03:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 03:08:20 INFO - Using env: {'DISPLAY': ':0', 03:08:20 INFO - 'HOME': '/home/cltbld', 03:08:20 INFO - 'LANG': 'en_US.UTF-8', 03:08:20 INFO - 'LANGUAGE': 'en_US:en', 03:08:20 INFO - 'LOGNAME': 'cltbld', 03:08:20 INFO - 'MAIL': '/var/mail/cltbld', 03:08:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:08:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:08:20 INFO - 'MOZ_ACCELERATED': '1', 03:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:20 INFO - 'MOZ_NO_REMOTE': '1', 03:08:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:08:20 INFO - 'MOZ_WEBRENDER': '1', 03:08:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:20 INFO - 'NO_EM_RESTART': '1', 03:08:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:20 INFO - 'PWD': '/builds/slave/test', 03:08:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:08:20 INFO - 'RUST_BACKTRACE': 'full', 03:08:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:08:20 INFO - 'SHELL': '/bin/bash', 03:08:20 INFO - 'SHLVL': '1', 03:08:20 INFO - 'TERM': 'linux', 03:08:20 INFO - 'TMOUT': '86400', 03:08:20 INFO - 'USER': 'cltbld', 03:08:20 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:20 INFO - '_': '/tools/buildbot/bin/python'} 03:08:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 03:08:20 INFO - mozversion application_buildid: 20170904091152 03:08:20 INFO - mozversion application_changeset: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 03:08:20 INFO - mozversion application_display_name: Nightly 03:08:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:08:20 INFO - mozversion application_name: Firefox 03:08:20 INFO - mozversion application_remotingname: firefox 03:08:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:08:20 INFO - mozversion application_vendor: Mozilla 03:08:20 INFO - mozversion application_version: 57.0a1 03:08:20 INFO - mozversion platform_buildid: 20170904091152 03:08:20 INFO - mozversion platform_changeset: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 03:08:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:08:20 INFO - mozversion platform_version: 57.0a1 03:08:20 INFO - using testdate: 1504519700 03:08:20 INFO - actual date: 1504519700 03:08:20 INFO - starting webserver on 'localhost:52444' 03:08:20 INFO - SUITE-START | Running 2 tests 03:08:21 INFO - TEST-START | dromaeo_css 03:08:21 INFO - Initialising browser for dromaeo_css test... 03:08:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52444/getInfo.html -profile /tmp/tmpZkvywg/profile 03:08:21 INFO - TEST-INFO | started process 3586 (/builds/slave/test/build/application/firefox/firefox http://localhost:52444/getInfo.html) 03:08:28 INFO - TEST-INFO | 3586: exit 0 03:08:28 INFO - Browser initialized. 03:08:28 INFO - Running cycle 1/1 for dromaeo_css test... 03:08:28 INFO - Using env: {'DISPLAY': ':0', 03:08:28 INFO - 'HOME': '/home/cltbld', 03:08:28 INFO - 'JSGC_DISABLE_POISONING': '1', 03:08:28 INFO - 'LANG': 'en_US.UTF-8', 03:08:28 INFO - 'LANGUAGE': 'en_US:en', 03:08:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:08:28 INFO - 'LOGNAME': 'cltbld', 03:08:28 INFO - 'MAIL': '/var/mail/cltbld', 03:08:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:08:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:08:28 INFO - 'MOZ_ACCELERATED': '1', 03:08:28 INFO - 'MOZ_CRASHREPORTER': '1', 03:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:08:28 INFO - 'MOZ_NO_REMOTE': '1', 03:08:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:08:28 INFO - 'MOZ_WEBRENDER': '1', 03:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:28 INFO - 'NO_EM_RESTART': '1', 03:08:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:28 INFO - 'PWD': '/builds/slave/test', 03:08:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:08:28 INFO - 'RUST_BACKTRACE': 'full', 03:08:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:08:28 INFO - 'SHELL': '/bin/bash', 03:08:28 INFO - 'SHLVL': '1', 03:08:28 INFO - 'TERM': 'linux', 03:08:28 INFO - 'TMOUT': '86400', 03:08:28 INFO - 'USER': 'cltbld', 03:08:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:28 INFO - '_': '/tools/buildbot/bin/python'} 03:08:28 INFO - TEST-INFO | started process 3782 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZkvywg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:08:29 INFO - PID 3782 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:08:29 INFO - PID 3782 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:08:29 INFO - PID 3782 | 03:08:29 INFO - PID 3782 | (/builds/slave/test/build/application/firefox/firefox:3844): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:08:29 INFO - PID 3782 | 03:08:40 INFO - PID 3782 | RSS: Main: 206393344 03:08:40 INFO - PID 3782 | 03:08:43 INFO - PID 3782 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:11:40 INFO - PID 3782 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52444/tests/dromaeo/cssquery-ext.html) 03:11:41 INFO - PID 3782 | RSS: Main: 215298048 03:11:41 INFO - PID 3782 | 03:14:43 INFO - PID 3782 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-ext.html (next: http://localhost:52444/tests/dromaeo/cssquery-jquery.html) 03:14:44 INFO - PID 3782 | RSS: Main: 213876736 03:14:44 INFO - PID 3782 | 03:17:46 INFO - PID 3782 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52444/tests/dromaeo/cssquery-mootools.html) 03:17:47 INFO - PID 3782 | RSS: Main: 215248896 03:17:47 INFO - PID 3782 | 03:20:49 INFO - PID 3782 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52444/tests/dromaeo/cssquery-prototype.html) 03:20:49 INFO - PID 3782 | RSS: Main: 213786624 03:20:49 INFO - PID 3782 | 03:23:53 INFO - PID 3782 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52444/tests/dromaeo/cssquery-yui.html) 03:23:54 INFO - PID 3782 | RSS: Main: 215330816 03:23:54 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-yui.html (next: http://localhost:52444/tests/dromaeo/cssquery-dojo.html) 03:26:56 INFO - PID 3782 | RSS: Main: 220336128 03:26:56 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | __start_tp_report 03:26:56 INFO - PID 3782 | _x_x_mozilla_page_load 03:26:56 INFO - PID 3782 | _x_x_mozilla_page_load_details 03:26:56 INFO - PID 3782 | |i|pagename|runs| 03:26:56 INFO - PID 3782 | |0;dojo.html;3322.5514555535;3334.683205095515;3361.1093060339;3369.511420843977;3373.342198271335;6247.344028876971;6473.67631618419;6497;6497.025446183069;6547.345265473462;6360.363963603637;6364.681765911709;6377.744890204398;6430.614163150202;6434.163558737369;5870.647761134345;5907.527397808176;5918.40815918409;5924.37794031627;5942.197803296564;5830.737616807232;5942.078977758454;5971.970140149315;5985.042393217086;6027.39726027396;5927.2294601701715;5993.340732519419;6030.035194368902;6055.848603784919;6080.057591073369;5803.2165657636315;5888.411158884121;5899;5900.409959004108;5997.88004239915;5826.796062137847;5922.466977972003;5962.582619216657;6003.7898673546215;6039.969800151015;6244.968775156095;6367.363263673642;6370.745170193187;6417.197850268716;6461.870762584769;6785.050092986986;6808;6839.247682754893;6854.794356169273;6925.376716095525;7002.229754726976;7017.403520700746;7017.719291228344;7022.403095736869;7027.367536921702;6443.548951573392;6457.6448295343735;6481.157449531578;6488.870222595616;6500.154979852683;7071.823204419828;7115.822104447327;7125.786226413215;7201.4238860891;7213.170485394142;6510.511711621552;6746.831329216809;6757.695903684346;6837.282085381063;6895.862082758316;6766;6784.8303792404595;6883.105196324445;6905.37851593359;6905.37851593359;6903.102596662402;6920.8961865572055;6933.861322773516;6951.6871740771785;6956.930430695729;6865.656717164122;6881.621510816954;6892;6978.825529361705;6980.092587963532;5320.946790532123;5413.188021796684;5417.214503896913;5421.322334708161;5500.532454741392;4685.695429797052;4689.7655117244;4703.7883295251795;4712.246040633482;4731.053789242096;5905;5952.494038006731;5971.671558064261;5976;5993.850153746192;6784.423324017514;6801.353871382211;6867.107276054181;6899;6910.585364878123;5916.556258280646;5970.791022314285;5982.820515384546;5985.850353741104;6069.514438844882;5973.880522389615;5974.2233509643465;6025.307089684742;6054.606450580786;6056.9697151513965;6371.362863713684;6466.418022377915;6535.542512024208;6563.015547667888;6677.065210870482;6877.105976223059;6910.067140935909;6912;6921.8961715575315;6923.861522769617;6582.341765823379;6595.505337099641;6613.305602911817;6626.5030122741955;6630.171228596426;6737.831554211184;6765.8985115224295;6770.1537307836525;6845.863082738218;6849.589024658537;6713.12729345182;6773.254941956282;6893.138357705287;6898.965505172442;6906.516543841883;6563.573367731082;6609.867802644017;6640.966795165993;6650.866982660416;6653.866922661617;6532.183477065367;6581.308962558863;6627.1716035495565;6635.369639884203;6648.90026649591;5781.855453613525;6511.772087976898;6553.901691474536;6583.769568065095;6685.8997115044285;6755.189377274758;6784.050232967487;6800.353966373186;6840.828979275558;6888.311168883071;6305.495560355253;6380.904286435614;6383;6512.6092434454085;6531.706073226811 03:26:56 INFO - PID 3782 | |1;ext.html;638.3616383616384;696.8710788504127;723.1864152828068;724.272106532934;725.9237780033096;30072.84963575182;30740.231494212654;39117.60882391175;39928.20143597126;39947.60052399475;39709;39788.80105599468;39872.40191397131;39901;39917.800410997974;7225.060742103519;7237.167725711558;7331.753601887692;7337.046183996073;7364.963175184143;7250;7342.8898566521275;7405.148407933103;7408.259174082603;7418.332350088491;5772.365039845614;5861.8827623447505;5935.584509084366;7366;7384.335409813116;7354.963225183867;7462.179160292363;7465.402767778564;7522.736704215381;7546.886796698026;7325;7355.77932662021;7381.261873812629;7382.372498337648;7383.114026316823;34243.31513369743;34768;34913.650863491544;34931.4760278596;35050;3396.6093899201414;3398.5072164536004;3429.176997520602;3430.9656903430896;3437.157896315394;29288.267793305124;29447.411051779058;29488.262793430335;29582.40835183306;29595.852020739974;5009.2486127080865;5058.873528161788;5092.465291144451;5117.078925793356;5197.558207552363;185591.07204464026;187023;187854;187938;188740.99999999863;45782.54217457849;45993.5400645996;46043.3093503591;46151;46169.30746038812;47979.20207979244;48131.75934120377;48140.759296203294;48143;48176.51823481791;15589.142597157264;16022.919885400732;16068.517944461684;16078.196090195443;16107.838921610633;13019;13030.739385212431;13116.606501804961;13140.27728474924;13171.47314107425;13564.457421703022;13644.386002629906;13756.17462952206;13815;13922.09506382082;5421.186821976657;5447.891042179213;5570.61005729595;5611.859703507445;5642;998.5007496251874;1012.3419777144915;1016.9389836609827;1026.2098184397971;1039.5166247694858;23219.767802322098;23802.761972380402;28644.856775715987;28834.42331153365;29321.413571728444;1107.379867274329;1117.94969226385;1125.3023125662141;1128.4990962020493;1133.4106264742288;12248.693782655504;12263.448144833505;12390.938045309716;12521.937390313173;12597.622071337875;237015;239310;239867;240112;240259;50157.742901569516;50523.494765052616;50528;50583.74708126436;50623;1397.9231142287272;1442.3004842651305;1448.7102579484356;1450.8186476690414;1459.7080583883267;22429;22610.208642697427;22623.434414139778;22624.208152714575;22712.65931011003;36233.81883090568;36285.63714362928;36306.455403168446;36461;36524.63475365212;216308;217709;218996;219018;219802;31403.214919627193;31694;31730.841345794048;31772.205694857814;31978.68021319757;38359.80820095882;39156.60843391644;39721;39906.60093399029;40038;29627.85186074142;29629.703702962695;29975;30351.848240759537;30563.847180763954;29168.416631666823;29212;29374.265643359086;29433.55849662214;29633.407331853672;32940.1764955878;33110;33156.83421582876;33509.16227094342;33516;28500.42999140047;28625.570616441186;28792.280192995342;28801.279968000967;28899.56650650284;22441.653500790006;22786.658200127342;22821.771782281965;22974.42563935915;23005.65491517595 03:26:56 INFO - PID 3782 | |2;jquery.html;563.6730696196206;565.4006752841988;567.4467394290565;579.1544345255926;587.5358466811219;21035.57928841422;21544.461388465297;21641.242556510526;21757.23849665262;22339;25819.354516137108;25979.87010064947;26069.348266293353;26100.34749131273;26117.86941065297;6388.744450221986;6483.578567393128;6524.314946930575;6528.281888992207;6542.509311801634;5744.6840423776675;6388.39310265524;6467.676616169197;6504.577202481823;6511.902321465158;6303.873922521547;6338.366163383648;6342.904856427157;6349.650769207692;6360.1413809135865;6305.464035557006;6310.5898116622275;6313.084602732601;6333.461655759266;6355.523335749815;5844.123381492767;5858.882822343529;5906.734196961104;5919.49684276837;5930.7924222652;27835.304117397227;28235.717642823514;28613.856930715214;28646.570301445496;29198;1984.335247692025;2024.3522072936667;2029.6245194639039;2047.580246049571;2059.4954236212075;18920.337788177487;19352.903235483875;19520.51198720029;19607.117679704446;19772.30796922115;2310.7689231076924;2343.9296821095395;2411.7467665895006;2431.258466167818;2439.3047981325235;132323.33838330844;133938;135423;135791.32104339416;135930.3203483986;14377.6405589859;14440.855591443951;14792.408303667948;14908.180050097131;15538.533843984698;23401.76598233996;23479.647805282933;23531;23536.64695029576;23548.41128971755;10194.643187488402;10370.170386369073;10503.2122590806;10524.421156836448;10611.734706632242;9635.710928672152;9703.66037188695;9714.805703885882;9777.217822574177;9820.803583928424;8766.561671916506;9044.773880653036;9196.954015229881;9309.208717258973;9358.57886395114;2681.705012448629;2688.4623075385;2787.317107308682;2812.395335002984;2838.503261929154;933.537898740128;941.4351389166583;964.3827950111934;981.1169947047572;998.5805961496189;19279.36125429354;19353.322633708034;19634.901825490782;19952;20133.697994530095;996.2030375699411;1038.9896101038894;1046.424466543398;1064.2922456566296;1068.3216157739737;10438.530266138043;12323.767623237605;13867.167969921837;14021.158730476203;14291.57125286243;477581;478557;481981;487019;490096;121163;124348;126162.36918815348;126169;127327.3633631826;1219.2196993923897;1220.127608759741;1245.2092920995233;1256.3215863433827;1259.817326487654;20829.791702083392;20902.581948360643;21221.15115395367;21451.570968580854;21726.022328994917;58129.12806307824;58910.70544647249;61161.38838611557;61566.69216653888;61614.4596385076;75717.62141189259;75850.62074689592;76026.6198669003;77816.61091694732;78385.21614784007;11944.820827687765;12560.560380386643;13890.236037018061;14272;14376.712465750836;18642.906785465984;18683;19513.70729439031;21776.45558861041;23165.96527354367;17666.911665441592;17672.469825905242;17938.19278132513;17992.820071799113;18126;15739.763903541685;15987.200639968187;16482.34070637161;16761.41335053267;17123.571910701834;18697.252109915997;18774.90612546983;18880.52798680044;19001;19076.713849291995;13844.10013349129;13997.580072597839;14033.438662453387;14122.50571230002;14262.42950281977;17625.73561396606;17784;17784.288628454717;17846.5538361542;18366.90816545909 03:26:56 INFO - PID 3782 | |3;mootools.html;1298.5519995601517;1376.8003639472283;1376.9793453098198;1382.937767800449;1384.7299776543578;14819.925900370497;15333.463328783495;15436.305366258517;15450.536483905473;15522.689546209069;14910.403583856632;16082.437114700988;16084.356625734987;16185.19074046299;16190.19049047546;2761.9447611047767;2788.10780550224;2793.2318612381655;2814.4511820194966;2828.08087371604;2592.41670624109;2675.130582560666;2725.6047873058396;2726.4001919577663;2749.8212616179885;2712.5524288492375;2771.9307017324527;2776.7500924916753;2776.875040623177;2787.874545645451;2654.362952891302;2683.449892771977;2687.865606719656;2688.529507336218;2715.1311580294314;2654.1639383594297;2661.5475369187093;2664.8401095934114;2673.3984853408024;2674.4918465491496;14899.851001489955;15254.618634534225;15265;15301.770473442908;15317;7785.3771698264;8070.515769053875;8083.353331733506;8091;8092.635831387602;5141.228815677641;5303.681779093228;5334.759935802898;5389.8113566025395;5395.217693434469;1845.280340667141;1861.0787660108197;1892.3475431430713;1906.0565020314893;1909.0931807391516;26395.604065939027;26828.59757103645;26907.86546067277;26980.325491862663;27024;6722.697478613474;6798.66006699663;6838;6950.235474097845;7058.823529411703;17278.740818887727;17429.65140697179;17430.041347725997;17464.47606571805;17485.125743712764;8226.300764434971;8492.70275540353;8499.872501912476;8507.702230421906;8511.95744021276;7387.81530461732;7432.256774322633;7455.739049133363;7480.0649918760155;7531.359834414026;4820.542048505386;4951.232558953368;4999.125153098266;5035.1188542005;5058.544730974157;1424.1628299493536;1426.3510102903153;1457.9575603443589;1523.8476152384894;1570.1207323898652;868.0971789339017;900.131373224828;910.9954450227707;911.48956584313;916.867054277126;8160.26557609818;8279.089300176976;8413.579321033923;8452.323814094858;8470.068292487822;982.7690492734201;995.2485873165714;1003.8494225866178;1006.4464544500496;1007.4408703169811;4694.88262793426;5259.08893666049;5316.494932981361;5442.428545002717;5448.37343705471;21282.680759788615;21858.781412185992;21920.342389728332;22280.220192293193;22440.32679019632;21270.149194032376;21343.573128537653;21466.24868129608;21589.352319430443;21816.236431724814;11124.777504450027;11153.721156970978;11256.662300131009;11406.37264950419;11441.71395715097;7900.130985591465;8194.754157375288;8268.379871509782;8368.121347258693;8446.535440550582;18299.634007320044;18604.512087550236;19207.711884322027;19364.225430982602;19646.607067858848;18834;18915.621687566447;19276.421707348803;19327.130279137753;19363.709544356563;11210.383428911487;11455.255408398427;11477.598284060017;11512.53949841997;11563.710907227387;13399.196048237138;13741.656458588615;13793.310334483436;13802.516911908035;13827.585172444842;10460.947695261475;10474.738131546772;10599.940005999339;10790.568377265135;10863.728406789893;10220.744481387728;10296.485175741333;10309.175265978554;10359.378437293786;10486.632967846088;9562.474063926273;9630.422174669542;9630.47032413224;9631.518424078628;9640.035996400584;6655.500837437308;6710.932890671031;6713.429358504582;6795.592264464148;6881.13985751781;6909;6994.6852391643515;7026.473014524034;7040.929590704028;7082.575045497287 03:26:56 INFO - PID 3782 | |4;prototype.html;501.99749001254986;505.5828941123574;527.8363707250753;534.834201397567;536.712858620638;6333.7149828257725;6367.17226760521;6415.230172379309;6501.2121666541725;6516.67416629169;6182.474489668372;6184.412480814327;6221.377862213776;6284.780032698856;6294.370562943704;5066.442691303953;5074.923876141861;5081.2886195932415;5113.897722045566;5123.95041983206;4928.67963582366;5085.745712714368;5114.130597798383;5122.282880396729;5151.484851514856;5054.393472783271;5072;5085.5423011928915;5104;5149.253358263069;5066.240063990394;5083.288339632454;5113.411957624883;5119.564836988842;5143.202803565453;4585.174668559658;4589.47221069578;4594.632429405639;4594.908101837945;4608.179768991263;6194.4115309045565;6223.657698826562;6244.532534854306;6268.843278918018;6281.780137695158;5776.624519406225;5864.237649105632;5879.294484661825;5893.994063027843;5920.703964801743;6737.629430381327;6758;6804.863902721967;6827.180738311385;6846.144231971004;4937.518744376673;4951.232558953368;4966.950330496721;5002.874928126789;5002.9493806300725;7000.579965202104;7039.577625342496;7080.7254694155035;7094.645267736644;7153.570785752871;5108.438071812097;5111.795528178905;5114.769835357418;5171.095058364846;5172.586193104543;5189.870253243624;5199.63602547818;5201.219817027476;5215.426303106655;5225.372955245395;5700.572457065654;5830.941690583125;5844.912326315109;5845.532357411397;5887.293524777054;6717.261101278856;6779.186497620219;6833.487488438387;6847.109875716125;6861.828454288583;1674.790651168604;1700.7414513260098;1752.79842818075;1793.1213705284317;1801.1534578747967;1202.3507306054807;1211.8727533609022;1230.7107829660022;1245.7729136800244;1251.949922003128;5857.4435428634215;5879.588428810028;5893.616914900518;5901.203337549376;5919.704014799243;6736.2590115087305;6892.827679307957;6903.75836845708;6967.8606427871155;6971.268016858258;5830.47525722687;5886.293644762656;5943.167956486095;5947.5539334550085;5993.730282137314;3632.763870348419;3674.3202507536193;3682.6317368263494;3696;3732.8880133595494;6529.347065293433;6533.967330163414;6546.9345306547275;6579.440747536417;6620.569662971891;7033.613151276627;7036.577805331696;7072.929270707227;7081.504294699322;7082.893756593655;6570.5400621956005;6579.901301480578;6624.436922861611;6662.533622646465;6671.1994560652065;6856.4514838813775;6902.585844849325;6909.827254318682;6986.615736134561;7039;6741.573033707959;6833.794986150423;6897.55165914214;6950.756723514855;6958.269381714847;6604.580015296734;6620.3710647488415;6633.734650613921;6652.966735166293;6724.529282950147;5079.263506791421;5093.516115968977;5098.419489958125;5110.66780659256;5143.61422893292;6600.471962242913;6866.862662746617;6885.345892140239;6937.514373993772;7002.7899163025195;6443.162388889415;6526.4452521536205;6538.934610653832;6609.1408116944485;6675.699593518206;6688.765893193715;6724.285307246816;6759;6774.93225067743;6800.639872025714;6520.282768895323;6588.604683718992;6615.834604134935;6645.800625981228;6649.434798042221;6667.033170239197;6698.397144256845;6707.631080290631;6780;6850.725970961105;6439.420452159328;6477.352264773485;6553.868922621617;6556.934430655632;6566 03:26:56 INFO - PID 3782 | |5;yui.html;866.856968600181;885.9025507194209;902.954852257387;903.3044555692118;914.1224424552431;17521.737173942383;18176.27294908202;18363.72454413185;18392.908035459805;18502.722459163106;18471.44585662429;19089.427317180507;19247.711284330748;19248.615027699438;19351.80648193521;960.6253561111172;971.5433746139303;978.1441238915949;981.4552923127661;982.5332966840765;980.84796856487;986.2110311750607;988.3180605382263;989.3569180033;991.9504024798767;924.9398789078722;1010.2524132142236;1021.1320377678987;1023.3859684189501;1024.892386299439;1007.2646967713573;1008.4755926917961;1009.369144284822;1009.5002973528077;1015.7105225010873;734.8824188129927;739.3789217057644;740.9666565004587;741.4994878457073;743.5724458436421;16817.243224054826;16864.241109150116;16865.83134168655;16943.322267109303;17993.370232041816;19202.711959320623;19817.80182198174;19920.501987450283;20156.69764953527;20344.18623255068;14618.561443156723;14669.119852808866;14742.852571474255;14840.257987100602;14845.257737113101;3529.347070791912;3577.910552236189;3697.8335974881193;3726.7018638509126;3729.2355067211156;125533.37233313776;127004;129670;131984;132557;16033.518994430186;16363.345466181458;16446.6710665786;16626;16631.750523742154;30341.69658303433;30717.69282307193;31610.841945790125;31821.840890795396;31920;8090.069641991128;8283.37874659391;8348.66605335792;8401.579921003926;8501.19238672325;4244.745315281093;4276.2089013532595;4325.243082460557;4347.195768782785;4348.565143485689;5060;5063.316452278895;5098;5179.922301165486;5187.844364669066;930.274385978924;954.6849539651898;960.5149399553181;973.0804389851522;979.2166266986383;623.7037407231529;625.1747693045137;637.1653134393958;646.3859333633068;656.3305428462941;10797.676069717922;10855.2401331906;11127.944360278309;11321.94339028316;11396.658100257006;639.6162302618392;647.2362612117652;654.8101050695245;666.247140730971;682.011083928306;4971.030649023478;4975.004999000214;4992.176290912032;5017.498250175026;5063.696178229318;105869;107785.4610726957;110083.44958275158;111118;111737.44131279291;97753;98388.50805745926;99480;100189;101444;88452;89549.55225223833;89674;89704.10295897219;93092;11893.524259029542;11905.166638335233;12033.759324813629;12044.51821927113;12219.87780122223;39765.403518947816;40190;40959;41782.79108604437;42095.36856947209;51453.742731286104;52985.47014529805;53017.7349113252;53404.73297633487;54197.45802541924;16543.91728041352;16604.916975415046;16704.41534546285;16763.832361676716;16921.492355229362;17767.11164441747;18270.26918923228;18353.54116147107;18594.81405185931;18865.434036978913;16965.830341696426;17003.40488082911;17031.574210644834;17128.914355428144;17183;16262.268197930885;16504.66990660204;16593.91703041477;16668.916655416644;16670.749938750687;15765.369385224463;15770.68458630798;15959.122248276457;16027.118508482145;16027.198640068184;12056.75886482283;12228.877711222774;12285.201461904948;12412.317322547347;12526.498940042296;11415.942920285346;11884.881151188378;12811.871881281068;13152.671183220496;13409.463621455032 03:26:56 INFO - PID 3782 | __end_tp_report 03:26:56 INFO - PID 3782 | __start_cc_report 03:26:56 INFO - PID 3782 | _x_x_mozilla_cycle_collect,380 03:26:56 INFO - PID 3782 | __end_cc_report 03:26:56 INFO - PID 3782 | __startTimestamp1504520816705__endTimestamp 03:26:56 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | ------- Summary: start ------- 03:26:56 INFO - PID 3782 | Number of tests: 6 03:26:56 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | [#0] dojo.html Cycles:165 Average:6325.05 Median:6512.61 stddev:738.06 (11.3%) stddev-sans-first:701.73 03:26:56 INFO - PID 3782 | Values: 3322.6 3334.7 3361.1 3369.5 3373.3 6247.3 6473.7 6497.0 6497.0 6547.3 6360.4 6364.7 6377.7 6430.6 6434.2 5870.6 5907.5 5918.4 5924.4 5942.2 5830.7 5942.1 5972.0 5985.0 6027.4 5927.2 5993.3 6030.0 6055.8 6080.1 5803.2 5888.4 5899.0 5900.4 5997.9 5826.8 5922.5 5962.6 6003.8 6040.0 6245.0 6367.4 6370.7 6417.2 6461.9 6785.1 6808.0 6839.2 6854.8 6925.4 7002.2 7017.4 7017.7 7022.4 7027.4 6443.5 6457.6 6481.2 6488.9 6500.2 7071.8 7115.8 7125.8 7201.4 7213.2 6510.5 6746.8 6757.7 6837.3 6895.9 6766.0 6784.8 6883.1 6905.4 6905.4 6903.1 6920.9 6933.9 6951.7 6956.9 6865.7 6881.6 6892.0 6978.8 6980.1 5320.9 5413.2 5417.2 5421.3 5500.5 4685.7 4689.8 4703.8 4712.2 4731.1 5905.0 5952.5 5971.7 5976.0 5993.9 6784.4 6801.4 6867.1 6899.0 6910.6 5916.6 5970.8 5982.8 5985.9 6069.5 5973.9 5974.2 6025.3 6054.6 6057.0 6371.4 6466.4 6535.5 6563.0 6677.1 6877.1 6910.1 6912.0 6921.9 6923.9 6582.3 6595.5 6613.3 6626.5 6630.2 6737.8 6765.9 6770.2 6845.9 6849.6 6713.1 6773.3 6893.1 6899.0 6906.5 6563.6 6609.9 6641.0 6650.9 6653.9 6532.2 6581.3 6627.2 6635.4 6648.9 5781.9 6511.8 6553.9 6583.8 6685.9 6755.2 6784.1 6800.4 6840.8 6888.3 6305.5 6380.9 6383.0 6512.6 6531.7 03:26:56 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | [#1] ext.html Cycles:180 Average:37232.14 Median:32459.43 stddev:56077.91 (172.8%) stddev-sans-first:56167.91 03:26:56 INFO - PID 3782 | Values: 638.4 696.9 723.2 724.3 725.9 30072.8 30740.2 39117.6 39928.2 39947.6 39709.0 39788.8 39872.4 39901.0 39917.8 7225.1 7237.2 7331.8 7337.0 7365.0 7250.0 7342.9 7405.1 7408.3 7418.3 5772.4 5861.9 5935.6 7366.0 7384.3 7355.0 7462.2 7465.4 7522.7 7546.9 7325.0 7355.8 7381.3 7382.4 7383.1 34243.3 34768.0 34913.7 34931.5 35050.0 3396.6 3398.5 3429.2 3431.0 3437.2 29288.3 29447.4 29488.3 29582.4 29595.9 5009.2 5058.9 5092.5 5117.1 5197.6 185591.1 187023.0 187854.0 187938.0 188741.0 45782.5 45993.5 46043.3 46151.0 46169.3 47979.2 48131.8 48140.8 48143.0 48176.5 15589.1 16022.9 16068.5 16078.2 16107.8 13019.0 13030.7 13116.6 13140.3 13171.5 13564.5 13644.4 13756.2 13815.0 13922.1 5421.2 5447.9 5570.6 5611.9 5642.0 998.5 1012.3 1016.9 1026.2 1039.5 23219.8 23802.8 28644.9 28834.4 29321.4 1107.4 1117.9 1125.3 1128.5 1133.4 12248.7 12263.4 12390.9 12521.9 12597.6 237015.0 239310.0 239867.0 240112.0 240259.0 50157.7 50523.5 50528.0 50583.7 50623.0 1397.9 1442.3 1448.7 1450.8 1459.7 22429.0 22610.2 22623.4 22624.2 22712.7 36233.8 36285.6 36306.5 36461.0 36524.6 216308.0 217709.0 218996.0 219018.0 219802.0 31403.2 31694.0 31730.8 31772.2 31978.7 38359.8 39156.6 39721.0 39906.6 40038.0 29627.9 29629.7 29975.0 30351.8 30563.8 29168.4 29212.0 29374.3 29433.6 29633.4 32940.2 33110.0 33156.8 33509.2 33516.0 28500.4 28625.6 28792.3 28801.3 28899.6 22441.7 22786.7 22821.8 22974.4 23005.7 03:26:56 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | [#2] jquery.html Cycles:180 Average:34949.28 Median:21766.85 stddev:81771.22 (375.7%) stddev-sans-first:81959.85 03:26:56 INFO - PID 3782 | Values: 563.7 565.4 567.4 579.2 587.5 21035.6 21544.5 21641.2 21757.2 22339.0 25819.4 25979.9 26069.3 26100.3 26117.9 6388.7 6483.6 6524.3 6528.3 6542.5 5744.7 6388.4 6467.7 6504.6 6511.9 6303.9 6338.4 6342.9 6349.7 6360.1 6305.5 6310.6 6313.1 6333.5 6355.5 5844.1 5858.9 5906.7 5919.5 5930.8 27835.3 28235.7 28613.9 28646.6 29198.0 1984.3 2024.4 2029.6 2047.6 2059.5 18920.3 19352.9 19520.5 19607.1 19772.3 2310.8 2343.9 2411.7 2431.3 2439.3 132323.3 133938.0 135423.0 135791.3 135930.3 14377.6 14440.9 14792.4 14908.2 15538.5 23401.8 23479.6 23531.0 23536.6 23548.4 10194.6 10370.2 10503.2 10524.4 10611.7 9635.7 9703.7 9714.8 9777.2 9820.8 8766.6 9044.8 9197.0 9309.2 9358.6 2681.7 2688.5 2787.3 2812.4 2838.5 933.5 941.4 964.4 981.1 998.6 19279.4 19353.3 19634.9 19952.0 20133.7 996.2 1039.0 1046.4 1064.3 1068.3 10438.5 12323.8 13867.2 14021.2 14291.6 477581.0 478557.0 481981.0 487019.0 490096.0 121163.0 124348.0 126162.4 126169.0 127327.4 1219.2 1220.1 1245.2 1256.3 1259.8 20829.8 20902.6 21221.2 21451.6 21726.0 58129.1 58910.7 61161.4 61566.7 61614.5 75717.6 75850.6 76026.6 77816.6 78385.2 11944.8 12560.6 13890.2 14272.0 14376.7 18642.9 18683.0 19513.7 21776.5 23166.0 17666.9 17672.5 17938.2 17992.8 18126.0 15739.8 15987.2 16482.3 16761.4 17123.6 18697.3 18774.9 18880.5 19001.0 19076.7 13844.1 13997.6 14033.4 14122.5 14262.4 17625.7 17784.0 17784.3 17846.6 18366.9 03:26:56 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | [#3] mootools.html Cycles:180 Average:9339.22 Median:2674.81 stddev:6750.96 (252.4%) stddev-sans-first:6742.87 03:26:56 INFO - PID 3782 | Values: 1298.6 1376.8 1377.0 1382.9 1384.7 14819.9 15333.5 15436.3 15450.5 15522.7 14910.4 16082.4 16084.4 16185.2 16190.2 2761.9 2788.1 2793.2 2814.5 2828.1 2592.4 2675.1 2725.6 2726.4 2749.8 2712.6 2771.9 2776.8 2776.9 2787.9 2654.4 2683.4 2687.9 2688.5 2715.1 2654.2 2661.5 2664.8 2673.4 2674.5 14899.9 15254.6 15265.0 15301.8 15317.0 7785.4 8070.5 8083.4 8091.0 8092.6 5141.2 5303.7 5334.8 5389.8 5395.2 1845.3 1861.1 1892.3 1906.1 1909.1 26395.6 26828.6 26907.9 26980.3 27024.0 6722.7 6798.7 6838.0 6950.2 7058.8 17278.7 17429.7 17430.0 17464.5 17485.1 8226.3 8492.7 8499.9 8507.7 8512.0 7387.8 7432.3 7455.7 7480.1 7531.4 4820.5 4951.2 4999.1 5035.1 5058.5 1424.2 1426.4 1458.0 1523.8 1570.1 868.1 900.1 911.0 911.5 916.9 8160.3 8279.1 8413.6 8452.3 8470.1 982.8 995.2 1003.8 1006.4 1007.4 4694.9 5259.1 5316.5 5442.4 5448.4 21282.7 21858.8 21920.3 22280.2 22440.3 21270.1 21343.6 21466.2 21589.4 21816.2 11124.8 11153.7 11256.7 11406.4 11441.7 7900.1 8194.8 8268.4 8368.1 8446.5 18299.6 18604.5 19207.7 19364.2 19646.6 18834.0 18915.6 19276.4 19327.1 19363.7 11210.4 11455.3 11477.6 11512.5 11563.7 13399.2 13741.7 13793.3 13802.5 13827.6 10460.9 10474.7 10599.9 10790.6 10863.7 10220.7 10296.5 10309.2 10359.4 10486.6 9562.5 9630.4 9630.5 9631.5 9640.0 6655.5 6710.9 6713.4 6795.6 6881.1 6909.0 6994.7 7026.5 7040.9 7082.6 03:26:56 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | [#4] prototype.html Cycles:180 Average:5646.50 Median:6256.69 stddev:1591.28 (25.4%) stddev-sans-first:1548.19 03:26:56 INFO - PID 3782 | Values: 502.0 505.6 527.8 534.8 536.7 6333.7 6367.2 6415.2 6501.2 6516.7 6182.5 6184.4 6221.4 6284.8 6294.4 5066.4 5074.9 5081.3 5113.9 5124.0 4928.7 5085.7 5114.1 5122.3 5151.5 5054.4 5072.0 5085.5 5104.0 5149.3 5066.2 5083.3 5113.4 5119.6 5143.2 4585.2 4589.5 4594.6 4594.9 4608.2 6194.4 6223.7 6244.5 6268.8 6281.8 5776.6 5864.2 5879.3 5894.0 5920.7 6737.6 6758.0 6804.9 6827.2 6846.1 4937.5 4951.2 4967.0 5002.9 5002.9 7000.6 7039.6 7080.7 7094.6 7153.6 5108.4 5111.8 5114.8 5171.1 5172.6 5189.9 5199.6 5201.2 5215.4 5225.4 5700.6 5830.9 5844.9 5845.5 5887.3 6717.3 6779.2 6833.5 6847.1 6861.8 1674.8 1700.7 1752.8 1793.1 1801.2 1202.4 1211.9 1230.7 1245.8 1251.9 5857.4 5879.6 5893.6 5901.2 5919.7 6736.3 6892.8 6903.8 6967.9 6971.3 5830.5 5886.3 5943.2 5947.6 5993.7 3632.8 3674.3 3682.6 3696.0 3732.9 6529.3 6534.0 6546.9 6579.4 6620.6 7033.6 7036.6 7072.9 7081.5 7082.9 6570.5 6579.9 6624.4 6662.5 6671.2 6856.5 6902.6 6909.8 6986.6 7039.0 6741.6 6833.8 6897.6 6950.8 6958.3 6604.6 6620.4 6633.7 6653.0 6724.5 5079.3 5093.5 5098.4 5110.7 5143.6 6600.5 6866.9 6885.3 6937.5 7002.8 6443.2 6526.4 6538.9 6609.1 6675.7 6688.8 6724.3 6759.0 6774.9 6800.6 6520.3 6588.6 6615.8 6645.8 6649.4 6667.0 6698.4 6707.6 6780.0 6850.7 6439.4 6477.4 6553.9 6556.9 6566.0 03:26:56 INFO - PID 3782 | 03:26:56 INFO - PID 3782 | [#5] yui.html Cycles:180 Average:22936.26 Median:19300.21 stddev:32268.58 (167.2%) stddev-sans-first:32316.55 03:26:56 INFO - PID 3782 | Values: 866.9 885.9 903.0 903.3 914.1 17521.7 18176.3 18363.7 18392.9 18502.7 18471.4 19089.4 19247.7 19248.6 19351.8 960.6 971.5 978.1 981.5 982.5 980.8 986.2 988.3 989.4 992.0 924.9 1010.3 1021.1 1023.4 1024.9 1007.3 1008.5 1009.4 1009.5 1015.7 734.9 739.4 741.0 741.5 743.6 16817.2 16864.2 16865.8 16943.3 17993.4 19202.7 19817.8 19920.5 20156.7 20344.2 14618.6 14669.1 14742.9 14840.3 14845.3 3529.3 3577.9 3697.8 3726.7 3729.2 125533.4 127004.0 129670.0 131984.0 132557.0 16033.5 16363.3 16446.7 16626.0 16631.8 30341.7 30717.7 31610.8 31821.8 31920.0 8090.1 8283.4 8348.7 8401.6 8501.2 4244.7 4276.2 4325.2 4347.2 4348.6 5060.0 5063.3 5098.0 5179.9 5187.8 930.3 954.7 960.5 973.1 979.2 623.7 625.2 637.2 646.4 656.3 10797.7 10855.2 11127.9 11321.9 11396.7 639.6 647.2 654.8 666.2 682.0 4971.0 4975.0 4992.2 5017.5 5063.7 105869.0 107785.5 110083.4 111118.0 111737.4 97753.0 98388.5 99480.0 100189.0 101444.0 88452.0 89549.6 89674.0 89704.1 93092.0 11893.5 11905.2 12033.8 12044.5 12219.9 39765.4 40190.0 40959.0 41782.8 42095.4 51453.7 52985.5 53017.7 53404.7 54197.5 16543.9 16604.9 16704.4 16763.8 16921.5 17767.1 18270.3 18353.5 18594.8 18865.4 16965.8 17003.4 17031.6 17128.9 17183.0 16262.3 16504.7 16593.9 16668.9 16670.7 15765.4 15770.7 15959.1 16027.1 16027.2 12056.8 12228.9 12285.2 12412.3 12526.5 11415.9 11884.9 12811.9 13152.7 13409.5 03:26:56 INFO - PID 3782 | -------- Summary: end -------- 03:26:56 INFO - PID 3782 | 03:26:57 INFO - TEST-INFO | 3782: exit 0 03:26:57 INFO - TEST-OK | dromaeo_css | took 1116625ms 03:26:57 INFO - TEST-START | kraken 03:26:57 INFO - Initialising browser for kraken test... 03:26:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52444/getInfo.html -profile /tmp/tmpvf8no9/profile 03:26:57 INFO - TEST-INFO | started process 4167 (/builds/slave/test/build/application/firefox/firefox http://localhost:52444/getInfo.html) 03:27:03 INFO - TEST-INFO | 4167: exit 0 03:27:03 INFO - Browser initialized. 03:27:03 INFO - Running cycle 1/1 for kraken test... 03:27:03 INFO - Using env: {'DISPLAY': ':0', 03:27:03 INFO - 'HOME': '/home/cltbld', 03:27:03 INFO - 'JSGC_DISABLE_POISONING': '1', 03:27:03 INFO - 'LANG': 'en_US.UTF-8', 03:27:03 INFO - 'LANGUAGE': 'en_US:en', 03:27:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:27:03 INFO - 'LOGNAME': 'cltbld', 03:27:03 INFO - 'MAIL': '/var/mail/cltbld', 03:27:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:27:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:27:03 INFO - 'MOZ_ACCELERATED': '1', 03:27:03 INFO - 'MOZ_CRASHREPORTER': '1', 03:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:27:03 INFO - 'MOZ_NO_REMOTE': '1', 03:27:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:27:03 INFO - 'MOZ_WEBRENDER': '1', 03:27:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:03 INFO - 'NO_EM_RESTART': '1', 03:27:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:03 INFO - 'PWD': '/builds/slave/test', 03:27:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:27:03 INFO - 'RUST_BACKTRACE': 'full', 03:27:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:27:03 INFO - 'SHELL': '/bin/bash', 03:27:03 INFO - 'SHLVL': '1', 03:27:03 INFO - 'TERM': 'linux', 03:27:03 INFO - 'TMOUT': '86400', 03:27:03 INFO - 'USER': 'cltbld', 03:27:03 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621', 03:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:03 INFO - '_': '/tools/buildbot/bin/python'} 03:27:03 INFO - TEST-INFO | started process 4364 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvf8no9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:27:04 INFO - PID 4364 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:27:04 INFO - PID 4364 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:27:05 INFO - PID 4364 | 03:27:05 INFO - PID 4364 | (/builds/slave/test/build/application/firefox/firefox:4426): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:27:05 INFO - PID 4364 | 03:27:15 INFO - PID 4364 | RSS: Main: 200814592 03:27:15 INFO - PID 4364 | 03:27:18 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52444/tests/kraken/driver.html?testName=audio-beat-detection) 03:27:19 INFO - PID 4364 | RSS: Main: 206135296 03:27:19 INFO - PID 4364 | 03:27:21 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52444/tests/kraken/driver.html?testName=audio-dft) 03:27:22 INFO - PID 4364 | RSS: Main: 207859712 03:27:22 INFO - PID 4364 | 03:27:25 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52444/tests/kraken/driver.html?testName=audio-fft) 03:27:26 INFO - PID 4364 | RSS: Main: 201859072 03:27:26 INFO - PID 4364 | 03:27:28 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52444/tests/kraken/driver.html?testName=audio-oscillator) 03:27:28 INFO - PID 4364 | RSS: Main: 203657216 03:27:28 INFO - PID 4364 | 03:27:31 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52444/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:27:31 INFO - PID 4364 | RSS: Main: 202534912 03:27:31 INFO - PID 4364 | 03:27:34 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52444/tests/kraken/driver.html?testName=imaging-darkroom) 03:27:35 INFO - PID 4364 | RSS: Main: 205107200 03:27:35 INFO - PID 4364 | 03:27:39 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52444/tests/kraken/driver.html?testName=imaging-desaturate) 03:27:39 INFO - PID 4364 | RSS: Main: 205316096 03:27:39 INFO - PID 4364 | 03:27:42 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52444/tests/kraken/driver.html?testName=json-parse-financial) 03:27:43 INFO - PID 4364 | RSS: Main: 206159872 03:27:43 INFO - PID 4364 | 03:27:44 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52444/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:27:45 INFO - PID 4364 | RSS: Main: 206061568 03:27:45 INFO - PID 4364 | 03:27:47 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:27:47 INFO - PID 4364 | RSS: Main: 205594624 03:27:47 INFO - PID 4364 | 03:27:50 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:27:50 INFO - PID 4364 | RSS: Main: 205381632 03:27:50 INFO - PID 4364 | 03:27:53 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:27:54 INFO - PID 4364 | RSS: Main: 206835712 03:27:54 INFO - PID 4364 | 03:27:57 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:27:57 INFO - PID 4364 | RSS: Main: 206671872 03:27:57 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52444/tests/kraken/driver.html?testName=ai-astar) 03:27:59 INFO - PID 4364 | RSS: Main: 208945152 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | __start_tp_report 03:27:59 INFO - PID 4364 | _x_x_mozilla_page_load 03:27:59 INFO - PID 4364 | _x_x_mozilla_page_load_details 03:27:59 INFO - PID 4364 | |i|pagename|runs| 03:27:59 INFO - PID 4364 | |0;ai-astar;99;108;104;108;111;123;115;109;114;110 03:27:59 INFO - PID 4364 | |1;audio-beat-detection;151;122;146;171;117;145;154;127;187;123 03:27:59 INFO - PID 4364 | |2;audio-dft;174;162;167;168;165;176;169;169;163;173 03:27:59 INFO - PID 4364 | |3;audio-fft;79;74;79;171;76;85;78;79;154;80 03:27:59 INFO - PID 4364 | |4;audio-oscillator;80;80;149;80;80;82;137;89;80;141 03:27:59 INFO - PID 4364 | |5;imaging-gaussian-blur;115;119;115;116;121;116;117;119;122;127 03:27:59 INFO - PID 4364 | |6;imaging-darkroom;148;147;148;151;149;149;150;148;148;154 03:27:59 INFO - PID 4364 | |7;imaging-desaturate;100;101;102;103;94;94;96;95;102;94 03:27:59 INFO - PID 4364 | |8;json-parse-financial;52;52;57;52;51;52;59;54;53;53 03:27:59 INFO - PID 4364 | |9;json-stringify-tinderbox;46;39;39;40;38;49;37;38;39;39 03:27:59 INFO - PID 4364 | |10;stanford-crypto-aes;80;86;75;80;74;75;80;82;80;75 03:27:59 INFO - PID 4364 | |11;stanford-crypto-ccm;121;124;124;127;137;138;131;134;141;163 03:27:59 INFO - PID 4364 | |12;stanford-crypto-pbkdf2;171;175;171;173;177;181;172;167;179;173 03:27:59 INFO - PID 4364 | |13;stanford-crypto-sha256-iterative;66;67;66;64;64;64;63;66;64;69 03:27:59 INFO - PID 4364 | __end_tp_report 03:27:59 INFO - PID 4364 | __start_cc_report 03:27:59 INFO - PID 4364 | _x_x_mozilla_cycle_collect,951 03:27:59 INFO - PID 4364 | __end_cc_report 03:27:59 INFO - PID 4364 | __startTimestamp1504520879900__endTimestamp 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | ------- Summary: start ------- 03:27:59 INFO - PID 4364 | Number of tests: 14 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#0] ai-astar Cycles:10 Average:110.10 Median:112.50 stddev:6.47 (5.8%) stddev-sans-first:5.48 03:27:59 INFO - PID 4364 | Values: 99.0 108.0 104.0 108.0 111.0 123.0 115.0 109.0 114.0 110.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:148.50 stddev:22.79 (15.3%) stddev-sans-first:24.04 03:27:59 INFO - PID 4364 | Values: 151.0 122.0 146.0 171.0 117.0 145.0 154.0 127.0 187.0 123.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#2] audio-dft Cycles:10 Average:168.60 Median:169.00 stddev:4.65 (2.8%) stddev-sans-first:4.50 03:27:59 INFO - PID 4364 | Values: 174.0 162.0 167.0 168.0 165.0 176.0 169.0 169.0 163.0 173.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#3] audio-fft Cycles:10 Average:95.50 Median:79.00 stddev:35.65 (45.1%) stddev-sans-first:37.31 03:27:59 INFO - PID 4364 | Values: 79.0 74.0 79.0 171.0 76.0 85.0 78.0 79.0 154.0 80.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#4] audio-oscillator Cycles:10 Average:99.80 Median:80.00 stddev:29.62 (37.0%) stddev-sans-first:30.54 03:27:59 INFO - PID 4364 | Values: 80.0 80.0 149.0 80.0 80.0 82.0 137.0 89.0 80.0 141.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#5] imaging-gaussian-blur Cycles:10 Average:118.70 Median:119.00 stddev:3.80 (3.2%) stddev-sans-first:3.79 03:27:59 INFO - PID 4364 | Values: 115.0 119.0 115.0 116.0 121.0 116.0 117.0 119.0 122.0 127.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:2.04 (1.4%) stddev-sans-first:2.12 03:27:59 INFO - PID 4364 | Values: 148.0 147.0 148.0 151.0 149.0 149.0 150.0 148.0 148.0 154.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:94.00 stddev:3.81 (4.1%) stddev-sans-first:3.98 03:27:59 INFO - PID 4364 | Values: 100.0 101.0 102.0 103.0 94.0 94.0 96.0 95.0 102.0 94.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.00 stddev:2.55 (4.8%) stddev-sans-first:2.65 03:27:59 INFO - PID 4364 | Values: 52.0 52.0 57.0 52.0 51.0 52.0 59.0 54.0 53.0 53.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#9] json-stringify-tinderbox Cycles:10 Average:40.40 Median:39.00 stddev:3.89 (10.0%) stddev-sans-first:3.56 03:27:59 INFO - PID 4364 | Values: 46.0 39.0 39.0 40.0 38.0 49.0 37.0 38.0 39.0 39.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:80.00 stddev:3.86 (4.8%) stddev-sans-first:4.07 03:27:59 INFO - PID 4364 | Values: 80.0 86.0 75.0 80.0 74.0 75.0 80.0 82.0 80.0 75.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:135.50 stddev:12.21 (9.0%) stddev-sans-first:12.01 03:27:59 INFO - PID 4364 | Values: 121.0 124.0 124.0 127.0 137.0 138.0 131.0 134.0 141.0 163.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:174.00 stddev:4.18 (2.4%) stddev-sans-first:4.29 03:27:59 INFO - PID 4364 | Values: 171.0 175.0 171.0 173.0 177.0 181.0 172.0 167.0 179.0 173.0 03:27:59 INFO - PID 4364 | 03:27:59 INFO - PID 4364 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:66.00 stddev:1.83 (2.8%) stddev-sans-first:1.92 03:27:59 INFO - PID 4364 | Values: 66.0 67.0 66.0 64.0 64.0 64.0 63.0 66.0 64.0 69.0 03:27:59 INFO - PID 4364 | -------- Summary: end -------- 03:27:59 INFO - PID 4364 | 03:28:00 INFO - PID 4364 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:28:00 INFO - TEST-INFO | 4364: exit 0 03:28:00 INFO - TEST-OK | kraken | took 63142ms 03:28:00 INFO - SUITE-END | took 1179s 03:28:01 INFO - Completed test suite (00:19:40) 03:28:01 INFO - javascript benchmark 03:28:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8478.24586001455, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.5514555535, 3334.683205095515, 3361.1093060339, 3369.511420843977, 3373.342198271335, 6247.344028876971, 6473.67631618419, 6497.0, 6497.025446183069, 6547.345265473462, 6360.363963603637, 6364.681765911709, 6377.744890204398, 6430.614163150202, 6434.163558737369, 5870.647761134345, 5907.527397808176, 5918.40815918409, 5924.37794031627, 5942.197803296564, 5830.737616807232, 5942.078977758454, 5971.970140149315, 5985.042393217086, 6027.39726027396, 5927.2294601701715, 5993.340732519419, 6030.035194368902, 6055.848603784919, 6080.057591073369, 5803.2165657636315, 5888.411158884121, 5899.0, 5900.409959004108, 5997.88004239915, 5826.796062137847, 5922.466977972003, 5962.582619216657, 6003.7898673546215, 6039.969800151015, 6244.968775156095, 6367.363263673642, 6370.745170193187, 6417.197850268716, 6461.870762584769, 6785.050092986986, 6808.0, 6839.247682754893, 6854.794356169273, 6925.376716095525, 7002.229754726976, 7017.403520700746, 7017.719291228344, 7022.403095736869, 7027.367536921702, 6443.548951573392, 6457.6448295343735, 6481.157449531578, 6488.870222595616, 6500.154979852683, 7071.823204419828, 7115.822104447327, 7125.786226413215, 7201.4238860891, 7213.170485394142, 6510.511711621552, 6746.831329216809, 6757.695903684346, 6837.282085381063, 6895.862082758316, 6766.0, 6784.8303792404595, 6883.105196324445, 6905.37851593359, 6905.37851593359, 6903.102596662402, 6920.8961865572055, 6933.861322773516, 6951.6871740771785, 6956.930430695729, 6865.656717164122, 6881.621510816954, 6892.0, 6978.825529361705, 6980.092587963532, 5320.946790532123, 5413.188021796684, 5417.214503896913, 5421.322334708161, 5500.532454741392, 4685.695429797052, 4689.7655117244, 4703.7883295251795, 4712.246040633482, 4731.053789242096, 5905.0, 5952.494038006731, 5971.671558064261, 5976.0, 5993.850153746192, 6784.423324017514, 6801.353871382211, 6867.107276054181, 6899.0, 6910.585364878123, 5916.556258280646, 5970.791022314285, 5982.820515384546, 5985.850353741104, 6069.514438844882, 5973.880522389615, 5974.2233509643465, 6025.307089684742, 6054.606450580786, 6056.9697151513965, 6371.362863713684, 6466.418022377915, 6535.542512024208, 6563.015547667888, 6677.065210870482, 6877.105976223059, 6910.067140935909, 6912.0, 6921.8961715575315, 6923.861522769617, 6582.341765823379, 6595.505337099641, 6613.305602911817, 6626.5030122741955, 6630.171228596426, 6737.831554211184, 6765.8985115224295, 6770.1537307836525, 6845.863082738218, 6849.589024658537, 6713.12729345182, 6773.254941956282, 6893.138357705287, 6898.965505172442, 6906.516543841883, 6563.573367731082, 6609.867802644017, 6640.966795165993, 6650.866982660416, 6653.866922661617, 6532.183477065367, 6581.308962558863, 6627.1716035495565, 6635.369639884203, 6648.90026649591, 5781.855453613525, 6511.772087976898, 6553.901691474536, 6583.769568065095, 6685.8997115044285, 6755.189377274758, 6784.050232967487, 6800.353966373186, 6840.828979275558, 6888.311168883071, 6305.495560355253, 6380.904286435614, 6383.0, 6512.6092434454085, 6531.706073226811], "value": 6271.493113818505, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.3616383616384, 696.8710788504127, 723.1864152828068, 724.272106532934, 725.9237780033096, 30072.84963575182, 30740.231494212654, 39117.60882391175, 39928.20143597126, 39947.60052399475, 39709.0, 39788.80105599468, 39872.40191397131, 39901.0, 39917.800410997974, 7225.060742103519, 7237.167725711558, 7331.753601887692, 7337.046183996073, 7364.963175184143, 7250.0, 7342.8898566521275, 7405.148407933103, 7408.259174082603, 7418.332350088491, 5772.365039845614, 5861.8827623447505, 5935.584509084366, 7366.0, 7384.335409813116, 7354.963225183867, 7462.179160292363, 7465.402767778564, 7522.736704215381, 7546.886796698026, 7325.0, 7355.77932662021, 7381.261873812629, 7382.372498337648, 7383.114026316823, 34243.31513369743, 34768.0, 34913.650863491544, 34931.4760278596, 35050.0, 3396.6093899201414, 3398.5072164536004, 3429.176997520602, 3430.9656903430896, 3437.157896315394, 29288.267793305124, 29447.411051779058, 29488.262793430335, 29582.40835183306, 29595.852020739974, 5009.2486127080865, 5058.873528161788, 5092.465291144451, 5117.078925793356, 5197.558207552363, 185591.07204464026, 187023.0, 187854.0, 187938.0, 188740.99999999863, 45782.54217457849, 45993.5400645996, 46043.3093503591, 46151.0, 46169.30746038812, 47979.20207979244, 48131.75934120377, 48140.759296203294, 48143.0, 48176.51823481791, 15589.142597157264, 16022.919885400732, 16068.517944461684, 16078.196090195443, 16107.838921610633, 13019.0, 13030.739385212431, 13116.606501804961, 13140.27728474924, 13171.47314107425, 13564.457421703022, 13644.386002629906, 13756.17462952206, 13815.0, 13922.09506382082, 5421.186821976657, 5447.891042179213, 5570.61005729595, 5611.859703507445, 5642.0, 998.5007496251874, 1012.3419777144915, 1016.9389836609827, 1026.2098184397971, 1039.5166247694858, 23219.767802322098, 23802.761972380402, 28644.856775715987, 28834.42331153365, 29321.413571728444, 1107.379867274329, 1117.94969226385, 1125.3023125662141, 1128.4990962020493, 1133.4106264742288, 12248.693782655504, 12263.448144833505, 12390.938045309716, 12521.937390313173, 12597.622071337875, 237015.0, 239310.0, 239867.0, 240112.0, 240259.0, 50157.742901569516, 50523.494765052616, 50528.0, 50583.74708126436, 50623.0, 1397.9231142287272, 1442.3004842651305, 1448.7102579484356, 1450.8186476690414, 1459.7080583883267, 22429.0, 22610.208642697427, 22623.434414139778, 22624.208152714575, 22712.65931011003, 36233.81883090568, 36285.63714362928, 36306.455403168446, 36461.0, 36524.63475365212, 216308.0, 217709.0, 218996.0, 219018.0, 219802.0, 31403.214919627193, 31694.0, 31730.841345794048, 31772.205694857814, 31978.68021319757, 38359.80820095882, 39156.60843391644, 39721.0, 39906.60093399029, 40038.0, 29627.85186074142, 29629.703702962695, 29975.0, 30351.848240759537, 30563.847180763954, 29168.416631666823, 29212.0, 29374.265643359086, 29433.55849662214, 29633.407331853672, 32940.1764955878, 33110.0, 33156.83421582876, 33509.16227094342, 33516.0, 28500.42999140047, 28625.570616441186, 28792.280192995342, 28801.279968000967, 28899.56650650284, 22441.653500790006, 22786.658200127342, 22821.771782281965, 22974.42563935915, 23005.65491517595], "value": 16481.41070793476, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.6730696196206, 565.4006752841988, 567.4467394290565, 579.1544345255926, 587.5358466811219, 21035.57928841422, 21544.461388465297, 21641.242556510526, 21757.23849665262, 22339.0, 25819.354516137108, 25979.87010064947, 26069.348266293353, 26100.34749131273, 26117.86941065297, 6388.744450221986, 6483.578567393128, 6524.314946930575, 6528.281888992207, 6542.509311801634, 5744.6840423776675, 6388.39310265524, 6467.676616169197, 6504.577202481823, 6511.902321465158, 6303.873922521547, 6338.366163383648, 6342.904856427157, 6349.650769207692, 6360.1413809135865, 6305.464035557006, 6310.5898116622275, 6313.084602732601, 6333.461655759266, 6355.523335749815, 5844.123381492767, 5858.882822343529, 5906.734196961104, 5919.49684276837, 5930.7924222652, 27835.304117397227, 28235.717642823514, 28613.856930715214, 28646.570301445496, 29198.0, 1984.335247692025, 2024.3522072936667, 2029.6245194639039, 2047.580246049571, 2059.4954236212075, 18920.337788177487, 19352.903235483875, 19520.51198720029, 19607.117679704446, 19772.30796922115, 2310.7689231076924, 2343.9296821095395, 2411.7467665895006, 2431.258466167818, 2439.3047981325235, 132323.33838330844, 133938.0, 135423.0, 135791.32104339416, 135930.3203483986, 14377.6405589859, 14440.855591443951, 14792.408303667948, 14908.180050097131, 15538.533843984698, 23401.76598233996, 23479.647805282933, 23531.0, 23536.64695029576, 23548.41128971755, 10194.643187488402, 10370.170386369073, 10503.2122590806, 10524.421156836448, 10611.734706632242, 9635.710928672152, 9703.66037188695, 9714.805703885882, 9777.217822574177, 9820.803583928424, 8766.561671916506, 9044.773880653036, 9196.954015229881, 9309.208717258973, 9358.57886395114, 2681.705012448629, 2688.4623075385, 2787.317107308682, 2812.395335002984, 2838.503261929154, 933.537898740128, 941.4351389166583, 964.3827950111934, 981.1169947047572, 998.5805961496189, 19279.36125429354, 19353.322633708034, 19634.901825490782, 19952.0, 20133.697994530095, 996.2030375699411, 1038.9896101038894, 1046.424466543398, 1064.2922456566296, 1068.3216157739737, 10438.530266138043, 12323.767623237605, 13867.167969921837, 14021.158730476203, 14291.57125286243, 477581.0, 478557.0, 481981.0, 487019.0, 490096.0, 121163.0, 124348.0, 126162.36918815348, 126169.0, 127327.3633631826, 1219.2196993923897, 1220.127608759741, 1245.2092920995233, 1256.3215863433827, 1259.817326487654, 20829.791702083392, 20902.581948360643, 21221.15115395367, 21451.570968580854, 21726.022328994917, 58129.12806307824, 58910.70544647249, 61161.38838611557, 61566.69216653888, 61614.4596385076, 75717.62141189259, 75850.62074689592, 76026.6198669003, 77816.61091694732, 78385.21614784007, 11944.820827687765, 12560.560380386643, 13890.236037018061, 14272.0, 14376.712465750836, 18642.906785465984, 18683.0, 19513.70729439031, 21776.45558861041, 23165.96527354367, 17666.911665441592, 17672.469825905242, 17938.19278132513, 17992.820071799113, 18126.0, 15739.763903541685, 15987.200639968187, 16482.34070637161, 16761.41335053267, 17123.571910701834, 18697.252109915997, 18774.90612546983, 18880.52798680044, 19001.0, 19076.713849291995, 13844.10013349129, 13997.580072597839, 14033.438662453387, 14122.50571230002, 14262.42950281977, 17625.73561396606, 17784.0, 17784.288628454717, 17846.5538361542, 18366.90816545909], "value": 12009.13364132368, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.5519995601517, 1376.8003639472283, 1376.9793453098198, 1382.937767800449, 1384.7299776543578, 14819.925900370497, 15333.463328783495, 15436.305366258517, 15450.536483905473, 15522.689546209069, 14910.403583856632, 16082.437114700988, 16084.356625734987, 16185.19074046299, 16190.19049047546, 2761.9447611047767, 2788.10780550224, 2793.2318612381655, 2814.4511820194966, 2828.08087371604, 2592.41670624109, 2675.130582560666, 2725.6047873058396, 2726.4001919577663, 2749.8212616179885, 2712.5524288492375, 2771.9307017324527, 2776.7500924916753, 2776.875040623177, 2787.874545645451, 2654.362952891302, 2683.449892771977, 2687.865606719656, 2688.529507336218, 2715.1311580294314, 2654.1639383594297, 2661.5475369187093, 2664.8401095934114, 2673.3984853408024, 2674.4918465491496, 14899.851001489955, 15254.618634534225, 15265.0, 15301.770473442908, 15317.0, 7785.3771698264, 8070.515769053875, 8083.353331733506, 8091.0, 8092.635831387602, 5141.228815677641, 5303.681779093228, 5334.759935802898, 5389.8113566025395, 5395.217693434469, 1845.280340667141, 1861.0787660108197, 1892.3475431430713, 1906.0565020314893, 1909.0931807391516, 26395.604065939027, 26828.59757103645, 26907.86546067277, 26980.325491862663, 27024.0, 6722.697478613474, 6798.66006699663, 6838.0, 6950.235474097845, 7058.823529411703, 17278.740818887727, 17429.65140697179, 17430.041347725997, 17464.47606571805, 17485.125743712764, 8226.300764434971, 8492.70275540353, 8499.872501912476, 8507.702230421906, 8511.95744021276, 7387.81530461732, 7432.256774322633, 7455.739049133363, 7480.0649918760155, 7531.359834414026, 4820.542048505386, 4951.232558953368, 4999.125153098266, 5035.1188542005, 5058.544730974157, 1424.1628299493536, 1426.3510102903153, 1457.9575603443589, 1523.8476152384894, 1570.1207323898652, 868.0971789339017, 900.131373224828, 910.9954450227707, 911.48956584313, 916.867054277126, 8160.26557609818, 8279.089300176976, 8413.579321033923, 8452.323814094858, 8470.068292487822, 982.7690492734201, 995.2485873165714, 1003.8494225866178, 1006.4464544500496, 1007.4408703169811, 4694.88262793426, 5259.08893666049, 5316.494932981361, 5442.428545002717, 5448.37343705471, 21282.680759788615, 21858.781412185992, 21920.342389728332, 22280.220192293193, 22440.32679019632, 21270.149194032376, 21343.573128537653, 21466.24868129608, 21589.352319430443, 21816.236431724814, 11124.777504450027, 11153.721156970978, 11256.662300131009, 11406.37264950419, 11441.71395715097, 7900.130985591465, 8194.754157375288, 8268.379871509782, 8368.121347258693, 8446.535440550582, 18299.634007320044, 18604.512087550236, 19207.711884322027, 19364.225430982602, 19646.607067858848, 18834.0, 18915.621687566447, 19276.421707348803, 19327.130279137753, 19363.709544356563, 11210.383428911487, 11455.255408398427, 11477.598284060017, 11512.53949841997, 11563.710907227387, 13399.196048237138, 13741.656458588615, 13793.310334483436, 13802.516911908035, 13827.585172444842, 10460.947695261475, 10474.738131546772, 10599.940005999339, 10790.568377265135, 10863.728406789893, 10220.744481387728, 10296.485175741333, 10309.175265978554, 10359.378437293786, 10486.632967846088, 9562.474063926273, 9630.422174669542, 9630.47032413224, 9631.518424078628, 9640.035996400584, 6655.500837437308, 6710.932890671031, 6713.429358504582, 6795.592264464148, 6881.13985751781, 6909.0, 6994.6852391643515, 7026.473014524034, 7040.929590704028, 7082.575045497287], "value": 6661.810762280207, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.99749001254986, 505.5828941123574, 527.8363707250753, 534.834201397567, 536.712858620638, 6333.7149828257725, 6367.17226760521, 6415.230172379309, 6501.2121666541725, 6516.67416629169, 6182.474489668372, 6184.412480814327, 6221.377862213776, 6284.780032698856, 6294.370562943704, 5066.442691303953, 5074.923876141861, 5081.2886195932415, 5113.897722045566, 5123.95041983206, 4928.67963582366, 5085.745712714368, 5114.130597798383, 5122.282880396729, 5151.484851514856, 5054.393472783271, 5072.0, 5085.5423011928915, 5104.0, 5149.253358263069, 5066.240063990394, 5083.288339632454, 5113.411957624883, 5119.564836988842, 5143.202803565453, 4585.174668559658, 4589.47221069578, 4594.632429405639, 4594.908101837945, 4608.179768991263, 6194.4115309045565, 6223.657698826562, 6244.532534854306, 6268.843278918018, 6281.780137695158, 5776.624519406225, 5864.237649105632, 5879.294484661825, 5893.994063027843, 5920.703964801743, 6737.629430381327, 6758.0, 6804.863902721967, 6827.180738311385, 6846.144231971004, 4937.518744376673, 4951.232558953368, 4966.950330496721, 5002.874928126789, 5002.9493806300725, 7000.579965202104, 7039.577625342496, 7080.7254694155035, 7094.645267736644, 7153.570785752871, 5108.438071812097, 5111.795528178905, 5114.769835357418, 5171.095058364846, 5172.586193104543, 5189.870253243624, 5199.63602547818, 5201.219817027476, 5215.426303106655, 5225.372955245395, 5700.572457065654, 5830.941690583125, 5844.912326315109, 5845.532357411397, 5887.293524777054, 6717.261101278856, 6779.186497620219, 6833.487488438387, 6847.109875716125, 6861.828454288583, 1674.790651168604, 1700.7414513260098, 1752.79842818075, 1793.1213705284317, 1801.1534578747967, 1202.3507306054807, 1211.8727533609022, 1230.7107829660022, 1245.7729136800244, 1251.949922003128, 5857.4435428634215, 5879.588428810028, 5893.616914900518, 5901.203337549376, 5919.704014799243, 6736.2590115087305, 6892.827679307957, 6903.75836845708, 6967.8606427871155, 6971.268016858258, 5830.47525722687, 5886.293644762656, 5943.167956486095, 5947.5539334550085, 5993.730282137314, 3632.763870348419, 3674.3202507536193, 3682.6317368263494, 3696.0, 3732.8880133595494, 6529.347065293433, 6533.967330163414, 6546.9345306547275, 6579.440747536417, 6620.569662971891, 7033.613151276627, 7036.577805331696, 7072.929270707227, 7081.504294699322, 7082.893756593655, 6570.5400621956005, 6579.901301480578, 6624.436922861611, 6662.533622646465, 6671.1994560652065, 6856.4514838813775, 6902.585844849325, 6909.827254318682, 6986.615736134561, 7039.0, 6741.573033707959, 6833.794986150423, 6897.55165914214, 6950.756723514855, 6958.269381714847, 6604.580015296734, 6620.3710647488415, 6633.734650613921, 6652.966735166293, 6724.529282950147, 5079.263506791421, 5093.516115968977, 5098.419489958125, 5110.66780659256, 5143.61422893292, 6600.471962242913, 6866.862662746617, 6885.345892140239, 6937.514373993772, 7002.7899163025195, 6443.162388889415, 6526.4452521536205, 6538.934610653832, 6609.1408116944485, 6675.699593518206, 6688.765893193715, 6724.285307246816, 6759.0, 6774.93225067743, 6800.639872025714, 6520.282768895323, 6588.604683718992, 6615.834604134935, 6645.800625981228, 6649.434798042221, 6667.033170239197, 6698.397144256845, 6707.631080290631, 6780.0, 6850.725970961105, 6439.420452159328, 6477.352264773485, 6553.868922621617, 6556.934430655632, 6566.0], "value": 5173.964454800301, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [866.856968600181, 885.9025507194209, 902.954852257387, 903.3044555692118, 914.1224424552431, 17521.737173942383, 18176.27294908202, 18363.72454413185, 18392.908035459805, 18502.722459163106, 18471.44585662429, 19089.427317180507, 19247.711284330748, 19248.615027699438, 19351.80648193521, 960.6253561111172, 971.5433746139303, 978.1441238915949, 981.4552923127661, 982.5332966840765, 980.84796856487, 986.2110311750607, 988.3180605382263, 989.3569180033, 991.9504024798767, 924.9398789078722, 1010.2524132142236, 1021.1320377678987, 1023.3859684189501, 1024.892386299439, 1007.2646967713573, 1008.4755926917961, 1009.369144284822, 1009.5002973528077, 1015.7105225010873, 734.8824188129927, 739.3789217057644, 740.9666565004587, 741.4994878457073, 743.5724458436421, 16817.243224054826, 16864.241109150116, 16865.83134168655, 16943.322267109303, 17993.370232041816, 19202.711959320623, 19817.80182198174, 19920.501987450283, 20156.69764953527, 20344.18623255068, 14618.561443156723, 14669.119852808866, 14742.852571474255, 14840.257987100602, 14845.257737113101, 3529.347070791912, 3577.910552236189, 3697.8335974881193, 3726.7018638509126, 3729.2355067211156, 125533.37233313776, 127004.0, 129670.0, 131984.0, 132557.0, 16033.518994430186, 16363.345466181458, 16446.6710665786, 16626.0, 16631.750523742154, 30341.69658303433, 30717.69282307193, 31610.841945790125, 31821.840890795396, 31920.0, 8090.069641991128, 8283.37874659391, 8348.66605335792, 8401.579921003926, 8501.19238672325, 4244.745315281093, 4276.2089013532595, 4325.243082460557, 4347.195768782785, 4348.565143485689, 5060.0, 5063.316452278895, 5098.0, 5179.922301165486, 5187.844364669066, 930.274385978924, 954.6849539651898, 960.5149399553181, 973.0804389851522, 979.2166266986383, 623.7037407231529, 625.1747693045137, 637.1653134393958, 646.3859333633068, 656.3305428462941, 10797.676069717922, 10855.2401331906, 11127.944360278309, 11321.94339028316, 11396.658100257006, 639.6162302618392, 647.2362612117652, 654.8101050695245, 666.247140730971, 682.011083928306, 4971.030649023478, 4975.004999000214, 4992.176290912032, 5017.498250175026, 5063.696178229318, 105869.0, 107785.4610726957, 110083.44958275158, 111118.0, 111737.44131279291, 97753.0, 98388.50805745926, 99480.0, 100189.0, 101444.0, 88452.0, 89549.55225223833, 89674.0, 89704.10295897219, 93092.0, 11893.524259029542, 11905.166638335233, 12033.759324813629, 12044.51821927113, 12219.87780122223, 39765.403518947816, 40190.0, 40959.0, 41782.79108604437, 42095.36856947209, 51453.742731286104, 52985.47014529805, 53017.7349113252, 53404.73297633487, 54197.45802541924, 16543.91728041352, 16604.916975415046, 16704.41534546285, 16763.832361676716, 16921.492355229362, 17767.11164441747, 18270.26918923228, 18353.54116147107, 18594.81405185931, 18865.434036978913, 16965.830341696426, 17003.40488082911, 17031.574210644834, 17128.914355428144, 17183.0, 16262.268197930885, 16504.66990660204, 16593.91703041477, 16668.916655416644, 16670.749938750687, 15765.369385224463, 15770.68458630798, 15959.122248276457, 16027.118508482145, 16027.198640068184, 12056.75886482283, 12228.877711222774, 12285.201461904948, 12412.317322547347, 12526.498940042296, 11415.942920285346, 11884.881151188378, 12811.871881281068, 13152.671183220496, 13409.463621455032], "value": 8679.99994899632, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 104.0, 108.0, 111.0, 123.0, 115.0, 109.0, 114.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 122.0, 146.0, 171.0, 117.0, 145.0, 154.0, 127.0, 187.0, 123.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 162.0, 167.0, 168.0, 165.0, 176.0, 169.0, 169.0, 163.0, 173.0], "value": 168.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 79.0, 171.0, 76.0, 85.0, 78.0, 79.0, 154.0, 80.0], "value": 95.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 149.0, 80.0, 80.0, 82.0, 137.0, 89.0, 80.0, 141.0], "value": 99.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 115.0, 116.0, 121.0, 116.0, 117.0, 119.0, 122.0, 127.0], "value": 118.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 151.0, 149.0, 149.0, 150.0, 148.0, 148.0, 154.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 102.0, 103.0, 94.0, 94.0, 96.0, 95.0, 102.0, 94.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 57.0, 52.0, 51.0, 52.0, 59.0, 54.0, 53.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 39.0, 39.0, 40.0, 38.0, 49.0, 37.0, 38.0, 39.0, 39.0], "value": 40.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 86.0, 75.0, 80.0, 74.0, 75.0, 80.0, 82.0, 80.0, 75.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 124.0, 124.0, 127.0, 137.0, 138.0, 131.0, 134.0, 141.0, 163.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 171.0, 173.0, 177.0, 181.0, 172.0, 167.0, 179.0, 173.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 64.0, 64.0, 64.0, 63.0, 66.0, 64.0, 69.0], "value": 65.3, "unit": "score"}]}]} 03:28:01 INFO - Return code: 0 03:28:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:28:01 INFO - # TBPL SUCCESS # 03:28:01 INFO - Running post-action listener: _package_coverage_data 03:28:01 INFO - Running post-action listener: _resource_record_post_action 03:28:01 INFO - [mozharness: 2017-09-04 10:28:01.165704Z] Finished run-tests step (success) 03:28:01 INFO - Running post-run listener: _resource_record_post_run 03:28:01 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 14516224; Write bytes: 397864960; Read time: 6024; Write time: 349136 03:28:01 INFO - TinderboxPrint: CPU usage
13.3% 03:28:01 INFO - TinderboxPrint: I/O read bytes / time
14,516,224 / 6,024 03:28:01 INFO - TinderboxPrint: I/O write bytes / time
397,864,960 / 349,136 03:28:01 INFO - TinderboxPrint: CPU idle
8,268.2 (86.7%) 03:28:01 INFO - TinderboxPrint: CPU user
1,203.0 (12.6%) 03:28:01 INFO - TinderboxPrint: Swap in / out
0 / 0 03:28:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 115539968; Read time: 168; Write time: 79492 03:28:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:28:01 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 12767232; Write bytes: 281554944; Read time: 5824; Write time: 269616 03:28:01 INFO - Running post-run listener: _upload_blobber_files 03:28:01 INFO - Blob upload gear active. 03:28:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:28:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:28:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:28:02 INFO - (blobuploader) - INFO - Open directory for files ... 03:28:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:28:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:28:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:28:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:28:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:28:03 INFO - (blobuploader) - INFO - Done attempting. 03:28:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:28:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:28:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:28:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:28:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:28:10 INFO - (blobuploader) - INFO - Done attempting. 03:28:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:28:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:28:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:28:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:28:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:28:11 INFO - (blobuploader) - INFO - Done attempting. 03:28:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:28:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:28:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:28:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:28:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:28:12 INFO - (blobuploader) - INFO - Done attempting. 03:28:12 INFO - (blobuploader) - INFO - Iteration through files over. 03:28:12 INFO - Return code: 0 03:28:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:28:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:28:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42096f331d9245463de03880465232e81c547b071924c74cfa8976c2a89239d3dbf7518f291e17271eaeb0323455e03af6adecf53f3c1d838b77c825693454f5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/217d25421a30a529c43924aeffca1c33657bc8a58fdf0091e22836c7b383308c1533a5466c7e1fdf52061847da417c143d1279ec6dcbb4baf36acf6ecbd72165", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31f58ea795f41e3cef7aac240939a78baa0f16893f5675ee61337101d6833096db36625d463575c45295ffbce1eeb1b643f78cf3b82cfced241733a806f72fa2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64fb68881b0e31a81d49c38c10d4b5fbfbc1ee961d455da20be4a9887275a647edac340bb74c30aa837631215f02d8bfc4fc0f937514e966aff286e1335fa0f3"} 03:28:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:28:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:28:12 INFO - Contents: 03:28:12 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42096f331d9245463de03880465232e81c547b071924c74cfa8976c2a89239d3dbf7518f291e17271eaeb0323455e03af6adecf53f3c1d838b77c825693454f5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/217d25421a30a529c43924aeffca1c33657bc8a58fdf0091e22836c7b383308c1533a5466c7e1fdf52061847da417c143d1279ec6dcbb4baf36acf6ecbd72165", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31f58ea795f41e3cef7aac240939a78baa0f16893f5675ee61337101d6833096db36625d463575c45295ffbce1eeb1b643f78cf3b82cfced241733a806f72fa2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64fb68881b0e31a81d49c38c10d4b5fbfbc1ee961d455da20be4a9887275a647edac340bb74c30aa837631215f02d8bfc4fc0f937514e966aff286e1335fa0f3"} 03:28:12 INFO - Running post-run listener: copy_logs_to_upload_dir 03:28:12 INFO - Copying logs to upload dir... 03:28:12 INFO - mkdir: /builds/slave/test/build/upload/logs 03:28:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.376062 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-09-04 03:28:12.408982) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:28:12.416131) ========= 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=20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42096f331d9245463de03880465232e81c547b071924c74cfa8976c2a89239d3dbf7518f291e17271eaeb0323455e03af6adecf53f3c1d838b77c825693454f5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/217d25421a30a529c43924aeffca1c33657bc8a58fdf0091e22836c7b383308c1533a5466c7e1fdf52061847da417c143d1279ec6dcbb4baf36acf6ecbd72165", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31f58ea795f41e3cef7aac240939a78baa0f16893f5675ee61337101d6833096db36625d463575c45295ffbce1eeb1b643f78cf3b82cfced241733a806f72fa2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64fb68881b0e31a81d49c38c10d4b5fbfbc1ee961d455da20be4a9887275a647edac340bb74c30aa837631215f02d8bfc4fc0f937514e966aff286e1335fa0f3"} build_url:https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42096f331d9245463de03880465232e81c547b071924c74cfa8976c2a89239d3dbf7518f291e17271eaeb0323455e03af6adecf53f3c1d838b77c825693454f5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/217d25421a30a529c43924aeffca1c33657bc8a58fdf0091e22836c7b383308c1533a5466c7e1fdf52061847da417c143d1279ec6dcbb4baf36acf6ecbd72165", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31f58ea795f41e3cef7aac240939a78baa0f16893f5675ee61337101d6833096db36625d463575c45295ffbce1eeb1b643f78cf3b82cfced241733a806f72fa2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64fb68881b0e31a81d49c38c10d4b5fbfbc1ee961d455da20be4a9887275a647edac340bb74c30aa837631215f02d8bfc4fc0f937514e966aff286e1335fa0f3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:28:12.445563) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:28:12.445893) ========= 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=20e666a3d4cfbb71505a330e000001b4-1504519625.855818-596106621 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004759 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:28:12.499616) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:28:12.501938) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:28:12.502261) ========= ========= Total master_lag: 0.37 =========