builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-004 starttime: 1506104773.18 results: success (0) revision: 14db7c0bcf9ae86c9ec2cc9d3c249a42e459a2a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.177196) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.177854) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.178138) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.199130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.199562) ========= 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=c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-22 11:26:13-- 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% 7.37M=0.002s 2017-09-22 11:26:13 (7.37 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078252 ========= master_lag: 0.53 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.806275) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.812645) ========= 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=c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016125 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.884526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:13.884978) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 14db7c0bcf9ae86c9ec2cc9d3c249a42e459a2a9 --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 14db7c0bcf9ae86c9ec2cc9d3c249a42e459a2a9 --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=c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-22 11:26:14,081 truncating revision to first 12 chars 2017-09-22 11:26:14,081 Setting DEBUG logging. 2017-09-22 11:26:14,081 attempt 1/10 2017-09-22 11:26:14,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/14db7c0bcf9a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-22 11:26:14,396 unpacking tar archive at: mozilla-central-14db7c0bcf9a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.596674 ========= master_lag: 0.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:14.821791) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:14.822167) ========= script_repo_revision: 14db7c0bcf9ae86c9ec2cc9d3c249a42e459a2a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:14.822588) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:14.822893) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 11:26:15.033545) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-22 11:26:15.033983) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:26:15 INFO - MultiFileLogger online at 20170922 11:26:15 in /builds/slave/test 11:26:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:26:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:26:15 INFO - {'append_to_log': False, 11:26:15 INFO - 'base_work_dir': '/builds/slave/test', 11:26:15 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:26:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:26:15 INFO - 'branch': 'Firefox-Non-PGO', 11:26:15 INFO - 'buildbot_json_path': 'buildprops.json', 11:26:15 INFO - 'code_coverage': False, 11:26:15 INFO - 'config_files': ('talos/linux_config.py',), 11:26:15 INFO - 'default_actions': ('clobber', 11:26:15 INFO - 'read-buildbot-config', 11:26:15 INFO - 'download-and-extract', 11:26:15 INFO - 'populate-webroot', 11:26:15 INFO - 'create-virtualenv', 11:26:15 INFO - 'install', 11:26:15 INFO - 'setup-mitmproxy', 11:26:15 INFO - 'run-tests'), 11:26:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:26:15 INFO - 'disable_ccov_upload': False, 11:26:15 INFO - 'disable_stylo': False, 11:26:15 INFO - 'download_minidump_stackwalk': True, 11:26:15 INFO - 'download_symbols': 'ondemand', 11:26:15 INFO - 'enable_stylo': False, 11:26:15 INFO - 'enable_webrender': False, 11:26:15 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:26:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:26:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:26:15 INFO - 'gecko_profile': False, 11:26:15 INFO - 'gecko_profile_interval': 0, 11:26:15 INFO - 'installer_path': 'installer.exe', 11:26:15 INFO - 'log_level': 'info', 11:26:15 INFO - 'log_name': 'talos', 11:26:15 INFO - 'log_to_console': True, 11:26:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:26:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:26:15 INFO - 'opt_config_files': (), 11:26:15 INFO - 'pip_index': False, 11:26:15 INFO - 'suite': 'dromaeojs-e10s', 11:26:15 INFO - 'system_bits': '32', 11:26:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:26:15 INFO - 'title': 'talos-linux64-ix-004', 11:26:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:26:15 INFO - 'use_talos_json': True, 11:26:15 INFO - 'verify': 'False', 11:26:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:26:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:26:15 INFO - 'work_dir': 'build'} 11:26:15 INFO - [mozharness: 2017-09-22 18:26:15.138309Z] Running clobber step. 11:26:15 INFO - Running pre-action listener: _resource_record_pre_action 11:26:15 INFO - Running main action method: clobber 11:26:15 INFO - rmtree: /builds/slave/test/build 11:26:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:26:16 INFO - Running post-action listener: _resource_record_post_action 11:26:16 INFO - [mozharness: 2017-09-22 18:26:16.641922Z] Finished clobber step (success) 11:26:16 INFO - [mozharness: 2017-09-22 18:26:16.642019Z] Running read-buildbot-config step. 11:26:16 INFO - Running pre-action listener: _resource_record_pre_action 11:26:16 INFO - Running main action method: read_buildbot_config 11:26:16 INFO - Using buildbot properties: 11:26:16 INFO - { 11:26:16 INFO - "project": "", 11:26:16 INFO - "product": "firefox", 11:26:16 INFO - "who": "ffxbld@noreply.mozilla.org", 11:26:16 INFO - "installer_path": "public/build/target.tar.bz2", 11:26:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:26:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:26:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:26:16 INFO - "stage_platform": "linux64", 11:26:16 INFO - "basedir": "/builds/slave/test", 11:26:16 INFO - "buildnumber": 855, 11:26:16 INFO - "platform": "ubuntu64_hw", 11:26:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:26:16 INFO - "slavebuilddir": "test", 11:26:16 INFO - "taskId": "AH2IQY7MRxSSPm47t8MAQQ", 11:26:16 INFO - "branch": "mozilla-central", 11:26:16 INFO - "script_repo_revision": "production", 11:26:16 INFO - "revision": "14db7c0bcf9ae86c9ec2cc9d3c249a42e459a2a9", 11:26:16 INFO - "slavename": "talos-linux64-ix-004", 11:26:16 INFO - "repo_path": "mozilla-central" 11:26:16 INFO - } 11:26:16 INFO - Finding installer, test and symbols from parent task. 11:26:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AH2IQY7MRxSSPm47t8MAQQ'}, attempt #1 11:26:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AH2IQY7MRxSSPm47t8MAQQ'}, attempt #1 11:26:17 INFO - Task dependencies: O2QMI2-cQHqjA_lN8nP03w 11:26:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w'}, attempt #1 11:26:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.tar.bz2 11:26:18 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.test_packages.json 11:26:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.crashreporter-symbols.zip 11:26:18 INFO - Running post-action listener: _resource_record_post_action 11:26:18 INFO - [mozharness: 2017-09-22 18:26:18.003426Z] Finished read-buildbot-config step (success) 11:26:18 INFO - [mozharness: 2017-09-22 18:26:18.003645Z] Running download-and-extract step. 11:26:18 INFO - Running pre-action listener: _resource_record_pre_action 11:26:18 INFO - Running main action method: download_and_extract 11:26:18 INFO - mkdir: /builds/slave/test/build/tests 11:26:18 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')]} 11:26:18 INFO - trying https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.test_packages.json 11:26:18 INFO - Downloading https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:26:18 INFO - Downloaded 989 bytes. 11:26:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:26:18 INFO - Using the following test package requirements: 11:26:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:26:18 INFO - u'common': [u'target.common.tests.zip'], 11:26:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:26:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:26:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:26:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:26:18 INFO - u'mozbase': [u'target.common.tests.zip'], 11:26:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:26:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:26:18 INFO - u'web-platform': [u'target.common.tests.zip', 11:26:18 INFO - u'target.web-platform.tests.tar.gz'], 11:26:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:26:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:26:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.common.tests.zip 11:26:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:26:18 INFO - Fetch https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.common.tests.zip into memory 11:26:21 INFO - Content-Length response header: 54737734 11:26:21 INFO - Bytes received: 54737734 11:26:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.talos.tests.zip 11:26:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:26:32 INFO - Fetch https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.talos.tests.zip into memory 11:26:34 INFO - Content-Length response header: 14513414 11:26:34 INFO - Bytes received: 14513414 11:26:34 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')]} 11:26:34 INFO - trying https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.tar.bz2 11:26:34 INFO - Downloading https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:26:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:26:36 INFO - Downloaded 61565956 bytes. 11:26:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.tar.bz2 11:26:37 INFO - mkdir: /builds/slave/test/properties 11:26:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:26:37 INFO - Writing to file /builds/slave/test/properties/build_url 11:26:37 INFO - Contents: 11:26:37 INFO - build_url:https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.tar.bz2 11:26:37 INFO - Running post-action listener: _resource_record_post_action 11:26:37 INFO - Running post-action listener: find_tests_for_verification 11:26:37 INFO - Running post-action listener: set_extra_try_arguments 11:26:37 INFO - [mozharness: 2017-09-22 18:26:37.118797Z] Finished download-and-extract step (success) 11:26:37 INFO - [mozharness: 2017-09-22 18:26:37.118899Z] Running populate-webroot step. 11:26:37 INFO - Running pre-action listener: _resource_record_pre_action 11:26:37 INFO - Running main action method: populate_webroot 11:26:37 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:26:37 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['tresize', 11:26:37 INFO - 'tcanvasmark']}, 11:26:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:26:37 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['dromaeo_css', 11:26:37 INFO - 'kraken']}, 11:26:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:37 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'tests': ['damp', 'tps']}, 11:26:37 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['damp', 'tps']}, 11:26:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:26:37 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['dromaeo_dom']}, 11:26:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:26:37 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['basic_compositor_video', 11:26:37 INFO - 'glvideo']}, 11:26:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:26:37 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['ts_paint_webext', 11:26:37 INFO - 'tp5o_webext']}, 11:26:37 INFO - 'other-e10s': {'tests': ['a11yr', 11:26:37 INFO - 'ts_paint', 11:26:37 INFO - 'tpaint', 11:26:37 INFO - 'sessionrestore', 11:26:37 INFO - 'sessionrestore_many_windows', 11:26:37 INFO - 'sessionrestore_no_auto_restore', 11:26:37 INFO - 'tabpaint']}, 11:26:37 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['a11yr', 11:26:37 INFO - 'ts_paint', 11:26:37 INFO - 'tpaint', 11:26:37 INFO - 'sessionrestore', 11:26:37 INFO - 'sessionrestore_many_windows', 11:26:37 INFO - 'sessionrestore_no_auto_restore', 11:26:37 INFO - 'tabpaint']}, 11:26:37 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:26:37 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:26:37 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['perf_reftest_singletons']}, 11:26:37 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['perf_reftest']}, 11:26:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:26:37 INFO - 'tsvgr_opacity', 11:26:37 INFO - 'tart', 11:26:37 INFO - 'tscrollx', 11:26:37 INFO - 'tsvg_static']}, 11:26:37 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['tsvgx', 11:26:37 INFO - 'tsvgr_opacity', 11:26:37 INFO - 'tart', 11:26:37 INFO - 'tscrollx', 11:26:37 INFO - 'tsvg_static']}, 11:26:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:26:37 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'talos_options': ['--disable-stylo'], 11:26:37 INFO - 'tests': ['tp5o']}, 11:26:37 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:37 INFO - 'talos_options': ['--mitmproxy', 11:26:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:37 INFO - '--firstNonBlankPaint'], 11:26:37 INFO - 'tests': ['tp6_google', 11:26:37 INFO - 'tp6_youtube', 11:26:37 INFO - 'tp6_amazon', 11:26:37 INFO - 'tp6_facebook']}, 11:26:37 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:37 INFO - 'talos_options': ['--disable-stylo', 11:26:37 INFO - '--mitmproxy', 11:26:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:37 INFO - '--firstNonBlankPaint'], 11:26:37 INFO - 'tests': ['tp6_google', 11:26:37 INFO - 'tp6_youtube', 11:26:37 INFO - 'tp6_amazon', 11:26:37 INFO - 'tp6_facebook']}, 11:26:37 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:37 INFO - 'talos_options': ['--stylo-threads=1', 11:26:37 INFO - '--mitmproxy', 11:26:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:37 INFO - '--firstNonBlankPaint'], 11:26:37 INFO - 'tests': ['tp6_google', 11:26:37 INFO - 'tp6_youtube', 11:26:37 INFO - 'tp6_amazon', 11:26:37 INFO - 'tp6_facebook']}, 11:26:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'talos_options': ['--xperf_path', 11:26:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:37 INFO - 'tests': ['tp5n']}, 11:26:37 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:37 INFO - 'talos_options': ['--disable-stylo', 11:26:37 INFO - '--xperf_path', 11:26:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:37 INFO - 'tests': ['tp5n']}}} 11:26:37 INFO - Running post-action listener: _resource_record_post_action 11:26:37 INFO - [mozharness: 2017-09-22 18:26:37.141721Z] Finished populate-webroot step (success) 11:26:37 INFO - [mozharness: 2017-09-22 18:26:37.141811Z] Running create-virtualenv step. 11:26:37 INFO - Running pre-action listener: _resource_record_pre_action 11:26:37 INFO - Running main action method: create_virtualenv 11:26:37 INFO - Creating virtualenv /builds/slave/test/build/venv 11:26:37 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:26:37 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:26:37 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:26:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:26:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:26:37 INFO - Installing distribute.............................................................................................................................................................................................done. 11:26:37 INFO - Installing pip...............done. 11:26:38 INFO - Return code: 0 11:26:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:26:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:26:38 INFO - Reading from file tmpfile_stdout 11:26:38 INFO - Output received: 11:26:38 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:26:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:26:38 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')]} 11:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:38 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')]} 11:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:38 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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:38 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 11:26:38 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 11:26:38 INFO - Using env: {'DISPLAY': ':0', 11:26:38 INFO - 'HOME': '/home/cltbld', 11:26:38 INFO - 'LANG': 'en_US.UTF-8', 11:26:38 INFO - 'LANGUAGE': 'en_US:en', 11:26:38 INFO - 'LOGNAME': 'cltbld', 11:26:38 INFO - 'MAIL': '/var/mail/cltbld', 11:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:38 INFO - 'MOZ_NO_REMOTE': '1', 11:26:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:38 INFO - 'NO_EM_RESTART': '1', 11:26:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:38 INFO - 'PWD': '/builds/slave/test', 11:26:38 INFO - 'SHELL': '/bin/bash', 11:26:38 INFO - 'SHLVL': '1', 11:26:38 INFO - 'TERM': 'linux', 11:26:38 INFO - 'TMOUT': '86400', 11:26:38 INFO - 'USER': 'cltbld', 11:26:38 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:38 INFO - '_': '/tools/buildbot/bin/python'} 11:26:39 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:26:39 INFO - Downloading/unpacking pip>=1.5 11:26:39 INFO - Running setup.py egg_info for package pip 11:26:39 INFO - warning: no files found matching 'pip/cacert.pem' 11:26:39 INFO - warning: no files found matching '*.html' under directory 'docs' 11:26:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:26:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:26:39 INFO - Installing collected packages: pip 11:26:39 INFO - Found existing installation: pip 1.1 11:26:39 INFO - Uninstalling pip: 11:26:39 INFO - Successfully uninstalled pip 11:26:39 INFO - Running setup.py install for pip 11:26:39 INFO - warning: no files found matching 'pip/cacert.pem' 11:26:39 INFO - warning: no files found matching '*.html' under directory 'docs' 11:26:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:26:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:26:39 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:26:39 INFO - Successfully installed pip 11:26:39 INFO - Cleaning up... 11:26:39 INFO - Return code: 0 11:26:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:26: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')]} 11:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26: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')]} 11:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:39 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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:39 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 11:26:39 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 11:26:39 INFO - Using env: {'DISPLAY': ':0', 11:26:39 INFO - 'HOME': '/home/cltbld', 11:26:39 INFO - 'LANG': 'en_US.UTF-8', 11:26:39 INFO - 'LANGUAGE': 'en_US:en', 11:26:39 INFO - 'LOGNAME': 'cltbld', 11:26:39 INFO - 'MAIL': '/var/mail/cltbld', 11:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:39 INFO - 'MOZ_NO_REMOTE': '1', 11:26:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:39 INFO - 'NO_EM_RESTART': '1', 11:26:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:39 INFO - 'PWD': '/builds/slave/test', 11:26:39 INFO - 'SHELL': '/bin/bash', 11:26:39 INFO - 'SHLVL': '1', 11:26:39 INFO - 'TERM': 'linux', 11:26:39 INFO - 'TMOUT': '86400', 11:26:39 INFO - 'USER': 'cltbld', 11:26:39 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:39 INFO - '_': '/tools/buildbot/bin/python'} 11:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:39 INFO - Downloading/unpacking psutil>=3.1.1 11:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:26:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:40 INFO - Installing collected packages: psutil 11:26:40 INFO - Running setup.py install for psutil 11:26:40 INFO - building 'psutil._psutil_linux' extension 11:26:40 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 11:26:41 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 11:26:41 INFO - building 'psutil._psutil_posix' extension 11:26:41 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 11:26:41 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 11:26:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:41 INFO - Successfully installed psutil 11:26:41 INFO - Cleaning up... 11:26:41 INFO - Return code: 0 11:26:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:26:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:26:41 INFO - Using env: {'DISPLAY': ':0', 11:26:41 INFO - 'HOME': '/home/cltbld', 11:26:41 INFO - 'LANG': 'en_US.UTF-8', 11:26:41 INFO - 'LANGUAGE': 'en_US:en', 11:26:41 INFO - 'LOGNAME': 'cltbld', 11:26:41 INFO - 'MAIL': '/var/mail/cltbld', 11:26:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:41 INFO - 'MOZ_NO_REMOTE': '1', 11:26:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:41 INFO - 'NO_EM_RESTART': '1', 11:26:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:41 INFO - 'PWD': '/builds/slave/test', 11:26:41 INFO - 'SHELL': '/bin/bash', 11:26:41 INFO - 'SHLVL': '1', 11:26:41 INFO - 'TERM': 'linux', 11:26:41 INFO - 'TMOUT': '86400', 11:26:41 INFO - 'USER': 'cltbld', 11:26:41 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:41 INFO - '_': '/tools/buildbot/bin/python'} 11:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:26:41 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 11:26:41 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 11:26:41 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 11:26:41 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 11:26:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:26:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:26:42 INFO - Installing collected packages: mozsystemmonitor 11:26:42 INFO - Running setup.py install for mozsystemmonitor 11:26:42 INFO - Successfully installed mozsystemmonitor 11:26:42 INFO - Cleaning up... 11:26:43 INFO - Return code: 0 11:26:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:26:43 INFO - Using env: {'DISPLAY': ':0', 11:26:43 INFO - 'HOME': '/home/cltbld', 11:26:43 INFO - 'LANG': 'en_US.UTF-8', 11:26:43 INFO - 'LANGUAGE': 'en_US:en', 11:26:43 INFO - 'LOGNAME': 'cltbld', 11:26:43 INFO - 'MAIL': '/var/mail/cltbld', 11:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:43 INFO - 'MOZ_NO_REMOTE': '1', 11:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:43 INFO - 'NO_EM_RESTART': '1', 11:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:43 INFO - 'PWD': '/builds/slave/test', 11:26:43 INFO - 'SHELL': '/bin/bash', 11:26:43 INFO - 'SHLVL': '1', 11:26:43 INFO - 'TERM': 'linux', 11:26:43 INFO - 'TMOUT': '86400', 11:26:43 INFO - 'USER': 'cltbld', 11:26:43 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:43 INFO - '_': '/tools/buildbot/bin/python'} 11:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:43 INFO - Downloading/unpacking jsonschema==2.5.1 11:26:43 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 11:26:43 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 11:26:43 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 11:26:43 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 11:26:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:26:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:26: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 11:26: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 11:26: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 11:26: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 11:26:46 INFO - Downloading functools32-3.2.3-2.tar.gz 11:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:26:46 INFO - warning: no files found matching '*.txt' 11:26:46 INFO - no previously-included directories found matching 'build' 11:26:46 INFO - no previously-included directories found matching 'dist' 11:26:46 INFO - no previously-included directories found matching '.git*' 11:26:46 INFO - Installing collected packages: jsonschema, functools32 11:26:46 INFO - Running setup.py install for functools32 11:26:46 INFO - warning: no files found matching '*.txt' 11:26:46 INFO - no previously-included directories found matching 'build' 11:26:46 INFO - no previously-included directories found matching 'dist' 11:26:46 INFO - no previously-included directories found matching '.git*' 11:26:46 INFO - Successfully installed jsonschema functools32 11:26:46 INFO - Cleaning up... 11:26:46 INFO - Return code: 0 11:26:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:26:46 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')]} 11:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:46 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')]} 11:26:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:46 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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:46 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 11:26:46 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 11:26:46 INFO - Using env: {'DISPLAY': ':0', 11:26:46 INFO - 'HOME': '/home/cltbld', 11:26:46 INFO - 'LANG': 'en_US.UTF-8', 11:26:46 INFO - 'LANGUAGE': 'en_US:en', 11:26:46 INFO - 'LOGNAME': 'cltbld', 11:26:46 INFO - 'MAIL': '/var/mail/cltbld', 11:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:46 INFO - 'MOZ_NO_REMOTE': '1', 11:26:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:46 INFO - 'NO_EM_RESTART': '1', 11:26:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:46 INFO - 'PWD': '/builds/slave/test', 11:26:46 INFO - 'SHELL': '/bin/bash', 11:26:46 INFO - 'SHLVL': '1', 11:26:46 INFO - 'TERM': 'linux', 11:26:46 INFO - 'TMOUT': '86400', 11:26:46 INFO - 'USER': 'cltbld', 11:26:46 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:46 INFO - '_': '/tools/buildbot/bin/python'} 11:26:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:26:46 INFO - Cleaning up... 11:26:46 INFO - Return code: 0 11:26:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:26:46 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')]} 11:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:26:47 INFO - Using env: {'DISPLAY': ':0', 11:26:47 INFO - 'HOME': '/home/cltbld', 11:26:47 INFO - 'LANG': 'en_US.UTF-8', 11:26:47 INFO - 'LANGUAGE': 'en_US:en', 11:26:47 INFO - 'LOGNAME': 'cltbld', 11:26:47 INFO - 'MAIL': '/var/mail/cltbld', 11:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:47 INFO - 'MOZ_NO_REMOTE': '1', 11:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:47 INFO - 'NO_EM_RESTART': '1', 11:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:47 INFO - 'PWD': '/builds/slave/test', 11:26:47 INFO - 'SHELL': '/bin/bash', 11:26:47 INFO - 'SHLVL': '1', 11:26:47 INFO - 'TERM': 'linux', 11:26:47 INFO - 'TMOUT': '86400', 11:26:47 INFO - 'USER': 'cltbld', 11:26:47 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:47 INFO - '_': '/tools/buildbot/bin/python'} 11:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:47 INFO - Downloading/unpacking blobuploader==1.2.4 11:26:47 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 11:26:47 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 11:26:47 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 11:26:47 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 11:26:48 INFO - Downloading blobuploader-1.2.4.tar.gz 11:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:26:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:26:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:48 INFO - Downloading docopt-0.6.1.tar.gz 11:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:26:48 INFO - Installing collected packages: blobuploader, requests, docopt 11:26:48 INFO - Running setup.py install for blobuploader 11:26:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:26:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:26:49 INFO - Running setup.py install for requests 11:26:49 INFO - Running setup.py install for docopt 11:26:49 INFO - Successfully installed blobuploader requests docopt 11:26:49 INFO - Cleaning up... 11:26:49 INFO - Return code: 0 11:26:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:49 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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:49 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 11:26:49 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 11:26:49 INFO - Using env: {'DISPLAY': ':0', 11:26:49 INFO - 'HOME': '/home/cltbld', 11:26:49 INFO - 'LANG': 'en_US.UTF-8', 11:26:49 INFO - 'LANGUAGE': 'en_US:en', 11:26:49 INFO - 'LOGNAME': 'cltbld', 11:26:49 INFO - 'MAIL': '/var/mail/cltbld', 11:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:49 INFO - 'MOZ_NO_REMOTE': '1', 11:26:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:49 INFO - 'NO_EM_RESTART': '1', 11:26:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:49 INFO - 'PWD': '/builds/slave/test', 11:26:49 INFO - 'SHELL': '/bin/bash', 11:26:49 INFO - 'SHLVL': '1', 11:26:49 INFO - 'TERM': 'linux', 11:26:49 INFO - 'TMOUT': '86400', 11:26:49 INFO - 'USER': 'cltbld', 11:26:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:49 INFO - '_': '/tools/buildbot/bin/python'} 11:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:49 INFO - Running setup.py (path:/tmp/pip-Xfo_hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:49 INFO - Running setup.py (path:/tmp/pip-o8I7gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:49 INFO - Running setup.py (path:/tmp/pip-3Bynja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:49 INFO - Running setup.py (path:/tmp/pip-1zIsDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:50 INFO - Running setup.py (path:/tmp/pip-D7HG0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:50 INFO - Running setup.py (path:/tmp/pip-fJpKny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:50 INFO - Running setup.py (path:/tmp/pip-4aD1Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:50 INFO - Running setup.py (path:/tmp/pip-2D7PH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:50 INFO - Running setup.py (path:/tmp/pip-lcQkvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:50 INFO - Running setup.py (path:/tmp/pip-ysezp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:50 INFO - Running setup.py (path:/tmp/pip-fmNV0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:50 INFO - Running setup.py (path:/tmp/pip-Tlbyyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:50 INFO - Running setup.py (path:/tmp/pip-QD4AfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:50 INFO - Running setup.py (path:/tmp/pip-HrjbJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:50 INFO - Running setup.py (path:/tmp/pip-iK77DZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:50 INFO - Running setup.py (path:/tmp/pip-XzMKhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:50 INFO - Running setup.py (path:/tmp/pip-4kGFiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:26:50 INFO - Running setup.py install for manifestparser 11:26:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Running setup.py install for mozcrash 11:26:51 INFO - Running setup.py install for mozdebug 11:26:51 INFO - Running setup.py install for mozdevice 11:26:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Running setup.py install for mozfile 11:26:51 INFO - Running setup.py install for mozhttpd 11:26:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Running setup.py install for mozinfo 11:26:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Running setup.py install for mozInstall 11:26:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Running setup.py install for mozleak 11:26:51 INFO - Running setup.py install for mozlog 11:26:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:26:51 INFO - Running setup.py install for moznetwork 11:26:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:26:52 INFO - Running setup.py install for mozprocess 11:26:52 INFO - Running setup.py install for mozprofile 11:26:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:26:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:26:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:26:52 INFO - Running setup.py install for mozrunner 11:26:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:26:52 INFO - Running setup.py install for mozscreenshot 11:26:52 INFO - Running setup.py install for moztest 11:26:52 INFO - Running setup.py install for mozversion 11:26:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:26:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:26:52 INFO - Cleaning up... 11:26:52 INFO - Return code: 0 11:26:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:52 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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:52 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 11:26:52 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 11:26:52 INFO - Using env: {'DISPLAY': ':0', 11:26:52 INFO - 'HOME': '/home/cltbld', 11:26:52 INFO - 'LANG': 'en_US.UTF-8', 11:26:52 INFO - 'LANGUAGE': 'en_US:en', 11:26:52 INFO - 'LOGNAME': 'cltbld', 11:26:52 INFO - 'MAIL': '/var/mail/cltbld', 11:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:52 INFO - 'MOZ_NO_REMOTE': '1', 11:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:52 INFO - 'NO_EM_RESTART': '1', 11:26:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:52 INFO - 'PWD': '/builds/slave/test', 11:26:52 INFO - 'SHELL': '/bin/bash', 11:26:52 INFO - 'SHLVL': '1', 11:26:52 INFO - 'TERM': 'linux', 11:26:52 INFO - 'TMOUT': '86400', 11:26:52 INFO - 'USER': 'cltbld', 11:26:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:52 INFO - '_': '/tools/buildbot/bin/python'} 11:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:52 INFO - Running setup.py (path:/tmp/pip-6IEzQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:52 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)) 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:52 INFO - Running setup.py (path:/tmp/pip-9PuRDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:53 INFO - Running setup.py (path:/tmp/pip-GWqy_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:53 INFO - Running setup.py (path:/tmp/pip-RemDs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:53 INFO - Running setup.py (path:/tmp/pip-5ULF8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:53 INFO - Running setup.py (path:/tmp/pip-DphRTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:53 INFO - Running setup.py (path:/tmp/pip-ftRkaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:53 INFO - Running setup.py (path:/tmp/pip-tXTcxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:53 INFO - Running setup.py (path:/tmp/pip-6nVHoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:53 INFO - Running setup.py (path:/tmp/pip-MEnrW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:53 INFO - Running setup.py (path:/tmp/pip-ZWkz33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:53 INFO - Running setup.py (path:/tmp/pip-vLDlrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:53 INFO - Running setup.py (path:/tmp/pip-z98X85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:53 INFO - Running setup.py (path:/tmp/pip-zY8umZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:53 INFO - Running setup.py (path:/tmp/pip-m7Chn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:53 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)) 11:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:53 INFO - Running setup.py (path:/tmp/pip-vTM6OQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:54 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)) 11:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:54 INFO - Running setup.py (path:/tmp/pip-0SzNKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:54 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)) 11:26:54 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)) 11:26:54 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)) 11:26:54 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)) 11:26:54 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)) 11:26:54 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)) 11:26:54 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)) 11:26:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:54 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 11:26:54 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 11:26:54 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 11:26:54 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 11:26:55 INFO - Downloading blessings-1.6.tar.gz 11:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:26:55 INFO - Installing collected packages: blessings 11:26:55 INFO - Running setup.py install for blessings 11:26:55 INFO - Successfully installed blessings 11:26:55 INFO - Cleaning up... 11:26:55 INFO - Return code: 0 11:26:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:26:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:55 INFO - Reading from file tmpfile_stdout 11:26:55 INFO - Current package versions: 11:26:55 INFO - argparse == 1.2.1 11:26:55 INFO - blessings == 1.6 11:26:55 INFO - blobuploader == 1.2.4 11:26:55 INFO - docopt == 0.6.1 11:26:55 INFO - functools32 == 3.2.3-2 11:26:55 INFO - jsonschema == 2.5.1 11:26:55 INFO - manifestparser == 1.1 11:26:55 INFO - mozInstall == 1.13 11:26:55 INFO - mozcrash == 1.0 11:26:55 INFO - mozdebug == 0.1 11:26:55 INFO - mozdevice == 0.51 11:26:55 INFO - mozfile == 1.2 11:26:55 INFO - mozhttpd == 0.7 11:26:55 INFO - mozinfo == 0.10 11:26:55 INFO - mozleak == 0.1 11:26:55 INFO - mozlog == 3.5 11:26:55 INFO - moznetwork == 0.27 11:26:55 INFO - mozprocess == 0.25 11:26:55 INFO - mozprofile == 0.28 11:26:55 INFO - mozrunner == 6.13 11:26:55 INFO - mozscreenshot == 0.1 11:26:55 INFO - mozsystemmonitor == 0.3 11:26:55 INFO - moztest == 0.8 11:26:55 INFO - mozversion == 1.4 11:26:55 INFO - psutil == 3.1.1 11:26:55 INFO - requests == 1.2.3 11:26:55 INFO - wsgiref == 0.1.2 11:26:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:55 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')]} 11:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:55 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')]} 11:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:55 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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:55 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 11:26:55 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 11:26:55 INFO - Using env: {'DISPLAY': ':0', 11:26:55 INFO - 'HOME': '/home/cltbld', 11:26:55 INFO - 'LANG': 'en_US.UTF-8', 11:26:55 INFO - 'LANGUAGE': 'en_US:en', 11:26:55 INFO - 'LOGNAME': 'cltbld', 11:26:55 INFO - 'MAIL': '/var/mail/cltbld', 11:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:55 INFO - 'MOZ_NO_REMOTE': '1', 11:26:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:55 INFO - 'NO_EM_RESTART': '1', 11:26:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:55 INFO - 'PWD': '/builds/slave/test', 11:26:55 INFO - 'SHELL': '/bin/bash', 11:26:55 INFO - 'SHLVL': '1', 11:26:55 INFO - 'TERM': 'linux', 11:26:55 INFO - 'TMOUT': '86400', 11:26:55 INFO - 'USER': 'cltbld', 11:26:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:55 INFO - '_': '/tools/buildbot/bin/python'} 11:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:55 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)) 11:26:55 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)) 11:26:55 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)) 11:26:55 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)) 11:26:55 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)) 11:26:55 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)) 11:26:55 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)) 11:26:55 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)) 11:26:55 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)) 11:26:55 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:26:57 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)) 11:26:57 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)) 11:26:57 INFO - Installing collected packages: simplejson 11:26:57 INFO - Running setup.py install for simplejson 11:26:57 INFO - building 'simplejson._speedups' extension 11:26:57 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 11:26:58 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 11:26:58 INFO - Successfully installed simplejson 11:26:58 INFO - Cleaning up... 11:26:58 INFO - Return code: 0 11:26:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:58 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 0x19ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d19a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', '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 11:26:58 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 11:26:58 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 11:26:58 INFO - Using env: {'DISPLAY': ':0', 11:26:58 INFO - 'HOME': '/home/cltbld', 11:26:58 INFO - 'LANG': 'en_US.UTF-8', 11:26:58 INFO - 'LANGUAGE': 'en_US:en', 11:26:58 INFO - 'LOGNAME': 'cltbld', 11:26:58 INFO - 'MAIL': '/var/mail/cltbld', 11:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:58 INFO - 'MOZ_NO_REMOTE': '1', 11:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:58 INFO - 'NO_EM_RESTART': '1', 11:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:58 INFO - 'PWD': '/builds/slave/test', 11:26:58 INFO - 'SHELL': '/bin/bash', 11:26:58 INFO - 'SHLVL': '1', 11:26:58 INFO - 'TERM': 'linux', 11:26:58 INFO - 'TMOUT': '86400', 11:26:58 INFO - 'USER': 'cltbld', 11:26:58 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:58 INFO - '_': '/tools/buildbot/bin/python'} 11:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:26:58 INFO - Cleaning up... 11:26:58 INFO - Return code: 0 11:26:58 INFO - Running post-action listener: _resource_record_post_action 11:26:58 INFO - Running post-action listener: _start_resource_monitoring 11:26:58 INFO - Starting resource monitoring. 11:26:58 INFO - [mozharness: 2017-09-22 18:26:58.404224Z] Finished create-virtualenv step (success) 11:26:58 INFO - [mozharness: 2017-09-22 18:26:58.404749Z] Running install step. 11:26:58 INFO - Running pre-action listener: _resource_record_pre_action 11:26:58 INFO - Running main action method: install 11:26:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:58 INFO - Reading from file tmpfile_stdout 11:26:58 INFO - Detecting whether we're running mozinstall >=1.0... 11:26:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:26:58 INFO - Reading from file tmpfile_stdout 11:26:58 INFO - Output received: 11:26:58 INFO - Usage: mozinstall [options] installer 11:26:58 INFO - Options: 11:26:58 INFO - -h, --help show this help message and exit 11:26:58 INFO - -d DEST, --destination=DEST 11:26:58 INFO - Directory to install application into. [default: 11:26:58 INFO - "/builds/slave/test"] 11:26:58 INFO - --app=APP Application being installed. [default: firefox] 11:26:58 INFO - mkdir: /builds/slave/test/build/application 11:26:58 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'] 11:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:27:16 INFO - Reading from file tmpfile_stdout 11:27:16 INFO - Output received: 11:27:16 INFO - /builds/slave/test/build/application/firefox/firefox 11:27:16 INFO - Running post-action listener: _resource_record_post_action 11:27:16 INFO - [mozharness: 2017-09-22 18:27:16.546901Z] Finished install step (success) 11:27:16 INFO - [mozharness: 2017-09-22 18:27:16.547207Z] Running setup-mitmproxy step. 11:27:16 INFO - Running pre-action listener: _resource_record_pre_action 11:27:16 INFO - Running main action method: setup_mitmproxy 11:27:16 INFO - Skipping: mitmproxy is not required 11:27:16 INFO - Running post-action listener: _resource_record_post_action 11:27:16 INFO - [mozharness: 2017-09-22 18:27:16.548339Z] Finished setup-mitmproxy step (success) 11:27:16 INFO - [mozharness: 2017-09-22 18:27:16.548559Z] Running run-tests step. 11:27:16 INFO - Running pre-action listener: _resource_record_pre_action 11:27:16 INFO - Running pre-action listener: _set_gcov_prefix 11:27:16 INFO - Running main action method: run_tests 11:27:16 WARNING - Try message not found. 11:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:27:16 INFO - Python 2.7.3 11:27:16 INFO - Return code: 0 11:27:16 INFO - grabbing minidump binary from tooltool 11:27:16 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')]} 11:27:16 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 0x1d1d090>, '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 0x1d1dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d17f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:27:16 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 11:27:16 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 11:27:16 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 11:27:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:27:16 INFO - Return code: 0 11:27:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:27:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:27:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:27:16 INFO - ENV: RUST_BACKTRACE is now full 11:27:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:27:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 11:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 11:27:16 INFO - Using env: {'DISPLAY': ':0', 11:27:16 INFO - 'HOME': '/home/cltbld', 11:27:16 INFO - 'LANG': 'en_US.UTF-8', 11:27:16 INFO - 'LANGUAGE': 'en_US:en', 11:27:16 INFO - 'LOGNAME': 'cltbld', 11:27:16 INFO - 'MAIL': '/var/mail/cltbld', 11:27:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:16 INFO - 'MOZ_NO_REMOTE': '1', 11:27:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:16 INFO - 'NO_EM_RESTART': '1', 11:27:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:16 INFO - 'PWD': '/builds/slave/test', 11:27:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:16 INFO - 'RUST_BACKTRACE': 'full', 11:27:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:16 INFO - 'SHELL': '/bin/bash', 11:27:16 INFO - 'SHLVL': '1', 11:27:16 INFO - 'TERM': 'linux', 11:27:16 INFO - 'TMOUT': '86400', 11:27:16 INFO - 'USER': 'cltbld', 11:27:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:16 INFO - '_': '/tools/buildbot/bin/python'} 11:27:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 11:27:16 INFO - mozversion application_buildid: 20170922173423 11:27:16 INFO - mozversion application_changeset: 14db7c0bcf9ae86c9ec2cc9d3c249a42e459a2a9 11:27:16 INFO - mozversion application_display_name: Nightly 11:27:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:27:16 INFO - mozversion application_name: Firefox 11:27:16 INFO - mozversion application_remotingname: firefox 11:27:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:27:16 INFO - mozversion application_vendor: Mozilla 11:27:16 INFO - mozversion application_version: 58.0a1 11:27:16 INFO - mozversion platform_buildid: 20170922173423 11:27:16 INFO - mozversion platform_changeset: 14db7c0bcf9ae86c9ec2cc9d3c249a42e459a2a9 11:27:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:27:16 INFO - mozversion platform_version: 58.0a1 11:27:16 INFO - using testdate: 1506104836 11:27:16 INFO - actual date: 1506104836 11:27:16 INFO - starting webserver on 'localhost:49911' 11:27:16 INFO - SUITE-START | Running 2 tests 11:27:16 INFO - TEST-START | dromaeo_css 11:27:16 INFO - Initialising browser for dromaeo_css test... 11:27:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49911/getInfo.html -profile /tmp/tmpch52gV/profile 11:27:16 INFO - TEST-INFO | started process 4297 (/builds/slave/test/build/application/firefox/firefox http://localhost:49911/getInfo.html) 11:27:22 INFO - TEST-INFO | 4297: exit 0 11:27:22 INFO - Browser initialized. 11:27:22 INFO - Running cycle 1/1 for dromaeo_css test... 11:27:22 INFO - Using env: {'DISPLAY': ':0', 11:27:22 INFO - 'HOME': '/home/cltbld', 11:27:22 INFO - 'JSGC_DISABLE_POISONING': '1', 11:27:22 INFO - 'LANG': 'en_US.UTF-8', 11:27:22 INFO - 'LANGUAGE': 'en_US:en', 11:27:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:27:22 INFO - 'LOGNAME': 'cltbld', 11:27:22 INFO - 'MAIL': '/var/mail/cltbld', 11:27:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:22 INFO - 'MOZ_CRASHREPORTER': '1', 11:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:27:22 INFO - 'MOZ_NO_REMOTE': '1', 11:27:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:22 INFO - 'NO_EM_RESTART': '1', 11:27:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:22 INFO - 'PWD': '/builds/slave/test', 11:27:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:22 INFO - 'RUST_BACKTRACE': 'full', 11:27:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:22 INFO - 'SHELL': '/bin/bash', 11:27:22 INFO - 'SHLVL': '1', 11:27:22 INFO - 'TERM': 'linux', 11:27:22 INFO - 'TMOUT': '86400', 11:27:22 INFO - 'USER': 'cltbld', 11:27:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:22 INFO - '_': '/tools/buildbot/bin/python'} 11:27:22 INFO - TEST-INFO | started process 4531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpch52gV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:23 INFO - PID 4531 | 11:27:23 INFO - PID 4531 | (/builds/slave/test/build/application/firefox/firefox:4580): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:27:23 INFO - PID 4531 | 11:27:34 INFO - PID 4531 | RSS: Main: 151568384 11:27:34 INFO - PID 4531 | 11:30:31 INFO - PID 4531 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49911/tests/dromaeo/cssquery-ext.html) 11:30:32 INFO - PID 4531 | RSS: Main: 152629248 11:30:32 INFO - PID 4531 | 11:33:34 INFO - PID 4531 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-ext.html (next: http://localhost:49911/tests/dromaeo/cssquery-jquery.html) 11:33:35 INFO - PID 4531 | RSS: Main: 152653824 11:33:35 INFO - PID 4531 | 11:36:37 INFO - PID 4531 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49911/tests/dromaeo/cssquery-mootools.html) 11:36:37 INFO - PID 4531 | RSS: Main: 152719360 11:36:37 INFO - PID 4531 | 11:39:39 INFO - PID 4531 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49911/tests/dromaeo/cssquery-prototype.html) 11:39:40 INFO - PID 4531 | RSS: Main: 153255936 11:39:40 INFO - PID 4531 | 11:42:43 INFO - PID 4531 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49911/tests/dromaeo/cssquery-yui.html) 11:42:43 INFO - PID 4531 | RSS: Main: 153448448 11:42:43 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-yui.html (next: http://localhost:49911/tests/dromaeo/cssquery-dojo.html) 11:45:45 INFO - PID 4531 | RSS: Main: 155549696 11:45:45 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | __start_tp_report 11:45:45 INFO - PID 4531 | _x_x_mozilla_page_load 11:45:45 INFO - PID 4531 | _x_x_mozilla_page_load_details 11:45:45 INFO - PID 4531 | |i|pagename|runs| 11:45:45 INFO - PID 4531 | |0;dojo.html;3138.8273644949527;3143.4027534768393;3146.4493713600114;3149.9685003149975;3152.1646763607646;6243.406876346739;6316.336784637616;6334.588251763632;6364.109024736541;6441.838954026146;6315.8105256842255;6328.778492752755;6332.1135041094285;6334.714937827797;6343.587666801654;5854.941450585482;5869.7652093916295;5882.911756323659;5899.203607512995;5922.911156332659;5857.560682948796;5881.617694849842;5940.910886336708;5972.372900845414;6002.639841609495;5884.823455296348;5964.761409543613;5985.970070149643;5991.850203744898;6008.158857759917;5666.291713535808;5759.992001399738;5906.05503119501;5962.046072628402;6007.789727359522;5267.578593712494;5733.483986441199;5761.53907687384;5879.617824841414;5898.587098903078;6344.111824344595;6356.682165891687;6360.0459931010255;6418.101465794793;6445.484361251089;6597.63712995785;6664.966675166642;6768.018637297613;6797.082393876813;6899.137607799025;6857.245702972669;6862.588244705334;6867.07294515244;6912.412444942186;6920.411764999981;6309.08518264849;6364.872702545969;6369.299377068519;6376.043593460971;6400.039994000937;7012.614306213105;7022.4733145014325;7033.8944915826305;7104.7158113675905;7107.040549525748;6558.245801732862;6601.240857301375;6650.70071846768;6751.898721519181;6767;6763;6885.724571017204;6886.345797149263;6893.0694356261265;6920.619365934922;6751.459883209332;6768.966155169291;6859.588424694534;6862.107925969592;6886.655667216619;6749.122614060141;6768.69540870662;6848.589084654936;6866.141732283465;6876.106106206161;5157.200633901752;5278;5294.285271488377;5333.359996800331;5353.759080841293;4676.415448068991;4711.552402521755;4736.597389221886;4739;4760.523947605212;5710.600257981901;5806.071028635398;5855.707214639251;5857.26784151981;5868.38381969887;6699.799006029827;6812.386885180357;6841.93158068413;6945.51381403307;6948.93051069493;5601.04782187021;5686.289213848269;5752.252207213036;5754.942450575524;5765.827025189167;5848.61983971049;5886.1465087562065;5897.115432685046;5900;5910.852228694231;6573.441257493167;6576.309487503839;6588.8023359299295;6644.900326495106;6650.999999999904;6665.766698165541;6833.965830170818;6847.417969472452;6944.375006249462;6973.163220413583;6550.868982620416;6572.539922205399;6578.934210658023;6581.07864898905;6583.30875258091;6607.603543787388;6785.626790526569;6789.966050169717;6796.184457865088;6802.6598670065305;6810.31896810315;6823.317668233137;6906.930930690629;6921.792346229621;6944.756933507303;6591.077249185214;6599.142111525471;6689.665516724047;6699.430548403246;6699.933000669931;6562.245341785565;6671.8665626686225;6675.899861501985;6691;6693.598384096969;6241.157443745218;6421.614703117828;6450.032495125769;6526.24948130952;6619.470442364503;6637.601743895381;6638.17022872141;6685.76599819004;6749.291324410866;6759.966200168968;6317.115603815561;6459.935400645933;6463.967680161569;6574.178227721535;6577.375149360803 11:45:45 INFO - PID 4531 | |1;ext.html;835.7534527314442;849.7705619482736;850.188070392775;857.0914830279904;863.8920134983127;40001.19997600046;41568.99999999996;41627;41654.58345416545;41726;37715;37793.81103094481;37877.43183852245;37959.81020094896;38073.80963095187;7147.642617869112;7271.127464704243;7288.161861385929;7289.817754556126;7290.854182916339;7170.067891174166;7256.346928776409;7263;7277.599732014737;7302.744403945863;7249;7283.308085731874;7959.840803183933;8169.468984516017;8169.836603267931;8093;8120.512769233865;8158.184181581853;8175.182481751836;8179.468334558234;7796.337311328544;7963.482373645695;7976;7984.201579842027;8092.150324215991;37795.81102094472;37945.241095178215;38059.42910856312;38139.809300953595;38147.23705525874;3511.7015053720465;3562.4834399012257;3564.0733409313507;3566.7146628269675;3568.286342731464;30854.537181942287;30865.845670771727;30871.536926946115;30913.69086309153;30973.380532389452;5164.561012313958;5195.9480405195845;5210.400803907562;5220.634555581111;5236.136037553799;191167;191825;192334.9999999986;192354;192974;47138.52861471342;47193.5280647196;47340;47430.76284618624;47473.762631186626;48853.51146488561;49070.509294906595;49206;49272;49292;15603.453879114177;15706.450274240347;15763.921180394254;15790.447334343244;15809.1304978225;12733.745325093447;12800.16798908068;12807.743845123045;12824.102312838198;12839.614811555668;13647.931760341135;13785.13900623423;13805.24071176095;13843.169409835442;13848.16910985324;5658.603897727119;5707.600467967202;5738.8278351649515;5752.181431098731;5797.159411885253;994.7413672445216;1002.9949850250702;1003.8996100389902;1019.6176433837311;1020.3826684855704;22595.88702056479;22945;23048.539029219322;23072.884635576716;23095.191668291864;1098.1983152298867;1099.0987390340003;1108.4236197177422;1114.3815182573749;1119.5242022140558;12236.877631223751;12364.44360003802;12367.62897113088;12391.62825115248;12450.81323780144;238429;239531;239692;241187;242260;51607.741961289954;53305.733471332394;57484.425155748744;57621.13568296479;57808;1428.7571112910623;1437.094630382852;1451.5137428961416;1452.7966084747932;1457.162131774234;26722.655468906687;27036.72963270342;27304.863475682494;27418.5887211696;27498.17505474839;37286;37370.25259494849;37459.250814984094;37473.250534988605;37645;201623;201849;203070;203122;203344;32238;33233;33247.168820779676;33373.83313083516;33412;42884.78557607192;42889.571104289804;43425.34861977136;43443;43589.56410435982;31287.061388158392;31452.213694657818;31723.84138079295;32123.839380802947;32400;31111.444427778068;31280.843595781877;31342.216444589067;31536.36927261396;32017.519737203496;35625.46561801627;35958.64041359553;36118.638813612575;36216.81891590525;36263.45604815877;30944.53583196299;30959.380812384075;31449.213769655027;31529.36941261208;31753;22843.321956314405;23169.07323707033;23281.53436931218;23341;23518.294451166494 11:45:45 INFO - PID 4531 | |2;jquery.html;568.5480043365527;573.4781348972435;580.5094694982738;589.4311988930682;592.9495992840608;21915.232966846164;22711.432214194654;22728.090876364946;22730.431739206528;22731.090756369766;23202.535949281046;24239.272821815328;24484.143054993077;24705.876470617666;25067.74932250677;6494.415502604765;6688.1305430294;6688.765893193739;6690.83272918176;6707.128073350457;6564.343565643445;6645.102911106987;6657.467402607804;6694.564853284545;6705.2624211336715;5949.85125371867;6483.351664833503;6488.7728929487475;6491.967540162316;6513.772017979371;6479.870402591945;6528.738850445977;6531.216254049497;6538.1827271591055;6545.967270163643;5952.791652292192;6041.577089603729;6082.513398928119;6210.50315974721;6237.656928868911;28805.85597072001;29392.706072939425;29394.265143371373;29412.705872941424;29425.11724648264;2073.460900165968;2085.979140208594;2098.286582561921;2101.8318534517202;2102.6845973104005;18682.532936676555;19520.609587808165;19556.217751289932;19902.303419380252;19980.700289495668;2456.152627343564;2466.8273220874544;2499.46261553765;2501.124606387768;2531.860747658878;118187;123729.38135309356;129445.35277323553;137089;137951.31024344813;14456.85543144555;14496.142386392761;14508.564743057725;14842.851237817289;14904;22292.21977230789;22421.55156896853;22428.32715018552;22529.662055069188;22564.210252641406;10008.14930730896;10161.54273057714;10177.898221017695;10313.690589282334;10325.690229292983;9435.29235307341;9452.90547094534;9471.810563788686;9561.330706850455;9633.518324083769;8847.159519845605;8954;9047.276217902552;9085.591148398338;9398.295127865438;2528.3552694062896;2592.468543948486;2595.156574113421;2623.34416395901;2762.778977681781;939.915407613318;973.5132433783108;982.0621306652273;984.9704508864745;987.0968064221157;19115.522111947314;19204.519887002938;19580.804191958185;19728.30950916711;19739.309124180872;1030.160419258302;1036.683811437519;1053.963111291101;1057.7197042783669;1066.6266806617778;10638.680839574825;10809;10829.83755243672;10888.727781805359;10959.342439453658;430271;443128;454092;455531;456966;110033.44983275032;111815;112830.74338513183;113721.43139284416;114613;1023.1405619279841;1070.8661417322835;1151.176908510419;1160.8839116088322;1221.3709939381404;19821.50446238798;20053.09761060785;20274.08766605477;20287;20289.49276268105;54493.25351530035;55113.72443137759;55593;56480;56926.715366422904;70602.64698676474;70884.29115708776;70902.29097708956;71575.28424715686;71611.64194178996;13846.169229845838;14330.641733956734;14349.56951291463;14354.210518421587;14364.35360408805;21971.67042494332;22346.441338966655;22363;22558.66162007604;22642.660360094284;17846.643067138844;18071.728924065887;18212.089395529907;18306.26774928988;18310.90844545822;16115.838841611903;16470.09414482215;16491.83508164903;16756.58108547296;16865.241064151898;18550.536236594195;18573.535661608566;18694.813051869853;18778.71831922495;18831.435056948314;14187.219702916025;14246.358913848695;14276.71446571042;14314.355853986388;14318.642033948818;17939.641207175522;17969.460916172535;18105.09474526242;18140.27438902482;18371.081445927917 11:45:45 INFO - PID 4531 | |3;mootools.html;1330.9401076951538;1389.4650559534575;1393.184986782732;1397.308332375474;1407.0221196268592;12664.810027849579;13314.600561983143;14405.78391324131;14879.330430130643;14892;15487.922560387211;15726.84273157268;15740;15740.606484837885;15741.134237616927;2697.5009623219717;2756.0629386008754;2758.6965433802266;2768.8338699677984;2898.8115772474825;2809.19937817722;2836.6454193225845;2846.1746093632823;2848.3591191981845;2849.4443583501225;2729.986350068247;2832.065418411919;2833.220864262334;2833.2916770807296;2838.843863587502;2703.7025927147997;2747.5054490191756;2773.861306934645;2783.8886444542095;2787.707290734474;2618.869056547165;2693.2324287577944;2772.9584056238973;2828.1515545336315;2828.533292006837;15214.695706085928;15339;15364.692706145814;15372.615684607863;15389;8061.95969020157;8291.626876790559;8328.208820162075;8329.66681332746;8352.248297653241;5441.972790136064;5596.488947984062;5718.828435146952;5767.855803604902;5783.71081445926;1852.1757817771097;1927.6626590346773;1939.2533874458295;1953.8534609904173;1976.8023197680118;27050;27876;27910.023149189885;28039.158825235274;28154.296142596395;7000.47496437759;7207.027051348001;7209.531380460253;7251.166115896739;7255.129384473791;17311.82688173102;17970.820291797176;18304.26782928668;18315;18321.7251741224;8350.707725229588;8484.490930544187;8522.360822938304;8575.442596231225;8581.914180858237;7612.733554325572;7653.311201991847;7725.343345815669;7798.103218129874;7808.297253247235;5324.5740340772645;5537.529310008695;5549.361823390281;5602.159676048544;5659.122835960433;1617.3934774459578;1618.7014439495945;1641.1137985487942;1645.1609679063763;1655.2882260628046;857.1813917708573;878.3280790195505;881.1320848963766;895.4314010603314;904.2720609908955;8242.835143297101;8328;8375.74872753806;8450.492970421672;8458.957705211435;732.8167958010497;737.0823324960459;738.1400668221569;744.6649007946446;751.9962400187964;4347.739135651871;4473.485549161823;4617.884552886205;4800.231962885921;5151.896962060738;22050.558988820132;22276;22680.65979010316;23334.416639583807;23446.64830027551;23329.416764581023;23415.297541073796;23508.294751157493;23540.64689029666;23578;10753;11840.76318473643;11891;11919.642410727693;12057.57798477049;8101.716439924574;8216.83566328682;8254.463459875089;8271.255586997198;8276.793080173044;18933.526661833563;19444.319448819224;19497;19713.507162321057;20055.799442005395;20078.598428031066;20259.392218233475;20281.08735106894;20364;20579.8971005144;11024.228304018641;11119.83320250213;11199.10407167441;11226.270292430965;11251.943740281245;14287.357068932131;14459.349329280418;14510.419583216553;14545.85454145445;14627.122372657675;11418.31490110596;11608.767824643628;11617.88382116168;11822.231554948901;11928.343941083323;11455.770884582094;11483.36841473727;11655.825162622397;11713;11793.646190614296;10087.243456740627;10115.696529104138;10119.190464762949;10222.591096356062;10224.13094886943;7265.564066155836;7359.043324367798;7373.446991475768;7375.188729239672;7425.071866016748;7111.857762844817;7129.073220481304;7216.386607138452;7225.313595208447;7234.16807067194 11:45:45 INFO - PID 4531 | |4;prototype.html;499.8125702861427;516.8733660253238;537.4652221040063;538.8087229033694;541.3449725831744;6217.689115544222;6355.872882542347;6374.490040796737;6399.7760078397205;6451.645159516236;6137.6370097512445;6212.65830379329;6218.564700470969;6266.84332891678;6279.15231443755;5003.249512573107;5031.798728050873;5078.64449488536;5087.7710503027265;5113.616478764089;4930.4330002049865;5067.8479645610505;5075.340205773263;5078.9622250311095;5101.464346243647;4979.203327467606;5034.773435195407;5067.721275329855;5068.695878247317;5069.149760337469;4962.206047032476;4996.3504744383;5014.899702005958;5043.747812609355;5054.418741844698;4514.7291162530355;4544.954550454486;4545.477270113946;4577.359169716243;4585.358049873021;6214.9689251553455;6215.937840621581;6223.159873417121;6245.906311405332;6257.468115210213;5782.992843721284;5784.942150578482;5822.767089316422;5846.853828654275;5864.179014937912;6542.378474044958;6548.380647740894;6577.539572229945;6614.809334319821;6636.117871045444;4944.233643785219;4953.925691114636;4964.801407943678;4975.776090075955;4975.950240497585;6823.624700641512;6826.897596536056;6957.582545047263;6961.338672826073;6991.650417479105;5062.620303477254;5073.543381095719;5100.38795344561;5104.132297509433;5121.206213036985;5162.148245539519;5186.559142472856;5193.402758682724;5195.116830138885;5199.688018718889;5678.801241956594;5711.428857114255;5774.624649397776;5818.5345172386105;5839.124131380327;6326.6520341381665;6450.225972883284;6591.901121483182;6663.06717059612;6688.966555167291;1655.147598986523;1728.3777839977597;1780.7445750745694;1782.7236778299384;1791.605846713721;1214.8519648931851;1232.3037483821613;1236.2458900070949;1264.905132115095;1269.4033804112053;5615.8876822464135;5642;5650.802221922213;5651.57613179005;5663.433656634386;6781.932180678229;6846.657667116624;6880.724771009103;6897.7240910363025;6898.758543451056;5674.773009079673;5681;5695.145728140812;5715;5807.274090738658;3552.3960926642535;3552.644735526478;3562.3943929532043;3621.511096002006;3633.4913112164318;6520.739170433129;6590.670466476753;6592.143021407186;6620.933790662128;6631.734730610721;6994.132405713633;7018.33325834045;7031.402330801837;7039.648017599099;7072.398846098036;6394.23269207698;6442.06590044441;6523.412892839572;6576.506761992774;6598.241202261705;6609.669516524251;6675.7329706813125;6788.76239331621;6851;6864.9245417804605;6604.3725846044545;6757.358050985148;6803.217629972616;6806.46854457743;6821.1814582250445;6535.183102112236;6567.802965911031;6574.605523668595;6596.637184954873;6614.470842332698;5061.190209566452;5089.465606111453;5110.3356563646485;5142.280080788767;5165.61257905651;6572.309907459648;6761.599632130719;6799.966000169968;6898.586084834926;6961.651917404009;6376.171097757262;6463.482921366185;6596.8350791230605;6603.943369060928;6626.072349870926;6585.847476691464;6609.834754131185;6636.170478690164;6654.434373078343;6674.599524028574;6157.72290246931;6487.643179624977;6527.477801775752;6529.723031414431;6533.41199292066;6516.012616152119;6553.2136143663065;6607.174103237096;6626.53614246998;6627.768028118992;6334.809955701337;6361.8727625448155;6406.9359306408205;6453.9354606453335;6476.125723027331 11:45:45 INFO - PID 4531 | |5;yui.html;875.6366108065151;885.7652722028663;907.5598334807621;910.7131253655102;911.6535716427759;18914.527136821587;19435.222591096375;19477.805221947776;19560.804391956077;19584;18232.544186395346;18360.35738749144;18397.448076557692;18403.90798046008;18407;953.046953046953;964.768455570665;972.3485264872552;972.6206779356057;972.6401231544303;970.9757256068584;971.2715463402449;975.7609385700513;975.878015248094;976.5263847034183;994.9651762188325;1016.9694909152737;1024.5184763161303;1024.5952848624788;1024.8975102489767;1008.2538921198334;1010.0555980158575;1015.7003683913226;1016.232744278069;1016.8322226832564;722.4617659843398;730.5251586468785;739.7780665800292;740.489062546841;740.8259059121102;20573.588528229502;20679.58640827175;20853.270135545336;20870.895645521676;20873.89563052175;20884.477888052767;21665.35003949884;21948.012339444762;22130;22182.778172218234;15953.680926381407;15980.520584382488;16167.676646467122;16401.58996025109;16616.169191540375;3618.294432585647;3619.6561326674187;3621.8551257949653;3645.7994810285422;3657.487951686766;105009;139138;139455.30272348574;140722.2963885184;144544;17325.22036508335;17800.91099544494;17953.192106355244;18072;18080.547986300448;32848.3430331392;33036;33111.17222069467;33147.66852331494;33149;9454.05459454049;9456.243500519942;9540.856887146698;9626.855597166048;9636.180924621345;4471.329300604935;4500.887477813081;4534.637229021736;4542.432195975503;4543.977280113579;5305.708186049805;5407.080796264566;5531.502164805187;5536.557075434036;5600.831975040755;1037.0821822686826;1052.084686322904;1063.6064656077147;1065.9040686338267;1068.2682362581502;649.7790751144634;666.1306994372294;669.912911321525;674.5042393840523;686.4405509509734;11620.477078531487;11769.234999724991;11820.88179118215;12254.326012069243;12349.876501235052;659.864727730814;672.1463741048939;675.3584095109666;678.9694463749144;680.2755065854849;5495.697736624444;5564.610477266552;5578.553715702815;5600.355959064678;5714.200011998323;123933;124772;125372;125893.37053314675;127106.36446817707;85487;106575.46712266545;111953.44023279831;114047;115010.42494787472;91991;94069;94342.5282873595;94881.52559237297;96700.51649741706;12421.31682757457;13041.804372934603;13127.803082953573;13138.408771605109;13149;41674.374884377365;41996.580034199265;42489.78755106204;42563.7871810639;43591.56408435875;55839.44160558342;55898.44101558932;57386.13920791108;57784.71107644435;58529.414705852396;17874.910625447308;18188.72716909221;18233.908830455763;18265.817341826412;18301.17644706018;18808.52978675544;19044.523886902385;19069.046547672282;19195.904020479808;19240;17248;17430.128493575525;17468.12659367052;17688.91155544214;17733.37933172333;18617.720734188726;18770.906145469184;18892.622147557246;18943.810561894206;19189.32837350686;17373.391931282345;17391;17422.128893555524;17638.91180544089;17705.734413984057;13091.214527128403;13235.470581176645;13281.867181328449;13337.466501339837;13433.462661493431;13376.933115334361;13744.175349479063;13886.652833678834;13990.510332138327;13996.37016334247 11:45:45 INFO - PID 4531 | __end_tp_report 11:45:45 INFO - PID 4531 | __start_cc_report 11:45:45 INFO - PID 4531 | _x_x_mozilla_cycle_collect,297 11:45:45 INFO - PID 4531 | __end_cc_report 11:45:45 INFO - PID 4531 | __startTimestamp1506105945896__endTimestamp 11:45:45 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | ------- Summary: start ------- 11:45:45 INFO - PID 4531 | Number of tests: 6 11:45:45 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | [#0] dojo.html Cycles:165 Average:6262.89 Median:6550.87 stddev:765.70 (11.7%) stddev-sans-first:727.77 11:45:45 INFO - PID 4531 | Values: 3138.8 3143.4 3146.4 3150.0 3152.2 6243.4 6316.3 6334.6 6364.1 6441.8 6315.8 6328.8 6332.1 6334.7 6343.6 5854.9 5869.8 5882.9 5899.2 5922.9 5857.6 5881.6 5940.9 5972.4 6002.6 5884.8 5964.8 5986.0 5991.9 6008.2 5666.3 5760.0 5906.1 5962.0 6007.8 5267.6 5733.5 5761.5 5879.6 5898.6 6344.1 6356.7 6360.0 6418.1 6445.5 6597.6 6665.0 6768.0 6797.1 6899.1 6857.2 6862.6 6867.1 6912.4 6920.4 6309.1 6364.9 6369.3 6376.0 6400.0 7012.6 7022.5 7033.9 7104.7 7107.0 6558.2 6601.2 6650.7 6751.9 6767.0 6763.0 6885.7 6886.3 6893.1 6920.6 6751.5 6769.0 6859.6 6862.1 6886.7 6749.1 6768.7 6848.6 6866.1 6876.1 5157.2 5278.0 5294.3 5333.4 5353.8 4676.4 4711.6 4736.6 4739.0 4760.5 5710.6 5806.1 5855.7 5857.3 5868.4 6699.8 6812.4 6841.9 6945.5 6948.9 5601.0 5686.3 5752.3 5754.9 5765.8 5848.6 5886.1 5897.1 5900.0 5910.9 6573.4 6576.3 6588.8 6644.9 6651.0 6665.8 6834.0 6847.4 6944.4 6973.2 6550.9 6572.5 6578.9 6581.1 6583.3 6607.6 6785.6 6790.0 6796.2 6802.7 6810.3 6823.3 6906.9 6921.8 6944.8 6591.1 6599.1 6689.7 6699.4 6699.9 6562.2 6671.9 6675.9 6691.0 6693.6 6241.2 6421.6 6450.0 6526.2 6619.5 6637.6 6638.2 6685.8 6749.3 6760.0 6317.1 6459.9 6464.0 6574.2 6577.4 11:45:45 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | [#1] ext.html Cycles:180 Average:37912.48 Median:19593.97 stddev:55127.75 (281.4%) stddev-sans-first:55212.10 11:45:45 INFO - PID 4531 | Values: 835.8 849.8 850.2 857.1 863.9 40001.2 41569.0 41627.0 41654.6 41726.0 37715.0 37793.8 37877.4 37959.8 38073.8 7147.6 7271.1 7288.2 7289.8 7290.9 7170.1 7256.3 7263.0 7277.6 7302.7 7249.0 7283.3 7959.8 8169.5 8169.8 8093.0 8120.5 8158.2 8175.2 8179.5 7796.3 7963.5 7976.0 7984.2 8092.2 37795.8 37945.2 38059.4 38139.8 38147.2 3511.7 3562.5 3564.1 3566.7 3568.3 30854.5 30865.8 30871.5 30913.7 30973.4 5164.6 5195.9 5210.4 5220.6 5236.1 191167.0 191825.0 192335.0 192354.0 192974.0 47138.5 47193.5 47340.0 47430.8 47473.8 48853.5 49070.5 49206.0 49272.0 49292.0 15603.5 15706.5 15763.9 15790.4 15809.1 12733.7 12800.2 12807.7 12824.1 12839.6 13647.9 13785.1 13805.2 13843.2 13848.2 5658.6 5707.6 5738.8 5752.2 5797.2 994.7 1003.0 1003.9 1019.6 1020.4 22595.9 22945.0 23048.5 23072.9 23095.2 1098.2 1099.1 1108.4 1114.4 1119.5 12236.9 12364.4 12367.6 12391.6 12450.8 238429.0 239531.0 239692.0 241187.0 242260.0 51607.7 53305.7 57484.4 57621.1 57808.0 1428.8 1437.1 1451.5 1452.8 1457.2 26722.7 27036.7 27304.9 27418.6 27498.2 37286.0 37370.3 37459.3 37473.3 37645.0 201623.0 201849.0 203070.0 203122.0 203344.0 32238.0 33233.0 33247.2 33373.8 33412.0 42884.8 42889.6 43425.3 43443.0 43589.6 31287.1 31452.2 31723.8 32123.8 32400.0 31111.4 31280.8 31342.2 31536.4 32017.5 35625.5 35958.6 36118.6 36216.8 36263.5 30944.5 30959.4 31449.2 31529.4 31753.0 22843.3 23169.1 23281.5 23341.0 23518.3 11:45:45 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | [#2] jquery.html Cycles:180 Average:33177.02 Median:22424.94 stddev:75755.34 (337.8%) stddev-sans-first:75928.30 11:45:45 INFO - PID 4531 | Values: 568.5 573.5 580.5 589.4 592.9 21915.2 22711.4 22728.1 22730.4 22731.1 23202.5 24239.3 24484.1 24705.9 25067.7 6494.4 6688.1 6688.8 6690.8 6707.1 6564.3 6645.1 6657.5 6694.6 6705.3 5949.9 6483.4 6488.8 6492.0 6513.8 6479.9 6528.7 6531.2 6538.2 6546.0 5952.8 6041.6 6082.5 6210.5 6237.7 28805.9 29392.7 29394.3 29412.7 29425.1 2073.5 2086.0 2098.3 2101.8 2102.7 18682.5 19520.6 19556.2 19902.3 19980.7 2456.2 2466.8 2499.5 2501.1 2531.9 118187.0 123729.4 129445.4 137089.0 137951.3 14456.9 14496.1 14508.6 14842.9 14904.0 22292.2 22421.6 22428.3 22529.7 22564.2 10008.1 10161.5 10177.9 10313.7 10325.7 9435.3 9452.9 9471.8 9561.3 9633.5 8847.2 8954.0 9047.3 9085.6 9398.3 2528.4 2592.5 2595.2 2623.3 2762.8 939.9 973.5 982.1 985.0 987.1 19115.5 19204.5 19580.8 19728.3 19739.3 1030.2 1036.7 1054.0 1057.7 1066.6 10638.7 10809.0 10829.8 10888.7 10959.3 430271.0 443128.0 454092.0 455531.0 456966.0 110033.4 111815.0 112830.7 113721.4 114613.0 1023.1 1070.9 1151.2 1160.9 1221.4 19821.5 20053.1 20274.1 20287.0 20289.5 54493.3 55113.7 55593.0 56480.0 56926.7 70602.6 70884.3 70902.3 71575.3 71611.6 13846.2 14330.6 14349.6 14354.2 14364.4 21971.7 22346.4 22363.0 22558.7 22642.7 17846.6 18071.7 18212.1 18306.3 18310.9 16115.8 16470.1 16491.8 16756.6 16865.2 18550.5 18573.5 18694.8 18778.7 18831.4 14187.2 14246.4 14276.7 14314.4 14318.6 17939.6 17969.5 18105.1 18140.3 18371.1 11:45:45 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | [#3] mootools.html Cycles:180 Average:9638.22 Median:2738.75 stddev:7035.29 (256.9%) stddev-sans-first:7027.34 11:45:45 INFO - PID 4531 | Values: 1330.9 1389.5 1393.2 1397.3 1407.0 12664.8 13314.6 14405.8 14879.3 14892.0 15487.9 15726.8 15740.0 15740.6 15741.1 2697.5 2756.1 2758.7 2768.8 2898.8 2809.2 2836.6 2846.2 2848.4 2849.4 2730.0 2832.1 2833.2 2833.3 2838.8 2703.7 2747.5 2773.9 2783.9 2787.7 2618.9 2693.2 2773.0 2828.2 2828.5 15214.7 15339.0 15364.7 15372.6 15389.0 8062.0 8291.6 8328.2 8329.7 8352.2 5442.0 5596.5 5718.8 5767.9 5783.7 1852.2 1927.7 1939.3 1953.9 1976.8 27050.0 27876.0 27910.0 28039.2 28154.3 7000.5 7207.0 7209.5 7251.2 7255.1 17311.8 17970.8 18304.3 18315.0 18321.7 8350.7 8484.5 8522.4 8575.4 8581.9 7612.7 7653.3 7725.3 7798.1 7808.3 5324.6 5537.5 5549.4 5602.2 5659.1 1617.4 1618.7 1641.1 1645.2 1655.3 857.2 878.3 881.1 895.4 904.3 8242.8 8328.0 8375.7 8450.5 8459.0 732.8 737.1 738.1 744.7 752.0 4347.7 4473.5 4617.9 4800.2 5151.9 22050.6 22276.0 22680.7 23334.4 23446.6 23329.4 23415.3 23508.3 23540.6 23578.0 10753.0 11840.8 11891.0 11919.6 12057.6 8101.7 8216.8 8254.5 8271.3 8276.8 18933.5 19444.3 19497.0 19713.5 20055.8 20078.6 20259.4 20281.1 20364.0 20579.9 11024.2 11119.8 11199.1 11226.3 11251.9 14287.4 14459.3 14510.4 14545.9 14627.1 11418.3 11608.8 11617.9 11822.2 11928.3 11455.8 11483.4 11655.8 11713.0 11793.6 10087.2 10115.7 10119.2 10222.6 10224.1 7265.6 7359.0 7373.4 7375.2 7425.1 7111.9 7129.1 7216.4 7225.3 7234.2 11:45:45 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | [#4] prototype.html Cycles:180 Average:5568.68 Median:6218.13 stddev:1558.56 (25.1%) stddev-sans-first:1515.78 11:45:45 INFO - PID 4531 | Values: 499.8 516.9 537.5 538.8 541.3 6217.7 6355.9 6374.5 6399.8 6451.6 6137.6 6212.7 6218.6 6266.8 6279.2 5003.2 5031.8 5078.6 5087.8 5113.6 4930.4 5067.8 5075.3 5079.0 5101.5 4979.2 5034.8 5067.7 5068.7 5069.1 4962.2 4996.4 5014.9 5043.7 5054.4 4514.7 4545.0 4545.5 4577.4 4585.4 6215.0 6215.9 6223.2 6245.9 6257.5 5783.0 5784.9 5822.8 5846.9 5864.2 6542.4 6548.4 6577.5 6614.8 6636.1 4944.2 4953.9 4964.8 4975.8 4976.0 6823.6 6826.9 6957.6 6961.3 6991.7 5062.6 5073.5 5100.4 5104.1 5121.2 5162.1 5186.6 5193.4 5195.1 5199.7 5678.8 5711.4 5774.6 5818.5 5839.1 6326.7 6450.2 6591.9 6663.1 6689.0 1655.1 1728.4 1780.7 1782.7 1791.6 1214.9 1232.3 1236.2 1264.9 1269.4 5615.9 5642.0 5650.8 5651.6 5663.4 6781.9 6846.7 6880.7 6897.7 6898.8 5674.8 5681.0 5695.1 5715.0 5807.3 3552.4 3552.6 3562.4 3621.5 3633.5 6520.7 6590.7 6592.1 6620.9 6631.7 6994.1 7018.3 7031.4 7039.6 7072.4 6394.2 6442.1 6523.4 6576.5 6598.2 6609.7 6675.7 6788.8 6851.0 6864.9 6604.4 6757.4 6803.2 6806.5 6821.2 6535.2 6567.8 6574.6 6596.6 6614.5 5061.2 5089.5 5110.3 5142.3 5165.6 6572.3 6761.6 6800.0 6898.6 6961.7 6376.2 6463.5 6596.8 6603.9 6626.1 6585.8 6609.8 6636.2 6654.4 6674.6 6157.7 6487.6 6527.5 6529.7 6533.4 6516.0 6553.2 6607.2 6626.5 6627.8 6334.8 6361.9 6406.9 6453.9 6476.1 11:45:45 INFO - PID 4531 | 11:45:45 INFO - PID 4531 | [#5] yui.html Cycles:180 Average:24641.50 Median:19519.30 stddev:34765.41 (178.1%) stddev-sans-first:34817.14 11:45:45 INFO - PID 4531 | Values: 875.6 885.8 907.6 910.7 911.7 18914.5 19435.2 19477.8 19560.8 19584.0 18232.5 18360.4 18397.4 18403.9 18407.0 953.0 964.8 972.3 972.6 972.6 971.0 971.3 975.8 975.9 976.5 995.0 1017.0 1024.5 1024.6 1024.9 1008.3 1010.1 1015.7 1016.2 1016.8 722.5 730.5 739.8 740.5 740.8 20573.6 20679.6 20853.3 20870.9 20873.9 20884.5 21665.4 21948.0 22130.0 22182.8 15953.7 15980.5 16167.7 16401.6 16616.2 3618.3 3619.7 3621.9 3645.8 3657.5 105009.0 139138.0 139455.3 140722.3 144544.0 17325.2 17800.9 17953.2 18072.0 18080.5 32848.3 33036.0 33111.2 33147.7 33149.0 9454.1 9456.2 9540.9 9626.9 9636.2 4471.3 4500.9 4534.6 4542.4 4544.0 5305.7 5407.1 5531.5 5536.6 5600.8 1037.1 1052.1 1063.6 1065.9 1068.3 649.8 666.1 669.9 674.5 686.4 11620.5 11769.2 11820.9 12254.3 12349.9 659.9 672.1 675.4 679.0 680.3 5495.7 5564.6 5578.6 5600.4 5714.2 123933.0 124772.0 125372.0 125893.4 127106.4 85487.0 106575.5 111953.4 114047.0 115010.4 91991.0 94069.0 94342.5 94881.5 96700.5 12421.3 13041.8 13127.8 13138.4 13149.0 41674.4 41996.6 42489.8 42563.8 43591.6 55839.4 55898.4 57386.1 57784.7 58529.4 17874.9 18188.7 18233.9 18265.8 18301.2 18808.5 19044.5 19069.0 19195.9 19240.0 17248.0 17430.1 17468.1 17688.9 17733.4 18617.7 18770.9 18892.6 18943.8 19189.3 17373.4 17391.0 17422.1 17638.9 17705.7 13091.2 13235.5 13281.9 13337.5 13433.5 13376.9 13744.2 13886.7 13990.5 13996.4 11:45:45 INFO - PID 4531 | -------- Summary: end -------- 11:45:45 INFO - PID 4531 | 11:45:46 INFO - TEST-INFO | 4531: exit 0 11:45:46 INFO - TEST-OK | dromaeo_css | took 1109674ms 11:45:46 INFO - TEST-START | kraken 11:45:46 INFO - Initialising browser for kraken test... 11:45:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49911/getInfo.html -profile /tmp/tmpmhPQQQ/profile 11:45:46 INFO - TEST-INFO | started process 4902 (/builds/slave/test/build/application/firefox/firefox http://localhost:49911/getInfo.html) 11:45:52 INFO - TEST-INFO | 4902: exit 0 11:45:52 INFO - Browser initialized. 11:45:52 INFO - Running cycle 1/1 for kraken test... 11:45:52 INFO - Using env: {'DISPLAY': ':0', 11:45:52 INFO - 'HOME': '/home/cltbld', 11:45:52 INFO - 'JSGC_DISABLE_POISONING': '1', 11:45:52 INFO - 'LANG': 'en_US.UTF-8', 11:45:52 INFO - 'LANGUAGE': 'en_US:en', 11:45:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:45:52 INFO - 'LOGNAME': 'cltbld', 11:45:52 INFO - 'MAIL': '/var/mail/cltbld', 11:45:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:45:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:45:52 INFO - 'MOZ_CRASHREPORTER': '1', 11:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:45:52 INFO - 'MOZ_NO_REMOTE': '1', 11:45:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:45:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:52 INFO - 'NO_EM_RESTART': '1', 11:45:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:52 INFO - 'PWD': '/builds/slave/test', 11:45:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:45:52 INFO - 'RUST_BACKTRACE': 'full', 11:45:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:45:52 INFO - 'SHELL': '/bin/bash', 11:45:52 INFO - 'SHLVL': '1', 11:45:52 INFO - 'TERM': 'linux', 11:45:52 INFO - 'TMOUT': '86400', 11:45:52 INFO - 'USER': 'cltbld', 11:45:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664', 11:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:52 INFO - '_': '/tools/buildbot/bin/python'} 11:45:52 INFO - TEST-INFO | started process 5141 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmhPQQQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:53 INFO - PID 5141 | 11:45:53 INFO - PID 5141 | (/builds/slave/test/build/application/firefox/firefox:5190): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:45:53 INFO - PID 5141 | 11:46:03 INFO - PID 5141 | RSS: Main: 151474176 11:46:03 INFO - PID 5141 | 11:46:06 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49911/tests/kraken/driver.html?testName=audio-beat-detection) 11:46:07 INFO - PID 5141 | RSS: Main: 157249536 11:46:07 INFO - PID 5141 | 11:46:09 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49911/tests/kraken/driver.html?testName=audio-dft) 11:46:10 INFO - PID 5141 | RSS: Main: 157802496 11:46:10 INFO - PID 5141 | 11:46:13 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49911/tests/kraken/driver.html?testName=audio-fft) 11:46:14 INFO - PID 5141 | RSS: Main: 154828800 11:46:14 INFO - PID 5141 | 11:46:16 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49911/tests/kraken/driver.html?testName=audio-oscillator) 11:46:16 INFO - PID 5141 | RSS: Main: 155463680 11:46:16 INFO - PID 5141 | 11:46:18 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49911/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:46:19 INFO - PID 5141 | RSS: Main: 155332608 11:46:19 INFO - PID 5141 | 11:46:22 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49911/tests/kraken/driver.html?testName=imaging-darkroom) 11:46:23 INFO - PID 5141 | RSS: Main: 157372416 11:46:23 INFO - PID 5141 | 11:46:26 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49911/tests/kraken/driver.html?testName=imaging-desaturate) 11:46:27 INFO - PID 5141 | RSS: Main: 158105600 11:46:27 INFO - PID 5141 | 11:46:30 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49911/tests/kraken/driver.html?testName=json-parse-financial) 11:46:31 INFO - PID 5141 | RSS: Main: 157683712 11:46:31 INFO - PID 5141 | 11:46:32 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49911/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:46:33 INFO - PID 5141 | RSS: Main: 157794304 11:46:33 INFO - PID 5141 | 11:46:35 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:46:35 INFO - PID 5141 | RSS: Main: 155598848 11:46:35 INFO - PID 5141 | 11:46:37 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:46:38 INFO - PID 5141 | RSS: Main: 154963968 11:46:38 INFO - PID 5141 | 11:46:41 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:46:41 INFO - PID 5141 | RSS: Main: 155496448 11:46:41 INFO - PID 5141 | 11:46:44 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:46:45 INFO - PID 5141 | RSS: Main: 155435008 11:46:45 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49911/tests/kraken/driver.html?testName=ai-astar) 11:46:47 INFO - PID 5141 | RSS: Main: 156536832 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | __start_tp_report 11:46:47 INFO - PID 5141 | _x_x_mozilla_page_load 11:46:47 INFO - PID 5141 | _x_x_mozilla_page_load_details 11:46:47 INFO - PID 5141 | |i|pagename|runs| 11:46:47 INFO - PID 5141 | |0;ai-astar;110;107;107;110;108;108;111;111;107;109 11:46:47 INFO - PID 5141 | |1;audio-beat-detection;118;115;118;128;116;115;117;119;117;155 11:46:47 INFO - PID 5141 | |2;audio-dft;161;161;161;166;164;162;163;169;163;166 11:46:47 INFO - PID 5141 | |3;audio-fft;74;77;74;74;73;74;75;75;76;75 11:46:47 INFO - PID 5141 | |4;audio-oscillator;79;80;80;81;82;79;79;79;80;79 11:46:47 INFO - PID 5141 | |5;imaging-gaussian-blur;114;115;113;114;113;113;114;115;115;113 11:46:47 INFO - PID 5141 | |6;imaging-darkroom;148;148;149;149;150;148;150;150;149;148 11:46:47 INFO - PID 5141 | |7;imaging-desaturate;99;95;94;95;99;99;100;95;99;95 11:46:47 INFO - PID 5141 | |8;json-parse-financial;53;52;53;51;51;52;57;54;54;50 11:46:47 INFO - PID 5141 | |9;json-stringify-tinderbox;38;41;38;38;38;39;38;38;39;39 11:46:47 INFO - PID 5141 | |10;stanford-crypto-aes;76;80;76;73;73;77;75;74;73;73 11:46:47 INFO - PID 5141 | |11;stanford-crypto-ccm;114;110;115;125;122;116;113;112;115;113 11:46:47 INFO - PID 5141 | |12;stanford-crypto-pbkdf2;182;173;166;175;171;168;171;176;165;170 11:46:47 INFO - PID 5141 | |13;stanford-crypto-sha256-iterative;66;65;65;72;67;68;65;66;70;68 11:46:47 INFO - PID 5141 | __end_tp_report 11:46:47 INFO - PID 5141 | __start_cc_report 11:46:47 INFO - PID 5141 | _x_x_mozilla_cycle_collect,773 11:46:47 INFO - PID 5141 | __end_cc_report 11:46:47 INFO - PID 5141 | __startTimestamp1506106007279__endTimestamp 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | ------- Summary: start ------- 11:46:47 INFO - PID 5141 | Number of tests: 14 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#0] ai-astar Cycles:10 Average:108.80 Median:109.50 stddev:1.62 (1.5%) stddev-sans-first:1.66 11:46:47 INFO - PID 5141 | Values: 110.0 107.0 107.0 110.0 108.0 108.0 111.0 111.0 107.0 109.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:118.00 stddev:12.25 (10.4%) stddev-sans-first:12.91 11:46:47 INFO - PID 5141 | Values: 118.0 115.0 118.0 128.0 116.0 115.0 117.0 119.0 117.0 155.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.50 stddev:2.67 (1.6%) stddev-sans-first:2.67 11:46:47 INFO - PID 5141 | Values: 161.0 161.0 161.0 166.0 164.0 162.0 163.0 169.0 163.0 166.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#3] audio-fft Cycles:10 Average:74.70 Median:75.00 stddev:1.16 (1.5%) stddev-sans-first:1.20 11:46:47 INFO - PID 5141 | Values: 74.0 77.0 74.0 74.0 73.0 74.0 75.0 75.0 76.0 75.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:80.00 stddev:1.03 (1.3%) stddev-sans-first:1.05 11:46:47 INFO - PID 5141 | Values: 79.0 80.0 80.0 81.0 82.0 79.0 79.0 79.0 80.0 79.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 11:46:47 INFO - PID 5141 | Values: 114.0 115.0 113.0 114.0 113.0 113.0 114.0 115.0 115.0 113.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.88 (0.6%) stddev-sans-first:0.87 11:46:47 INFO - PID 5141 | Values: 148.0 148.0 149.0 149.0 150.0 148.0 150.0 150.0 149.0 148.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:97.00 stddev:2.36 (2.4%) stddev-sans-first:2.39 11:46:47 INFO - PID 5141 | Values: 99.0 95.0 94.0 95.0 99.0 99.0 100.0 95.0 99.0 95.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:2.00 (3.8%) stddev-sans-first:2.12 11:46:47 INFO - PID 5141 | Values: 53.0 52.0 53.0 51.0 51.0 52.0 57.0 54.0 54.0 50.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:38.50 stddev:0.97 (2.5%) stddev-sans-first:1.00 11:46:47 INFO - PID 5141 | Values: 38.0 41.0 38.0 38.0 38.0 39.0 38.0 38.0 39.0 39.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:75.50 stddev:2.31 (3.1%) stddev-sans-first:2.42 11:46:47 INFO - PID 5141 | Values: 76.0 80.0 76.0 73.0 73.0 77.0 75.0 74.0 73.0 73.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#11] stanford-crypto-ccm Cycles:10 Average:115.50 Median:115.00 stddev:4.60 (4.0%) stddev-sans-first:4.85 11:46:47 INFO - PID 5141 | Values: 114.0 110.0 115.0 125.0 122.0 116.0 113.0 112.0 115.0 113.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:5.08 (3.0%) stddev-sans-first:3.78 11:46:47 INFO - PID 5141 | Values: 182.0 173.0 166.0 175.0 171.0 168.0 171.0 176.0 165.0 170.0 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:2.35 (3.5%) stddev-sans-first:2.45 11:46:47 INFO - PID 5141 | Values: 66.0 65.0 65.0 72.0 67.0 68.0 65.0 66.0 70.0 68.0 11:46:47 INFO - PID 5141 | -------- Summary: end -------- 11:46:47 INFO - PID 5141 | 11:46:47 INFO - PID 5141 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:46:47 INFO - TEST-INFO | 5141: exit 0 11:46:47 INFO - TEST-OK | kraken | took 61377ms 11:46:47 INFO - SUITE-END | took 1171s 11:46:48 INFO - Completed test suite (00:19:31) 11:46:48 INFO - javascript benchmark 11:46:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8598.19667399558, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3138.8273644949527, 3143.4027534768393, 3146.4493713600114, 3149.9685003149975, 3152.1646763607646, 6243.406876346739, 6316.336784637616, 6334.588251763632, 6364.109024736541, 6441.838954026146, 6315.8105256842255, 6328.778492752755, 6332.1135041094285, 6334.714937827797, 6343.587666801654, 5854.941450585482, 5869.7652093916295, 5882.911756323659, 5899.203607512995, 5922.911156332659, 5857.560682948796, 5881.617694849842, 5940.910886336708, 5972.372900845414, 6002.639841609495, 5884.823455296348, 5964.761409543613, 5985.970070149643, 5991.850203744898, 6008.158857759917, 5666.291713535808, 5759.992001399738, 5906.05503119501, 5962.046072628402, 6007.789727359522, 5267.578593712494, 5733.483986441199, 5761.53907687384, 5879.617824841414, 5898.587098903078, 6344.111824344595, 6356.682165891687, 6360.0459931010255, 6418.101465794793, 6445.484361251089, 6597.63712995785, 6664.966675166642, 6768.018637297613, 6797.082393876813, 6899.137607799025, 6857.245702972669, 6862.588244705334, 6867.07294515244, 6912.412444942186, 6920.411764999981, 6309.08518264849, 6364.872702545969, 6369.299377068519, 6376.043593460971, 6400.039994000937, 7012.614306213105, 7022.4733145014325, 7033.8944915826305, 7104.7158113675905, 7107.040549525748, 6558.245801732862, 6601.240857301375, 6650.70071846768, 6751.898721519181, 6767.0, 6763.0, 6885.724571017204, 6886.345797149263, 6893.0694356261265, 6920.619365934922, 6751.459883209332, 6768.966155169291, 6859.588424694534, 6862.107925969592, 6886.655667216619, 6749.122614060141, 6768.69540870662, 6848.589084654936, 6866.141732283465, 6876.106106206161, 5157.200633901752, 5278.0, 5294.285271488377, 5333.359996800331, 5353.759080841293, 4676.415448068991, 4711.552402521755, 4736.597389221886, 4739.0, 4760.523947605212, 5710.600257981901, 5806.071028635398, 5855.707214639251, 5857.26784151981, 5868.38381969887, 6699.799006029827, 6812.386885180357, 6841.93158068413, 6945.51381403307, 6948.93051069493, 5601.04782187021, 5686.289213848269, 5752.252207213036, 5754.942450575524, 5765.827025189167, 5848.61983971049, 5886.1465087562065, 5897.115432685046, 5900.0, 5910.852228694231, 6573.441257493167, 6576.309487503839, 6588.8023359299295, 6644.900326495106, 6650.999999999904, 6665.766698165541, 6833.965830170818, 6847.417969472452, 6944.375006249462, 6973.163220413583, 6550.868982620416, 6572.539922205399, 6578.934210658023, 6581.07864898905, 6583.30875258091, 6607.603543787388, 6785.626790526569, 6789.966050169717, 6796.184457865088, 6802.6598670065305, 6810.31896810315, 6823.317668233137, 6906.930930690629, 6921.792346229621, 6944.756933507303, 6591.077249185214, 6599.142111525471, 6689.665516724047, 6699.430548403246, 6699.933000669931, 6562.245341785565, 6671.8665626686225, 6675.899861501985, 6691.0, 6693.598384096969, 6241.157443745218, 6421.614703117828, 6450.032495125769, 6526.24948130952, 6619.470442364503, 6637.601743895381, 6638.17022872141, 6685.76599819004, 6749.291324410866, 6759.966200168968, 6317.115603815561, 6459.935400645933, 6463.967680161569, 6574.178227721535, 6577.375149360803], "value": 6203.409649485846, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [835.7534527314442, 849.7705619482736, 850.188070392775, 857.0914830279904, 863.8920134983127, 40001.19997600046, 41568.99999999996, 41627.0, 41654.58345416545, 41726.0, 37715.0, 37793.81103094481, 37877.43183852245, 37959.81020094896, 38073.80963095187, 7147.642617869112, 7271.127464704243, 7288.161861385929, 7289.817754556126, 7290.854182916339, 7170.067891174166, 7256.346928776409, 7263.0, 7277.599732014737, 7302.744403945863, 7249.0, 7283.308085731874, 7959.840803183933, 8169.468984516017, 8169.836603267931, 8093.0, 8120.512769233865, 8158.184181581853, 8175.182481751836, 8179.468334558234, 7796.337311328544, 7963.482373645695, 7976.0, 7984.201579842027, 8092.150324215991, 37795.81102094472, 37945.241095178215, 38059.42910856312, 38139.809300953595, 38147.23705525874, 3511.7015053720465, 3562.4834399012257, 3564.0733409313507, 3566.7146628269675, 3568.286342731464, 30854.537181942287, 30865.845670771727, 30871.536926946115, 30913.69086309153, 30973.380532389452, 5164.561012313958, 5195.9480405195845, 5210.400803907562, 5220.634555581111, 5236.136037553799, 191167.0, 191825.0, 192334.9999999986, 192354.0, 192974.0, 47138.52861471342, 47193.5280647196, 47340.0, 47430.76284618624, 47473.762631186626, 48853.51146488561, 49070.509294906595, 49206.0, 49272.0, 49292.0, 15603.453879114177, 15706.450274240347, 15763.921180394254, 15790.447334343244, 15809.1304978225, 12733.745325093447, 12800.16798908068, 12807.743845123045, 12824.102312838198, 12839.614811555668, 13647.931760341135, 13785.13900623423, 13805.24071176095, 13843.169409835442, 13848.16910985324, 5658.603897727119, 5707.600467967202, 5738.8278351649515, 5752.181431098731, 5797.159411885253, 994.7413672445216, 1002.9949850250702, 1003.8996100389902, 1019.6176433837311, 1020.3826684855704, 22595.88702056479, 22945.0, 23048.539029219322, 23072.884635576716, 23095.191668291864, 1098.1983152298867, 1099.0987390340003, 1108.4236197177422, 1114.3815182573749, 1119.5242022140558, 12236.877631223751, 12364.44360003802, 12367.62897113088, 12391.62825115248, 12450.81323780144, 238429.0, 239531.0, 239692.0, 241187.0, 242260.0, 51607.741961289954, 53305.733471332394, 57484.425155748744, 57621.13568296479, 57808.0, 1428.7571112910623, 1437.094630382852, 1451.5137428961416, 1452.7966084747932, 1457.162131774234, 26722.655468906687, 27036.72963270342, 27304.863475682494, 27418.5887211696, 27498.17505474839, 37286.0, 37370.25259494849, 37459.250814984094, 37473.250534988605, 37645.0, 201623.0, 201849.0, 203070.0, 203122.0, 203344.0, 32238.0, 33233.0, 33247.168820779676, 33373.83313083516, 33412.0, 42884.78557607192, 42889.571104289804, 43425.34861977136, 43443.0, 43589.56410435982, 31287.061388158392, 31452.213694657818, 31723.84138079295, 32123.839380802947, 32400.0, 31111.444427778068, 31280.843595781877, 31342.216444589067, 31536.36927261396, 32017.519737203496, 35625.46561801627, 35958.64041359553, 36118.638813612575, 36216.81891590525, 36263.45604815877, 30944.53583196299, 30959.380812384075, 31449.213769655027, 31529.36941261208, 31753.0, 22843.321956314405, 23169.07323707033, 23281.53436931218, 23341.0, 23518.294451166494], "value": 17129.05711256374, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.5480043365527, 573.4781348972435, 580.5094694982738, 589.4311988930682, 592.9495992840608, 21915.232966846164, 22711.432214194654, 22728.090876364946, 22730.431739206528, 22731.090756369766, 23202.535949281046, 24239.272821815328, 24484.143054993077, 24705.876470617666, 25067.74932250677, 6494.415502604765, 6688.1305430294, 6688.765893193739, 6690.83272918176, 6707.128073350457, 6564.343565643445, 6645.102911106987, 6657.467402607804, 6694.564853284545, 6705.2624211336715, 5949.85125371867, 6483.351664833503, 6488.7728929487475, 6491.967540162316, 6513.772017979371, 6479.870402591945, 6528.738850445977, 6531.216254049497, 6538.1827271591055, 6545.967270163643, 5952.791652292192, 6041.577089603729, 6082.513398928119, 6210.50315974721, 6237.656928868911, 28805.85597072001, 29392.706072939425, 29394.265143371373, 29412.705872941424, 29425.11724648264, 2073.460900165968, 2085.979140208594, 2098.286582561921, 2101.8318534517202, 2102.6845973104005, 18682.532936676555, 19520.609587808165, 19556.217751289932, 19902.303419380252, 19980.700289495668, 2456.152627343564, 2466.8273220874544, 2499.46261553765, 2501.124606387768, 2531.860747658878, 118187.0, 123729.38135309356, 129445.35277323553, 137089.0, 137951.31024344813, 14456.85543144555, 14496.142386392761, 14508.564743057725, 14842.851237817289, 14904.0, 22292.21977230789, 22421.55156896853, 22428.32715018552, 22529.662055069188, 22564.210252641406, 10008.14930730896, 10161.54273057714, 10177.898221017695, 10313.690589282334, 10325.690229292983, 9435.29235307341, 9452.90547094534, 9471.810563788686, 9561.330706850455, 9633.518324083769, 8847.159519845605, 8954.0, 9047.276217902552, 9085.591148398338, 9398.295127865438, 2528.3552694062896, 2592.468543948486, 2595.156574113421, 2623.34416395901, 2762.778977681781, 939.915407613318, 973.5132433783108, 982.0621306652273, 984.9704508864745, 987.0968064221157, 19115.522111947314, 19204.519887002938, 19580.804191958185, 19728.30950916711, 19739.309124180872, 1030.160419258302, 1036.683811437519, 1053.963111291101, 1057.7197042783669, 1066.6266806617778, 10638.680839574825, 10809.0, 10829.83755243672, 10888.727781805359, 10959.342439453658, 430271.0, 443128.0, 454092.0, 455531.0, 456966.0, 110033.44983275032, 111815.0, 112830.74338513183, 113721.43139284416, 114613.0, 1023.1405619279841, 1070.8661417322835, 1151.176908510419, 1160.8839116088322, 1221.3709939381404, 19821.50446238798, 20053.09761060785, 20274.08766605477, 20287.0, 20289.49276268105, 54493.25351530035, 55113.72443137759, 55593.0, 56480.0, 56926.715366422904, 70602.64698676474, 70884.29115708776, 70902.29097708956, 71575.28424715686, 71611.64194178996, 13846.169229845838, 14330.641733956734, 14349.56951291463, 14354.210518421587, 14364.35360408805, 21971.67042494332, 22346.441338966655, 22363.0, 22558.66162007604, 22642.660360094284, 17846.643067138844, 18071.728924065887, 18212.089395529907, 18306.26774928988, 18310.90844545822, 16115.838841611903, 16470.09414482215, 16491.83508164903, 16756.58108547296, 16865.241064151898, 18550.536236594195, 18573.535661608566, 18694.813051869853, 18778.71831922495, 18831.435056948314, 14187.219702916025, 14246.358913848695, 14276.71446571042, 14314.355853986388, 14318.642033948818, 17939.641207175522, 17969.460916172535, 18105.09474526242, 18140.27438902482, 18371.081445927917], "value": 11867.347667209951, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.9401076951538, 1389.4650559534575, 1393.184986782732, 1397.308332375474, 1407.0221196268592, 12664.810027849579, 13314.600561983143, 14405.78391324131, 14879.330430130643, 14892.0, 15487.922560387211, 15726.84273157268, 15740.0, 15740.606484837885, 15741.134237616927, 2697.5009623219717, 2756.0629386008754, 2758.6965433802266, 2768.8338699677984, 2898.8115772474825, 2809.19937817722, 2836.6454193225845, 2846.1746093632823, 2848.3591191981845, 2849.4443583501225, 2729.986350068247, 2832.065418411919, 2833.220864262334, 2833.2916770807296, 2838.843863587502, 2703.7025927147997, 2747.5054490191756, 2773.861306934645, 2783.8886444542095, 2787.707290734474, 2618.869056547165, 2693.2324287577944, 2772.9584056238973, 2828.1515545336315, 2828.533292006837, 15214.695706085928, 15339.0, 15364.692706145814, 15372.615684607863, 15389.0, 8061.95969020157, 8291.626876790559, 8328.208820162075, 8329.66681332746, 8352.248297653241, 5441.972790136064, 5596.488947984062, 5718.828435146952, 5767.855803604902, 5783.71081445926, 1852.1757817771097, 1927.6626590346773, 1939.2533874458295, 1953.8534609904173, 1976.8023197680118, 27050.0, 27876.0, 27910.023149189885, 28039.158825235274, 28154.296142596395, 7000.47496437759, 7207.027051348001, 7209.531380460253, 7251.166115896739, 7255.129384473791, 17311.82688173102, 17970.820291797176, 18304.26782928668, 18315.0, 18321.7251741224, 8350.707725229588, 8484.490930544187, 8522.360822938304, 8575.442596231225, 8581.914180858237, 7612.733554325572, 7653.311201991847, 7725.343345815669, 7798.103218129874, 7808.297253247235, 5324.5740340772645, 5537.529310008695, 5549.361823390281, 5602.159676048544, 5659.122835960433, 1617.3934774459578, 1618.7014439495945, 1641.1137985487942, 1645.1609679063763, 1655.2882260628046, 857.1813917708573, 878.3280790195505, 881.1320848963766, 895.4314010603314, 904.2720609908955, 8242.835143297101, 8328.0, 8375.74872753806, 8450.492970421672, 8458.957705211435, 732.8167958010497, 737.0823324960459, 738.1400668221569, 744.6649007946446, 751.9962400187964, 4347.739135651871, 4473.485549161823, 4617.884552886205, 4800.231962885921, 5151.896962060738, 22050.558988820132, 22276.0, 22680.65979010316, 23334.416639583807, 23446.64830027551, 23329.416764581023, 23415.297541073796, 23508.294751157493, 23540.64689029666, 23578.0, 10753.0, 11840.76318473643, 11891.0, 11919.642410727693, 12057.57798477049, 8101.716439924574, 8216.83566328682, 8254.463459875089, 8271.255586997198, 8276.793080173044, 18933.526661833563, 19444.319448819224, 19497.0, 19713.507162321057, 20055.799442005395, 20078.598428031066, 20259.392218233475, 20281.08735106894, 20364.0, 20579.8971005144, 11024.228304018641, 11119.83320250213, 11199.10407167441, 11226.270292430965, 11251.943740281245, 14287.357068932131, 14459.349329280418, 14510.419583216553, 14545.85454145445, 14627.122372657675, 11418.31490110596, 11608.767824643628, 11617.88382116168, 11822.231554948901, 11928.343941083323, 11455.770884582094, 11483.36841473727, 11655.825162622397, 11713.0, 11793.646190614296, 10087.243456740627, 10115.696529104138, 10119.190464762949, 10222.591096356062, 10224.13094886943, 7265.564066155836, 7359.043324367798, 7373.446991475768, 7375.188729239672, 7425.071866016748, 7111.857762844817, 7129.073220481304, 7216.386607138452, 7225.313595208447, 7234.16807067194], "value": 6800.933695335885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.8125702861427, 516.8733660253238, 537.4652221040063, 538.8087229033694, 541.3449725831744, 6217.689115544222, 6355.872882542347, 6374.490040796737, 6399.7760078397205, 6451.645159516236, 6137.6370097512445, 6212.65830379329, 6218.564700470969, 6266.84332891678, 6279.15231443755, 5003.249512573107, 5031.798728050873, 5078.64449488536, 5087.7710503027265, 5113.616478764089, 4930.4330002049865, 5067.8479645610505, 5075.340205773263, 5078.9622250311095, 5101.464346243647, 4979.203327467606, 5034.773435195407, 5067.721275329855, 5068.695878247317, 5069.149760337469, 4962.206047032476, 4996.3504744383, 5014.899702005958, 5043.747812609355, 5054.418741844698, 4514.7291162530355, 4544.954550454486, 4545.477270113946, 4577.359169716243, 4585.358049873021, 6214.9689251553455, 6215.937840621581, 6223.159873417121, 6245.906311405332, 6257.468115210213, 5782.992843721284, 5784.942150578482, 5822.767089316422, 5846.853828654275, 5864.179014937912, 6542.378474044958, 6548.380647740894, 6577.539572229945, 6614.809334319821, 6636.117871045444, 4944.233643785219, 4953.925691114636, 4964.801407943678, 4975.776090075955, 4975.950240497585, 6823.624700641512, 6826.897596536056, 6957.582545047263, 6961.338672826073, 6991.650417479105, 5062.620303477254, 5073.543381095719, 5100.38795344561, 5104.132297509433, 5121.206213036985, 5162.148245539519, 5186.559142472856, 5193.402758682724, 5195.116830138885, 5199.688018718889, 5678.801241956594, 5711.428857114255, 5774.624649397776, 5818.5345172386105, 5839.124131380327, 6326.6520341381665, 6450.225972883284, 6591.901121483182, 6663.06717059612, 6688.966555167291, 1655.147598986523, 1728.3777839977597, 1780.7445750745694, 1782.7236778299384, 1791.605846713721, 1214.8519648931851, 1232.3037483821613, 1236.2458900070949, 1264.905132115095, 1269.4033804112053, 5615.8876822464135, 5642.0, 5650.802221922213, 5651.57613179005, 5663.433656634386, 6781.932180678229, 6846.657667116624, 6880.724771009103, 6897.7240910363025, 6898.758543451056, 5674.773009079673, 5681.0, 5695.145728140812, 5715.0, 5807.274090738658, 3552.3960926642535, 3552.644735526478, 3562.3943929532043, 3621.511096002006, 3633.4913112164318, 6520.739170433129, 6590.670466476753, 6592.143021407186, 6620.933790662128, 6631.734730610721, 6994.132405713633, 7018.33325834045, 7031.402330801837, 7039.648017599099, 7072.398846098036, 6394.23269207698, 6442.06590044441, 6523.412892839572, 6576.506761992774, 6598.241202261705, 6609.669516524251, 6675.7329706813125, 6788.76239331621, 6851.0, 6864.9245417804605, 6604.3725846044545, 6757.358050985148, 6803.217629972616, 6806.46854457743, 6821.1814582250445, 6535.183102112236, 6567.802965911031, 6574.605523668595, 6596.637184954873, 6614.470842332698, 5061.190209566452, 5089.465606111453, 5110.3356563646485, 5142.280080788767, 5165.61257905651, 6572.309907459648, 6761.599632130719, 6799.966000169968, 6898.586084834926, 6961.651917404009, 6376.171097757262, 6463.482921366185, 6596.8350791230605, 6603.943369060928, 6626.072349870926, 6585.847476691464, 6609.834754131185, 6636.170478690164, 6654.434373078343, 6674.599524028574, 6157.72290246931, 6487.643179624977, 6527.477801775752, 6529.723031414431, 6533.41199292066, 6516.012616152119, 6553.2136143663065, 6607.174103237096, 6626.53614246998, 6627.768028118992, 6334.809955701337, 6361.8727625448155, 6406.9359306408205, 6453.9354606453335, 6476.125723027331], "value": 5111.335178904695, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [875.6366108065151, 885.7652722028663, 907.5598334807621, 910.7131253655102, 911.6535716427759, 18914.527136821587, 19435.222591096375, 19477.805221947776, 19560.804391956077, 19584.0, 18232.544186395346, 18360.35738749144, 18397.448076557692, 18403.90798046008, 18407.0, 953.046953046953, 964.768455570665, 972.3485264872552, 972.6206779356057, 972.6401231544303, 970.9757256068584, 971.2715463402449, 975.7609385700513, 975.878015248094, 976.5263847034183, 994.9651762188325, 1016.9694909152737, 1024.5184763161303, 1024.5952848624788, 1024.8975102489767, 1008.2538921198334, 1010.0555980158575, 1015.7003683913226, 1016.232744278069, 1016.8322226832564, 722.4617659843398, 730.5251586468785, 739.7780665800292, 740.489062546841, 740.8259059121102, 20573.588528229502, 20679.58640827175, 20853.270135545336, 20870.895645521676, 20873.89563052175, 20884.477888052767, 21665.35003949884, 21948.012339444762, 22130.0, 22182.778172218234, 15953.680926381407, 15980.520584382488, 16167.676646467122, 16401.58996025109, 16616.169191540375, 3618.294432585647, 3619.6561326674187, 3621.8551257949653, 3645.7994810285422, 3657.487951686766, 105009.0, 139138.0, 139455.30272348574, 140722.2963885184, 144544.0, 17325.22036508335, 17800.91099544494, 17953.192106355244, 18072.0, 18080.547986300448, 32848.3430331392, 33036.0, 33111.17222069467, 33147.66852331494, 33149.0, 9454.05459454049, 9456.243500519942, 9540.856887146698, 9626.855597166048, 9636.180924621345, 4471.329300604935, 4500.887477813081, 4534.637229021736, 4542.432195975503, 4543.977280113579, 5305.708186049805, 5407.080796264566, 5531.502164805187, 5536.557075434036, 5600.831975040755, 1037.0821822686826, 1052.084686322904, 1063.6064656077147, 1065.9040686338267, 1068.2682362581502, 649.7790751144634, 666.1306994372294, 669.912911321525, 674.5042393840523, 686.4405509509734, 11620.477078531487, 11769.234999724991, 11820.88179118215, 12254.326012069243, 12349.876501235052, 659.864727730814, 672.1463741048939, 675.3584095109666, 678.9694463749144, 680.2755065854849, 5495.697736624444, 5564.610477266552, 5578.553715702815, 5600.355959064678, 5714.200011998323, 123933.0, 124772.0, 125372.0, 125893.37053314675, 127106.36446817707, 85487.0, 106575.46712266545, 111953.44023279831, 114047.0, 115010.42494787472, 91991.0, 94069.0, 94342.5282873595, 94881.52559237297, 96700.51649741706, 12421.31682757457, 13041.804372934603, 13127.803082953573, 13138.408771605109, 13149.0, 41674.374884377365, 41996.580034199265, 42489.78755106204, 42563.7871810639, 43591.56408435875, 55839.44160558342, 55898.44101558932, 57386.13920791108, 57784.71107644435, 58529.414705852396, 17874.910625447308, 18188.72716909221, 18233.908830455763, 18265.817341826412, 18301.17644706018, 18808.52978675544, 19044.523886902385, 19069.046547672282, 19195.904020479808, 19240.0, 17248.0, 17430.128493575525, 17468.12659367052, 17688.91155544214, 17733.37933172333, 18617.720734188726, 18770.906145469184, 18892.622147557246, 18943.810561894206, 19189.32837350686, 17373.391931282345, 17391.0, 17422.128893555524, 17638.91180544089, 17705.734413984057, 13091.214527128403, 13235.470581176645, 13281.867181328449, 13337.466501339837, 13433.462661493431, 13376.933115334361, 13744.175349479063, 13886.652833678834, 13990.510332138327, 13996.37016334247], "value": 9217.19821142617, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1429.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 107.0, 107.0, 110.0, 108.0, 108.0, 111.0, 111.0, 107.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 118.0, 128.0, 116.0, 115.0, 117.0, 119.0, 117.0, 155.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 166.0, 164.0, 162.0, 163.0, 169.0, 163.0, 166.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 74.0, 74.0, 73.0, 74.0, 75.0, 75.0, 76.0, 75.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 80.0, 81.0, 82.0, 79.0, 79.0, 79.0, 80.0, 79.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 114.0, 113.0, 113.0, 114.0, 115.0, 115.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 149.0, 150.0, 148.0, 150.0, 150.0, 149.0, 148.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 95.0, 94.0, 95.0, 99.0, 99.0, 100.0, 95.0, 99.0, 95.0], "value": 97.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 51.0, 51.0, 52.0, 57.0, 54.0, 54.0, 50.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 41.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 39.0, 39.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 80.0, 76.0, 73.0, 73.0, 77.0, 75.0, 74.0, 73.0, 73.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 115.0, 125.0, 122.0, 116.0, 113.0, 112.0, 115.0, 113.0], "value": 115.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 173.0, 166.0, 175.0, 171.0, 168.0, 171.0, 176.0, 165.0, 170.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 72.0, 67.0, 68.0, 65.0, 66.0, 70.0, 68.0], "value": 67.2, "unit": "score"}]}]} 11:46:48 INFO - Return code: 0 11:46:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:46:48 INFO - # TBPL SUCCESS # 11:46:48 INFO - Running post-action listener: _package_coverage_data 11:46:48 INFO - Running post-action listener: _resource_record_post_action 11:46:48 INFO - [mozharness: 2017-09-22 18:46:48.588581Z] Finished run-tests step (success) 11:46:48 INFO - Running post-run listener: _resource_record_post_run 11:46:48 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 350224384; Read time: 8; Write time: 320000 11:46:48 INFO - TinderboxPrint: CPU usage
13.1% 11:46:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 11:46:48 INFO - TinderboxPrint: I/O write bytes / time
350,224,384 / 320,000 11:46:48 INFO - TinderboxPrint: CPU idle
8,240.6 (86.9%) 11:46:48 INFO - TinderboxPrint: CPU user
1,195.2 (12.6%) 11:46:48 INFO - TinderboxPrint: Swap in / out
0 / 0 11:46:48 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13352960; Read time: 0; Write time: 1576 11:46:48 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:46:48 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 336871424; Read time: 0; Write time: 318424 11:46:49 INFO - Running post-run listener: _upload_blobber_files 11:46:49 INFO - Blob upload gear active. 11:46:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:46:49 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 11:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:46:49 INFO - (blobuploader) - INFO - Open directory for files ... 11:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:50 INFO - (blobuploader) - INFO - Done attempting. 11:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:51 INFO - (blobuploader) - INFO - Done attempting. 11:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:46:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:52 INFO - (blobuploader) - INFO - Done attempting. 11:46:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:46:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:55 INFO - (blobuploader) - INFO - Done attempting. 11:46:55 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:56 INFO - Return code: 0 11:46:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/361e22347a70bd5513fb65afd9275f843ea0d96015c5215ca79d63e1877548d4ffebba8293b5cd6a7952d064bde4afa41ed72ab2a2c2d989974fbe683354ce1e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/12f84e794706f6a3100018897171c6a6e09b0b55e175b7e44d4a7990d56b60e0989586deb45c33bdc0393b65bf2d4217a42e29ebe490b7e2f26d9e2d7841e498", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0815c10b3cee188b082d4a6e5b34bb8e7694ad372d1cb7e841165f8a370462019d0a31524e12e8a21c21bb0eed7f316f84d43de711f4e3437a83bbb6000a1102", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c3424588a39046c08112a8a17949e027a4468b81ee3ba94a460154952f838446535f6eb86ab2d04f460e468b7a66adbc1390760906294f2a64a4a6341741c6c"} 11:46:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:56 INFO - Contents: 11:46:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/361e22347a70bd5513fb65afd9275f843ea0d96015c5215ca79d63e1877548d4ffebba8293b5cd6a7952d064bde4afa41ed72ab2a2c2d989974fbe683354ce1e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/12f84e794706f6a3100018897171c6a6e09b0b55e175b7e44d4a7990d56b60e0989586deb45c33bdc0393b65bf2d4217a42e29ebe490b7e2f26d9e2d7841e498", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0815c10b3cee188b082d4a6e5b34bb8e7694ad372d1cb7e841165f8a370462019d0a31524e12e8a21c21bb0eed7f316f84d43de711f4e3437a83bbb6000a1102", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c3424588a39046c08112a8a17949e027a4468b81ee3ba94a460154952f838446535f6eb86ab2d04f460e468b7a66adbc1390760906294f2a64a4a6341741c6c"} 11:46:56 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:56 INFO - Copying logs to upload dir... 11:46:56 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.082516 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-22 11:46:56.304449) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 11:46:56.308897) ========= 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=c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664 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-Non-PGO/sha512/361e22347a70bd5513fb65afd9275f843ea0d96015c5215ca79d63e1877548d4ffebba8293b5cd6a7952d064bde4afa41ed72ab2a2c2d989974fbe683354ce1e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/12f84e794706f6a3100018897171c6a6e09b0b55e175b7e44d4a7990d56b60e0989586deb45c33bdc0393b65bf2d4217a42e29ebe490b7e2f26d9e2d7841e498", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0815c10b3cee188b082d4a6e5b34bb8e7694ad372d1cb7e841165f8a370462019d0a31524e12e8a21c21bb0eed7f316f84d43de711f4e3437a83bbb6000a1102", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c3424588a39046c08112a8a17949e027a4468b81ee3ba94a460154952f838446535f6eb86ab2d04f460e468b7a66adbc1390760906294f2a64a4a6341741c6c"} build_url:https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011617 build_url: 'https://queue.taskcluster.net/v1/task/O2QMI2-cQHqjA_lN8nP03w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/361e22347a70bd5513fb65afd9275f843ea0d96015c5215ca79d63e1877548d4ffebba8293b5cd6a7952d064bde4afa41ed72ab2a2c2d989974fbe683354ce1e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/12f84e794706f6a3100018897171c6a6e09b0b55e175b7e44d4a7990d56b60e0989586deb45c33bdc0393b65bf2d4217a42e29ebe490b7e2f26d9e2d7841e498", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0815c10b3cee188b082d4a6e5b34bb8e7694ad372d1cb7e841165f8a370462019d0a31524e12e8a21c21bb0eed7f316f84d43de711f4e3437a83bbb6000a1102", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c3424588a39046c08112a8a17949e027a4468b81ee3ba94a460154952f838446535f6eb86ab2d04f460e468b7a66adbc1390760906294f2a64a4a6341741c6c"}' ========= master_lag: 0.15 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 11:46:56.465769) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 11:46:56.466101) ========= 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=c6927cf182745ad25b1d38b9000001fe-1506104640.137249-1139243664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004745 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 11:46:56.485880) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 11:46:56.486175) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 11:46:56.489479) ========= ========= Total master_lag: 1.27 =========