builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1505469450.39 results: success (0) revision: 893fe1549e1e7342a66514b65960f08d40810a34 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 02:57:30.391955) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 02:57:30.392438) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 02:57:30.392765) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 02:57:30.446483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:57:30.446798) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 02:57:30-- 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% 503K=0.02s 2017-09-15 02:57:30 (503 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.228691 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:57:30.728479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:57:30.728812) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015653 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:57:30.779057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 32 secs) (at 2017-09-15 02:57:30.779434) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 893fe1549e1e7342a66514b65960f08d40810a34 --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 893fe1549e1e7342a66514b65960f08d40810a34 --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=88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 02:57:30,875 truncating revision to first 12 chars 2017-09-15 02:57:30,875 Setting DEBUG logging. 2017-09-15 02:57:30,875 attempt 1/10 2017-09-15 02:57:30,875 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/893fe1549e1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 02:57:32,024 attempt 1/10 2017-09-15 02:57:32,565 current task status: no status available at this point. state: PENDING 2017-09-15 02:57:32,566 sleeping for 10.00s (attempt 1/10) 2017-09-15 02:57:42,576 attempt 2/10 2017-09-15 02:57:43,623 current task status: no status available at this point. state: PENDING 2017-09-15 02:57:43,623 sleeping for 16.00s (attempt 2/10) 2017-09-15 02:57:59,634 attempt 3/10 2017-09-15 02:58:00,158 current task status: no status available at this point. state: PENDING 2017-09-15 02:58:00,158 sleeping for 25.00s (attempt 3/10) 2017-09-15 02:58:25,183 attempt 4/10 2017-09-15 02:58:25,889 current task status: no status available at this point. state: PENDING 2017-09-15 02:58:25,889 sleeping for 36.50s (attempt 4/10) 2017-09-15 02:59:02,425 attempt 5/10 2017-09-15 02:59:02,790 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-15 02:59:02,992 unpacking tar archive at: mozilla-central-893fe1549e1e/testing/mozharness/ program finished with exit code 0 elapsedTime=92.471635 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 32 secs) (at 2017-09-15 02:59:03.265530) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 02:59:03.265871) ========= script_repo_revision: 893fe1549e1e7342a66514b65960f08d40810a34 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 02:59:03.266277) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 02:59:03.266541) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 02:59:03.279356) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-09-15 02:59:03.279734) ========= /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=88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:59:03 INFO - MultiFileLogger online at 20170915 02:59:03 in /builds/slave/test 02:59:03 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 02:59:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:59:03 INFO - {'append_to_log': False, 02:59:03 INFO - 'base_work_dir': '/builds/slave/test', 02:59:03 INFO - 'blob_upload_branch': 'Firefox', 02:59:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:59:03 INFO - 'branch': 'Firefox', 02:59:03 INFO - 'buildbot_json_path': 'buildprops.json', 02:59:03 INFO - 'code_coverage': False, 02:59:03 INFO - 'config_files': ('talos/linux_config.py',), 02:59:03 INFO - 'default_actions': ('clobber', 02:59:03 INFO - 'read-buildbot-config', 02:59:03 INFO - 'download-and-extract', 02:59:03 INFO - 'populate-webroot', 02:59:03 INFO - 'create-virtualenv', 02:59:03 INFO - 'install', 02:59:03 INFO - 'setup-mitmproxy', 02:59:03 INFO - 'run-tests'), 02:59:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:59:03 INFO - 'disable_ccov_upload': False, 02:59:03 INFO - 'disable_stylo': False, 02:59:03 INFO - 'download_minidump_stackwalk': True, 02:59:03 INFO - 'download_symbols': 'ondemand', 02:59:03 INFO - 'enable_stylo': False, 02:59:03 INFO - 'enable_webrender': False, 02:59:03 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:59:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:59:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:59:03 INFO - 'gecko_profile': False, 02:59:03 INFO - 'gecko_profile_interval': 0, 02:59:03 INFO - 'installer_path': 'installer.exe', 02:59:03 INFO - 'log_level': 'info', 02:59:03 INFO - 'log_name': 'talos', 02:59:03 INFO - 'log_to_console': True, 02:59:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:59:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:59:03 INFO - 'opt_config_files': (), 02:59:03 INFO - 'pip_index': False, 02:59:03 INFO - 'suite': 'dromaeojs-e10s', 02:59:03 INFO - 'system_bits': '32', 02:59:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:59:03 INFO - 'title': 'talos-linux64-ix-044', 02:59:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:59:03 INFO - 'use_talos_json': True, 02:59:03 INFO - 'verify': 'False', 02:59:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:59:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:59:03 INFO - 'work_dir': 'build'} 02:59:03 INFO - [mozharness: 2017-09-15 09:59:03.587313Z] Running clobber step. 02:59:03 INFO - Running pre-action listener: _resource_record_pre_action 02:59:03 INFO - Running main action method: clobber 02:59:03 INFO - rmtree: /builds/slave/test/build 02:59:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:59:04 INFO - Running post-action listener: _resource_record_post_action 02:59:04 INFO - [mozharness: 2017-09-15 09:59:04.540508Z] Finished clobber step (success) 02:59:04 INFO - [mozharness: 2017-09-15 09:59:04.540604Z] Running read-buildbot-config step. 02:59:04 INFO - Running pre-action listener: _resource_record_pre_action 02:59:04 INFO - Running main action method: read_buildbot_config 02:59:04 INFO - Using buildbot properties: 02:59:04 INFO - { 02:59:04 INFO - "project": "", 02:59:04 INFO - "product": "firefox", 02:59:04 INFO - "who": "archaeopteryx@coole-files.de", 02:59:04 INFO - "installer_path": "public/build/target.tar.bz2", 02:59:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 02:59:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:59:04 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 02:59:04 INFO - "stage_platform": "linux64-qr", 02:59:04 INFO - "basedir": "/builds/slave/test", 02:59:04 INFO - "buildnumber": 57, 02:59:04 INFO - "platform": "ubuntu64_hw_qr", 02:59:04 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:59:04 INFO - "slavebuilddir": "test", 02:59:04 INFO - "taskId": "Bby4NX2SSy2nT7BcWJdU-Q", 02:59:04 INFO - "branch": "mozilla-central", 02:59:04 INFO - "script_repo_revision": "production", 02:59:04 INFO - "revision": "893fe1549e1e7342a66514b65960f08d40810a34", 02:59:04 INFO - "slavename": "talos-linux64-ix-044", 02:59:04 INFO - "repo_path": "mozilla-central" 02:59:04 INFO - } 02:59:04 INFO - Finding installer, test and symbols from parent task. 02:59:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bby4NX2SSy2nT7BcWJdU-Q'}, attempt #1 02:59:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bby4NX2SSy2nT7BcWJdU-Q'}, attempt #1 02:59:05 INFO - Task dependencies: VYH2wZ13SaWnxkKe49_76g 02:59:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g'}, attempt #1 02:59:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.tar.bz2 02:59:05 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.test_packages.json 02:59:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.crashreporter-symbols.zip 02:59:05 INFO - Running post-action listener: _resource_record_post_action 02:59:05 INFO - [mozharness: 2017-09-15 09:59:05.985396Z] Finished read-buildbot-config step (success) 02:59:05 INFO - [mozharness: 2017-09-15 09:59:05.985652Z] Running download-and-extract step. 02:59:05 INFO - Running pre-action listener: _resource_record_pre_action 02:59:05 INFO - Running main action method: download_and_extract 02:59:05 INFO - mkdir: /builds/slave/test/build/tests 02:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:05 INFO - trying https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.test_packages.json 02:59:05 INFO - Downloading https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:59:06 INFO - Downloaded 989 bytes. 02:59:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:59:06 INFO - Using the following test package requirements: 02:59:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:59:06 INFO - u'common': [u'target.common.tests.zip'], 02:59:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:59:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:59:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:59:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:59:06 INFO - u'mozbase': [u'target.common.tests.zip'], 02:59:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:59:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:59:06 INFO - u'web-platform': [u'target.common.tests.zip', 02:59:06 INFO - u'target.web-platform.tests.tar.gz'], 02:59:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:59:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:59:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.common.tests.zip 02:59:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:59:06 INFO - Fetch https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.common.tests.zip into memory 02:59:07 INFO - Content-Length response header: 54736771 02:59:07 INFO - Bytes received: 54736771 02:59:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.talos.tests.zip 02:59:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:59:19 INFO - Fetch https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.talos.tests.zip into memory 02:59:19 INFO - Content-Length response header: 14502661 02:59:19 INFO - Bytes received: 14502661 02:59: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')]} 02:59:20 INFO - trying https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.tar.bz2 02:59:20 INFO - Downloading https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:59:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:59:21 INFO - Downloaded 61595327 bytes. 02:59:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.tar.bz2 02:59:21 INFO - mkdir: /builds/slave/test/properties 02:59:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:59:21 INFO - Writing to file /builds/slave/test/properties/build_url 02:59:21 INFO - Contents: 02:59:21 INFO - build_url:https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.tar.bz2 02:59:21 INFO - Running post-action listener: _resource_record_post_action 02:59:21 INFO - Running post-action listener: find_tests_for_verification 02:59:21 INFO - Running post-action listener: set_extra_try_arguments 02:59:21 INFO - [mozharness: 2017-09-15 09:59:21.307039Z] Finished download-and-extract step (success) 02:59:21 INFO - [mozharness: 2017-09-15 09:59:21.307136Z] Running populate-webroot step. 02:59:21 INFO - Running pre-action listener: _resource_record_pre_action 02:59:21 INFO - Running main action method: populate_webroot 02:59:21 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:59:21 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['tresize', 02:59:21 INFO - 'tcanvasmark']}, 02:59:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:59:21 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['dromaeo_css', 02:59:21 INFO - 'kraken']}, 02:59:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:59:21 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:59:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'tests': ['damp', 'tps']}, 02:59:21 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['damp', 'tps']}, 02:59:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:59:21 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['dromaeo_dom']}, 02:59:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:59:21 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['basic_compositor_video', 02:59:21 INFO - 'glvideo']}, 02:59:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:59:21 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['ts_paint_webext', 02:59:21 INFO - 'tp5o_webext']}, 02:59:21 INFO - 'other-e10s': {'tests': ['a11yr', 02:59:21 INFO - 'ts_paint', 02:59:21 INFO - 'tpaint', 02:59:21 INFO - 'sessionrestore', 02:59:21 INFO - 'sessionrestore_many_windows', 02:59:21 INFO - 'sessionrestore_no_auto_restore', 02:59:21 INFO - 'tabpaint']}, 02:59:21 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['a11yr', 02:59:21 INFO - 'ts_paint', 02:59:21 INFO - 'tpaint', 02:59:21 INFO - 'sessionrestore', 02:59:21 INFO - 'sessionrestore_many_windows', 02:59:21 INFO - 'sessionrestore_no_auto_restore', 02:59:21 INFO - 'tabpaint']}, 02:59:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:59:21 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:59:21 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['bloom_basic_singleton']}, 02:59:21 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['bloom_basic']}, 02:59:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:59:21 INFO - 'tsvgr_opacity', 02:59:21 INFO - 'tart', 02:59:21 INFO - 'tscrollx', 02:59:21 INFO - 'tsvg_static']}, 02:59:21 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['tsvgx', 02:59:21 INFO - 'tsvgr_opacity', 02:59:21 INFO - 'tart', 02:59:21 INFO - 'tscrollx', 02:59:21 INFO - 'tsvg_static']}, 02:59:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:59:21 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'talos_options': ['--disable-stylo'], 02:59:21 INFO - 'tests': ['tp5o']}, 02:59:21 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:59:21 INFO - 'talos_options': ['--mitmproxy', 02:59:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:59:21 INFO - '--firstNonBlankPaint'], 02:59:21 INFO - 'tests': ['tp6_google', 02:59:21 INFO - 'tp6_youtube', 02:59:21 INFO - 'tp6_amazon', 02:59:21 INFO - 'tp6_facebook']}, 02:59:21 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:59:21 INFO - 'talos_options': ['--disable-stylo', 02:59:21 INFO - '--mitmproxy', 02:59:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:59:21 INFO - '--firstNonBlankPaint'], 02:59:21 INFO - 'tests': ['tp6_google', 02:59:21 INFO - 'tp6_youtube', 02:59:21 INFO - 'tp6_amazon', 02:59:21 INFO - 'tp6_facebook']}, 02:59:21 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:59:21 INFO - 'talos_options': ['--stylo-threads=1', 02:59:21 INFO - '--mitmproxy', 02:59:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:59:21 INFO - '--firstNonBlankPaint'], 02:59:21 INFO - 'tests': ['tp6_google', 02:59:21 INFO - 'tp6_youtube', 02:59:21 INFO - 'tp6_amazon', 02:59:21 INFO - 'tp6_facebook']}, 02:59:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'talos_options': ['--xperf_path', 02:59:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:59:21 INFO - 'tests': ['tp5n']}, 02:59:21 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:59:21 INFO - 'talos_options': ['--disable-stylo', 02:59:21 INFO - '--xperf_path', 02:59:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:59:21 INFO - 'tests': ['tp5n']}}} 02:59:21 INFO - Running post-action listener: _resource_record_post_action 02:59:21 INFO - [mozharness: 2017-09-15 09:59:21.321890Z] Finished populate-webroot step (success) 02:59:21 INFO - [mozharness: 2017-09-15 09:59:21.321978Z] Running create-virtualenv step. 02:59:21 INFO - Running pre-action listener: _resource_record_pre_action 02:59:21 INFO - Running main action method: create_virtualenv 02:59:21 INFO - Creating virtualenv /builds/slave/test/build/venv 02:59:21 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:59:21 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:59:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:59:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:59:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:59:22 INFO - Installing distribute.............................................................................................................................................................................................done. 02:59:22 INFO - Installing pip...............done. 02:59:22 INFO - Return code: 0 02:59:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:59:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:59:22 INFO - Reading from file tmpfile_stdout 02:59:22 INFO - Output received: 02:59:22 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:59:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:22 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:22 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 02:59:22 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 02:59:22 INFO - Using env: {'DISPLAY': ':0', 02:59:22 INFO - 'HOME': '/home/cltbld', 02:59:22 INFO - 'LANG': 'en_US.UTF-8', 02:59:22 INFO - 'LANGUAGE': 'en_US:en', 02:59:22 INFO - 'LOGNAME': 'cltbld', 02:59:22 INFO - 'MAIL': '/var/mail/cltbld', 02:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:22 INFO - 'MOZ_NO_REMOTE': '1', 02:59:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:22 INFO - 'NO_EM_RESTART': '1', 02:59:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:22 INFO - 'PWD': '/builds/slave/test', 02:59:22 INFO - 'SHELL': '/bin/bash', 02:59:22 INFO - 'SHLVL': '1', 02:59:22 INFO - 'TERM': 'linux', 02:59:22 INFO - 'TMOUT': '86400', 02:59:22 INFO - 'USER': 'cltbld', 02:59:22 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:22 INFO - '_': '/tools/buildbot/bin/python'} 02:59:23 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:59:23 INFO - Downloading/unpacking pip>=1.5 02:59:23 INFO - Running setup.py egg_info for package pip 02:59:23 INFO - warning: no files found matching 'pip/cacert.pem' 02:59:23 INFO - warning: no files found matching '*.html' under directory 'docs' 02:59:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:59:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:59:23 INFO - Installing collected packages: pip 02:59:23 INFO - Found existing installation: pip 1.1 02:59:23 INFO - Uninstalling pip: 02:59:23 INFO - Successfully uninstalled pip 02:59:23 INFO - Running setup.py install for pip 02:59:23 INFO - warning: no files found matching 'pip/cacert.pem' 02:59:23 INFO - warning: no files found matching '*.html' under directory 'docs' 02:59:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:59:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:59:23 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:59:23 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:59:23 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:59:23 INFO - Successfully installed pip 02:59:23 INFO - Cleaning up... 02:59:23 INFO - Return code: 0 02:59:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:59:23 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')]} 02:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:23 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')]} 02:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:23 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:23 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 02:59:23 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 02:59:23 INFO - Using env: {'DISPLAY': ':0', 02:59:23 INFO - 'HOME': '/home/cltbld', 02:59:23 INFO - 'LANG': 'en_US.UTF-8', 02:59:23 INFO - 'LANGUAGE': 'en_US:en', 02:59:23 INFO - 'LOGNAME': 'cltbld', 02:59:23 INFO - 'MAIL': '/var/mail/cltbld', 02:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:23 INFO - 'MOZ_NO_REMOTE': '1', 02:59:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:23 INFO - 'NO_EM_RESTART': '1', 02:59:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:23 INFO - 'PWD': '/builds/slave/test', 02:59:23 INFO - 'SHELL': '/bin/bash', 02:59:23 INFO - 'SHLVL': '1', 02:59:23 INFO - 'TERM': 'linux', 02:59:23 INFO - 'TMOUT': '86400', 02:59:23 INFO - 'USER': 'cltbld', 02:59:23 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:23 INFO - '_': '/tools/buildbot/bin/python'} 02:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:24 INFO - Downloading/unpacking psutil>=3.1.1 02:59:24 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 02:59:24 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 02:59:24 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 02:59:24 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 02:59:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:59:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:59:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:59:32 INFO - Installing collected packages: psutil 02:59:32 INFO - Running setup.py install for psutil 02:59:32 INFO - building 'psutil._psutil_linux' extension 02:59:32 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 02:59:33 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 02:59:33 INFO - building 'psutil._psutil_posix' extension 02:59:33 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 02:59:33 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 02:59:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:59:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:59:33 INFO - Successfully installed psutil 02:59:33 INFO - Cleaning up... 02:59:33 INFO - Return code: 0 02:59:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:33 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:33 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 02:59:33 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 02:59:33 INFO - Using env: {'DISPLAY': ':0', 02:59:33 INFO - 'HOME': '/home/cltbld', 02:59:33 INFO - 'LANG': 'en_US.UTF-8', 02:59:33 INFO - 'LANGUAGE': 'en_US:en', 02:59:33 INFO - 'LOGNAME': 'cltbld', 02:59:33 INFO - 'MAIL': '/var/mail/cltbld', 02:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:33 INFO - 'MOZ_NO_REMOTE': '1', 02:59:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:33 INFO - 'NO_EM_RESTART': '1', 02:59:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:33 INFO - 'PWD': '/builds/slave/test', 02:59:33 INFO - 'SHELL': '/bin/bash', 02:59:33 INFO - 'SHLVL': '1', 02:59:33 INFO - 'TERM': 'linux', 02:59:33 INFO - 'TMOUT': '86400', 02:59:33 INFO - 'USER': 'cltbld', 02:59:33 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:33 INFO - '_': '/tools/buildbot/bin/python'} 02:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:59:33 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 02:59:33 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 02:59:33 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 02:59:33 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 02:59:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:59:35 INFO - Installing collected packages: mozsystemmonitor 02:59:35 INFO - Running setup.py install for mozsystemmonitor 02:59:35 INFO - Successfully installed mozsystemmonitor 02:59:35 INFO - Cleaning up... 02:59:35 INFO - Return code: 0 02:59:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:35 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:35 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 02:59:35 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 02:59:35 INFO - Using env: {'DISPLAY': ':0', 02:59:35 INFO - 'HOME': '/home/cltbld', 02:59:35 INFO - 'LANG': 'en_US.UTF-8', 02:59:35 INFO - 'LANGUAGE': 'en_US:en', 02:59:35 INFO - 'LOGNAME': 'cltbld', 02:59:35 INFO - 'MAIL': '/var/mail/cltbld', 02:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:35 INFO - 'MOZ_NO_REMOTE': '1', 02:59:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:35 INFO - 'NO_EM_RESTART': '1', 02:59:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:35 INFO - 'PWD': '/builds/slave/test', 02:59:35 INFO - 'SHELL': '/bin/bash', 02:59:35 INFO - 'SHLVL': '1', 02:59:35 INFO - 'TERM': 'linux', 02:59:35 INFO - 'TMOUT': '86400', 02:59:35 INFO - 'USER': 'cltbld', 02:59:35 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:35 INFO - '_': '/tools/buildbot/bin/python'} 02:59:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:35 INFO - Downloading/unpacking jsonschema==2.5.1 02:59:35 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 02:59:35 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 02:59:35 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 02:59:35 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 02:59:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:59:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:59:36 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 02:59:36 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 02:59:36 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 02:59:36 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 02:59:36 INFO - Downloading functools32-3.2.3-2.tar.gz 02:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:59:36 INFO - warning: no files found matching '*.txt' 02:59:36 INFO - no previously-included directories found matching 'build' 02:59:36 INFO - no previously-included directories found matching 'dist' 02:59:36 INFO - no previously-included directories found matching '.git*' 02:59:36 INFO - Installing collected packages: jsonschema, functools32 02:59:36 INFO - Running setup.py install for functools32 02:59:36 INFO - warning: no files found matching '*.txt' 02:59:36 INFO - no previously-included directories found matching 'build' 02:59:36 INFO - no previously-included directories found matching 'dist' 02:59:36 INFO - no previously-included directories found matching '.git*' 02:59:36 INFO - Successfully installed jsonschema functools32 02:59:36 INFO - Cleaning up... 02:59:36 INFO - Return code: 0 02:59:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:36 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:36 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 02:59:36 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 02:59:36 INFO - Using env: {'DISPLAY': ':0', 02:59:36 INFO - 'HOME': '/home/cltbld', 02:59:36 INFO - 'LANG': 'en_US.UTF-8', 02:59:36 INFO - 'LANGUAGE': 'en_US:en', 02:59:36 INFO - 'LOGNAME': 'cltbld', 02:59:36 INFO - 'MAIL': '/var/mail/cltbld', 02:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:36 INFO - 'MOZ_NO_REMOTE': '1', 02:59:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:36 INFO - 'NO_EM_RESTART': '1', 02:59:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:36 INFO - 'PWD': '/builds/slave/test', 02:59:36 INFO - 'SHELL': '/bin/bash', 02:59:36 INFO - 'SHLVL': '1', 02:59:36 INFO - 'TERM': 'linux', 02:59:36 INFO - 'TMOUT': '86400', 02:59:36 INFO - 'USER': 'cltbld', 02:59:36 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:36 INFO - '_': '/tools/buildbot/bin/python'} 02:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:59:37 INFO - Cleaning up... 02:59:37 INFO - Return code: 0 02:59:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:59:37 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')]} 02:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:37 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')]} 02:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:37 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:37 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 02:59:37 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 02:59:37 INFO - Using env: {'DISPLAY': ':0', 02:59:37 INFO - 'HOME': '/home/cltbld', 02:59:37 INFO - 'LANG': 'en_US.UTF-8', 02:59:37 INFO - 'LANGUAGE': 'en_US:en', 02:59:37 INFO - 'LOGNAME': 'cltbld', 02:59:37 INFO - 'MAIL': '/var/mail/cltbld', 02:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:37 INFO - 'MOZ_NO_REMOTE': '1', 02:59:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:37 INFO - 'NO_EM_RESTART': '1', 02:59:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:37 INFO - 'PWD': '/builds/slave/test', 02:59:37 INFO - 'SHELL': '/bin/bash', 02:59:37 INFO - 'SHLVL': '1', 02:59:37 INFO - 'TERM': 'linux', 02:59:37 INFO - 'TMOUT': '86400', 02:59:37 INFO - 'USER': 'cltbld', 02:59:37 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:37 INFO - '_': '/tools/buildbot/bin/python'} 02:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:37 INFO - Downloading/unpacking blobuploader==1.2.4 02:59:37 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 02:59:37 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 02:59:37 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 02:59:37 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 02:59:38 INFO - Downloading blobuploader-1.2.4.tar.gz 02:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:59:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:59:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:59:38 INFO - Downloading docopt-0.6.1.tar.gz 02:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:59:39 INFO - Installing collected packages: blobuploader, requests, docopt 02:59:39 INFO - Running setup.py install for blobuploader 02:59:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:59:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:59:39 INFO - Running setup.py install for requests 02:59:39 INFO - Running setup.py install for docopt 02:59:39 INFO - Successfully installed blobuploader requests docopt 02:59:39 INFO - Cleaning up... 02:59:39 INFO - Return code: 0 02:59:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:59: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')]} 02:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59: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')]} 02:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:39 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:39 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 02:59:39 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 02:59:39 INFO - Using env: {'DISPLAY': ':0', 02:59:39 INFO - 'HOME': '/home/cltbld', 02:59:39 INFO - 'LANG': 'en_US.UTF-8', 02:59:39 INFO - 'LANGUAGE': 'en_US:en', 02:59:39 INFO - 'LOGNAME': 'cltbld', 02:59:39 INFO - 'MAIL': '/var/mail/cltbld', 02:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:39 INFO - 'MOZ_NO_REMOTE': '1', 02:59:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:39 INFO - 'NO_EM_RESTART': '1', 02:59:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:39 INFO - 'PWD': '/builds/slave/test', 02:59:39 INFO - 'SHELL': '/bin/bash', 02:59:39 INFO - 'SHLVL': '1', 02:59:39 INFO - 'TERM': 'linux', 02:59:39 INFO - 'TMOUT': '86400', 02:59:39 INFO - 'USER': 'cltbld', 02:59:39 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:39 INFO - '_': '/tools/buildbot/bin/python'} 02:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:59:39 INFO - Running setup.py (path:/tmp/pip-e_S8vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:59:39 INFO - Running setup.py (path:/tmp/pip-SNv3Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:59:39 INFO - Running setup.py (path:/tmp/pip-PP1En2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:59:40 INFO - Running setup.py (path:/tmp/pip-U81dgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:59:40 INFO - Running setup.py (path:/tmp/pip-b_5FxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:59:40 INFO - Running setup.py (path:/tmp/pip-AKNEZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:59:40 INFO - Running setup.py (path:/tmp/pip-tBCKjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:59:40 INFO - Running setup.py (path:/tmp/pip-up8VpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:59:40 INFO - Running setup.py (path:/tmp/pip-1eHXZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:59:40 INFO - Running setup.py (path:/tmp/pip-nJ5jop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:59:40 INFO - Running setup.py (path:/tmp/pip-umMYpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:59:40 INFO - Running setup.py (path:/tmp/pip-LTdTyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:59:40 INFO - Running setup.py (path:/tmp/pip-1biC0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:59:40 INFO - Running setup.py (path:/tmp/pip-kzl7Ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:59:40 INFO - Running setup.py (path:/tmp/pip-dbcSsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:59:40 INFO - Running setup.py (path:/tmp/pip-IwQf3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:59:40 INFO - Running setup.py (path:/tmp/pip-DCg9qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:59:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:59:41 INFO - Running setup.py install for manifestparser 02:59:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:59:41 INFO - Running setup.py install for mozcrash 02:59:41 INFO - Running setup.py install for mozdebug 02:59:41 INFO - Running setup.py install for mozdevice 02:59:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:59:41 INFO - Running setup.py install for mozfile 02:59:41 INFO - Running setup.py install for mozhttpd 02:59:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:59:41 INFO - Running setup.py install for mozinfo 02:59:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:59:41 INFO - Running setup.py install for mozInstall 02:59:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:59:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:59:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:59:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:59:41 INFO - Running setup.py install for mozleak 02:59:41 INFO - Running setup.py install for mozlog 02:59:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:59:42 INFO - Running setup.py install for moznetwork 02:59:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:59:42 INFO - Running setup.py install for mozprocess 02:59:42 INFO - Running setup.py install for mozprofile 02:59:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:59:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:59:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:59:42 INFO - Running setup.py install for mozrunner 02:59:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:59:42 INFO - Running setup.py install for mozscreenshot 02:59:42 INFO - Running setup.py install for moztest 02:59:42 INFO - Running setup.py install for mozversion 02:59:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:59:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:59:42 INFO - Cleaning up... 02:59:42 INFO - Return code: 0 02:59:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:59:42 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')]} 02:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:42 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')]} 02:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:42 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:42 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 02:59:42 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 02:59:42 INFO - Using env: {'DISPLAY': ':0', 02:59:42 INFO - 'HOME': '/home/cltbld', 02:59:42 INFO - 'LANG': 'en_US.UTF-8', 02:59:42 INFO - 'LANGUAGE': 'en_US:en', 02:59:42 INFO - 'LOGNAME': 'cltbld', 02:59:42 INFO - 'MAIL': '/var/mail/cltbld', 02:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:42 INFO - 'MOZ_NO_REMOTE': '1', 02:59:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:42 INFO - 'NO_EM_RESTART': '1', 02:59:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:42 INFO - 'PWD': '/builds/slave/test', 02:59:42 INFO - 'SHELL': '/bin/bash', 02:59:42 INFO - 'SHLVL': '1', 02:59:42 INFO - 'TERM': 'linux', 02:59:42 INFO - 'TMOUT': '86400', 02:59:42 INFO - 'USER': 'cltbld', 02:59:42 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:42 INFO - '_': '/tools/buildbot/bin/python'} 02:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:59:43 INFO - Running setup.py (path:/tmp/pip-iBHjXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:59:43 INFO - Running setup.py (path:/tmp/pip-ZOBFsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:59:43 INFO - Running setup.py (path:/tmp/pip-fCGjaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:59:43 INFO - Running setup.py (path:/tmp/pip-BPeGWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:59:43 INFO - Running setup.py (path:/tmp/pip-KX1F1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:59:43 INFO - Running setup.py (path:/tmp/pip-wEGiTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:59:43 INFO - Running setup.py (path:/tmp/pip-d9vqwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:59:43 INFO - Running setup.py (path:/tmp/pip-g8aSHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:59:43 INFO - Running setup.py (path:/tmp/pip-rg7dZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:59:43 INFO - Running setup.py (path:/tmp/pip-TyeAnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:59:43 INFO - Running setup.py (path:/tmp/pip-NCAwU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:59:43 INFO - Running setup.py (path:/tmp/pip-fDkdHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:59:43 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)) 02:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:59:43 INFO - Running setup.py (path:/tmp/pip-J43ybw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:59:44 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)) 02:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:59:44 INFO - Running setup.py (path:/tmp/pip-Isfl8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:59:44 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)) 02:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:59:44 INFO - Running setup.py (path:/tmp/pip-vBOfIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:59:44 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)) 02:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:59:44 INFO - Running setup.py (path:/tmp/pip-RqaP6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:59:44 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)) 02:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:59:44 INFO - Running setup.py (path:/tmp/pip-d2cW7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:59:44 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)) 02:59:44 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)) 02:59:44 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)) 02:59:44 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)) 02:59:44 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)) 02:59:44 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)) 02:59:44 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)) 02:59:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:59:44 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 02:59:44 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 02:59:44 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 02:59:44 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 02:59:45 INFO - Downloading blessings-1.6.tar.gz 02:59:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:59:45 INFO - Installing collected packages: blessings 02:59:45 INFO - Running setup.py install for blessings 02:59:45 INFO - Successfully installed blessings 02:59:45 INFO - Cleaning up... 02:59:45 INFO - Return code: 0 02:59:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:59:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:59:45 INFO - Reading from file tmpfile_stdout 02:59:45 INFO - Current package versions: 02:59:45 INFO - argparse == 1.2.1 02:59:45 INFO - blessings == 1.6 02:59:45 INFO - blobuploader == 1.2.4 02:59:45 INFO - docopt == 0.6.1 02:59:45 INFO - functools32 == 3.2.3-2 02:59:45 INFO - jsonschema == 2.5.1 02:59:45 INFO - manifestparser == 1.1 02:59:45 INFO - mozInstall == 1.13 02:59:45 INFO - mozcrash == 1.0 02:59:45 INFO - mozdebug == 0.1 02:59:45 INFO - mozdevice == 0.51 02:59:45 INFO - mozfile == 1.2 02:59:45 INFO - mozhttpd == 0.7 02:59:45 INFO - mozinfo == 0.10 02:59:45 INFO - mozleak == 0.1 02:59:45 INFO - mozlog == 3.5 02:59:45 INFO - moznetwork == 0.27 02:59:45 INFO - mozprocess == 0.25 02:59:45 INFO - mozprofile == 0.28 02:59:45 INFO - mozrunner == 6.13 02:59:45 INFO - mozscreenshot == 0.1 02:59:45 INFO - mozsystemmonitor == 0.3 02:59:45 INFO - moztest == 0.8 02:59:45 INFO - mozversion == 1.4 02:59:45 INFO - psutil == 3.1.1 02:59:45 INFO - requests == 1.2.3 02:59:45 INFO - wsgiref == 0.1.2 02:59:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:59:45 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')]} 02:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:45 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')]} 02:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:45 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:45 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 02:59:45 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 02:59:45 INFO - Using env: {'DISPLAY': ':0', 02:59:45 INFO - 'HOME': '/home/cltbld', 02:59:45 INFO - 'LANG': 'en_US.UTF-8', 02:59:45 INFO - 'LANGUAGE': 'en_US:en', 02:59:45 INFO - 'LOGNAME': 'cltbld', 02:59:45 INFO - 'MAIL': '/var/mail/cltbld', 02:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:45 INFO - 'MOZ_NO_REMOTE': '1', 02:59:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:45 INFO - 'NO_EM_RESTART': '1', 02:59:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:45 INFO - 'PWD': '/builds/slave/test', 02:59:45 INFO - 'SHELL': '/bin/bash', 02:59:45 INFO - 'SHLVL': '1', 02:59:45 INFO - 'TERM': 'linux', 02:59:45 INFO - 'TMOUT': '86400', 02:59:45 INFO - 'USER': 'cltbld', 02:59:45 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:45 INFO - '_': '/tools/buildbot/bin/python'} 02:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:46 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)) 02:59:46 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)) 02:59:46 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)) 02:59:46 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)) 02:59:46 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)) 02:59:46 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)) 02:59:46 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)) 02:59:46 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)) 02:59:46 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)) 02:59:46 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:59:46 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 02:59:46 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 02:59:46 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 02:59:46 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 02:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:59:47 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)) 02:59:47 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)) 02:59:47 INFO - Installing collected packages: simplejson 02:59:47 INFO - Running setup.py install for simplejson 02:59:47 INFO - building 'simplejson._speedups' extension 02:59:47 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 02:59:48 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 02:59:48 INFO - Successfully installed simplejson 02:59:48 INFO - Cleaning up... 02:59:48 INFO - Return code: 0 02:59:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:59:48 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')]} 02:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:59:48 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')]} 02:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:59:48 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 0x15851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', '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 02:59:48 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 02:59:48 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 02:59:48 INFO - Using env: {'DISPLAY': ':0', 02:59:48 INFO - 'HOME': '/home/cltbld', 02:59:48 INFO - 'LANG': 'en_US.UTF-8', 02:59:48 INFO - 'LANGUAGE': 'en_US:en', 02:59:48 INFO - 'LOGNAME': 'cltbld', 02:59:48 INFO - 'MAIL': '/var/mail/cltbld', 02:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:59:48 INFO - 'MOZ_NO_REMOTE': '1', 02:59:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:59:48 INFO - 'NO_EM_RESTART': '1', 02:59:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:59:48 INFO - 'PWD': '/builds/slave/test', 02:59:48 INFO - 'SHELL': '/bin/bash', 02:59:48 INFO - 'SHLVL': '1', 02:59:48 INFO - 'TERM': 'linux', 02:59:48 INFO - 'TMOUT': '86400', 02:59:48 INFO - 'USER': 'cltbld', 02:59:48 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 02:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:59:48 INFO - '_': '/tools/buildbot/bin/python'} 02:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:59:48 INFO - Cleaning up... 02:59:48 INFO - Return code: 0 02:59:48 INFO - Running post-action listener: _resource_record_post_action 02:59:48 INFO - Running post-action listener: _start_resource_monitoring 02:59:48 INFO - Starting resource monitoring. 02:59:48 INFO - [mozharness: 2017-09-15 09:59:48.590457Z] Finished create-virtualenv step (success) 02:59:48 INFO - [mozharness: 2017-09-15 09:59:48.591063Z] Running install step. 02:59:48 INFO - Running pre-action listener: _resource_record_pre_action 02:59:48 INFO - Running main action method: install 02:59:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:59:48 INFO - Reading from file tmpfile_stdout 02:59:48 INFO - Detecting whether we're running mozinstall >=1.0... 02:59:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:59:48 INFO - Reading from file tmpfile_stdout 02:59:48 INFO - Output received: 02:59:48 INFO - Usage: mozinstall [options] installer 02:59:48 INFO - Options: 02:59:48 INFO - -h, --help show this help message and exit 02:59:48 INFO - -d DEST, --destination=DEST 02:59:48 INFO - Directory to install application into. [default: 02:59:48 INFO - "/builds/slave/test"] 02:59:48 INFO - --app=APP Application being installed. [default: firefox] 02:59:48 INFO - mkdir: /builds/slave/test/build/application 02:59:48 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'] 02:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:00:06 INFO - Reading from file tmpfile_stdout 03:00:06 INFO - Output received: 03:00:06 INFO - /builds/slave/test/build/application/firefox/firefox 03:00:06 INFO - Running post-action listener: _resource_record_post_action 03:00:06 INFO - [mozharness: 2017-09-15 10:00:06.935741Z] Finished install step (success) 03:00:06 INFO - [mozharness: 2017-09-15 10:00:06.935993Z] Running setup-mitmproxy step. 03:00:06 INFO - Running pre-action listener: _resource_record_pre_action 03:00:06 INFO - Running main action method: setup_mitmproxy 03:00:06 INFO - Skipping: mitmproxy is not required 03:00:06 INFO - Running post-action listener: _resource_record_post_action 03:00:06 INFO - [mozharness: 2017-09-15 10:00:06.937110Z] Finished setup-mitmproxy step (success) 03:00:06 INFO - [mozharness: 2017-09-15 10:00:06.937328Z] Running run-tests step. 03:00:06 INFO - Running pre-action listener: _resource_record_pre_action 03:00:06 INFO - Running pre-action listener: _set_gcov_prefix 03:00:06 INFO - Running main action method: run_tests 03:00:06 WARNING - Try message not found. 03:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:00:06 INFO - Python 2.7.3 03:00:06 INFO - Return code: 0 03:00:06 INFO - grabbing minidump binary from tooltool 03:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:06 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 0x18f5090>, '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 0x18f5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18eff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:00:06 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:00:06 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:00:06 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:00:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:00:07 INFO - Return code: 0 03:00:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:00:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:00:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:00:07 INFO - ENV: RUST_BACKTRACE is now full 03:00:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:00:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:00:07 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/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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:00:07 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/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --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:00:07 INFO - Using env: {'DISPLAY': ':0', 03:00:07 INFO - 'HOME': '/home/cltbld', 03:00:07 INFO - 'LANG': 'en_US.UTF-8', 03:00:07 INFO - 'LANGUAGE': 'en_US:en', 03:00:07 INFO - 'LOGNAME': 'cltbld', 03:00:07 INFO - 'MAIL': '/var/mail/cltbld', 03:00:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:00:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:00:07 INFO - 'MOZ_ACCELERATED': '1', 03:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:07 INFO - 'MOZ_NO_REMOTE': '1', 03:00:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:00:07 INFO - 'MOZ_WEBRENDER': '1', 03:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:07 INFO - 'NO_EM_RESTART': '1', 03:00:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:07 INFO - 'PWD': '/builds/slave/test', 03:00:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:00:07 INFO - 'RUST_BACKTRACE': 'full', 03:00:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:00:07 INFO - 'SHELL': '/bin/bash', 03:00:07 INFO - 'SHLVL': '1', 03:00:07 INFO - 'TERM': 'linux', 03:00:07 INFO - 'TMOUT': '86400', 03:00:07 INFO - 'USER': 'cltbld', 03:00:07 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 03:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:07 INFO - '_': '/tools/buildbot/bin/python'} 03:00:07 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/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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:00:07 INFO - mozversion application_buildid: 20170915090610 03:00:07 INFO - mozversion application_changeset: 893fe1549e1e7342a66514b65960f08d40810a34 03:00:07 INFO - mozversion application_display_name: Nightly 03:00:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:00:07 INFO - mozversion application_name: Firefox 03:00:07 INFO - mozversion application_remotingname: firefox 03:00:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:00:07 INFO - mozversion application_vendor: Mozilla 03:00:07 INFO - mozversion application_version: 57.0a1 03:00:07 INFO - mozversion platform_buildid: 20170915090610 03:00:07 INFO - mozversion platform_changeset: 893fe1549e1e7342a66514b65960f08d40810a34 03:00:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:00:07 INFO - mozversion platform_version: 57.0a1 03:00:07 INFO - using testdate: 1505469607 03:00:07 INFO - actual date: 1505469607 03:00:07 INFO - starting webserver on 'localhost:42366' 03:00:07 INFO - SUITE-START | Running 2 tests 03:00:07 INFO - TEST-START | dromaeo_css 03:00:07 INFO - Initialising browser for dromaeo_css test... 03:00:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42366/getInfo.html -profile /tmp/tmpXmni0R/profile 03:00:07 INFO - TEST-INFO | started process 3555 (/builds/slave/test/build/application/firefox/firefox http://localhost:42366/getInfo.html) 03:00:15 INFO - TEST-INFO | 3555: exit 0 03:00:16 INFO - Browser initialized. 03:00:16 INFO - Running cycle 1/1 for dromaeo_css test... 03:00:16 INFO - Using env: {'DISPLAY': ':0', 03:00:16 INFO - 'HOME': '/home/cltbld', 03:00:16 INFO - 'JSGC_DISABLE_POISONING': '1', 03:00:16 INFO - 'LANG': 'en_US.UTF-8', 03:00:16 INFO - 'LANGUAGE': 'en_US:en', 03:00:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:00:16 INFO - 'LOGNAME': 'cltbld', 03:00:16 INFO - 'MAIL': '/var/mail/cltbld', 03:00:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:00:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:00:16 INFO - 'MOZ_ACCELERATED': '1', 03:00:16 INFO - 'MOZ_CRASHREPORTER': '1', 03:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:00:16 INFO - 'MOZ_NO_REMOTE': '1', 03:00:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:00:16 INFO - 'MOZ_WEBRENDER': '1', 03:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:16 INFO - 'NO_EM_RESTART': '1', 03:00:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:16 INFO - 'PWD': '/builds/slave/test', 03:00:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:00:16 INFO - 'RUST_BACKTRACE': 'full', 03:00:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:00:16 INFO - 'SHELL': '/bin/bash', 03:00:16 INFO - 'SHLVL': '1', 03:00:16 INFO - 'TERM': 'linux', 03:00:16 INFO - 'TMOUT': '86400', 03:00:16 INFO - 'USER': 'cltbld', 03:00:16 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 03:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:16 INFO - '_': '/tools/buildbot/bin/python'} 03:00:16 INFO - TEST-INFO | started process 3806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXmni0R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:00:16 INFO - PID 3806 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:00:17 INFO - PID 3806 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:00:17 INFO - PID 3806 | 03:00:17 INFO - PID 3806 | (/builds/slave/test/build/application/firefox/firefox:3866): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:00:17 INFO - PID 3806 | 03:00:27 INFO - PID 3806 | RSS: Main: 194584576 03:00:27 INFO - PID 3806 | 03:03:25 INFO - PID 3806 | Cycle 1(1): loaded http://localhost:42366/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42366/tests/dromaeo/cssquery-ext.html) 03:03:27 INFO - PID 3806 | RSS: Main: 207949824 03:03:27 INFO - PID 3806 | 03:06:29 INFO - PID 3806 | Cycle 1(1): loaded http://localhost:42366/tests/dromaeo/cssquery-ext.html (next: http://localhost:42366/tests/dromaeo/cssquery-jquery.html) 03:06:29 INFO - PID 3806 | RSS: Main: 204681216 03:06:29 INFO - PID 3806 | 03:09:32 INFO - PID 3806 | Cycle 1(1): loaded http://localhost:42366/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42366/tests/dromaeo/cssquery-mootools.html) 03:09:32 INFO - PID 3806 | RSS: Main: 206839808 03:09:32 INFO - PID 3806 | 03:12:34 INFO - PID 3806 | Cycle 1(1): loaded http://localhost:42366/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42366/tests/dromaeo/cssquery-prototype.html) 03:12:35 INFO - PID 3806 | RSS: Main: 205754368 03:12:35 INFO - PID 3806 | 03:15:39 INFO - PID 3806 | Cycle 1(1): loaded http://localhost:42366/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42366/tests/dromaeo/cssquery-yui.html) 03:15:39 INFO - PID 3806 | RSS: Main: 206856192 03:15:39 INFO - PID 3806 | 03:18:41 INFO - PID 3806 | Cycle 1(1): loaded http://localhost:42366/tests/dromaeo/cssquery-yui.html (next: http://localhost:42366/tests/dromaeo/cssquery-dojo.html) 03:18:42 INFO - PID 3806 | RSS: Main: 209371136 03:18:42 INFO - PID 3806 | 03:18:42 INFO - PID 3806 | __start_tp_report 03:18:42 INFO - PID 3806 | _x_x_mozilla_page_load 03:18:42 INFO - PID 3806 | _x_x_mozilla_page_load_details 03:18:42 INFO - PID 3806 | |i|pagename|runs| 03:18:42 INFO - PID 3806 | |0;dojo.html;3131.264152924063;3136.8431578421073;3160.6365267994174;3201.3277211785526;3211.0527394418627;6188.443040126388;6262.530310226734;6307.148534947781;6376.075469056984;6382.648954307517;6243.875122497548;6261.718222679979;6264.62412255265;6282.874342513147;6286.182796236483;5712.200291959129;5770.942290577103;5775.537956963432;5779;5844;5842.24050873388;5912.556558258127;5943.673097979609;5955.34491205967;5978.133170690247;5870.530357571384;5902.793402230923;5958.37437069108;5962.0162673158875;5980;5641.943580564182;5727.914081288784;5760.049591817345;5885.735141918624;5950.196723442323;5816.243888294494;5859.6191247569195;5892.911606325909;5905.202797622309;5911.379305172938;6196.101565273055;6279.058141278799;6365.2361716594305;6419.5506314558;6427.967860160669;6665.066890635315;6690.297518760557;6694.196696396415;6767.4924380671155;6844.247132815386;6742.258351581322;6842.007908853188;6844.726210951556;6855.485838562078;6875.759348422781;6292.370762923717;6310.87378252437;6317.968410157966;6373.808785736435;6378.553501254868;6850.588964662136;6874.484413668994;6891.173059232824;6926.307369263134;7000.08998830159;6498.317676643979;6596.637184954873;6605.5376123670885;6610.438112760373;6776.322367763184;6833.3166683331265;6873.48448866337;6884.105066341343;6886.311368863174;6908.6545672716165;6664.733410663616;6711.496637752188;6773.051772751819;6818.28408017151;6843.828904277333;6624.8343791405605;6703.865922681616;6788.049673045777;6837.111175547247;6908.6545672716165;5343.919841202385;5352.839414817562;5371.704556249444;5380.623356365017;5410.1343784995;4587.42657167854;4657.767111644404;4692.249240121564;4716.504766999416;4775.832845850378;5779.653220806765;5805.94194058054;5895.941040589625;5932.021216499315;5953.3451320354725;6800.6939687714175;6805.965970170217;6855;6860.279670634612;6891.517593768388;5686.687232202185;5769.9711501442225;5785.71071446426;5812.82561523155;5838.036723940502;5772.624779389326;5813.360530341659;5845.766169353178;5900.0264956281735;5907.409259074058;6605.504587155982;6624.105745724266;6625.138731964814;6642.634655094016;6649.202095748445;6758.966205169139;6850.21222559412;6915.792526224222;6930.064441300561;6941.132358455193;6518.641474718936;6544.8363790905605;6577.539572229898;6657.1345725055435;6667.49993750461;6565.376289252514;6700.899486507608;6705.664716764045;6767.864642707217;6824.283450237852;6828.897566536407;6857.7256909723055;6901.482388820758;6911.997760324624;6930.757423490355;6636.568623039487;6689.46484281266;6699.229588597374;6745.52781305304;6746.325367463214;6610.603363798187;6613.305602911625;6616.106825578678;6690.498212633975;6701.731930722912;6445.097686324012;6456.386643268882;6527.15147030883;6569.80290591283;6645.202575690948;6505.21937367519;6530.7387704493185;6588;6591.901121483277;6611.173603299588;6290.433860952537;6343.746250150127;6416.069669897932;6505.121808555785;6561.212654481493 03:18:42 INFO - PID 3806 | |1;ext.html;730.1749023603328;743.4684200796436;746.2015643261713;750.1635676221014;753.6796861333935;39031.414528782094;39428.40857387138;42678;42680.359794603064;42958.14083718332;39129.41305880414;39364;39452.210955780865;39467.407988880186;39685.801570992175;7030.929690703078;7095.042169307155;7117.395021423185;7127.501074924758;7139.85720285594;6998.475114366417;7116.252793456691;7133.286671332851;7135.5361901476235;7171.9282807171785;7083.185433675116;7105.609191494465;7107.466939979497;7110.751123710671;7127.536710113826;7003.404710599605;7099.893501597454;7674.963879876201;7937.642806073741;7958.6816527339415;7776.961115194445;7826.217378262185;7863.68545258189;7870.29167374939;7888.605569721548;37834.43248351277;38041;38148.80925595354;38485.80757096224;38588.614113858785;3436.7937923724658;3443.1908501502126;3483.8432270547887;3523.700485458739;3526.418141006731;32582.18544536382;32898.67101329004;33026.33947321064;33065.50401743952;33187.3362532748;5121.4110377306715;5151.896962060738;5152.871178220537;5165.560927321182;5203.765830537597;190075;192612.0369398158;193443;194378;194972.99999999857;40476.79761601173;41205.793971029954;48565.75717121392;48998.75500622545;49076;43492.78253608775;51176.74411627918;51840.48159518432;51846.48153518417;51868.22197667038;16155.192240387934;16352.591185220226;16356.345746170258;16366.590835229215;16432.917835410746;13199.40602672882;13269.867301326864;13301.135426197266;13360.198388096745;13432.731345373038;14108.435662573587;14157;14253.287335633177;14274.857251427353;14341.354639041268;5638.520725738358;5712.942870571241;5758.568107391962;5851.61964472308;5904.704764761745;983.9114479696862;1000.3697670467704;1009.6718566465928;1010.6058637131524;1018.6129270877168;22303.55392892133;22414.775852241593;22708.886455567947;22744.31767046991;23005.309840704806;1089.629525961177;1093.704699731068;1113.5768408004906;1114.1198453222025;1114.9275297105662;12039.337836418907;12131.636050918487;12243;12298.692532686755;12318.137730358969;228444;230225;231257;231899;232532;52802;52891;52907.735461322445;52991.47008529866;53362.466375336524;1374.2304309586664;1430.9713805723827;1434.3330351386655;1448.8188976377953;1458.8103546539094;21812.672809907548;22408;22462.32613021612;22640.320790376314;22702.886485567466;35487;35959.28081438304;35967.64032359748;35973.460398094576;36002.8199858999;182980;183858;184845;186231;186412;30423.391532169677;30911.690883091782;31047.205751482474;32278.83860580682;33902.32195356128;40960;43232;43388.56611433845;44011.33982990322;44133;31343;31918.36163276768;32021;32066;32257.67742322547;31090.37819243557;31639.367212655157;31662.525062124547;31777.523337150422;31906.042818715476;35492.112697182776;35705;35774.46338304875;35779;35833.10417239486;29709;31195.532067018557;31255;31276.530852037693;32102.678973209968;23674.28977130689;23687;23856.88071559631;23864.522709545363;24211 03:18:42 INFO - PID 3806 | |2;jquery.html;558.7764894042383;565.9963728401459;573.6300086444244;573.9770409183632;580.4195804195807;22095.005724742387;22478.325650230498;22755.54488910221;22760.43098922528;22845.771542284572;24006.159784407548;24527.14155004575;24823.255302340913;24839.254822355313;25435.74564254357;6351.2060992375955;6455.870882582346;6473.158489396372;6480.4491618212505;6493.1558897343275;6357.141785858919;6429.4534964528075;6442.355764423543;6458.644774537399;6469.967650161742;6224.533160012995;6477.157969463963;6480.675966201695;6490.610563366189;6506.577072490297;6455.4835613150835;6459.51553633477;6483.384078512534;6524.967375163117;6544.869102617921;5823.505002074829;5980.551458640576;6040.365761595013;6041.72812223451;6057.515398768132;28875;29251.561226581405;29284.267893302625;29473.557896631566;29584.408311833642;2033.085111699741;2063.556335387884;2066.08059413561;2068.2967790951147;2074.6887966804948;19087.713684294748;19558.217671293274;19820.008999550108;19846.900765496226;19902.701459478118;2395.7125144982538;2425.4178997040585;2478.4547399572066;2489.2948330393738;2497.7377375375504;118023.40988295003;124490.37754811168;138061;143762.99999999895;144031;20082.69875951862;21432.249871254742;21984.340469785933;22427.10291588318;22614.321570352917;43714;43863.78068109639;43994.560054399684;44049.55950440519;44136;11395.544178232944;11706.88293117075;11743.412829358498;11843;11965.940170299093;9641.37331073492;9669.951650241705;9810.558524866397;9837.80324393508;9927.255455840841;11022.448877556251;11088.501017454233;11107.166962477684;11456;11538.88461115395;3924.0778417071965;4157.812898419518;4241.893952651209;4276.636485898734;4440.844570440084;918.9172974432333;922.8062106957614;954.7231302922214;962.8122516109431;973.8539219117189;19316.903415482833;19773.406797796088;19970.723546633184;20057.699134512994;20140.597188056156;994.6319861651091;1019.3119644240109;1024.4263212600968;1025.36939782035;1035.9015893490107;10719.571217151382;10754;10835.83746243807;10860.728481788019;10941.28881622692;498432;511324;518949;525632;526293;110837.44581277203;110965.44517277362;112652.43673781742;113683.43158284322;115055;1024.3137098144261;1056.186736213127;1106.7067227184803;1146.4668928948079;1169.7894379011689;20867.582648347252;20918.372448826558;20962.895185523976;21008.26471073566;21028.894855525625;55644;56171.71914140403;56395;56578;57415.138772917606;67612;69129.65435172792;70836.29163708296;71427;72477;19795.30716424976;20250.291239806535;20314.79685203129;20446.488837779176;20485.38543843687;22512.212072578037;22917.197898073486;23056.07775689021;23232.3030309091;23244.88377558169;18147.364842230458;18271.177796999433;18310.26758929628;18346.908265458587;18400.355987540373;16225.18874056316;16338.836611633731;16819.654427646004;16889.66220675555;17062.31750729957;18548;19106;19152.712709309093;19172.90413547923;19322.227110915406;18672.626547468702;19712;19905.701414479085;19914;19997.60004799925;18261;18399.448016559523;18561.53596160107;18573.071346432353;18634.34779782701 03:18:42 INFO - PID 3806 | |3;mootools.html;1323.589687196969;1396.0925398490983;1401.2713389037708;1406.6975600245958;1423.4662001749343;14902.478413255525;15413.845861541382;15421;15440.84559154408;15502.069875807445;14959.626009349771;15046.774298385533;15108.54674359771;15175.772363414557;15238.619034524143;2697.231289082612;2717.2120085175325;2759.558470644697;2771.8336899786077;2772.4039331543713;2698.4603079384106;2718.7281271872853;2731.9043833465835;2739.0824073935255;2744.1218809980815;2548.7833534149618;2641.498115358085;2644.444666620012;2651.7745991590737;2758.0622588319966;2451.240115564181;2475.71529274134;2491.6885389326335;2516.3331717094984;2533.569293220157;2715.9049433269743;2724.6049322848276;2741.876615552305;2743.396452780405;2759.4756996170863;14684.192369419678;14949.70100597982;14997;15001.924990375088;15018.474353397687;7914.683412663487;8159.306458950996;8209.71266005693;8210.137935516805;8216.548089855056;5233.607479439057;5372.946270537283;5398.217258497535;5520.365158006841;5549.38956714761;1814;1836.1920754867815;1838.6782313095155;1866.1975350599237;1882.6234753049446;26319.34201644955;26436.735632643813;26534.203973880816;26647;26849.19452416411;6555;6842.55523390978;6900.654967251718;6958.860822783618;6991.405730512964;17277;17324.913375433294;17327.393541225996;17396.47810565685;17467.253274672432;8296.543690096982;8463.238308552136;8502.44734092282;8524.659013639384;8555.14448555152;7471.701131954661;7561.357284630868;7571.545707257582;7577.772666820004;7658.310752032344;5150.510701483353;5216.530512253916;5290.074237008585;5293.841184764463;5311.044012077786;1458.5624312706145;1525.4279645133074;1554.9222538873012;1560.84391560843;1573.7245981953113;745.2137994415835;863.6200071968314;864.0668078475231;880.7754022724164;887.9067697891628;7188.856222875618;7308.451866110063;7357.337839594462;7368.815779605553;7375.557466552024;926.1710768861831;942.3262367407336;952.5999080386255;954.8329042417688;957.411192116857;4710.128626204095;4717.292406139107;4727.196376615983;4746.620270378361;4806.591439727592;21343.46641333948;22100.447488812588;22212.666809997863;22425;22493.212737554433;21028.789712102684;21825.890870545547;21839.890800545894;21924.89037554802;21993.450163745714;11305.982461578333;11525.942370288094;11656.70858228534;11727.413629318671;11845.940770296093;7449.813754656177;7649.120351159465;7674.846503070019;7713.151553329241;7810;18388.356407525673;18639.813601863807;18760.906195468935;18874.7168792471;18941;18745.250189992246;19158.13788379554;19178;19634.018299085274;19853.00734963275;11179.55281788752;11189.664310070712;11266.436678166223;11283.266587671775;11299.887001129884;14006.159630422208;14027.649308767363;14043.058856423699;14223.786643200567;14274.928625356806;11766.764664706487;11773.646790596296;11800.05599552051;11814.232074915102;11943.820842687193;11258.155638327322;11408.657740267805;11459.19785614999;11481.598144064918;11627.825582616098;9941.25440591973;10033.799324013626;10098.8485172721;10167.440790756284;10179.847302290324;6389.393007664265;6432.6462044588;6570.474362050929;6625.668716564249;6673.399394054559;6545.14913061299;6753.831154221184;6765.661716914233;6813.7955861324235;6849.075374824335 03:18:42 INFO - PID 3806 | |4;prototype.html;498.1122340135245;506.9493897185883;519.1952473665818;519.7531172692974;526.3420724094882;6205.503559715224;6229;6266.373362663731;6284.057391391294;6287.59130656507;6007.909881351772;6020.428059334367;6070.817875463732;6082.756689732417;6166.969165154168;5113.565346945514;5122.388715955261;5156.716380599066;5159.845204643848;5161.793528258865;5041.747912604374;5098.388193416795;5131.794728210867;5149.845504634848;5151.510606492396;4733.5503127202865;4904.48502907195;4913.582345500637;4929.901401971958;4966.056449274644;4870.634702397334;4901.583365413926;4917.557419832232;4927.95072049282;4961.131801934665;4255.5744425557505;4406.184855801687;4416.734995900256;4418.977905110454;4429.557044295577;6100.786472473442;6133.080037994292;6133.29467111279;6151.384861513813;6170.907436388413;5594.384617692051;5626.634268772557;5647.830565083054;5655.886882262332;5703.971480142614;6526.086347911296;6577.671116444207;6600.636964966925;6611.801645950629;6693.966530167367;4832.395950506187;4862.124817532878;4870.220764677634;4871.975640121812;4873.439554451213;6914.688839002257;6926.826829329257;6934.341237582422;7005.789826305219;7048.118985126859;4941.715154059971;4950.826721064817;4966.15575352191;5011.248312753116;5013.949860501421;5022.573081288131;5043.848684539396;5079.968012794911;5097.260897169964;5103.800606857386;5651.124075768263;5653.293338332708;5654.349749778746;5659.377468478464;5670.291213598301;6632.767853125118;6633.7346506140175;6651.002349647495;6716.462682985447;6723.596584204865;1636.4190712297202;1682.7055265328518;1723.8965662060316;1739.199968014702;1745.4327343613377;1375.931203439824;1395.1768456610446;1398.2239856879326;1436.5115860607443;1447.9565613031625;5601.60788744784;5609.719514024364;5666.036773748473;5683.232763576947;5785.971070144707;6555.328391260198;6585.506087043491;6641.535092543476;6678.799636010927;6690.230623478362;5600.467955544217;5736.799212027559;5743.162188099852;5773.278340207474;5818.7672493100645;3522.154682876091;3531.170175008871;3561.5904171020147;3565.928681426357;3588.623194564533;6452.483801295885;6455.870882582416;6490.870182596416;6530.8040758777315;6559.147310849655;6898.931010689929;6927.965360173268;6931.722731090801;6952.965235173791;6958.269381714948;6487.513436492188;6535.281119076898;6540.631574739014;6543.280239173687;6544.149260596282;6660.333966603494;6725.679640524011;6745.797626071226;6838.350356716104;6839.829004274933;6615.699795133202;6711.530192886451;6716.798496045127;6753.223379311442;6814.897776533257;6366.586171898812;6399.520035997412;6463.903041454476;6476.481881449378;6557.278699343164;4907.9263811043575;4924.212126059813;4942.20924652054;4960.891621061107;4993.151164302005;6751.189857217166;6763.3236676333945;6826.556273842383;6840.657967101525;6844.486663500158;6381.309737342956;6475.672487140031;6484.935150648433;6500.399840064012;6509.777660058301;6430.003349480838;6455.644939528183;6551.967240163769;6574.3754343337305;6600.570962887397;6161.691915404302;6290.395949032967;6391.762882991102;6410.230772307353;6558.344165583403;6556.967215163893;6567.671616419065;6592.571482853599;6603.141591593062;6612.636305003271;6297.748090076346;6322.683865806784;6346.682665866781;6355.3326900674765;6390.9041364378645 03:18:42 INFO - PID 3806 | |5;yui.html;896.6547879066558;902.8420026495365;902.932280078994;919.1451949686789;932.841416959117;16344.673106537863;16967.406140785075;17046.318147274123;17161.65676686467;17175.828241717594;15862.524124276262;16205.91897040516;16259.18704064795;16391.09848958307;16735.49793506197;933.9953300233524;946.6733976778035;953.0279115302387;955.9904400956005;957.750984743968;964.9517524123801;973.897740737223;975.2441857560376;976.3214565876718;978.4765150644407;991.8760154980628;1010.8129995950736;1027.337367398028;1036.6527213383488;1041.8280983637728;1017.7964407118606;1025.436010194394;1025.8358662613946;1026.6612018034032;1027.0243268894542;719.4855678190119;741;743.9770315815754;746.4588173574115;747.2975403121048;17516.124193790387;17674.646507069916;17688.380906668204;17716.645667086585;17767.733483997752;20416.387508374773;20521.17915283387;21081.156753729832;21168.047437865334;21472.570548589098;16888.071156086535;16898.577535561588;17008.06455644939;17136.657266854716;17299.913500432544;3635.1093981974586;3655.4334078217917;3689.8524059037873;3697.1681371691425;3701.241245544657;133015;136230;136487;138175;138399;14757;15542.766858497132;15852.44516441919;16116.919415403083;16190.838091619169;30353.848230759148;31386.68613313883;31767.682323176934;32001.359972800412;32128.196795079843;8215.917840821634;8231.053428855757;8252.133525979805;8259.876101858477;8288.461250018729;3509.6139424663265;3623.0761155905075;3632.54593175853;3662.4689420033947;3697.352963231424;5377.838664840061;5435.646682965595;5439.836804895859;5459.863503412446;5537.003339398947;989.2284018465608;1010.6058637131524;1022.9232807539464;1028.3572767020612;1030.258214085872;666.1107421592131;681.9693113809891;686.4062585863193;692.9237783843773;704.3977399323607;10632.78734425307;10649.84025239622;10797.136229101652;10889.891101089046;11118.499667514981;671.5534169777142;672.085963090197;679.3240725478082;690.5097380859646;697.5570512724456;5016.372953380827;5086.694798312114;5167.896642067138;5174.97412512935;5226.425093239741;119384.40307798405;123137;124564;124660;130636;106018;108584;110196;113130;114020;93307.53346233361;95378;96675;100340.49829750805;100969.49515252377;9582.377145485521;9618.278629102986;9621.470819104736;9643.951780241054;9664.130228279488;44080.559194407644;45237;45354.54645453503;45850.08299833918;46869.765651171525;57899;58114;59699.1045134332;60145.69927150336;60411;15606.297716602554;15633.530994070195;15835;15849.128297943262;15969.44106956251;15806.841931580537;15863.206839658202;16332;16386.180690965164;16558.337666493688;14835.703285934005;14853.628659283604;14960;14969.326380312694;15070.945033847964;13393.19640821554;13570.253636049718;13581.86418135806;13680.315984200552;13786.241756703477;13468.663283417993;13543.187408755506;13753.862461375658;13836.446542138605;13925.442982280596;9561.235101191749;9613;9832.9016709832;9925.399928192513;9988.60045598168;10375.896241037493;10621.937806219315;10831.566737330419;10954.397508137128;11008.339499630049 03:18:42 INFO - PID 3806 | __end_tp_report 03:18:42 INFO - PID 3806 | __start_cc_report 03:18:42 INFO - PID 3806 | _x_x_mozilla_cycle_collect,346 03:18:42 INFO - PID 3806 | __end_cc_report 03:18:42 INFO - PID 3806 | __startTimestamp1505470722217__endTimestamp 03:18:42 INFO - PID 3806 | 03:18:42 INFO - PID 3806 | ------- Summary: start ------- 03:18:42 INFO - PID 3806 | Number of tests: 6 03:18:42 INFO - PID 3806 | 03:18:42 INFO - PID 3806 | [#0] dojo.html Cycles:165 Average:6248.18 Median:6505.22 stddev:749.42 (11.5%) stddev-sans-first:710.71 03:18:42 INFO - PID 3806 | Values: 3131.3 3136.8 3160.6 3201.3 3211.1 6188.4 6262.5 6307.1 6376.1 6382.6 6243.9 6261.7 6264.6 6282.9 6286.2 5712.2 5770.9 5775.5 5779.0 5844.0 5842.2 5912.6 5943.7 5955.3 5978.1 5870.5 5902.8 5958.4 5962.0 5980.0 5641.9 5727.9 5760.0 5885.7 5950.2 5816.2 5859.6 5892.9 5905.2 5911.4 6196.1 6279.1 6365.2 6419.6 6428.0 6665.1 6690.3 6694.2 6767.5 6844.2 6742.3 6842.0 6844.7 6855.5 6875.8 6292.4 6310.9 6318.0 6373.8 6378.6 6850.6 6874.5 6891.2 6926.3 7000.1 6498.3 6596.6 6605.5 6610.4 6776.3 6833.3 6873.5 6884.1 6886.3 6908.7 6664.7 6711.5 6773.1 6818.3 6843.8 6624.8 6703.9 6788.0 6837.1 6908.7 5343.9 5352.8 5371.7 5380.6 5410.1 4587.4 4657.8 4692.2 4716.5 4775.8 5779.7 5805.9 5895.9 5932.0 5953.3 6800.7 6806.0 6855.0 6860.3 6891.5 5686.7 5770.0 5785.7 5812.8 5838.0 5772.6 5813.4 5845.8 5900.0 5907.4 6605.5 6624.1 6625.1 6642.6 6649.2 6759.0 6850.2 6915.8 6930.1 6941.1 6518.6 6544.8 6577.5 6657.1 6667.5 6565.4 6700.9 6705.7 6767.9 6824.3 6828.9 6857.7 6901.5 6912.0 6930.8 6636.6 6689.5 6699.2 6745.5 6746.3 6610.6 6613.3 6616.1 6690.5 6701.7 6445.1 6456.4 6527.2 6569.8 6645.2 6505.2 6530.7 6588.0 6591.9 6611.2 6290.4 6343.7 6416.1 6505.1 6561.2 03:18:42 INFO - PID 3806 | 03:18:42 INFO - PID 3806 | [#1] ext.html Cycles:180 Average:36970.94 Median:3463.52 stddev:52837.72 (1525.6%) stddev-sans-first:52915.87 03:18:42 INFO - PID 3806 | Values: 730.2 743.5 746.2 750.2 753.7 39031.4 39428.4 42678.0 42680.4 42958.1 39129.4 39364.0 39452.2 39467.4 39685.8 7030.9 7095.0 7117.4 7127.5 7139.9 6998.5 7116.3 7133.3 7135.5 7171.9 7083.2 7105.6 7107.5 7110.8 7127.5 7003.4 7099.9 7675.0 7937.6 7958.7 7777.0 7826.2 7863.7 7870.3 7888.6 37834.4 38041.0 38148.8 38485.8 38588.6 3436.8 3443.2 3483.8 3523.7 3526.4 32582.2 32898.7 33026.3 33065.5 33187.3 5121.4 5151.9 5152.9 5165.6 5203.8 190075.0 192612.0 193443.0 194378.0 194973.0 40476.8 41205.8 48565.8 48998.8 49076.0 43492.8 51176.7 51840.5 51846.5 51868.2 16155.2 16352.6 16356.3 16366.6 16432.9 13199.4 13269.9 13301.1 13360.2 13432.7 14108.4 14157.0 14253.3 14274.9 14341.4 5638.5 5712.9 5758.6 5851.6 5904.7 983.9 1000.4 1009.7 1010.6 1018.6 22303.6 22414.8 22708.9 22744.3 23005.3 1089.6 1093.7 1113.6 1114.1 1114.9 12039.3 12131.6 12243.0 12298.7 12318.1 228444.0 230225.0 231257.0 231899.0 232532.0 52802.0 52891.0 52907.7 52991.5 53362.5 1374.2 1431.0 1434.3 1448.8 1458.8 21812.7 22408.0 22462.3 22640.3 22702.9 35487.0 35959.3 35967.6 35973.5 36002.8 182980.0 183858.0 184845.0 186231.0 186412.0 30423.4 30911.7 31047.2 32278.8 33902.3 40960.0 43232.0 43388.6 44011.3 44133.0 31343.0 31918.4 32021.0 32066.0 32257.7 31090.4 31639.4 31662.5 31777.5 31906.0 35492.1 35705.0 35774.5 35779.0 35833.1 29709.0 31195.5 31255.0 31276.5 32102.7 23674.3 23687.0 23856.9 23864.5 24211.0 03:18:42 INFO - PID 3806 | 03:18:42 INFO - PID 3806 | [#2] jquery.html Cycles:180 Average:36528.69 Median:22563.27 stddev:86233.22 (382.2%) stddev-sans-first:86432.84 03:18:42 INFO - PID 3806 | Values: 558.8 566.0 573.6 574.0 580.4 22095.0 22478.3 22755.5 22760.4 22845.8 24006.2 24527.1 24823.3 24839.3 25435.7 6351.2 6455.9 6473.2 6480.4 6493.2 6357.1 6429.5 6442.4 6458.6 6470.0 6224.5 6477.2 6480.7 6490.6 6506.6 6455.5 6459.5 6483.4 6525.0 6544.9 5823.5 5980.6 6040.4 6041.7 6057.5 28875.0 29251.6 29284.3 29473.6 29584.4 2033.1 2063.6 2066.1 2068.3 2074.7 19087.7 19558.2 19820.0 19846.9 19902.7 2395.7 2425.4 2478.5 2489.3 2497.7 118023.4 124490.4 138061.0 143763.0 144031.0 20082.7 21432.2 21984.3 22427.1 22614.3 43714.0 43863.8 43994.6 44049.6 44136.0 11395.5 11706.9 11743.4 11843.0 11965.9 9641.4 9670.0 9810.6 9837.8 9927.3 11022.4 11088.5 11107.2 11456.0 11538.9 3924.1 4157.8 4241.9 4276.6 4440.8 918.9 922.8 954.7 962.8 973.9 19316.9 19773.4 19970.7 20057.7 20140.6 994.6 1019.3 1024.4 1025.4 1035.9 10719.6 10754.0 10835.8 10860.7 10941.3 498432.0 511324.0 518949.0 525632.0 526293.0 110837.4 110965.4 112652.4 113683.4 115055.0 1024.3 1056.2 1106.7 1146.5 1169.8 20867.6 20918.4 20962.9 21008.3 21028.9 55644.0 56171.7 56395.0 56578.0 57415.1 67612.0 69129.7 70836.3 71427.0 72477.0 19795.3 20250.3 20314.8 20446.5 20485.4 22512.2 22917.2 23056.1 23232.3 23244.9 18147.4 18271.2 18310.3 18346.9 18400.4 16225.2 16338.8 16819.7 16889.7 17062.3 18548.0 19106.0 19152.7 19172.9 19322.2 18672.6 19712.0 19905.7 19914.0 19997.6 18261.0 18399.4 18561.5 18573.1 18634.3 03:18:42 INFO - PID 3806 | 03:18:42 INFO - PID 3806 | [#3] mootools.html Cycles:180 Average:9336.11 Median:14480.42 stddev:6768.93 (46.7%) stddev-sans-first:6761.15 03:18:42 INFO - PID 3806 | Values: 1323.6 1396.1 1401.3 1406.7 1423.5 14902.5 15413.8 15421.0 15440.8 15502.1 14959.6 15046.8 15108.5 15175.8 15238.6 2697.2 2717.2 2759.6 2771.8 2772.4 2698.5 2718.7 2731.9 2739.1 2744.1 2548.8 2641.5 2644.4 2651.8 2758.1 2451.2 2475.7 2491.7 2516.3 2533.6 2715.9 2724.6 2741.9 2743.4 2759.5 14684.2 14949.7 14997.0 15001.9 15018.5 7914.7 8159.3 8209.7 8210.1 8216.5 5233.6 5372.9 5398.2 5520.4 5549.4 1814.0 1836.2 1838.7 1866.2 1882.6 26319.3 26436.7 26534.2 26647.0 26849.2 6555.0 6842.6 6900.7 6958.9 6991.4 17277.0 17324.9 17327.4 17396.5 17467.3 8296.5 8463.2 8502.4 8524.7 8555.1 7471.7 7561.4 7571.5 7577.8 7658.3 5150.5 5216.5 5290.1 5293.8 5311.0 1458.6 1525.4 1554.9 1560.8 1573.7 745.2 863.6 864.1 880.8 887.9 7188.9 7308.5 7357.3 7368.8 7375.6 926.2 942.3 952.6 954.8 957.4 4710.1 4717.3 4727.2 4746.6 4806.6 21343.5 22100.4 22212.7 22425.0 22493.2 21028.8 21825.9 21839.9 21924.9 21993.5 11306.0 11525.9 11656.7 11727.4 11845.9 7449.8 7649.1 7674.8 7713.2 7810.0 18388.4 18639.8 18760.9 18874.7 18941.0 18745.3 19158.1 19178.0 19634.0 19853.0 11179.6 11189.7 11266.4 11283.3 11299.9 14006.2 14027.6 14043.1 14223.8 14274.9 11766.8 11773.6 11800.1 11814.2 11943.8 11258.2 11408.7 11459.2 11481.6 11627.8 9941.3 10033.8 10098.8 10167.4 10179.8 6389.4 6432.6 6570.5 6625.7 6673.4 6545.1 6753.8 6765.7 6813.8 6849.1 03:18:42 INFO - PID 3806 | 03:18:42 INFO - PID 3806 | [#4] prototype.html Cycles:180 Average:5514.30 Median:6133.19 stddev:1542.07 (25.1%) stddev-sans-first:1499.73 03:18:42 INFO - PID 3806 | Values: 498.1 506.9 519.2 519.8 526.3 6205.5 6229.0 6266.4 6284.1 6287.6 6007.9 6020.4 6070.8 6082.8 6167.0 5113.6 5122.4 5156.7 5159.8 5161.8 5041.7 5098.4 5131.8 5149.8 5151.5 4733.6 4904.5 4913.6 4929.9 4966.1 4870.6 4901.6 4917.6 4928.0 4961.1 4255.6 4406.2 4416.7 4419.0 4429.6 6100.8 6133.1 6133.3 6151.4 6170.9 5594.4 5626.6 5647.8 5655.9 5704.0 6526.1 6577.7 6600.6 6611.8 6694.0 4832.4 4862.1 4870.2 4872.0 4873.4 6914.7 6926.8 6934.3 7005.8 7048.1 4941.7 4950.8 4966.2 5011.2 5013.9 5022.6 5043.8 5080.0 5097.3 5103.8 5651.1 5653.3 5654.3 5659.4 5670.3 6632.8 6633.7 6651.0 6716.5 6723.6 1636.4 1682.7 1723.9 1739.2 1745.4 1375.9 1395.2 1398.2 1436.5 1448.0 5601.6 5609.7 5666.0 5683.2 5786.0 6555.3 6585.5 6641.5 6678.8 6690.2 5600.5 5736.8 5743.2 5773.3 5818.8 3522.2 3531.2 3561.6 3565.9 3588.6 6452.5 6455.9 6490.9 6530.8 6559.1 6898.9 6928.0 6931.7 6953.0 6958.3 6487.5 6535.3 6540.6 6543.3 6544.1 6660.3 6725.7 6745.8 6838.4 6839.8 6615.7 6711.5 6716.8 6753.2 6814.9 6366.6 6399.5 6463.9 6476.5 6557.3 4907.9 4924.2 4942.2 4960.9 4993.2 6751.2 6763.3 6826.6 6840.7 6844.5 6381.3 6475.7 6484.9 6500.4 6509.8 6430.0 6455.6 6552.0 6574.4 6600.6 6161.7 6290.4 6391.8 6410.2 6558.3 6557.0 6567.7 6592.6 6603.1 6612.6 6297.7 6322.7 6346.7 6355.3 6390.9 03:18:42 INFO - PID 3806 | 03:18:42 INFO - PID 3806 | [#5] yui.html Cycles:180 Average:23856.49 Median:20468.78 stddev:35620.40 (174.0%) stddev-sans-first:35678.61 03:18:42 INFO - PID 3806 | Values: 896.7 902.8 902.9 919.1 932.8 16344.7 16967.4 17046.3 17161.7 17175.8 15862.5 16205.9 16259.2 16391.1 16735.5 934.0 946.7 953.0 956.0 957.8 965.0 973.9 975.2 976.3 978.5 991.9 1010.8 1027.3 1036.7 1041.8 1017.8 1025.4 1025.8 1026.7 1027.0 719.5 741.0 744.0 746.5 747.3 17516.1 17674.6 17688.4 17716.6 17767.7 20416.4 20521.2 21081.2 21168.0 21472.6 16888.1 16898.6 17008.1 17136.7 17299.9 3635.1 3655.4 3689.9 3697.2 3701.2 133015.0 136230.0 136487.0 138175.0 138399.0 14757.0 15542.8 15852.4 16116.9 16190.8 30353.8 31386.7 31767.7 32001.4 32128.2 8215.9 8231.1 8252.1 8259.9 8288.5 3509.6 3623.1 3632.5 3662.5 3697.4 5377.8 5435.6 5439.8 5459.9 5537.0 989.2 1010.6 1022.9 1028.4 1030.3 666.1 682.0 686.4 692.9 704.4 10632.8 10649.8 10797.1 10889.9 11118.5 671.6 672.1 679.3 690.5 697.6 5016.4 5086.7 5167.9 5175.0 5226.4 119384.4 123137.0 124564.0 124660.0 130636.0 106018.0 108584.0 110196.0 113130.0 114020.0 93307.5 95378.0 96675.0 100340.5 100969.5 9582.4 9618.3 9621.5 9644.0 9664.1 44080.6 45237.0 45354.5 45850.1 46869.8 57899.0 58114.0 59699.1 60145.7 60411.0 15606.3 15633.5 15835.0 15849.1 15969.4 15806.8 15863.2 16332.0 16386.2 16558.3 14835.7 14853.6 14960.0 14969.3 15070.9 13393.2 13570.3 13581.9 13680.3 13786.2 13468.7 13543.2 13753.9 13836.4 13925.4 9561.2 9613.0 9832.9 9925.4 9988.6 10375.9 10621.9 10831.6 10954.4 11008.3 03:18:42 INFO - PID 3806 | -------- Summary: end -------- 03:18:42 INFO - PID 3806 | 03:18:43 INFO - TEST-INFO | 3806: exit 0 03:18:43 INFO - TEST-OK | dromaeo_css | took 1115757ms 03:18:43 INFO - TEST-START | kraken 03:18:43 INFO - Initialising browser for kraken test... 03:18:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42366/getInfo.html -profile /tmp/tmpF17pY4/profile 03:18:43 INFO - TEST-INFO | started process 4186 (/builds/slave/test/build/application/firefox/firefox http://localhost:42366/getInfo.html) 03:18:49 INFO - TEST-INFO | 4186: exit 0 03:18:49 INFO - Browser initialized. 03:18:49 INFO - Running cycle 1/1 for kraken test... 03:18:49 INFO - Using env: {'DISPLAY': ':0', 03:18:49 INFO - 'HOME': '/home/cltbld', 03:18:49 INFO - 'JSGC_DISABLE_POISONING': '1', 03:18:49 INFO - 'LANG': 'en_US.UTF-8', 03:18:49 INFO - 'LANGUAGE': 'en_US:en', 03:18:49 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:18:49 INFO - 'LOGNAME': 'cltbld', 03:18:49 INFO - 'MAIL': '/var/mail/cltbld', 03:18:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:18:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:18:49 INFO - 'MOZ_ACCELERATED': '1', 03:18:49 INFO - 'MOZ_CRASHREPORTER': '1', 03:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:49 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:18:49 INFO - 'MOZ_NO_REMOTE': '1', 03:18:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:18:49 INFO - 'MOZ_WEBRENDER': '1', 03:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:49 INFO - 'NO_EM_RESTART': '1', 03:18:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:49 INFO - 'PWD': '/builds/slave/test', 03:18:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:18:49 INFO - 'RUST_BACKTRACE': 'full', 03:18:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:18:49 INFO - 'SHELL': '/bin/bash', 03:18:49 INFO - 'SHLVL': '1', 03:18:49 INFO - 'TERM': 'linux', 03:18:49 INFO - 'TMOUT': '86400', 03:18:49 INFO - 'USER': 'cltbld', 03:18:49 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847', 03:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:49 INFO - '_': '/tools/buildbot/bin/python'} 03:18:49 INFO - TEST-INFO | started process 4433 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF17pY4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:18:50 INFO - PID 4433 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:18:50 INFO - PID 4433 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:18:50 INFO - PID 4433 | 03:18:50 INFO - PID 4433 | (/builds/slave/test/build/application/firefox/firefox:4493): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:18:50 INFO - PID 4433 | 03:19:00 INFO - PID 4433 | RSS: Main: 194072576 03:19:00 INFO - PID 4433 | 03:19:03 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42366/tests/kraken/driver.html?testName=audio-beat-detection) 03:19:04 INFO - PID 4433 | RSS: Main: 200200192 03:19:04 INFO - PID 4433 | 03:19:07 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42366/tests/kraken/driver.html?testName=audio-dft) 03:19:07 INFO - PID 4433 | RSS: Main: 199933952 03:19:07 INFO - PID 4433 | 03:19:10 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42366/tests/kraken/driver.html?testName=audio-fft) 03:19:11 INFO - PID 4433 | RSS: Main: 196177920 03:19:11 INFO - PID 4433 | 03:19:13 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42366/tests/kraken/driver.html?testName=audio-oscillator) 03:19:14 INFO - PID 4433 | RSS: Main: 197402624 03:19:14 INFO - PID 4433 | 03:19:16 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42366/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:19:17 INFO - PID 4433 | RSS: Main: 196440064 03:19:17 INFO - PID 4433 | 03:19:20 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42366/tests/kraken/driver.html?testName=imaging-darkroom) 03:19:20 INFO - PID 4433 | RSS: Main: 200306688 03:19:20 INFO - PID 4433 | 03:19:24 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42366/tests/kraken/driver.html?testName=imaging-desaturate) 03:19:25 INFO - PID 4433 | RSS: Main: 199659520 03:19:25 INFO - PID 4433 | 03:19:28 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42366/tests/kraken/driver.html?testName=json-parse-financial) 03:19:28 INFO - PID 4433 | RSS: Main: 200556544 03:19:28 INFO - PID 4433 | 03:19:30 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42366/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:19:31 INFO - PID 4433 | RSS: Main: 200888320 03:19:31 INFO - PID 4433 | 03:19:32 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42366/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:19:33 INFO - PID 4433 | RSS: Main: 200495104 03:19:33 INFO - PID 4433 | 03:19:35 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42366/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:19:36 INFO - PID 4433 | RSS: Main: 200949760 03:19:36 INFO - PID 4433 | 03:19:39 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42366/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:19:39 INFO - PID 4433 | RSS: Main: 200183808 03:19:39 INFO - PID 4433 | 03:19:42 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42366/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:19:43 INFO - PID 4433 | RSS: Main: 201465856 03:19:43 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | Cycle 1(1): loaded http://localhost:42366/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42366/tests/kraken/driver.html?testName=ai-astar) 03:19:45 INFO - PID 4433 | RSS: Main: 203595776 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | __start_tp_report 03:19:45 INFO - PID 4433 | _x_x_mozilla_page_load 03:19:45 INFO - PID 4433 | _x_x_mozilla_page_load_details 03:19:45 INFO - PID 4433 | |i|pagename|runs| 03:19:45 INFO - PID 4433 | |0;ai-astar;116;116;109;118;96;113;117;114;108;109 03:19:45 INFO - PID 4433 | |1;audio-beat-detection;121;126;118;126;143;119;121;147;117;121 03:19:45 INFO - PID 4433 | |2;audio-dft;162;172;166;167;162;166;169;166;168;164 03:19:45 INFO - PID 4433 | |3;audio-fft;75;76;77;75;73;76;74;80;74;74 03:19:45 INFO - PID 4433 | |4;audio-oscillator;79;80;88;84;80;87;89;79;81;86 03:19:45 INFO - PID 4433 | |5;imaging-gaussian-blur;117;121;114;110;124;121;114;119;116;116 03:19:45 INFO - PID 4433 | |6;imaging-darkroom;150;155;157;147;149;150;148;148;150;148 03:19:45 INFO - PID 4433 | |7;imaging-desaturate;94;103;94;94;94;94;94;95;97;94 03:19:45 INFO - PID 4433 | |8;json-parse-financial;56;58;52;55;52;51;51;59;52;52 03:19:45 INFO - PID 4433 | |9;json-stringify-tinderbox;38;45;45;40;39;40;43;38;38;42 03:19:45 INFO - PID 4433 | |10;stanford-crypto-aes;90;82;78;77;88;83;79;81;77;79 03:19:45 INFO - PID 4433 | |11;stanford-crypto-ccm;124;121;129;114;129;121;118;122;119;121 03:19:45 INFO - PID 4433 | |12;stanford-crypto-pbkdf2;171;176;171;167;167;169;186;177;172;175 03:19:45 INFO - PID 4433 | |13;stanford-crypto-sha256-iterative;85;64;67;71;68;64;68;65;65;88 03:19:45 INFO - PID 4433 | __end_tp_report 03:19:45 INFO - PID 4433 | __start_cc_report 03:19:45 INFO - PID 4433 | _x_x_mozilla_cycle_collect,933 03:19:45 INFO - PID 4433 | __end_cc_report 03:19:45 INFO - PID 4433 | __startTimestamp1505470785508__endTimestamp 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | ------- Summary: start ------- 03:19:45 INFO - PID 4433 | Number of tests: 14 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#0] ai-astar Cycles:10 Average:111.60 Median:116.00 stddev:6.55 (5.6%) stddev-sans-first:6.75 03:19:45 INFO - PID 4433 | Values: 116.0 116.0 109.0 118.0 96.0 113.0 117.0 114.0 108.0 109.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#1] audio-beat-detection Cycles:10 Average:125.90 Median:123.50 stddev:10.54 (8.5%) stddev-sans-first:11.02 03:19:45 INFO - PID 4433 | Values: 121.0 126.0 118.0 126.0 143.0 119.0 121.0 147.0 117.0 121.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#2] audio-dft Cycles:10 Average:166.20 Median:166.50 stddev:3.08 (1.9%) stddev-sans-first:2.87 03:19:45 INFO - PID 4433 | Values: 162.0 172.0 166.0 167.0 162.0 166.0 169.0 166.0 168.0 164.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#3] audio-fft Cycles:10 Average:75.40 Median:75.50 stddev:2.01 (2.7%) stddev-sans-first:2.13 03:19:45 INFO - PID 4433 | Values: 75.0 76.0 77.0 75.0 73.0 76.0 74.0 80.0 74.0 74.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:85.00 stddev:3.95 (4.6%) stddev-sans-first:3.87 03:19:45 INFO - PID 4433 | Values: 79.0 80.0 88.0 84.0 80.0 87.0 89.0 79.0 81.0 86.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:118.00 stddev:4.13 (3.5%) stddev-sans-first:4.38 03:19:45 INFO - PID 4433 | Values: 117.0 121.0 114.0 110.0 124.0 121.0 114.0 119.0 116.0 116.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:3.26 (2.2%) stddev-sans-first:3.46 03:19:45 INFO - PID 4433 | Values: 150.0 155.0 157.0 147.0 149.0 150.0 148.0 148.0 150.0 148.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:94.00 stddev:2.87 (3.1%) stddev-sans-first:3.00 03:19:45 INFO - PID 4433 | Values: 94.0 103.0 94.0 94.0 94.0 94.0 94.0 95.0 97.0 94.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.50 stddev:2.97 (5.6%) stddev-sans-first:3.05 03:19:45 INFO - PID 4433 | Values: 56.0 58.0 52.0 55.0 52.0 51.0 51.0 59.0 52.0 52.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#9] json-stringify-tinderbox Cycles:10 Average:40.80 Median:41.00 stddev:2.78 (6.8%) stddev-sans-first:2.76 03:19:45 INFO - PID 4433 | Values: 38.0 45.0 45.0 40.0 39.0 40.0 43.0 38.0 38.0 42.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.50 stddev:4.50 (5.5%) stddev-sans-first:3.54 03:19:45 INFO - PID 4433 | Values: 90.0 82.0 78.0 77.0 88.0 83.0 79.0 81.0 77.0 79.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:121.50 stddev:4.64 (3.8%) stddev-sans-first:4.85 03:19:45 INFO - PID 4433 | Values: 124.0 121.0 129.0 114.0 129.0 121.0 118.0 122.0 119.0 121.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:173.50 stddev:5.72 (3.3%) stddev-sans-first:6.02 03:19:45 INFO - PID 4433 | Values: 171.0 176.0 171.0 167.0 167.0 169.0 186.0 177.0 172.0 175.0 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:68.00 stddev:8.73 (12.8%) stddev-sans-first:7.52 03:19:45 INFO - PID 4433 | Values: 85.0 64.0 67.0 71.0 68.0 64.0 68.0 65.0 65.0 88.0 03:19:45 INFO - PID 4433 | -------- Summary: end -------- 03:19:45 INFO - PID 4433 | 03:19:45 INFO - PID 4433 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:19:46 INFO - TEST-INFO | 4433: exit 0 03:19:46 INFO - TEST-OK | kraken | took 63298ms 03:19:46 INFO - SUITE-END | took 1179s 03:19:46 INFO - Completed test suite (00:19:39) 03:19:46 INFO - javascript benchmark 03:19:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8515.88845263856, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3131.264152924063, 3136.8431578421073, 3160.6365267994174, 3201.3277211785526, 3211.0527394418627, 6188.443040126388, 6262.530310226734, 6307.148534947781, 6376.075469056984, 6382.648954307517, 6243.875122497548, 6261.718222679979, 6264.62412255265, 6282.874342513147, 6286.182796236483, 5712.200291959129, 5770.942290577103, 5775.537956963432, 5779.0, 5844.0, 5842.24050873388, 5912.556558258127, 5943.673097979609, 5955.34491205967, 5978.133170690247, 5870.530357571384, 5902.793402230923, 5958.37437069108, 5962.0162673158875, 5980.0, 5641.943580564182, 5727.914081288784, 5760.049591817345, 5885.735141918624, 5950.196723442323, 5816.243888294494, 5859.6191247569195, 5892.911606325909, 5905.202797622309, 5911.379305172938, 6196.101565273055, 6279.058141278799, 6365.2361716594305, 6419.5506314558, 6427.967860160669, 6665.066890635315, 6690.297518760557, 6694.196696396415, 6767.4924380671155, 6844.247132815386, 6742.258351581322, 6842.007908853188, 6844.726210951556, 6855.485838562078, 6875.759348422781, 6292.370762923717, 6310.87378252437, 6317.968410157966, 6373.808785736435, 6378.553501254868, 6850.588964662136, 6874.484413668994, 6891.173059232824, 6926.307369263134, 7000.08998830159, 6498.317676643979, 6596.637184954873, 6605.5376123670885, 6610.438112760373, 6776.322367763184, 6833.3166683331265, 6873.48448866337, 6884.105066341343, 6886.311368863174, 6908.6545672716165, 6664.733410663616, 6711.496637752188, 6773.051772751819, 6818.28408017151, 6843.828904277333, 6624.8343791405605, 6703.865922681616, 6788.049673045777, 6837.111175547247, 6908.6545672716165, 5343.919841202385, 5352.839414817562, 5371.704556249444, 5380.623356365017, 5410.1343784995, 4587.42657167854, 4657.767111644404, 4692.249240121564, 4716.504766999416, 4775.832845850378, 5779.653220806765, 5805.94194058054, 5895.941040589625, 5932.021216499315, 5953.3451320354725, 6800.6939687714175, 6805.965970170217, 6855.0, 6860.279670634612, 6891.517593768388, 5686.687232202185, 5769.9711501442225, 5785.71071446426, 5812.82561523155, 5838.036723940502, 5772.624779389326, 5813.360530341659, 5845.766169353178, 5900.0264956281735, 5907.409259074058, 6605.504587155982, 6624.105745724266, 6625.138731964814, 6642.634655094016, 6649.202095748445, 6758.966205169139, 6850.21222559412, 6915.792526224222, 6930.064441300561, 6941.132358455193, 6518.641474718936, 6544.8363790905605, 6577.539572229898, 6657.1345725055435, 6667.49993750461, 6565.376289252514, 6700.899486507608, 6705.664716764045, 6767.864642707217, 6824.283450237852, 6828.897566536407, 6857.7256909723055, 6901.482388820758, 6911.997760324624, 6930.757423490355, 6636.568623039487, 6689.46484281266, 6699.229588597374, 6745.52781305304, 6746.325367463214, 6610.603363798187, 6613.305602911625, 6616.106825578678, 6690.498212633975, 6701.731930722912, 6445.097686324012, 6456.386643268882, 6527.15147030883, 6569.80290591283, 6645.202575690948, 6505.21937367519, 6530.7387704493185, 6588.0, 6591.901121483277, 6611.173603299588, 6290.433860952537, 6343.746250150127, 6416.069669897932, 6505.121808555785, 6561.212654481493], "value": 6190.835915906013, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.1749023603328, 743.4684200796436, 746.2015643261713, 750.1635676221014, 753.6796861333935, 39031.414528782094, 39428.40857387138, 42678.0, 42680.359794603064, 42958.14083718332, 39129.41305880414, 39364.0, 39452.210955780865, 39467.407988880186, 39685.801570992175, 7030.929690703078, 7095.042169307155, 7117.395021423185, 7127.501074924758, 7139.85720285594, 6998.475114366417, 7116.252793456691, 7133.286671332851, 7135.5361901476235, 7171.9282807171785, 7083.185433675116, 7105.609191494465, 7107.466939979497, 7110.751123710671, 7127.536710113826, 7003.404710599605, 7099.893501597454, 7674.963879876201, 7937.642806073741, 7958.6816527339415, 7776.961115194445, 7826.217378262185, 7863.68545258189, 7870.29167374939, 7888.605569721548, 37834.43248351277, 38041.0, 38148.80925595354, 38485.80757096224, 38588.614113858785, 3436.7937923724658, 3443.1908501502126, 3483.8432270547887, 3523.700485458739, 3526.418141006731, 32582.18544536382, 32898.67101329004, 33026.33947321064, 33065.50401743952, 33187.3362532748, 5121.4110377306715, 5151.896962060738, 5152.871178220537, 5165.560927321182, 5203.765830537597, 190075.0, 192612.0369398158, 193443.0, 194378.0, 194972.99999999857, 40476.79761601173, 41205.793971029954, 48565.75717121392, 48998.75500622545, 49076.0, 43492.78253608775, 51176.74411627918, 51840.48159518432, 51846.48153518417, 51868.22197667038, 16155.192240387934, 16352.591185220226, 16356.345746170258, 16366.590835229215, 16432.917835410746, 13199.40602672882, 13269.867301326864, 13301.135426197266, 13360.198388096745, 13432.731345373038, 14108.435662573587, 14157.0, 14253.287335633177, 14274.857251427353, 14341.354639041268, 5638.520725738358, 5712.942870571241, 5758.568107391962, 5851.61964472308, 5904.704764761745, 983.9114479696862, 1000.3697670467704, 1009.6718566465928, 1010.6058637131524, 1018.6129270877168, 22303.55392892133, 22414.775852241593, 22708.886455567947, 22744.31767046991, 23005.309840704806, 1089.629525961177, 1093.704699731068, 1113.5768408004906, 1114.1198453222025, 1114.9275297105662, 12039.337836418907, 12131.636050918487, 12243.0, 12298.692532686755, 12318.137730358969, 228444.0, 230225.0, 231257.0, 231899.0, 232532.0, 52802.0, 52891.0, 52907.735461322445, 52991.47008529866, 53362.466375336524, 1374.2304309586664, 1430.9713805723827, 1434.3330351386655, 1448.8188976377953, 1458.8103546539094, 21812.672809907548, 22408.0, 22462.32613021612, 22640.320790376314, 22702.886485567466, 35487.0, 35959.28081438304, 35967.64032359748, 35973.460398094576, 36002.8199858999, 182980.0, 183858.0, 184845.0, 186231.0, 186412.0, 30423.391532169677, 30911.690883091782, 31047.205751482474, 32278.83860580682, 33902.32195356128, 40960.0, 43232.0, 43388.56611433845, 44011.33982990322, 44133.0, 31343.0, 31918.36163276768, 32021.0, 32066.0, 32257.67742322547, 31090.37819243557, 31639.367212655157, 31662.525062124547, 31777.523337150422, 31906.042818715476, 35492.112697182776, 35705.0, 35774.46338304875, 35779.0, 35833.10417239486, 29709.0, 31195.532067018557, 31255.0, 31276.530852037693, 32102.678973209968, 23674.28977130689, 23687.0, 23856.88071559631, 23864.522709545363, 24211.0], "value": 16819.20630859473, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.7764894042383, 565.9963728401459, 573.6300086444244, 573.9770409183632, 580.4195804195807, 22095.005724742387, 22478.325650230498, 22755.54488910221, 22760.43098922528, 22845.771542284572, 24006.159784407548, 24527.14155004575, 24823.255302340913, 24839.254822355313, 25435.74564254357, 6351.2060992375955, 6455.870882582346, 6473.158489396372, 6480.4491618212505, 6493.1558897343275, 6357.141785858919, 6429.4534964528075, 6442.355764423543, 6458.644774537399, 6469.967650161742, 6224.533160012995, 6477.157969463963, 6480.675966201695, 6490.610563366189, 6506.577072490297, 6455.4835613150835, 6459.51553633477, 6483.384078512534, 6524.967375163117, 6544.869102617921, 5823.505002074829, 5980.551458640576, 6040.365761595013, 6041.72812223451, 6057.515398768132, 28875.0, 29251.561226581405, 29284.267893302625, 29473.557896631566, 29584.408311833642, 2033.085111699741, 2063.556335387884, 2066.08059413561, 2068.2967790951147, 2074.6887966804948, 19087.713684294748, 19558.217671293274, 19820.008999550108, 19846.900765496226, 19902.701459478118, 2395.7125144982538, 2425.4178997040585, 2478.4547399572066, 2489.2948330393738, 2497.7377375375504, 118023.40988295003, 124490.37754811168, 138061.0, 143762.99999999895, 144031.0, 20082.69875951862, 21432.249871254742, 21984.340469785933, 22427.10291588318, 22614.321570352917, 43714.0, 43863.78068109639, 43994.560054399684, 44049.55950440519, 44136.0, 11395.544178232944, 11706.88293117075, 11743.412829358498, 11843.0, 11965.940170299093, 9641.37331073492, 9669.951650241705, 9810.558524866397, 9837.80324393508, 9927.255455840841, 11022.448877556251, 11088.501017454233, 11107.166962477684, 11456.0, 11538.88461115395, 3924.0778417071965, 4157.812898419518, 4241.893952651209, 4276.636485898734, 4440.844570440084, 918.9172974432333, 922.8062106957614, 954.7231302922214, 962.8122516109431, 973.8539219117189, 19316.903415482833, 19773.406797796088, 19970.723546633184, 20057.699134512994, 20140.597188056156, 994.6319861651091, 1019.3119644240109, 1024.4263212600968, 1025.36939782035, 1035.9015893490107, 10719.571217151382, 10754.0, 10835.83746243807, 10860.728481788019, 10941.28881622692, 498432.0, 511324.0, 518949.0, 525632.0, 526293.0, 110837.44581277203, 110965.44517277362, 112652.43673781742, 113683.43158284322, 115055.0, 1024.3137098144261, 1056.186736213127, 1106.7067227184803, 1146.4668928948079, 1169.7894379011689, 20867.582648347252, 20918.372448826558, 20962.895185523976, 21008.26471073566, 21028.894855525625, 55644.0, 56171.71914140403, 56395.0, 56578.0, 57415.138772917606, 67612.0, 69129.65435172792, 70836.29163708296, 71427.0, 72477.0, 19795.30716424976, 20250.291239806535, 20314.79685203129, 20446.488837779176, 20485.38543843687, 22512.212072578037, 22917.197898073486, 23056.07775689021, 23232.3030309091, 23244.88377558169, 18147.364842230458, 18271.177796999433, 18310.26758929628, 18346.908265458587, 18400.355987540373, 16225.18874056316, 16338.836611633731, 16819.654427646004, 16889.66220675555, 17062.31750729957, 18548.0, 19106.0, 19152.712709309093, 19172.90413547923, 19322.227110915406, 18672.626547468702, 19712.0, 19905.701414479085, 19914.0, 19997.60004799925, 18261.0, 18399.448016559523, 18561.53596160107, 18573.071346432353, 18634.34779782701], "value": 12801.905832222361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.589687196969, 1396.0925398490983, 1401.2713389037708, 1406.6975600245958, 1423.4662001749343, 14902.478413255525, 15413.845861541382, 15421.0, 15440.84559154408, 15502.069875807445, 14959.626009349771, 15046.774298385533, 15108.54674359771, 15175.772363414557, 15238.619034524143, 2697.231289082612, 2717.2120085175325, 2759.558470644697, 2771.8336899786077, 2772.4039331543713, 2698.4603079384106, 2718.7281271872853, 2731.9043833465835, 2739.0824073935255, 2744.1218809980815, 2548.7833534149618, 2641.498115358085, 2644.444666620012, 2651.7745991590737, 2758.0622588319966, 2451.240115564181, 2475.71529274134, 2491.6885389326335, 2516.3331717094984, 2533.569293220157, 2715.9049433269743, 2724.6049322848276, 2741.876615552305, 2743.396452780405, 2759.4756996170863, 14684.192369419678, 14949.70100597982, 14997.0, 15001.924990375088, 15018.474353397687, 7914.683412663487, 8159.306458950996, 8209.71266005693, 8210.137935516805, 8216.548089855056, 5233.607479439057, 5372.946270537283, 5398.217258497535, 5520.365158006841, 5549.38956714761, 1814.0, 1836.1920754867815, 1838.6782313095155, 1866.1975350599237, 1882.6234753049446, 26319.34201644955, 26436.735632643813, 26534.203973880816, 26647.0, 26849.19452416411, 6555.0, 6842.55523390978, 6900.654967251718, 6958.860822783618, 6991.405730512964, 17277.0, 17324.913375433294, 17327.393541225996, 17396.47810565685, 17467.253274672432, 8296.543690096982, 8463.238308552136, 8502.44734092282, 8524.659013639384, 8555.14448555152, 7471.701131954661, 7561.357284630868, 7571.545707257582, 7577.772666820004, 7658.310752032344, 5150.510701483353, 5216.530512253916, 5290.074237008585, 5293.841184764463, 5311.044012077786, 1458.5624312706145, 1525.4279645133074, 1554.9222538873012, 1560.84391560843, 1573.7245981953113, 745.2137994415835, 863.6200071968314, 864.0668078475231, 880.7754022724164, 887.9067697891628, 7188.856222875618, 7308.451866110063, 7357.337839594462, 7368.815779605553, 7375.557466552024, 926.1710768861831, 942.3262367407336, 952.5999080386255, 954.8329042417688, 957.411192116857, 4710.128626204095, 4717.292406139107, 4727.196376615983, 4746.620270378361, 4806.591439727592, 21343.46641333948, 22100.447488812588, 22212.666809997863, 22425.0, 22493.212737554433, 21028.789712102684, 21825.890870545547, 21839.890800545894, 21924.89037554802, 21993.450163745714, 11305.982461578333, 11525.942370288094, 11656.70858228534, 11727.413629318671, 11845.940770296093, 7449.813754656177, 7649.120351159465, 7674.846503070019, 7713.151553329241, 7810.0, 18388.356407525673, 18639.813601863807, 18760.906195468935, 18874.7168792471, 18941.0, 18745.250189992246, 19158.13788379554, 19178.0, 19634.018299085274, 19853.00734963275, 11179.55281788752, 11189.664310070712, 11266.436678166223, 11283.266587671775, 11299.887001129884, 14006.159630422208, 14027.649308767363, 14043.058856423699, 14223.786643200567, 14274.928625356806, 11766.764664706487, 11773.646790596296, 11800.05599552051, 11814.232074915102, 11943.820842687193, 11258.155638327322, 11408.657740267805, 11459.19785614999, 11481.598144064918, 11627.825582616098, 9941.25440591973, 10033.799324013626, 10098.8485172721, 10167.440790756284, 10179.847302290324, 6389.393007664265, 6432.6462044588, 6570.474362050929, 6625.668716564249, 6673.399394054559, 6545.14913061299, 6753.831154221184, 6765.661716914233, 6813.7955861324235, 6849.075374824335], "value": 6620.5206859788705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.1122340135245, 506.9493897185883, 519.1952473665818, 519.7531172692974, 526.3420724094882, 6205.503559715224, 6229.0, 6266.373362663731, 6284.057391391294, 6287.59130656507, 6007.909881351772, 6020.428059334367, 6070.817875463732, 6082.756689732417, 6166.969165154168, 5113.565346945514, 5122.388715955261, 5156.716380599066, 5159.845204643848, 5161.793528258865, 5041.747912604374, 5098.388193416795, 5131.794728210867, 5149.845504634848, 5151.510606492396, 4733.5503127202865, 4904.48502907195, 4913.582345500637, 4929.901401971958, 4966.056449274644, 4870.634702397334, 4901.583365413926, 4917.557419832232, 4927.95072049282, 4961.131801934665, 4255.5744425557505, 4406.184855801687, 4416.734995900256, 4418.977905110454, 4429.557044295577, 6100.786472473442, 6133.080037994292, 6133.29467111279, 6151.384861513813, 6170.907436388413, 5594.384617692051, 5626.634268772557, 5647.830565083054, 5655.886882262332, 5703.971480142614, 6526.086347911296, 6577.671116444207, 6600.636964966925, 6611.801645950629, 6693.966530167367, 4832.395950506187, 4862.124817532878, 4870.220764677634, 4871.975640121812, 4873.439554451213, 6914.688839002257, 6926.826829329257, 6934.341237582422, 7005.789826305219, 7048.118985126859, 4941.715154059971, 4950.826721064817, 4966.15575352191, 5011.248312753116, 5013.949860501421, 5022.573081288131, 5043.848684539396, 5079.968012794911, 5097.260897169964, 5103.800606857386, 5651.124075768263, 5653.293338332708, 5654.349749778746, 5659.377468478464, 5670.291213598301, 6632.767853125118, 6633.7346506140175, 6651.002349647495, 6716.462682985447, 6723.596584204865, 1636.4190712297202, 1682.7055265328518, 1723.8965662060316, 1739.199968014702, 1745.4327343613377, 1375.931203439824, 1395.1768456610446, 1398.2239856879326, 1436.5115860607443, 1447.9565613031625, 5601.60788744784, 5609.719514024364, 5666.036773748473, 5683.232763576947, 5785.971070144707, 6555.328391260198, 6585.506087043491, 6641.535092543476, 6678.799636010927, 6690.230623478362, 5600.467955544217, 5736.799212027559, 5743.162188099852, 5773.278340207474, 5818.7672493100645, 3522.154682876091, 3531.170175008871, 3561.5904171020147, 3565.928681426357, 3588.623194564533, 6452.483801295885, 6455.870882582416, 6490.870182596416, 6530.8040758777315, 6559.147310849655, 6898.931010689929, 6927.965360173268, 6931.722731090801, 6952.965235173791, 6958.269381714948, 6487.513436492188, 6535.281119076898, 6540.631574739014, 6543.280239173687, 6544.149260596282, 6660.333966603494, 6725.679640524011, 6745.797626071226, 6838.350356716104, 6839.829004274933, 6615.699795133202, 6711.530192886451, 6716.798496045127, 6753.223379311442, 6814.897776533257, 6366.586171898812, 6399.520035997412, 6463.903041454476, 6476.481881449378, 6557.278699343164, 4907.9263811043575, 4924.212126059813, 4942.20924652054, 4960.891621061107, 4993.151164302005, 6751.189857217166, 6763.3236676333945, 6826.556273842383, 6840.657967101525, 6844.486663500158, 6381.309737342956, 6475.672487140031, 6484.935150648433, 6500.399840064012, 6509.777660058301, 6430.003349480838, 6455.644939528183, 6551.967240163769, 6574.3754343337305, 6600.570962887397, 6161.691915404302, 6290.395949032967, 6391.762882991102, 6410.230772307353, 6558.344165583403, 6556.967215163893, 6567.671616419065, 6592.571482853599, 6603.141591593062, 6612.636305003271, 6297.748090076346, 6322.683865806784, 6346.682665866781, 6355.3326900674765, 6390.9041364378645], "value": 5070.118338116205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.6547879066558, 902.8420026495365, 902.932280078994, 919.1451949686789, 932.841416959117, 16344.673106537863, 16967.406140785075, 17046.318147274123, 17161.65676686467, 17175.828241717594, 15862.524124276262, 16205.91897040516, 16259.18704064795, 16391.09848958307, 16735.49793506197, 933.9953300233524, 946.6733976778035, 953.0279115302387, 955.9904400956005, 957.750984743968, 964.9517524123801, 973.897740737223, 975.2441857560376, 976.3214565876718, 978.4765150644407, 991.8760154980628, 1010.8129995950736, 1027.337367398028, 1036.6527213383488, 1041.8280983637728, 1017.7964407118606, 1025.436010194394, 1025.8358662613946, 1026.6612018034032, 1027.0243268894542, 719.4855678190119, 741.0, 743.9770315815754, 746.4588173574115, 747.2975403121048, 17516.124193790387, 17674.646507069916, 17688.380906668204, 17716.645667086585, 17767.733483997752, 20416.387508374773, 20521.17915283387, 21081.156753729832, 21168.047437865334, 21472.570548589098, 16888.071156086535, 16898.577535561588, 17008.06455644939, 17136.657266854716, 17299.913500432544, 3635.1093981974586, 3655.4334078217917, 3689.8524059037873, 3697.1681371691425, 3701.241245544657, 133015.0, 136230.0, 136487.0, 138175.0, 138399.0, 14757.0, 15542.766858497132, 15852.44516441919, 16116.919415403083, 16190.838091619169, 30353.848230759148, 31386.68613313883, 31767.682323176934, 32001.359972800412, 32128.196795079843, 8215.917840821634, 8231.053428855757, 8252.133525979805, 8259.876101858477, 8288.461250018729, 3509.6139424663265, 3623.0761155905075, 3632.54593175853, 3662.4689420033947, 3697.352963231424, 5377.838664840061, 5435.646682965595, 5439.836804895859, 5459.863503412446, 5537.003339398947, 989.2284018465608, 1010.6058637131524, 1022.9232807539464, 1028.3572767020612, 1030.258214085872, 666.1107421592131, 681.9693113809891, 686.4062585863193, 692.9237783843773, 704.3977399323607, 10632.78734425307, 10649.84025239622, 10797.136229101652, 10889.891101089046, 11118.499667514981, 671.5534169777142, 672.085963090197, 679.3240725478082, 690.5097380859646, 697.5570512724456, 5016.372953380827, 5086.694798312114, 5167.896642067138, 5174.97412512935, 5226.425093239741, 119384.40307798405, 123137.0, 124564.0, 124660.0, 130636.0, 106018.0, 108584.0, 110196.0, 113130.0, 114020.0, 93307.53346233361, 95378.0, 96675.0, 100340.49829750805, 100969.49515252377, 9582.377145485521, 9618.278629102986, 9621.470819104736, 9643.951780241054, 9664.130228279488, 44080.559194407644, 45237.0, 45354.54645453503, 45850.08299833918, 46869.765651171525, 57899.0, 58114.0, 59699.1045134332, 60145.69927150336, 60411.0, 15606.297716602554, 15633.530994070195, 15835.0, 15849.128297943262, 15969.44106956251, 15806.841931580537, 15863.206839658202, 16332.0, 16386.180690965164, 16558.337666493688, 14835.703285934005, 14853.628659283604, 14960.0, 14969.326380312694, 15070.945033847964, 13393.19640821554, 13570.253636049718, 13581.86418135806, 13680.315984200552, 13786.241756703477, 13468.663283417993, 13543.187408755506, 13753.862461375658, 13836.446542138605, 13925.442982280596, 9561.235101191749, 9613.0, 9832.9016709832, 9925.399928192513, 9988.60045598168, 10375.896241037493, 10621.937806219315, 10831.566737330419, 10954.397508137128, 11008.339499630049], "value": 8523.422398618914, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1466.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 109.0, 118.0, 96.0, 113.0, 117.0, 114.0, 108.0, 109.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 126.0, 118.0, 126.0, 143.0, 119.0, 121.0, 147.0, 117.0, 121.0], "value": 125.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 172.0, 166.0, 167.0, 162.0, 166.0, 169.0, 166.0, 168.0, 164.0], "value": 166.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 75.0, 73.0, 76.0, 74.0, 80.0, 74.0, 74.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 88.0, 84.0, 80.0, 87.0, 89.0, 79.0, 81.0, 86.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 121.0, 114.0, 110.0, 124.0, 121.0, 114.0, 119.0, 116.0, 116.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 155.0, 157.0, 147.0, 149.0, 150.0, 148.0, 148.0, 150.0, 148.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 103.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 97.0, 94.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 52.0, 55.0, 52.0, 51.0, 51.0, 59.0, 52.0, 52.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 45.0, 45.0, 40.0, 39.0, 40.0, 43.0, 38.0, 38.0, 42.0], "value": 40.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 82.0, 78.0, 77.0, 88.0, 83.0, 79.0, 81.0, 77.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 121.0, 129.0, 114.0, 129.0, 121.0, 118.0, 122.0, 119.0, 121.0], "value": 121.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 176.0, 171.0, 167.0, 167.0, 169.0, 186.0, 177.0, 172.0, 175.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 64.0, 67.0, 71.0, 68.0, 64.0, 68.0, 65.0, 65.0, 88.0], "value": 70.5, "unit": "score"}]}]} 03:19:46 INFO - Return code: 0 03:19:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:19:46 INFO - # TBPL SUCCESS # 03:19:46 INFO - Running post-action listener: _package_coverage_data 03:19:46 INFO - Running post-action listener: _resource_record_post_action 03:19:46 INFO - [mozharness: 2017-09-15 10:19:46.769951Z] Finished run-tests step (success) 03:19:46 INFO - Running post-run listener: _resource_record_post_run 03:19:46 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 12873728; Write bytes: 404017152; Read time: 3260; Write time: 268076 03:19:46 INFO - TinderboxPrint: CPU usage
13.3% 03:19:46 INFO - TinderboxPrint: I/O read bytes / time
12,873,728 / 3,260 03:19:46 INFO - TinderboxPrint: I/O write bytes / time
404,017,152 / 268,076 03:19:46 INFO - TinderboxPrint: CPU idle
8,276.4 (86.7%) 03:19:46 INFO - TinderboxPrint: CPU user
1,204.0 (12.6%) 03:19:46 INFO - TinderboxPrint: Swap in / out
0 / 0 03:19:46 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 532480; Read time: 0; Write time: 44 03:19:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:19:46 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 10645504; Write bytes: 382480384; Read time: 3108; Write time: 259676 03:19:47 INFO - Running post-run listener: _upload_blobber_files 03:19:47 INFO - Blob upload gear active. 03:19:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:19:47 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:19:47 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:19:47 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:19:47 INFO - (blobuploader) - INFO - Open directory for files ... 03:19:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:19:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:19:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:19:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:19:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:19:48 INFO - (blobuploader) - INFO - Done attempting. 03:19:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:19:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:19:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:19:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:19:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:19:57 INFO - (blobuploader) - INFO - Done attempting. 03:19:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:19:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:19:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:19:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:19:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:19:58 INFO - (blobuploader) - INFO - Done attempting. 03:19:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:19:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:19:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:19:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:19:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:19:59 INFO - (blobuploader) - INFO - Done attempting. 03:19:59 INFO - (blobuploader) - INFO - Iteration through files over. 03:19:59 INFO - Return code: 0 03:19:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:19:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:19:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b8702bff867acf3377e5b888067bcbd4697985521ee11afc73cd42423442adcd92cd836e8bd380723ff2c0290648670bc402e934f1ee94e7e4fa7892e24e8fb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f88ff40d5be610c200a3bbef08ae06eed7474f58b8e1f379d1198c8c8ce6240acd19177985816580960d4c2c0898f8aa95b3538d7c6d33158bebcc070cd83d0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da638e36362e7b0dd0f8dbf7e34a4c53ac6b8de7f4d1c5785d64869bb02cc134e09d36f744359f63def19d448788288892e7f281b89bb518987cc842cc1b4990", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86b60f1346b8ad3c486196f3ede1bd398790a463982e5f2da611078cb15f4d06672091e06c70dea342aab1ff153588d650b9e3bf9222a1f893e93601e314c73a"} 03:19:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:19:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:19:59 INFO - Contents: 03:19:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b8702bff867acf3377e5b888067bcbd4697985521ee11afc73cd42423442adcd92cd836e8bd380723ff2c0290648670bc402e934f1ee94e7e4fa7892e24e8fb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f88ff40d5be610c200a3bbef08ae06eed7474f58b8e1f379d1198c8c8ce6240acd19177985816580960d4c2c0898f8aa95b3538d7c6d33158bebcc070cd83d0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da638e36362e7b0dd0f8dbf7e34a4c53ac6b8de7f4d1c5785d64869bb02cc134e09d36f744359f63def19d448788288892e7f281b89bb518987cc842cc1b4990", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86b60f1346b8ad3c486196f3ede1bd398790a463982e5f2da611078cb15f4d06672091e06c70dea342aab1ff153588d650b9e3bf9222a1f893e93601e314c73a"} 03:19:59 INFO - Running post-run listener: copy_logs_to_upload_dir 03:19:59 INFO - Copying logs to upload dir... 03:19:59 INFO - mkdir: /builds/slave/test/build/upload/logs 03:19:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.183553 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-09-15 03:19:59.510111) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 03:19:59.514048) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b8702bff867acf3377e5b888067bcbd4697985521ee11afc73cd42423442adcd92cd836e8bd380723ff2c0290648670bc402e934f1ee94e7e4fa7892e24e8fb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f88ff40d5be610c200a3bbef08ae06eed7474f58b8e1f379d1198c8c8ce6240acd19177985816580960d4c2c0898f8aa95b3538d7c6d33158bebcc070cd83d0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da638e36362e7b0dd0f8dbf7e34a4c53ac6b8de7f4d1c5785d64869bb02cc134e09d36f744359f63def19d448788288892e7f281b89bb518987cc842cc1b4990", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86b60f1346b8ad3c486196f3ede1bd398790a463982e5f2da611078cb15f4d06672091e06c70dea342aab1ff153588d650b9e3bf9222a1f893e93601e314c73a"} build_url:https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/VYH2wZ13SaWnxkKe49_76g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b8702bff867acf3377e5b888067bcbd4697985521ee11afc73cd42423442adcd92cd836e8bd380723ff2c0290648670bc402e934f1ee94e7e4fa7892e24e8fb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f88ff40d5be610c200a3bbef08ae06eed7474f58b8e1f379d1198c8c8ce6240acd19177985816580960d4c2c0898f8aa95b3538d7c6d33158bebcc070cd83d0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da638e36362e7b0dd0f8dbf7e34a4c53ac6b8de7f4d1c5785d64869bb02cc134e09d36f744359f63def19d448788288892e7f281b89bb518987cc842cc1b4990", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86b60f1346b8ad3c486196f3ede1bd398790a463982e5f2da611078cb15f4d06672091e06c70dea342aab1ff153588d650b9e3bf9222a1f893e93601e314c73a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 03:19:59.543236) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:19:59.543566) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1505462578.933762-1952223847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:19:59.627743) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 03:19:59.628069) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 03:19:59.628405) ========= ========= Total master_lag: 0.25 =========