builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-035 starttime: 1507143985.02 results: success (0) revision: ee1c41cf306df0043a8e68af042f133acf2ef94e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.016528) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.017004) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.017279) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.035783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.036050) ========= 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=ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-04 12:06:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.8M=0s 2017-10-04 12:06:25 (36.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125518 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.181635) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.181935) ========= 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=ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015502 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.231238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.231528) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ee1c41cf306df0043a8e68af042f133acf2ef94e --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 ee1c41cf306df0043a8e68af042f133acf2ef94e --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=ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-04 12:06:25,468 truncating revision to first 12 chars 2017-10-04 12:06:25,468 Setting DEBUG logging. 2017-10-04 12:06:25,468 attempt 1/10 2017-10-04 12:06:25,468 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ee1c41cf306d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-04 12:06:25,839 unpacking tar archive at: mozilla-central-ee1c41cf306d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.681221 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.932409) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.935701) ========= script_repo_revision: ee1c41cf306df0043a8e68af042f133acf2ef94e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.936052) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.936307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-04 12:06:25.954876) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-04 12:06:25.955138) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:06:26 INFO - MultiFileLogger online at 20171004 12:06:26 in /builds/slave/test 12:06:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:06:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:06:26 INFO - {'append_to_log': False, 12:06:26 INFO - 'base_work_dir': '/builds/slave/test', 12:06:26 INFO - 'blob_upload_branch': 'Firefox', 12:06:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:06:26 INFO - 'branch': 'Firefox', 12:06:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:06:26 INFO - 'code_coverage': False, 12:06:26 INFO - 'config_files': ('talos/linux_config.py',), 12:06:26 INFO - 'default_actions': ('clobber', 12:06:26 INFO - 'read-buildbot-config', 12:06:26 INFO - 'download-and-extract', 12:06:26 INFO - 'populate-webroot', 12:06:26 INFO - 'create-virtualenv', 12:06:26 INFO - 'install', 12:06:26 INFO - 'setup-mitmproxy', 12:06:26 INFO - 'run-tests'), 12:06:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:06:26 INFO - 'disable_ccov_upload': False, 12:06:26 INFO - 'disable_stylo': False, 12:06:26 INFO - 'download_minidump_stackwalk': True, 12:06:26 INFO - 'download_symbols': 'ondemand', 12:06:26 INFO - 'enable_stylo': False, 12:06:26 INFO - 'enable_webrender': False, 12:06:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:06:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:06:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:06:26 INFO - 'gecko_profile': False, 12:06:26 INFO - 'gecko_profile_interval': 0, 12:06:26 INFO - 'installer_path': 'installer.exe', 12:06:26 INFO - 'log_level': 'info', 12:06:26 INFO - 'log_name': 'talos', 12:06:26 INFO - 'log_to_console': True, 12:06:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:06:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:06:26 INFO - 'opt_config_files': (), 12:06:26 INFO - 'pip_index': False, 12:06:26 INFO - 'suite': 'dromaeojs-e10s', 12:06:26 INFO - 'system_bits': '32', 12:06:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:06:26 INFO - 'title': 'talos-linux64-ix-035', 12:06:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:06:26 INFO - 'use_talos_json': True, 12:06:26 INFO - 'verify': 'False', 12:06:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:06:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:06:26 INFO - 'work_dir': 'build'} 12:06:26 INFO - [mozharness: 2017-10-04 19:06:26.327535Z] Running clobber step. 12:06:26 INFO - Running pre-action listener: _resource_record_pre_action 12:06:26 INFO - Running main action method: clobber 12:06:26 INFO - rmtree: /builds/slave/test/build 12:06:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:06:27 INFO - Running post-action listener: _resource_record_post_action 12:06:27 INFO - [mozharness: 2017-10-04 19:06:27.838244Z] Finished clobber step (success) 12:06:27 INFO - [mozharness: 2017-10-04 19:06:27.838341Z] Running read-buildbot-config step. 12:06:27 INFO - Running pre-action listener: _resource_record_pre_action 12:06:27 INFO - Running main action method: read_buildbot_config 12:06:27 INFO - Using buildbot properties: 12:06:27 INFO - { 12:06:27 INFO - "project": "", 12:06:27 INFO - "product": "firefox", 12:06:27 INFO - "who": "ffxbld@noreply.mozilla.org", 12:06:27 INFO - "installer_path": "public/build/target.tar.bz2", 12:06:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:06:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:06:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:06:27 INFO - "stage_platform": "linux64-pgo", 12:06:27 INFO - "basedir": "/builds/slave/test", 12:06:27 INFO - "buildnumber": 230, 12:06:27 INFO - "platform": "ubuntu64_hw", 12:06:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:06:27 INFO - "slavebuilddir": "test", 12:06:27 INFO - "taskId": "drpX3HDiS_-5JfkzJii6yA", 12:06:27 INFO - "branch": "mozilla-central", 12:06:27 INFO - "script_repo_revision": "production", 12:06:27 INFO - "revision": "ee1c41cf306df0043a8e68af042f133acf2ef94e", 12:06:27 INFO - "slavename": "talos-linux64-ix-035", 12:06:27 INFO - "repo_path": "mozilla-central" 12:06:27 INFO - } 12:06:27 INFO - Finding installer, test and symbols from parent task. 12:06:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/drpX3HDiS_-5JfkzJii6yA'}, attempt #1 12:06:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/drpX3HDiS_-5JfkzJii6yA'}, attempt #1 12:06:29 INFO - Task dependencies: EGsrJkSyRyyUTBZ-iPsEpw 12:06:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw'}, attempt #1 12:06:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.tar.bz2 12:06:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.test_packages.json 12:06:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.crashreporter-symbols.zip 12:06:29 INFO - Running post-action listener: _resource_record_post_action 12:06:29 INFO - [mozharness: 2017-10-04 19:06:29.842037Z] Finished read-buildbot-config step (success) 12:06:29 INFO - [mozharness: 2017-10-04 19:06:29.842254Z] Running download-and-extract step. 12:06:29 INFO - Running pre-action listener: _resource_record_pre_action 12:06:29 INFO - Running main action method: download_and_extract 12:06:29 INFO - mkdir: /builds/slave/test/build/tests 12:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:29 INFO - trying https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.test_packages.json 12:06:29 INFO - Downloading https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:06:30 INFO - Downloaded 989 bytes. 12:06:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:06:30 INFO - Using the following test package requirements: 12:06:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:06:30 INFO - u'common': [u'target.common.tests.zip'], 12:06:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:06:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:06:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:06:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:06:30 INFO - u'mozbase': [u'target.common.tests.zip'], 12:06:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:06:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:06:30 INFO - u'web-platform': [u'target.common.tests.zip', 12:06:30 INFO - u'target.web-platform.tests.tar.gz'], 12:06:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:06:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:06:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.common.tests.zip 12:06:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:06:30 INFO - Fetch https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.common.tests.zip into memory 12:06:32 INFO - Content-Length response header: 55887684 12:06:32 INFO - Bytes received: 55887684 12:06:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.talos.tests.zip 12:06:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:06:42 INFO - Fetch https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.talos.tests.zip into memory 12:06:44 INFO - Content-Length response header: 14533844 12:06:44 INFO - Bytes received: 14533844 12:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:44 INFO - trying https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.tar.bz2 12:06:44 INFO - Downloading https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:06:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:06:46 INFO - Downloaded 64040165 bytes. 12:06:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.tar.bz2 12:06:46 INFO - mkdir: /builds/slave/test/properties 12:06:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:06:46 INFO - Writing to file /builds/slave/test/properties/build_url 12:06:46 INFO - Contents: 12:06:46 INFO - build_url:https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.tar.bz2 12:06:46 INFO - Running post-action listener: _resource_record_post_action 12:06:47 INFO - Running post-action listener: find_tests_for_verification 12:06:47 INFO - Running post-action listener: set_extra_try_arguments 12:06:47 INFO - [mozharness: 2017-10-04 19:06:47.000227Z] Finished download-and-extract step (success) 12:06:47 INFO - [mozharness: 2017-10-04 19:06:47.000327Z] Running populate-webroot step. 12:06:47 INFO - Running pre-action listener: _resource_record_pre_action 12:06:47 INFO - Running main action method: populate_webroot 12:06:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:06:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['tresize', 12:06:47 INFO - 'tcanvasmark']}, 12:06:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:06:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['dromaeo_css', 12:06:47 INFO - 'kraken']}, 12:06:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:06:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:06:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'tests': ['damp', 'tps']}, 12:06:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['damp', 'tps']}, 12:06:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:06:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['dromaeo_dom']}, 12:06:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:06:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['basic_compositor_video', 12:06:47 INFO - 'glvideo']}, 12:06:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:06:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['ts_paint_webext', 12:06:47 INFO - 'tp5o_webext']}, 12:06:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:06:47 INFO - 'ts_paint', 12:06:47 INFO - 'tpaint', 12:06:47 INFO - 'sessionrestore', 12:06:47 INFO - 'sessionrestore_many_windows', 12:06:47 INFO - 'sessionrestore_no_auto_restore', 12:06:47 INFO - 'tabpaint', 12:06:47 INFO - 'cpstartup']}, 12:06:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['a11yr', 12:06:47 INFO - 'ts_paint', 12:06:47 INFO - 'tpaint', 12:06:47 INFO - 'sessionrestore', 12:06:47 INFO - 'sessionrestore_many_windows', 12:06:47 INFO - 'sessionrestore_no_auto_restore', 12:06:47 INFO - 'tabpaint', 12:06:47 INFO - 'cpstartup']}, 12:06:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:06:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:06:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['perf_reftest_singletons']}, 12:06:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['perf_reftest']}, 12:06:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:06:47 INFO - 'tsvgr_opacity', 12:06:47 INFO - 'tart', 12:06:47 INFO - 'tscrollx', 12:06:47 INFO - 'tsvg_static']}, 12:06:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['tsvgx', 12:06:47 INFO - 'tsvgr_opacity', 12:06:47 INFO - 'tart', 12:06:47 INFO - 'tscrollx', 12:06:47 INFO - 'tsvg_static']}, 12:06:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:06:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-stylo'], 12:06:47 INFO - 'tests': ['tp5o']}, 12:06:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:06:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:06:47 INFO - 'talos_options': ['--mitmproxy', 12:06:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:06:47 INFO - '--firstNonBlankPaint'], 12:06:47 INFO - 'tests': ['tp6_google', 12:06:47 INFO - 'tp6_youtube', 12:06:47 INFO - 'tp6_amazon', 12:06:47 INFO - 'tp6_facebook']}, 12:06:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:06:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:06:47 INFO - 'talos_options': ['--disable-stylo', 12:06:47 INFO - '--mitmproxy', 12:06:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:06:47 INFO - '--firstNonBlankPaint'], 12:06:47 INFO - 'tests': ['tp6_google', 12:06:47 INFO - 'tp6_youtube', 12:06:47 INFO - 'tp6_amazon', 12:06:47 INFO - 'tp6_facebook']}, 12:06:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:06:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:06:47 INFO - 'talos_options': ['--stylo-threads=1', 12:06:47 INFO - '--mitmproxy', 12:06:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:06:47 INFO - '--firstNonBlankPaint'], 12:06:47 INFO - 'tests': ['tp6_google', 12:06:47 INFO - 'tp6_youtube', 12:06:47 INFO - 'tp6_amazon', 12:06:47 INFO - 'tp6_facebook']}, 12:06:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'talos_options': ['--xperf_path', 12:06:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:06:47 INFO - 'tests': ['tp5n']}, 12:06:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-stylo', 12:06:47 INFO - '--xperf_path', 12:06:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:06:47 INFO - 'tests': ['tp5n']}}} 12:06:47 INFO - Running post-action listener: _resource_record_post_action 12:06:47 INFO - [mozharness: 2017-10-04 19:06:47.015842Z] Finished populate-webroot step (success) 12:06:47 INFO - [mozharness: 2017-10-04 19:06:47.015931Z] Running create-virtualenv step. 12:06:47 INFO - Running pre-action listener: _resource_record_pre_action 12:06:47 INFO - Running main action method: create_virtualenv 12:06:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:06:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:06:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:06:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:06:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:06:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:06:47 INFO - Installing distribute.............................................................................................................................................................................................done. 12:06:47 INFO - Installing pip...............done. 12:06:47 INFO - Return code: 0 12:06:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:06:48 INFO - Reading from file tmpfile_stdout 12:06:48 INFO - Output received: 12:06:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:06:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:06:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:06:48 INFO - Using env: {'DISPLAY': ':0', 12:06:48 INFO - 'HOME': '/home/cltbld', 12:06:48 INFO - 'LANG': 'en_US.UTF-8', 12:06:48 INFO - 'LANGUAGE': 'en_US:en', 12:06:48 INFO - 'LOGNAME': 'cltbld', 12:06:48 INFO - 'MAIL': '/var/mail/cltbld', 12:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:48 INFO - 'MOZ_NO_REMOTE': '1', 12:06:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:48 INFO - 'NO_EM_RESTART': '1', 12:06:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:48 INFO - 'PWD': '/builds/slave/test', 12:06:48 INFO - 'SHELL': '/bin/bash', 12:06:48 INFO - 'SHLVL': '1', 12:06:48 INFO - 'TERM': 'linux', 12:06:48 INFO - 'TMOUT': '86400', 12:06:48 INFO - 'USER': 'cltbld', 12:06:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:48 INFO - '_': '/tools/buildbot/bin/python'} 12:06:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:06:49 INFO - Downloading/unpacking pip>=1.5 12:06:49 INFO - Running setup.py egg_info for package pip 12:06:49 INFO - warning: no files found matching 'pip/cacert.pem' 12:06:49 INFO - warning: no files found matching '*.html' under directory 'docs' 12:06:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:06:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:06:49 INFO - Installing collected packages: pip 12:06:49 INFO - Found existing installation: pip 1.1 12:06:49 INFO - Uninstalling pip: 12:06:49 INFO - Successfully uninstalled pip 12:06:49 INFO - Running setup.py install for pip 12:06:49 INFO - warning: no files found matching 'pip/cacert.pem' 12:06:49 INFO - warning: no files found matching '*.html' under directory 'docs' 12:06:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:06:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:06:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:06:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:06:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:06:49 INFO - Successfully installed pip 12:06:49 INFO - Cleaning up... 12:06:49 INFO - Return code: 0 12:06:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:06: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')]} 12:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06: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')]} 12:06:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:06:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:06:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:06:49 INFO - Using env: {'DISPLAY': ':0', 12:06:49 INFO - 'HOME': '/home/cltbld', 12:06:49 INFO - 'LANG': 'en_US.UTF-8', 12:06:49 INFO - 'LANGUAGE': 'en_US:en', 12:06:49 INFO - 'LOGNAME': 'cltbld', 12:06:49 INFO - 'MAIL': '/var/mail/cltbld', 12:06:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:49 INFO - 'MOZ_NO_REMOTE': '1', 12:06:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:49 INFO - 'NO_EM_RESTART': '1', 12:06:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:49 INFO - 'PWD': '/builds/slave/test', 12:06:49 INFO - 'SHELL': '/bin/bash', 12:06:49 INFO - 'SHLVL': '1', 12:06:49 INFO - 'TERM': 'linux', 12:06:49 INFO - 'TMOUT': '86400', 12:06:49 INFO - 'USER': 'cltbld', 12:06:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:06:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:49 INFO - '_': '/tools/buildbot/bin/python'} 12:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:49 INFO - Downloading/unpacking psutil>=3.1.1 12:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:06:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:06:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:06:50 INFO - Installing collected packages: psutil 12:06:50 INFO - Running setup.py install for psutil 12:06:50 INFO - building 'psutil._psutil_linux' extension 12:06:50 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 12:06:50 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 12:06:50 INFO - building 'psutil._psutil_posix' extension 12:06:50 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 12:06:51 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 12:06:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:06:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:06:51 INFO - Successfully installed psutil 12:06:51 INFO - Cleaning up... 12:06:52 INFO - Return code: 0 12:06:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:06: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')]} 12:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06: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')]} 12:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:52 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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:06:52 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 12:06:52 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 12:06:52 INFO - Using env: {'DISPLAY': ':0', 12:06:52 INFO - 'HOME': '/home/cltbld', 12:06:52 INFO - 'LANG': 'en_US.UTF-8', 12:06:52 INFO - 'LANGUAGE': 'en_US:en', 12:06:52 INFO - 'LOGNAME': 'cltbld', 12:06:52 INFO - 'MAIL': '/var/mail/cltbld', 12:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:52 INFO - 'MOZ_NO_REMOTE': '1', 12:06:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:52 INFO - 'NO_EM_RESTART': '1', 12:06:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:52 INFO - 'PWD': '/builds/slave/test', 12:06:52 INFO - 'SHELL': '/bin/bash', 12:06:52 INFO - 'SHLVL': '1', 12:06:52 INFO - 'TERM': 'linux', 12:06:52 INFO - 'TMOUT': '86400', 12:06:52 INFO - 'USER': 'cltbld', 12:06:52 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:52 INFO - '_': '/tools/buildbot/bin/python'} 12:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:06:53 INFO - Installing collected packages: mozsystemmonitor 12:06:53 INFO - Running setup.py install for mozsystemmonitor 12:06:53 INFO - Successfully installed mozsystemmonitor 12:06:53 INFO - Cleaning up... 12:06:53 INFO - Return code: 0 12:06:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:53 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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:06:53 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 12:06:53 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 12:06:53 INFO - Using env: {'DISPLAY': ':0', 12:06:53 INFO - 'HOME': '/home/cltbld', 12:06:53 INFO - 'LANG': 'en_US.UTF-8', 12:06:53 INFO - 'LANGUAGE': 'en_US:en', 12:06:53 INFO - 'LOGNAME': 'cltbld', 12:06:53 INFO - 'MAIL': '/var/mail/cltbld', 12:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:53 INFO - 'MOZ_NO_REMOTE': '1', 12:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:53 INFO - 'NO_EM_RESTART': '1', 12:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:53 INFO - 'PWD': '/builds/slave/test', 12:06:53 INFO - 'SHELL': '/bin/bash', 12:06:53 INFO - 'SHLVL': '1', 12:06:53 INFO - 'TERM': 'linux', 12:06:53 INFO - 'TMOUT': '86400', 12:06:53 INFO - 'USER': 'cltbld', 12:06:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:53 INFO - '_': '/tools/buildbot/bin/python'} 12:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:53 INFO - Downloading/unpacking jsonschema==2.5.1 12:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:06:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:06: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 12:06: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 12:06: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 12:06: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 12:06:55 INFO - Downloading functools32-3.2.3-2.tar.gz 12:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:06:55 INFO - warning: no files found matching '*.txt' 12:06:55 INFO - no previously-included directories found matching 'build' 12:06:55 INFO - no previously-included directories found matching 'dist' 12:06:55 INFO - no previously-included directories found matching '.git*' 12:06:55 INFO - Installing collected packages: jsonschema, functools32 12:06:55 INFO - Running setup.py install for functools32 12:06:55 INFO - warning: no files found matching '*.txt' 12:06:55 INFO - no previously-included directories found matching 'build' 12:06:55 INFO - no previously-included directories found matching 'dist' 12:06:55 INFO - no previously-included directories found matching '.git*' 12:06:55 INFO - Successfully installed jsonschema functools32 12:06:55 INFO - Cleaning up... 12:06:55 INFO - Return code: 0 12:06:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:06: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')]} 12:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06: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')]} 12:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:55 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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:06:55 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 12:06:55 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 12:06:55 INFO - Using env: {'DISPLAY': ':0', 12:06:55 INFO - 'HOME': '/home/cltbld', 12:06:55 INFO - 'LANG': 'en_US.UTF-8', 12:06:55 INFO - 'LANGUAGE': 'en_US:en', 12:06:55 INFO - 'LOGNAME': 'cltbld', 12:06:55 INFO - 'MAIL': '/var/mail/cltbld', 12:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:55 INFO - 'MOZ_NO_REMOTE': '1', 12:06:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:55 INFO - 'NO_EM_RESTART': '1', 12:06:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:55 INFO - 'PWD': '/builds/slave/test', 12:06:55 INFO - 'SHELL': '/bin/bash', 12:06:55 INFO - 'SHLVL': '1', 12:06:55 INFO - 'TERM': 'linux', 12:06:55 INFO - 'TMOUT': '86400', 12:06:55 INFO - 'USER': 'cltbld', 12:06:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:55 INFO - '_': '/tools/buildbot/bin/python'} 12:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:06:55 INFO - Cleaning up... 12:06:55 INFO - Return code: 0 12:06:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:06: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')]} 12:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06: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')]} 12:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:55 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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:06:55 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 12:06:55 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 12:06:55 INFO - Using env: {'DISPLAY': ':0', 12:06:55 INFO - 'HOME': '/home/cltbld', 12:06:55 INFO - 'LANG': 'en_US.UTF-8', 12:06:55 INFO - 'LANGUAGE': 'en_US:en', 12:06:55 INFO - 'LOGNAME': 'cltbld', 12:06:55 INFO - 'MAIL': '/var/mail/cltbld', 12:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:55 INFO - 'MOZ_NO_REMOTE': '1', 12:06:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:55 INFO - 'NO_EM_RESTART': '1', 12:06:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:55 INFO - 'PWD': '/builds/slave/test', 12:06:55 INFO - 'SHELL': '/bin/bash', 12:06:55 INFO - 'SHLVL': '1', 12:06:55 INFO - 'TERM': 'linux', 12:06:55 INFO - 'TMOUT': '86400', 12:06:55 INFO - 'USER': 'cltbld', 12:06:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:55 INFO - '_': '/tools/buildbot/bin/python'} 12:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:55 INFO - Downloading/unpacking blobuploader==1.2.4 12:06: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 12:06: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 12:06: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 12:06: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 12:06:56 INFO - Downloading blobuploader-1.2.4.tar.gz 12:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:06:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:06:56 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 12:06:56 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 12:06:56 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 12:06:56 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 12:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:06:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:06:57 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 12:06:57 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 12:06:57 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 12:06:57 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 12:06:57 INFO - Downloading docopt-0.6.1.tar.gz 12:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:06:57 INFO - Installing collected packages: blobuploader, requests, docopt 12:06:57 INFO - Running setup.py install for blobuploader 12:06:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:06:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:06:57 INFO - Running setup.py install for requests 12:06:57 INFO - Running setup.py install for docopt 12:06:57 INFO - Successfully installed blobuploader requests docopt 12:06:57 INFO - Cleaning up... 12:06:58 INFO - Return code: 0 12:06:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06: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')]} 12:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06: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')]} 12:06:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:58 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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:06:58 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 12:06:58 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 12:06:58 INFO - Using env: {'DISPLAY': ':0', 12:06:58 INFO - 'HOME': '/home/cltbld', 12:06:58 INFO - 'LANG': 'en_US.UTF-8', 12:06:58 INFO - 'LANGUAGE': 'en_US:en', 12:06:58 INFO - 'LOGNAME': 'cltbld', 12:06:58 INFO - 'MAIL': '/var/mail/cltbld', 12:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:58 INFO - 'MOZ_NO_REMOTE': '1', 12:06:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:58 INFO - 'NO_EM_RESTART': '1', 12:06:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:58 INFO - 'PWD': '/builds/slave/test', 12:06:58 INFO - 'SHELL': '/bin/bash', 12:06:58 INFO - 'SHLVL': '1', 12:06:58 INFO - 'TERM': 'linux', 12:06:58 INFO - 'TMOUT': '86400', 12:06:58 INFO - 'USER': 'cltbld', 12:06:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:58 INFO - '_': '/tools/buildbot/bin/python'} 12:06:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:06:58 INFO - Running setup.py (path:/tmp/pip-dQP7Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:06:58 INFO - Running setup.py (path:/tmp/pip-tUHtDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:06:58 INFO - Running setup.py (path:/tmp/pip-Ak7R7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:06:58 INFO - Running setup.py (path:/tmp/pip-vYmuhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:06:58 INFO - Running setup.py (path:/tmp/pip-4VVPaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:06:58 INFO - Running setup.py (path:/tmp/pip-08H6cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:06:58 INFO - Running setup.py (path:/tmp/pip-sXpk9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:06:58 INFO - Running setup.py (path:/tmp/pip-FHY8ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:06:58 INFO - Running setup.py (path:/tmp/pip-UVKv3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:06:58 INFO - Running setup.py (path:/tmp/pip-elj8dx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:06:58 INFO - Running setup.py (path:/tmp/pip-48XjNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:06:59 INFO - Running setup.py (path:/tmp/pip-ymYK3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:06:59 INFO - Running setup.py (path:/tmp/pip-uwCXgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:06:59 INFO - Running setup.py (path:/tmp/pip-HYlW8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:59 INFO - Running setup.py (path:/tmp/pip-Ra_L0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:06:59 INFO - Running setup.py (path:/tmp/pip-ZY6gXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:06:59 INFO - Running setup.py (path:/tmp/pip-jpHrTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:06:59 INFO - Running setup.py install for manifestparser 12:06:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:06:59 INFO - Running setup.py install for mozcrash 12:06:59 INFO - Running setup.py install for mozdebug 12:06:59 INFO - Running setup.py install for mozdevice 12:06:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:06:59 INFO - Running setup.py install for mozfile 12:06:59 INFO - Running setup.py install for mozhttpd 12:06:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Running setup.py install for mozinfo 12:07:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Running setup.py install for mozInstall 12:07:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Running setup.py install for mozleak 12:07:00 INFO - Running setup.py install for mozlog 12:07:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Running setup.py install for moznetwork 12:07:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Running setup.py install for mozprocess 12:07:00 INFO - Running setup.py install for mozprofile 12:07:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Running setup.py install for mozrunner 12:07:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:07:00 INFO - Running setup.py install for mozscreenshot 12:07:00 INFO - Running setup.py install for moztest 12:07:01 INFO - Running setup.py install for mozversion 12:07:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:07:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:07:01 INFO - Cleaning up... 12:07:01 INFO - Return code: 0 12:07:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:01 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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:07:01 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 12:07:01 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 12:07:01 INFO - Using env: {'DISPLAY': ':0', 12:07:01 INFO - 'HOME': '/home/cltbld', 12:07:01 INFO - 'LANG': 'en_US.UTF-8', 12:07:01 INFO - 'LANGUAGE': 'en_US:en', 12:07:01 INFO - 'LOGNAME': 'cltbld', 12:07:01 INFO - 'MAIL': '/var/mail/cltbld', 12:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:01 INFO - 'MOZ_NO_REMOTE': '1', 12:07:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:01 INFO - 'NO_EM_RESTART': '1', 12:07:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:01 INFO - 'PWD': '/builds/slave/test', 12:07:01 INFO - 'SHELL': '/bin/bash', 12:07:01 INFO - 'SHLVL': '1', 12:07:01 INFO - 'TERM': 'linux', 12:07:01 INFO - 'TMOUT': '86400', 12:07:01 INFO - 'USER': 'cltbld', 12:07:01 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:01 INFO - '_': '/tools/buildbot/bin/python'} 12:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:01 INFO - Running setup.py (path:/tmp/pip-AtEEw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:01 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)) 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:01 INFO - Running setup.py (path:/tmp/pip-W1QjF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:01 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)) 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:01 INFO - Running setup.py (path:/tmp/pip-kqoXTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:01 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)) 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:01 INFO - Running setup.py (path:/tmp/pip-SZSqCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:01 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)) 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:01 INFO - Running setup.py (path:/tmp/pip-f7tX3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:01 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)) 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:01 INFO - Running setup.py (path:/tmp/pip-Oz09BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:01 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)) 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:01 INFO - Running setup.py (path:/tmp/pip-KnDGfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:01 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)) 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:01 INFO - Running setup.py (path:/tmp/pip-hrWbdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:01 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)) 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:01 INFO - Running setup.py (path:/tmp/pip-MH51PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:02 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)) 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:02 INFO - Running setup.py (path:/tmp/pip-7v3hxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:02 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)) 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:02 INFO - Running setup.py (path:/tmp/pip-8fYxUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:02 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)) 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:02 INFO - Running setup.py (path:/tmp/pip-A6vMLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:02 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)) 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:02 INFO - Running setup.py (path:/tmp/pip-U0vQky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:02 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)) 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:02 INFO - Running setup.py (path:/tmp/pip-zQluq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:02 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)) 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:02 INFO - Running setup.py (path:/tmp/pip-qd4D1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:02 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)) 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:02 INFO - Running setup.py (path:/tmp/pip-4On2EF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:02 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)) 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:02 INFO - Running setup.py (path:/tmp/pip-YUV4Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:02 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)) 12:07:02 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)) 12:07:02 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)) 12:07:02 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)) 12:07:02 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)) 12:07:02 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)) 12:07:02 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)) 12:07:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:07:02 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 12:07:02 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 12:07:02 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 12:07:02 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 12:07:03 INFO - Downloading blessings-1.6.tar.gz 12:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:07:03 INFO - Installing collected packages: blessings 12:07:03 INFO - Running setup.py install for blessings 12:07:03 INFO - Successfully installed blessings 12:07:03 INFO - Cleaning up... 12:07:03 INFO - Return code: 0 12:07:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:07:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:04 INFO - Reading from file tmpfile_stdout 12:07:04 INFO - Current package versions: 12:07:04 INFO - argparse == 1.2.1 12:07:04 INFO - blessings == 1.6 12:07:04 INFO - blobuploader == 1.2.4 12:07:04 INFO - docopt == 0.6.1 12:07:04 INFO - functools32 == 3.2.3-2 12:07:04 INFO - jsonschema == 2.5.1 12:07:04 INFO - manifestparser == 1.1 12:07:04 INFO - mozInstall == 1.13 12:07:04 INFO - mozcrash == 1.0 12:07:04 INFO - mozdebug == 0.1 12:07:04 INFO - mozdevice == 0.51 12:07:04 INFO - mozfile == 1.2 12:07:04 INFO - mozhttpd == 0.7 12:07:04 INFO - mozinfo == 0.10 12:07:04 INFO - mozleak == 0.1 12:07:04 INFO - mozlog == 3.5 12:07:04 INFO - moznetwork == 0.27 12:07:04 INFO - mozprocess == 0.25 12:07:04 INFO - mozprofile == 0.28 12:07:04 INFO - mozrunner == 6.13 12:07:04 INFO - mozscreenshot == 0.1 12:07:04 INFO - mozsystemmonitor == 0.3 12:07:04 INFO - moztest == 0.8 12:07:04 INFO - mozversion == 1.4 12:07:04 INFO - psutil == 3.1.1 12:07:04 INFO - requests == 1.2.3 12:07:04 INFO - wsgiref == 0.1.2 12:07:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:04 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')]} 12:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:04 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')]} 12:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:04 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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:07:04 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 12:07:04 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 12:07:04 INFO - Using env: {'DISPLAY': ':0', 12:07:04 INFO - 'HOME': '/home/cltbld', 12:07:04 INFO - 'LANG': 'en_US.UTF-8', 12:07:04 INFO - 'LANGUAGE': 'en_US:en', 12:07:04 INFO - 'LOGNAME': 'cltbld', 12:07:04 INFO - 'MAIL': '/var/mail/cltbld', 12:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:04 INFO - 'MOZ_NO_REMOTE': '1', 12:07:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:04 INFO - 'NO_EM_RESTART': '1', 12:07:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:04 INFO - 'PWD': '/builds/slave/test', 12:07:04 INFO - 'SHELL': '/bin/bash', 12:07:04 INFO - 'SHLVL': '1', 12:07:04 INFO - 'TERM': 'linux', 12:07:04 INFO - 'TMOUT': '86400', 12:07:04 INFO - 'USER': 'cltbld', 12:07:04 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:04 INFO - '_': '/tools/buildbot/bin/python'} 12:07:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:04 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)) 12:07:04 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)) 12:07:04 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)) 12:07:04 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)) 12:07:04 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)) 12:07:04 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)) 12:07:04 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)) 12:07:04 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)) 12:07:04 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)) 12:07:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:07:04 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 12:07:04 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 12:07:04 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 12:07:04 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 12:07:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:07:05 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)) 12:07:05 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)) 12:07:05 INFO - Installing collected packages: simplejson 12:07:05 INFO - Running setup.py install for simplejson 12:07:05 INFO - building 'simplejson._speedups' extension 12:07:05 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 12:07:06 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 12:07:06 INFO - Successfully installed simplejson 12:07:06 INFO - Cleaning up... 12:07:06 INFO - Return code: 0 12:07:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:06 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 0x28bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a85b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', '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 12:07:06 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 12:07:06 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 12:07:06 INFO - Using env: {'DISPLAY': ':0', 12:07:06 INFO - 'HOME': '/home/cltbld', 12:07:06 INFO - 'LANG': 'en_US.UTF-8', 12:07:06 INFO - 'LANGUAGE': 'en_US:en', 12:07:06 INFO - 'LOGNAME': 'cltbld', 12:07:06 INFO - 'MAIL': '/var/mail/cltbld', 12:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:06 INFO - 'MOZ_NO_REMOTE': '1', 12:07:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:06 INFO - 'NO_EM_RESTART': '1', 12:07:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:06 INFO - 'PWD': '/builds/slave/test', 12:07:06 INFO - 'SHELL': '/bin/bash', 12:07:06 INFO - 'SHLVL': '1', 12:07:06 INFO - 'TERM': 'linux', 12:07:06 INFO - 'TMOUT': '86400', 12:07:06 INFO - 'USER': 'cltbld', 12:07:06 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:06 INFO - '_': '/tools/buildbot/bin/python'} 12:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:07:06 INFO - Cleaning up... 12:07:06 INFO - Return code: 0 12:07:06 INFO - Running post-action listener: _resource_record_post_action 12:07:06 INFO - Running post-action listener: _start_resource_monitoring 12:07:06 INFO - Starting resource monitoring. 12:07:06 INFO - [mozharness: 2017-10-04 19:07:06.909881Z] Finished create-virtualenv step (success) 12:07:06 INFO - [mozharness: 2017-10-04 19:07:06.910354Z] Running install step. 12:07:06 INFO - Running pre-action listener: _resource_record_pre_action 12:07:06 INFO - Running main action method: install 12:07:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:07 INFO - Reading from file tmpfile_stdout 12:07:07 INFO - Detecting whether we're running mozinstall >=1.0... 12:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:07:07 INFO - Reading from file tmpfile_stdout 12:07:07 INFO - Output received: 12:07:07 INFO - Usage: mozinstall [options] installer 12:07:07 INFO - Options: 12:07:07 INFO - -h, --help show this help message and exit 12:07:07 INFO - -d DEST, --destination=DEST 12:07:07 INFO - Directory to install application into. [default: 12:07:07 INFO - "/builds/slave/test"] 12:07:07 INFO - --app=APP Application being installed. [default: firefox] 12:07:07 INFO - mkdir: /builds/slave/test/build/application 12:07:07 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'] 12:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:07:25 INFO - Reading from file tmpfile_stdout 12:07:25 INFO - Output received: 12:07:25 INFO - /builds/slave/test/build/application/firefox/firefox 12:07:25 INFO - Running post-action listener: _resource_record_post_action 12:07:25 INFO - [mozharness: 2017-10-04 19:07:25.146906Z] Finished install step (success) 12:07:25 INFO - [mozharness: 2017-10-04 19:07:25.147053Z] Running setup-mitmproxy step. 12:07:25 INFO - Running pre-action listener: _resource_record_pre_action 12:07:25 INFO - Running main action method: setup_mitmproxy 12:07:25 INFO - Skipping: mitmproxy is not required 12:07:25 INFO - Running post-action listener: _resource_record_post_action 12:07:25 INFO - [mozharness: 2017-10-04 19:07:25.147587Z] Finished setup-mitmproxy step (success) 12:07:25 INFO - [mozharness: 2017-10-04 19:07:25.147700Z] Running run-tests step. 12:07:25 INFO - Running pre-action listener: _resource_record_pre_action 12:07:25 INFO - Running pre-action listener: _set_gcov_prefix 12:07:25 INFO - Running main action method: run_tests 12:07:25 WARNING - Try message not found. 12:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:07:25 INFO - Python 2.7.3 12:07:25 INFO - Return code: 0 12:07:25 INFO - grabbing minidump binary from tooltool 12:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:25 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 0x2c27d60>, '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 0x2c28a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c28ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:07:25 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 12:07:25 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 12:07:25 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 12:07:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:07:25 INFO - Return code: 0 12:07:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:07:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:07:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:07:25 INFO - ENV: RUST_BACKTRACE is now full 12:07:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:07:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 12:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 12:07:25 INFO - Using env: {'DISPLAY': ':0', 12:07:25 INFO - 'HOME': '/home/cltbld', 12:07:25 INFO - 'LANG': 'en_US.UTF-8', 12:07:25 INFO - 'LANGUAGE': 'en_US:en', 12:07:25 INFO - 'LOGNAME': 'cltbld', 12:07:25 INFO - 'MAIL': '/var/mail/cltbld', 12:07:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:07:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:25 INFO - 'MOZ_NO_REMOTE': '1', 12:07:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:25 INFO - 'NO_EM_RESTART': '1', 12:07:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:25 INFO - 'PWD': '/builds/slave/test', 12:07:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:07:25 INFO - 'RUST_BACKTRACE': 'full', 12:07:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:07:25 INFO - 'SHELL': '/bin/bash', 12:07:25 INFO - 'SHLVL': '1', 12:07:25 INFO - 'TERM': 'linux', 12:07:25 INFO - 'TMOUT': '86400', 12:07:25 INFO - 'USER': 'cltbld', 12:07:25 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:25 INFO - '_': '/tools/buildbot/bin/python'} 12:07:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 12:07:25 INFO - mozversion application_buildid: 20171004174332 12:07:25 INFO - mozversion application_changeset: ee1c41cf306df0043a8e68af042f133acf2ef94e 12:07:25 INFO - mozversion application_display_name: Nightly 12:07:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:07:25 INFO - mozversion application_name: Firefox 12:07:25 INFO - mozversion application_remotingname: firefox 12:07:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:07:25 INFO - mozversion application_vendor: Mozilla 12:07:25 INFO - mozversion application_version: 58.0a1 12:07:25 INFO - mozversion platform_buildid: 20171004174332 12:07:25 INFO - mozversion platform_changeset: ee1c41cf306df0043a8e68af042f133acf2ef94e 12:07:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:07:25 INFO - mozversion platform_version: 58.0a1 12:07:25 INFO - using testdate: 1507144045 12:07:25 INFO - actual date: 1507144045 12:07:25 INFO - starting webserver on 'localhost:58151' 12:07:25 INFO - SUITE-START | Running 2 tests 12:07:25 INFO - TEST-START | dromaeo_css 12:07:25 INFO - Initialising browser for dromaeo_css test... 12:07:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58151/getInfo.html -profile /tmp/tmpFd4yn9/profile 12:07:25 INFO - TEST-INFO | started process 12710 (/builds/slave/test/build/application/firefox/firefox http://localhost:58151/getInfo.html) 12:07:31 INFO - TEST-INFO | 12710: exit 0 12:07:31 INFO - Browser initialized. 12:07:31 INFO - Running cycle 1/1 for dromaeo_css test... 12:07:31 INFO - Using env: {'DISPLAY': ':0', 12:07:31 INFO - 'HOME': '/home/cltbld', 12:07:31 INFO - 'JSGC_DISABLE_POISONING': '1', 12:07:31 INFO - 'LANG': 'en_US.UTF-8', 12:07:31 INFO - 'LANGUAGE': 'en_US:en', 12:07:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:07:31 INFO - 'LOGNAME': 'cltbld', 12:07:31 INFO - 'MAIL': '/var/mail/cltbld', 12:07:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:07:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:07:31 INFO - 'MOZ_CRASHREPORTER': '1', 12:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:07:31 INFO - 'MOZ_NO_REMOTE': '1', 12:07:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:31 INFO - 'NO_EM_RESTART': '1', 12:07:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:31 INFO - 'PWD': '/builds/slave/test', 12:07:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:07:31 INFO - 'RUST_BACKTRACE': 'full', 12:07:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:07:31 INFO - 'SHELL': '/bin/bash', 12:07:31 INFO - 'SHLVL': '1', 12:07:31 INFO - 'TERM': 'linux', 12:07:31 INFO - 'TMOUT': '86400', 12:07:31 INFO - 'USER': 'cltbld', 12:07:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:31 INFO - '_': '/tools/buildbot/bin/python'} 12:07:31 INFO - TEST-INFO | started process 12982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFd4yn9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:07:31 INFO - PID 12982 | 12:07:31 INFO - PID 12982 | (/builds/slave/test/build/application/firefox/firefox:13032): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:07:31 INFO - PID 12982 | 12:07:42 INFO - PID 12982 | RSS: Main: 145629184 12:07:42 INFO - PID 12982 | 12:10:39 INFO - PID 12982 | Cycle 1(1): loaded http://localhost:58151/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58151/tests/dromaeo/cssquery-ext.html) 12:10:41 INFO - PID 12982 | RSS: Main: 142823424 12:10:41 INFO - PID 12982 | 12:13:42 INFO - PID 12982 | Cycle 1(1): loaded http://localhost:58151/tests/dromaeo/cssquery-ext.html (next: http://localhost:58151/tests/dromaeo/cssquery-jquery.html) 12:13:43 INFO - PID 12982 | RSS: Main: 143953920 12:13:43 INFO - PID 12982 | 12:16:44 INFO - PID 12982 | Cycle 1(1): loaded http://localhost:58151/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58151/tests/dromaeo/cssquery-mootools.html) 12:16:45 INFO - PID 12982 | RSS: Main: 145592320 12:16:45 INFO - PID 12982 | 12:19:47 INFO - PID 12982 | Cycle 1(1): loaded http://localhost:58151/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58151/tests/dromaeo/cssquery-prototype.html) 12:19:47 INFO - PID 12982 | RSS: Main: 145141760 12:19:47 INFO - PID 12982 | 12:22:50 INFO - PID 12982 | Cycle 1(1): loaded http://localhost:58151/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58151/tests/dromaeo/cssquery-yui.html) 12:22:51 INFO - PID 12982 | RSS: Main: 148283392 12:22:51 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | Cycle 1(1): loaded http://localhost:58151/tests/dromaeo/cssquery-yui.html (next: http://localhost:58151/tests/dromaeo/cssquery-dojo.html) 12:25:52 INFO - PID 12982 | RSS: Main: 149262336 12:25:52 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | __start_tp_report 12:25:52 INFO - PID 12982 | _x_x_mozilla_page_load 12:25:52 INFO - PID 12982 | _x_x_mozilla_page_load_details 12:25:52 INFO - PID 12982 | |i|pagename|runs| 12:25:52 INFO - PID 12982 | |0;dojo.html;3878.5539662938772;3898;3899.8635047773314;3904.4728961590185;3938.271419787338;6342.048692696087;6359.141515895352;6388.073729309253;6417.647029413392;6445.677716114194;6222.906656400146;6332.430081292683;6345.587536810104;6354.904676429857;6362.077498762676;5679.375268720448;5709.543236541067;5737.512311453532;5751.942480575203;5765.769369225226;5723.713814309289;5784.739686714108;5804.303483581976;5806.738696758656;5832.912506312408;5551.639143455683;5713.857153571194;5718.399568045358;5760.135979603072;5761.654300741927;5706.686132262724;5718.885622287573;5738.569607279429;5783.277090363705;5793.5654825887805;5662.546996240332;5688.857778555528;5702.885942281131;5777.277840269966;5777.711114444303;6263.060540918853;6322.146510221107;6385.616862988236;6408.198975128109;6420.4542613877875;6872.725090996354;6875.831108711481;6960.756373526953;6969.825754356131;6991.965040174767;6938.687759050805;6939.965300173467;7017.754378596725;7020.508564400494;7039.296070392971;6359.141515895341;6393.1688880445245;6421.775237866652;6447.54867159299;6473.708683109271;6981.650917454107;7155.534890232222;7167.4266058715175;7174.246704095995;7174.820629484305;6809.693563789641;6845.281245469254;6887.552309099892;6901.275366086589;6926.688299026556;6811.318868113248;6889.758858440031;6902.447804175654;6920.100386949664;6938.687759050754;6839.897401538981;6858.1084459021;6870.896936545956;6915.101036865175;6951.131108611424;6843.247242803288;6853.657317134123;6970.860582788316;6975.686094125776;6987.685554150075;4926.876828079255;4939.851804445872;4988.6009119270375;5000.199968005102;5022.096022715946;4522.23122069249;4531.954680453219;4572.1312950539295;4596.586307232298;4613.446386433649;6300.936990630035;6376.744930202833;6380.202474690664;6386.936130638727;6394.584352017104;6832.111825462757;6841.657917104224;6857.897131543031;6921.342472465108;6933.479989000845;6269.843253918597;6270.749170033238;6310.716017779211;6340.682965851689;6355.586886852339;5549.472800083986;5611.7474713637985;5628.859278517988;5630.465105814941;5649.293838270216;7175.103112110986;7252.782416527512;7269.527480713737;7270.927290727025;7285.562866228043;6786.524943254122;6792.592444453151;6804.115464989619;6868.244493105854;6870.931290687229;7161.4270858330165;7223.349898509159;7240.1311842579225;7244.022057022235;7246.347828695442;6825.453963682993;6833.384995350243;6856.6228857413325;6927.89608155868;6975.337342952412;6804.4896632753735;6939.757108501381;6949.270326615835;6958.826029349104;7029.61337126463;6595.571287866465;6652.767153149616;6668.099806526056;6668.83327916806;6678.432333251728;6632.071509988509;6655.134832471747;6662.267150613526;6714.52998290115;6733;6575.375339342755;6644.800655980328;6660.500462465237;6667.799966001027;6675.7329706813125;6704;6735.865282694416;6748.122744043243;6839.931600684128;6842.692078856364;6451.3548645135115;6527.2167339919515;6553.4101930826455;6590.373914478117;6621.33786621334 12:25:52 INFO - PID 12982 | |1;ext.html;939.4269495607682;962.5668449197854;990.5493000684689;1020.6121673763971;1023.5342918971869;42307.78846105769;43305.78347108268;43368.78315608425;43399;43427.13145737084;39768.20463590726;39825.40261896074;39991.40012899809;40152.39771403431;40162.39756403656;7674.846503069935;7884.724034658788;7887.447878648497;7889;7889.684412623488;7697.307242348187;7772.533647981139;7778.533288002738;7790.143084260727;7795.688172473095;7936.8412631747615;8125.512469251864;8126.715564955228;8139.552324622144;8142.185781421869;8027.197280271956;8102.675892964275;8105.635246413925;8110.716124935599;8119.878201826978;8258.174182581753;8367.28878045367;8419.158084191593;8449.493030418133;8462.15378462155;40998.180036399106;41279.7936010318;41384.58615413868;41439;41443.37834932478;3705;3744;3747.5877653458097;3747.5877653458097;3750.4374343848367;32399.190020249684;32403.189920251945;32511.837440812644;32890.34219315624;33274;5439.428859969726;5498.835034948958;5516.282883225195;5532.723363831833;5558.638688485235;212302;213260;213564;213694;213752;52228;52246.47753522492;52260.738696306275;52272.21591676128;52284.21573676398;54932.72533637306;54961.725191374586;54992;55070.17394739002;55102.173467398025;16294.674106517803;16566.254518546455;16584.75122873158;16730.16349182536;16744.581385465463;13383.598492045254;13427.3957671905;13434;13455.596332110052;13480.93259533696;14857.034292770935;15163.241837908061;15191.392344306325;15300.158491282864;15393.769093463607;6059.303180134253;6119.602225855394;6200.937990620127;6264.404881536247;6330.746770129143;978.7357413498315;985.8274801909637;990.2226751999646;1003.7189586915675;1004.6835246897203;24868;25043.123490678103;25167.496650066896;25183.48899066062;25237.11670091538;1081.6430577909427;1098.7363032872054;1111.927724697892;1112.404863398078;1119.8264269038311;13170.539031133865;13239.602811915658;13296.202227866359;13491.527796527074;13556.86443135556;280080;280926;282211;282307;283000;49447.50552494501;50090.24864627034;55823;55984;56052.15921761177;1522.0943538594518;1527.694461107783;1539.6074001129864;1539.899906506096;1546.0988665929033;30677.947478299517;30812.69187308188;31228.531572025982;31316;31402.68597314089;40958.79520602378;41030.38454423127;41041.384379234936;41059.1788164241;41072.17855642811;243284;244289;245545;245653;247344;35785.821070894475;35924.64075359213;36104.097397565274;36128.27743445169;36282.637173627925;44454.88862778457;45558.772206138754;46075.3088703663;46087.76956115198;46183;34869.651303486644;34990.30019399547;35287.29425411529;35349;35468.290634186655;34640.48039279362;34735.82632086823;34829.65170348366;35421.114472138404;35460.64539354573;40275.3958690614;40475.797621011705;40811.79594102011;40995.18009639731;41060;33418.33163336671;33658.158546036546;33737;34702.82648586741;34722.65277347295;25322.873385632953;25380.619290710285;26147;26240.95036198607;26253.50762440248 12:25:52 INFO - PID 12982 | |2;jquery.html;622.3776223776224;631.8673078653483;634.6604566556892;635.1489004733658;646.4505170604987;22674.206402775908;22896.77103228967;23213.18753843616;23399.532009359824;23703.644445333313;25662.615060774104;26113.347166320804;26125.085622003233;26147.084852030184;26147.738522614767;6779.220389655179;6802.931970680304;6837.589744615314;6924.653767311639;6926.238113807477;6535.477161827066;6768.153980752406;6800.115984921953;6840.863182736367;6841.692123854438;6788.321167883221;6789.660516974156;6794.5243832931465;6808;6810.489213308997;6399.200099987502;6728.226253980782;6730.226024007253;6753.966230168842;6755.459563234929;6191.288001879751;6221.564490485623;6232.1898153240245;6233.906491402633;6267.153934218868;30226.697733022607;30570.930017449282;30700.53949190764;30729.231769205948;30780.999235049872;2006.9297574584966;2024.757029156496;2038.1949130093678;2040.2349119080345;2048.3957232616267;20339.491512712153;20973.16107355704;21515.67726484104;21583.352499424884;21633.891830540746;2561.8334808237405;2596.1173201111524;2616.175904590067;2622.3706310485536;2641.3660721426913;136858.3157084218;144976;157921;165645.17177414155;166650;15301.311440985182;16212.513624591282;16398.344066237456;16540.503784886474;16582.58543536397;26043.479130417287;26179.214623561325;26223.77097946658;26228.868855655983;26285.34286642849;10619.575216991389;10848.830233953084;10858.94570527158;10864.456777161111;10997.34015959045;9744.658936937172;9764.218862490983;9831.213502919893;9902.455364954852;9930.751731206778;9900.55447504864;9923.305368624127;10019.649312274036;10041.096301332915;10147.719333266705;2829.5472724364004;2874.238326843388;2909.7526710229868;2973.6431628204323;2976.255936015996;1026.414943482208;1041.614602597044;1043.9582416703247;1045.8913551635292;1060.4697651174413;20701;20720.48198795042;20871.686924696143;21118.260860869796;21446.78553214448;1091.252492042953;1091.7543552700579;1093.1965005720788;1103.1615971861443;1115.6151127860876;11147.38689372076;11533.769324613628;11536;11671.64985050433;11727.35499547533;612073;616712;619631;621547;628681;121748;126747;127076;128167;132974.00538991924;1094.6934858239706;1148.8736239013701;1188.3523479703583;1217.78688729472;1250.0749445410331;21548.233980175755;22105.66841497411;22160.224392146196;22228.444288892908;22389.32832015042;63114.68442657757;64324;65526.67236663786;66464;66583.66708166429;77354.6132269335;79522.60238699001;80910.5954470224;81440;81985.18014819776;15179.468718594797;15377.461788837338;15424.5372638821;15487.457938972082;15566.221688915735;23905.28284151478;23920.88039559791;23961;24044.15845445401;24076;19317.574285871186;19417;19624.313149040285;19643.803561964196;19782.109805059095;16109.275082621545;16134.515964521082;16829.831701683317;16845.41041063557;16927.49217523476;19123;19186.616267674846;19393.51516212106;19399.90300048491;19858.90070549638;15182.848171518584;15245.466408675644;15371.30829112715;15388.692226155637;15455.459058933333;19332.323368682028;19353.612927741084;19377.51556211106;19406.320778772675;19598.11808468594 12:25:52 INFO - PID 12982 | |3;mootools.html;1457.6865973815632;1505.9774103388459;1512.2136489025706;1515.5832146159812;1527.9999999999995;16051.59871003224;16518;16523.83476165238;16532.25604847782;16565.503034908957;15934;16283.348666053344;16299.266533006014;16325.673486530262;16327;2438.7195472520666;2466.506698660266;2472.208893154191;2481.813863960201;2488.191337815208;2315.2128276386024;2377.5363804058215;2384.5588566115325;2385;2387.319613910036;2273.124846933934;2336.7312759032675;2337.4857531343077;2337.807718063791;2338.181636427254;2412.746661600524;2428.2351059416314;2434.2088821133025;2445.5475736988624;2447.4493239021167;2255.0528777913314;2406.867622280774;2500.312414086141;2505.361132911114;2508.109621084507;16069.758953615705;16176.919115404466;16212.675746485003;16231.83768162315;16257.349706011746;8019.599020048974;8214.958925205396;8272.586370681502;8306.626201820933;8311.584420778936;5223.843284701427;5272.947270527284;5320.361556613231;5409.323834520685;5500.724963751836;2182.978170218293;2191.2659259148204;2203.46015226269;2208.381653137124;2241.652543855705;29734.85132574345;29897;29971.70028299711;29997.25006874824;30029.549556756447;7411.777646670608;7416.332530072319;7612.391008719289;7660.084978755312;7661.157272699998;19068.618627627646;19069.523261918563;19211.903940480486;19230.134643941055;19235;8051.8792218115605;8245;8249.381296402673;8251;8264.380171487042;7515.586642734702;7553.244675532513;7597.088349397997;7666.808329791688;7681.155072941972;5723.656580605094;5789.392113828072;5890.852728681732;5948.405159484017;6012.30858451275;1596.4971034124214;1682.8233035531339;1702.2510095558162;1708.7266037433951;1738.8087310395847;973.464594473037;980.4019548075668;989.4805227255748;991.4150651115876;1005.5776573839005;9325.300602454843;9352.859707104399;9371.718848434557;9506.667266645634;9551.952240238754;1031.9226058045676;1059.300861431467;1068.7167900506372;1072.6192201768415;1086.2287775679356;5886.352501224862;5892.646441213541;5952.821415357546;5953.821385358447;6142.600730952474;23774.64338034931;24418.633720494207;24482.14312499054;24492.265232043068;24619.384515387257;22738.545229095325;24284.757152428603;24379.51240975206;24395.39011524726;24563.263102106965;11941.223820451816;12564.748705025848;12699.365031748375;12699.682507937376;12731.172473789175;9166.17504424605;9308.767280818034;9462.668806591737;9507.429554226768;9531.04689531069;20475.59048819045;20878.164873404894;20961.37115886526;21081.89459052695;21186.788132119098;21181.576368472237;21502.89248553747;21537.461563461038;21969.890150549145;22475.21336753206;12726.87273127257;12733.363331833556;12821.807672885101;12913.870861291267;12978.935105324414;15473.690526189639;15635.218239087773;15683.529494115195;15692.450764223197;15715.685686286439;11345.546178152781;11948.522059117886;12050.457729402373;12050.939745301217;12102.576409825613;12093.879061209627;12312.75374492523;12315.69210769738;12481.438335275116;12564.497420103458;11202.43987800623;11423.600173993871;11427.428628568372;11431.256968297033;11443.542258309575;8352.29005534512;8391.916080839357;8488.61801218959;8505.149485051692;8519.531425771394;8498.575071246289;8506.191911768372;8538.316934645089;8550.78623034429;8614.44006139599 12:25:52 INFO - PID 12982 | |4;prototype.html;555.1506195520855;558.2296430925322;570.1305509098622;583.7723287917712;598.2551723104735;6085.543584231184;6173.166622505966;6174.950258456062;6235.8752824943585;6248.781487609912;6090.299615544202;6142.508599312056;6143.754249830002;6181.59819611726;6254.374562543755;5123.847134394769;5125.359330083739;5133.407629147285;5156.53591176794;5192.494176688756;4926.5566099051075;4995.725235112068;5048.1670524363435;5050.368703912011;5078.898422031576;4984;5037.11850426174;5043.193089105744;5057.772400241998;5060.645754797166;4980.463870651842;5004.199328107486;5004.549590536869;5014.6991180529285;5031.52200540948;4618.6536009799065;4624.606908412789;4629.328747331618;4644.722701257181;4658.1848176568965;6186.195794546725;6194.845128871769;6225.501959843202;6234.220722409699;6240.843978900519;5861.794837180678;5919.940800591982;5939.940600593982;5973.313068997034;5981.431763982415;6578.539502234846;6634.701438435233;6634.867302653968;6649.135612370409;6699.899501507482;4871.3667223260745;5097.413797413308;5145.974270128663;5146.150885103953;5159.484051594848;6772.966135169342;6783.457323414164;6839.316068393171;6862.245153033163;6892.689828957709;5215.895682086337;5227.3465816772905;5252;5272.947270527322;5281.973590132025;5287.762050707727;5298.841034768963;5333.306670132858;5346.77024284409;5358.303420555303;5877.235959325346;5881.911771323434;5883.823485295448;5888.352281249059;5889.528837692974;6665.900011499734;6689.397954184147;6693.330666933268;6704.631245281556;6713.899291510631;1818.7453756474104;1825.872188946761;1844.5849683821034;1846.8891866488054;1854.6754317994566;1278.5077745068215;1320.1748906933167;1339.9062065655505;1344.4084602774944;1351.7026254224054;6094.725737341831;6106.969465152646;6119.265688117454;6152.78465253714;6180.6909654517995;6594.73621055162;6611.438027767597;6626.00609908508;6651.66741662915;6699.698513566804;6036.607620504653;6131.202943617302;6140.478059364915;6146.385361463907;6169.321374648785;3472.774269672463;3528.523649307362;3539.0975301298517;3542.3446662367546;3566.9108272292883;7041.612711300928;7049.506534542533;7057.89413158803;7060.823479413056;7087.964560177269;6910.991624857571;6921.861562768717;6965.268646792115;6983.650817459106;6985.091938048123;6958.686859091252;6992.93007069933;7022.876339785712;7025.505945389314;7043.436525077981;6636.13730215069;6647.368499992493;6662.533622646368;6670.866582668223;6680.298568650222;6663.26704062543;6697.296783837627;6727.764528241488;6727.966360168168;6784.728610855511;7005.586480517241;7009.864180133745;7010.773114705008;7024.964875175591;7103.147622285359;5262.447443018428;5267.499587539178;5296.947030529645;5324;5329.360476742816;6557.2131344239;6746.763863264762;6755.311172206948;6768.153980752406;6772.968109567011;6210.192674952227;6465.575409221542;6525.249596296487;6533.607983521004;6553.737850485927;6382.329855365306;6387.297397286387;6469.28837827848;6522.28254891952;6542;6137.3248942617165;6300.71646775887;6377.330380310001;6421.743130274736;6499.415052645096;6509.283978762427;6534.509911756542;6577.934220657924;6595.934040659724;6691.197056353074;6232.937670623236;6264.906026409516;6371.649559274099;6378.45783108441;6397.488200943821 12:25:52 INFO - PID 12982 | |5;yui.html;865.221300829254;894.7271082319894;897.4032268541416;983.0955520920752;1011.5599714124354;15551.611209719762;15972.281247343868;15994.440194593191;15998.999999999985;16030;15838.84161158388;15899.84100158998;16128.516144515654;16144.354225830954;17406.30374785007;1003.0420947994647;1014.3254735600835;1028.4857571214393;1028.4857571214393;1028.8096702110097;1044.780596074825;1069.7325668582855;1074.0977578833779;1075.0754351257913;1075.596651255779;1091.0834898685102;1122.5509796081592;1124.5220781168011;1138.2203190814319;1143.8055530559825;1132.4394424759755;1132.8640563132394;1133.5465813674555;1133.6429024857184;1134.9262297950606;821.035283541836;821.876718492225;822.416084579943;823.3824631526355;825.1418524734269;18116.818831811644;18497.907510462363;18601.627967440574;18626.25494980199;18635.161417736235;21905.671414928787;21930.122795088177;22277.55444891093;22607.88696056509;22613.208537701103;15504.612384690361;15778.9211053944;15824.208789560591;15980.76028859568;16031.599210019844;3977.3039718049226;3983.1237127831832;3988.641022308006;3989.122393073519;3990.3615421532418;160735.19632401763;162249;164077;165007.17496412442;166887;14907.92546037263;15261.9236903817;15474.613134671497;15504.534863954099;15521.301541430663;28779.712202878123;29363.265918352212;29422.411551768844;29449.263768405533;29641.110766677033;8772.956135219283;8844.690435834715;8892.15524525169;8966.462012279284;8997.055309192441;4093.263212621757;4179.2059508693255;4180.958190418118;4193.5387107418155;4195.538490766014;5806.274215723035;5862.8534286642325;5966.76132954677;5981.551383646244;5982.790602328898;1007.9344842585208;1020.831562792131;1023.4115383654429;1023.892491288404;1028.3469996552094;634.3941535833268;642.8939225027818;653.167211804943;660.1220376898809;660.1253339325413;12269.20250183753;12546.937265313798;12556.937215314048;12662.556810511587;13090.672733181747;734.1483878700682;739.0466298474907;743.2530307041388;750.2947229604155;755.8148253677774;5808.157817116495;5822.33043200029;5850.5612079094235;5859.853503662443;5961.821145365646;154843;158116;160274;162986.1850690739;164860.17569912312;146812;148192;148497;150773;153004;130096.34951825181;130398;131193;132755;133428.66571334357;10957.397343146204;11058;11064.446777661246;11080.667579972614;11449.542018319175;59980;61547.384526154165;61858.69070654618;62317.688411557654;62362;75108.62445687737;75156;75225.24774752402;76672;78760.2123978753;16122.838771612604;16228.675426491169;16388.344466221693;16451;16599.751003735197;18120.546986325448;19071.52321191981;19117.617647647246;19141.425757227305;19306.806931931063;14869.330880110638;15242.237888105772;15269.694606108038;15309.08145511273;15324.233788310763;14426;14534.63663408381;14581.781273280698;14613.92693036528;14653;13746.381412836246;13771.242581657703;13899.652508687364;13900.443982240597;13927.4429022842;10918.399488028235;10945.616903408343;10990.560377585127;11165.72085697864;11182.832257515982;11636.883631163919;11754.882451175721;11774.882251177722;11813.881861181279;11853.288802671867 12:25:52 INFO - PID 12982 | __end_tp_report 12:25:52 INFO - PID 12982 | __start_cc_report 12:25:52 INFO - PID 12982 | _x_x_mozilla_cycle_collect,271 12:25:52 INFO - PID 12982 | __end_cc_report 12:25:52 INFO - PID 12982 | __startTimestamp1507145152889__endTimestamp 12:25:52 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | ------- Summary: start ------- 12:25:52 INFO - PID 12982 | Number of tests: 6 12:25:52 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | [#0] dojo.html Cycles:165 Average:6359.19 Median:6632.07 stddev:767.29 (11.6%) stddev-sans-first:744.56 12:25:52 INFO - PID 12982 | Values: 3878.6 3898.0 3899.9 3904.5 3938.3 6342.0 6359.1 6388.1 6417.6 6445.7 6222.9 6332.4 6345.6 6354.9 6362.1 5679.4 5709.5 5737.5 5751.9 5765.8 5723.7 5784.7 5804.3 5806.7 5832.9 5551.6 5713.9 5718.4 5760.1 5761.7 5706.7 5718.9 5738.6 5783.3 5793.6 5662.5 5688.9 5702.9 5777.3 5777.7 6263.1 6322.1 6385.6 6408.2 6420.5 6872.7 6875.8 6960.8 6969.8 6992.0 6938.7 6940.0 7017.8 7020.5 7039.3 6359.1 6393.2 6421.8 6447.5 6473.7 6981.7 7155.5 7167.4 7174.2 7174.8 6809.7 6845.3 6887.6 6901.3 6926.7 6811.3 6889.8 6902.4 6920.1 6938.7 6839.9 6858.1 6870.9 6915.1 6951.1 6843.2 6853.7 6970.9 6975.7 6987.7 4926.9 4939.9 4988.6 5000.2 5022.1 4522.2 4532.0 4572.1 4596.6 4613.4 6300.9 6376.7 6380.2 6386.9 6394.6 6832.1 6841.7 6857.9 6921.3 6933.5 6269.8 6270.7 6310.7 6340.7 6355.6 5549.5 5611.7 5628.9 5630.5 5649.3 7175.1 7252.8 7269.5 7270.9 7285.6 6786.5 6792.6 6804.1 6868.2 6870.9 7161.4 7223.3 7240.1 7244.0 7246.3 6825.5 6833.4 6856.6 6927.9 6975.3 6804.5 6939.8 6949.3 6958.8 7029.6 6595.6 6652.8 6668.1 6668.8 6678.4 6632.1 6655.1 6662.3 6714.5 6733.0 6575.4 6644.8 6660.5 6667.8 6675.7 6704.0 6735.9 6748.1 6839.9 6842.7 6451.4 6527.2 6553.4 6590.4 6621.3 12:25:52 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | [#1] ext.html Cycles:180 Average:42409.22 Median:3745.79 stddev:64515.49 (1722.3%) stddev-sans-first:64621.33 12:25:52 INFO - PID 12982 | Values: 939.4 962.6 990.5 1020.6 1023.5 42307.8 43305.8 43368.8 43399.0 43427.1 39768.2 39825.4 39991.4 40152.4 40162.4 7674.8 7884.7 7887.4 7889.0 7889.7 7697.3 7772.5 7778.5 7790.1 7795.7 7936.8 8125.5 8126.7 8139.6 8142.2 8027.2 8102.7 8105.6 8110.7 8119.9 8258.2 8367.3 8419.2 8449.5 8462.2 40998.2 41279.8 41384.6 41439.0 41443.4 3705.0 3744.0 3747.6 3747.6 3750.4 32399.2 32403.2 32511.8 32890.3 33274.0 5439.4 5498.8 5516.3 5532.7 5558.6 212302.0 213260.0 213564.0 213694.0 213752.0 52228.0 52246.5 52260.7 52272.2 52284.2 54932.7 54961.7 54992.0 55070.2 55102.2 16294.7 16566.3 16584.8 16730.2 16744.6 13383.6 13427.4 13434.0 13455.6 13480.9 14857.0 15163.2 15191.4 15300.2 15393.8 6059.3 6119.6 6200.9 6264.4 6330.7 978.7 985.8 990.2 1003.7 1004.7 24868.0 25043.1 25167.5 25183.5 25237.1 1081.6 1098.7 1111.9 1112.4 1119.8 13170.5 13239.6 13296.2 13491.5 13556.9 280080.0 280926.0 282211.0 282307.0 283000.0 49447.5 50090.2 55823.0 55984.0 56052.2 1522.1 1527.7 1539.6 1539.9 1546.1 30677.9 30812.7 31228.5 31316.0 31402.7 40958.8 41030.4 41041.4 41059.2 41072.2 243284.0 244289.0 245545.0 245653.0 247344.0 35785.8 35924.6 36104.1 36128.3 36282.6 44454.9 45558.8 46075.3 46087.8 46183.0 34869.7 34990.3 35287.3 35349.0 35468.3 34640.5 34735.8 34829.7 35421.1 35460.6 40275.4 40475.8 40811.8 40995.2 41060.0 33418.3 33658.2 33737.0 34702.8 34722.7 25322.9 25380.6 26147.0 26241.0 26253.5 12:25:52 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | [#2] jquery.html Cycles:180 Average:40280.10 Median:22194.33 stddev:103596.90 (466.8%) stddev-sans-first:103844.73 12:25:52 INFO - PID 12982 | Values: 622.4 631.9 634.7 635.1 646.5 22674.2 22896.8 23213.2 23399.5 23703.6 25662.6 26113.3 26125.1 26147.1 26147.7 6779.2 6802.9 6837.6 6924.7 6926.2 6535.5 6768.2 6800.1 6840.9 6841.7 6788.3 6789.7 6794.5 6808.0 6810.5 6399.2 6728.2 6730.2 6754.0 6755.5 6191.3 6221.6 6232.2 6233.9 6267.2 30226.7 30570.9 30700.5 30729.2 30781.0 2006.9 2024.8 2038.2 2040.2 2048.4 20339.5 20973.2 21515.7 21583.4 21633.9 2561.8 2596.1 2616.2 2622.4 2641.4 136858.3 144976.0 157921.0 165645.2 166650.0 15301.3 16212.5 16398.3 16540.5 16582.6 26043.5 26179.2 26223.8 26228.9 26285.3 10619.6 10848.8 10858.9 10864.5 10997.3 9744.7 9764.2 9831.2 9902.5 9930.8 9900.6 9923.3 10019.6 10041.1 10147.7 2829.5 2874.2 2909.8 2973.6 2976.3 1026.4 1041.6 1044.0 1045.9 1060.5 20701.0 20720.5 20871.7 21118.3 21446.8 1091.3 1091.8 1093.2 1103.2 1115.6 11147.4 11533.8 11536.0 11671.6 11727.4 612073.0 616712.0 619631.0 621547.0 628681.0 121748.0 126747.0 127076.0 128167.0 132974.0 1094.7 1148.9 1188.4 1217.8 1250.1 21548.2 22105.7 22160.2 22228.4 22389.3 63114.7 64324.0 65526.7 66464.0 66583.7 77354.6 79522.6 80910.6 81440.0 81985.2 15179.5 15377.5 15424.5 15487.5 15566.2 23905.3 23920.9 23961.0 24044.2 24076.0 19317.6 19417.0 19624.3 19643.8 19782.1 16109.3 16134.5 16829.8 16845.4 16927.5 19123.0 19186.6 19393.5 19399.9 19858.9 15182.8 15245.5 15371.3 15388.7 15455.5 19332.3 19353.6 19377.5 19406.3 19598.1 12:25:52 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | [#3] mootools.html Cycles:180 Average:10270.52 Median:13079.60 stddev:7560.31 (57.8%) stddev-sans-first:7552.52 12:25:52 INFO - PID 12982 | Values: 1457.7 1506.0 1512.2 1515.6 1528.0 16051.6 16518.0 16523.8 16532.3 16565.5 15934.0 16283.3 16299.3 16325.7 16327.0 2438.7 2466.5 2472.2 2481.8 2488.2 2315.2 2377.5 2384.6 2385.0 2387.3 2273.1 2336.7 2337.5 2337.8 2338.2 2412.7 2428.2 2434.2 2445.5 2447.4 2255.1 2406.9 2500.3 2505.4 2508.1 16069.8 16176.9 16212.7 16231.8 16257.3 8019.6 8215.0 8272.6 8306.6 8311.6 5223.8 5272.9 5320.4 5409.3 5500.7 2183.0 2191.3 2203.5 2208.4 2241.7 29734.9 29897.0 29971.7 29997.3 30029.5 7411.8 7416.3 7612.4 7660.1 7661.2 19068.6 19069.5 19211.9 19230.1 19235.0 8051.9 8245.0 8249.4 8251.0 8264.4 7515.6 7553.2 7597.1 7666.8 7681.2 5723.7 5789.4 5890.9 5948.4 6012.3 1596.5 1682.8 1702.3 1708.7 1738.8 973.5 980.4 989.5 991.4 1005.6 9325.3 9352.9 9371.7 9506.7 9552.0 1031.9 1059.3 1068.7 1072.6 1086.2 5886.4 5892.6 5952.8 5953.8 6142.6 23774.6 24418.6 24482.1 24492.3 24619.4 22738.5 24284.8 24379.5 24395.4 24563.3 11941.2 12564.7 12699.4 12699.7 12731.2 9166.2 9308.8 9462.7 9507.4 9531.0 20475.6 20878.2 20961.4 21081.9 21186.8 21181.6 21502.9 21537.5 21969.9 22475.2 12726.9 12733.4 12821.8 12913.9 12978.9 15473.7 15635.2 15683.5 15692.5 15715.7 11345.5 11948.5 12050.5 12050.9 12102.6 12093.9 12312.8 12315.7 12481.4 12564.5 11202.4 11423.6 11427.4 11431.3 11443.5 8352.3 8391.9 8488.6 8505.1 8519.5 8498.6 8506.2 8538.3 8550.8 8614.4 12:25:52 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | [#4] prototype.html Cycles:180 Average:5634.29 Median:6177.82 stddev:1554.62 (25.2%) stddev-sans-first:1511.52 12:25:52 INFO - PID 12982 | Values: 555.2 558.2 570.1 583.8 598.3 6085.5 6173.2 6175.0 6235.9 6248.8 6090.3 6142.5 6143.8 6181.6 6254.4 5123.8 5125.4 5133.4 5156.5 5192.5 4926.6 4995.7 5048.2 5050.4 5078.9 4984.0 5037.1 5043.2 5057.8 5060.6 4980.5 5004.2 5004.5 5014.7 5031.5 4618.7 4624.6 4629.3 4644.7 4658.2 6186.2 6194.8 6225.5 6234.2 6240.8 5861.8 5919.9 5939.9 5973.3 5981.4 6578.5 6634.7 6634.9 6649.1 6699.9 4871.4 5097.4 5146.0 5146.2 5159.5 6773.0 6783.5 6839.3 6862.2 6892.7 5215.9 5227.3 5252.0 5272.9 5282.0 5287.8 5298.8 5333.3 5346.8 5358.3 5877.2 5881.9 5883.8 5888.4 5889.5 6665.9 6689.4 6693.3 6704.6 6713.9 1818.7 1825.9 1844.6 1846.9 1854.7 1278.5 1320.2 1339.9 1344.4 1351.7 6094.7 6107.0 6119.3 6152.8 6180.7 6594.7 6611.4 6626.0 6651.7 6699.7 6036.6 6131.2 6140.5 6146.4 6169.3 3472.8 3528.5 3539.1 3542.3 3566.9 7041.6 7049.5 7057.9 7060.8 7088.0 6911.0 6921.9 6965.3 6983.7 6985.1 6958.7 6992.9 7022.9 7025.5 7043.4 6636.1 6647.4 6662.5 6670.9 6680.3 6663.3 6697.3 6727.8 6728.0 6784.7 7005.6 7009.9 7010.8 7025.0 7103.1 5262.4 5267.5 5296.9 5324.0 5329.4 6557.2 6746.8 6755.3 6768.2 6773.0 6210.2 6465.6 6525.2 6533.6 6553.7 6382.3 6387.3 6469.3 6522.3 6542.0 6137.3 6300.7 6377.3 6421.7 6499.4 6509.3 6534.5 6577.9 6595.9 6691.2 6232.9 6264.9 6371.6 6378.5 6397.5 12:25:52 INFO - PID 12982 | 12:25:52 INFO - PID 12982 | [#5] yui.html Cycles:180 Average:28863.93 Median:88383.40 stddev:46219.57 (52.3%) stddev-sans-first:46301.42 12:25:52 INFO - PID 12982 | Values: 865.2 894.7 897.4 983.1 1011.6 15551.6 15972.3 15994.4 15999.0 16030.0 15838.8 15899.8 16128.5 16144.4 17406.3 1003.0 1014.3 1028.5 1028.5 1028.8 1044.8 1069.7 1074.1 1075.1 1075.6 1091.1 1122.6 1124.5 1138.2 1143.8 1132.4 1132.9 1133.5 1133.6 1134.9 821.0 821.9 822.4 823.4 825.1 18116.8 18497.9 18601.6 18626.3 18635.2 21905.7 21930.1 22277.6 22607.9 22613.2 15504.6 15778.9 15824.2 15980.8 16031.6 3977.3 3983.1 3988.6 3989.1 3990.4 160735.2 162249.0 164077.0 165007.2 166887.0 14907.9 15261.9 15474.6 15504.5 15521.3 28779.7 29363.3 29422.4 29449.3 29641.1 8773.0 8844.7 8892.2 8966.5 8997.1 4093.3 4179.2 4181.0 4193.5 4195.5 5806.3 5862.9 5966.8 5981.6 5982.8 1007.9 1020.8 1023.4 1023.9 1028.3 634.4 642.9 653.2 660.1 660.1 12269.2 12546.9 12556.9 12662.6 13090.7 734.1 739.0 743.3 750.3 755.8 5808.2 5822.3 5850.6 5859.9 5961.8 154843.0 158116.0 160274.0 162986.2 164860.2 146812.0 148192.0 148497.0 150773.0 153004.0 130096.3 130398.0 131193.0 132755.0 133428.7 10957.4 11058.0 11064.4 11080.7 11449.5 59980.0 61547.4 61858.7 62317.7 62362.0 75108.6 75156.0 75225.2 76672.0 78760.2 16122.8 16228.7 16388.3 16451.0 16599.8 18120.5 19071.5 19117.6 19141.4 19306.8 14869.3 15242.2 15269.7 15309.1 15324.2 14426.0 14534.6 14581.8 14613.9 14653.0 13746.4 13771.2 13899.7 13900.4 13927.4 10918.4 10945.6 10990.6 11165.7 11182.8 11636.9 11754.9 11774.9 11813.9 11853.3 12:25:52 INFO - PID 12982 | -------- Summary: end -------- 12:25:52 INFO - PID 12982 | 12:25:53 INFO - TEST-INFO | 12982: exit 0 12:25:53 INFO - TEST-OK | dromaeo_css | took 1107858ms 12:25:53 INFO - TEST-START | kraken 12:25:53 INFO - Initialising browser for kraken test... 12:25:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58151/getInfo.html -profile /tmp/tmpv922SC/profile 12:25:53 INFO - TEST-INFO | started process 13392 (/builds/slave/test/build/application/firefox/firefox http://localhost:58151/getInfo.html) 12:25:58 INFO - TEST-INFO | 13392: exit 0 12:25:58 INFO - Browser initialized. 12:25:58 INFO - Running cycle 1/1 for kraken test... 12:25:58 INFO - Using env: {'DISPLAY': ':0', 12:25:58 INFO - 'HOME': '/home/cltbld', 12:25:58 INFO - 'JSGC_DISABLE_POISONING': '1', 12:25:58 INFO - 'LANG': 'en_US.UTF-8', 12:25:58 INFO - 'LANGUAGE': 'en_US:en', 12:25:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:25:58 INFO - 'LOGNAME': 'cltbld', 12:25:58 INFO - 'MAIL': '/var/mail/cltbld', 12:25:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:25:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:25:58 INFO - 'MOZ_CRASHREPORTER': '1', 12:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:25:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:25:58 INFO - 'MOZ_NO_REMOTE': '1', 12:25:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:25:58 INFO - 'NO_EM_RESTART': '1', 12:25:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:25:58 INFO - 'PWD': '/builds/slave/test', 12:25:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:25:58 INFO - 'RUST_BACKTRACE': 'full', 12:25:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:25:58 INFO - 'SHELL': '/bin/bash', 12:25:58 INFO - 'SHLVL': '1', 12:25:58 INFO - 'TERM': 'linux', 12:25:58 INFO - 'TMOUT': '86400', 12:25:58 INFO - 'USER': 'cltbld', 12:25:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144', 12:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:25:58 INFO - '_': '/tools/buildbot/bin/python'} 12:25:58 INFO - TEST-INFO | started process 13667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv922SC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:25:59 INFO - PID 13667 | 12:25:59 INFO - PID 13667 | (/builds/slave/test/build/application/firefox/firefox:13718): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:25:59 INFO - PID 13667 | 12:26:09 INFO - PID 13667 | RSS: Main: 146776064 12:26:09 INFO - PID 13667 | 12:26:12 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58151/tests/kraken/driver.html?testName=audio-beat-detection) 12:26:13 INFO - PID 13667 | RSS: Main: 153411584 12:26:13 INFO - PID 13667 | 12:26:15 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58151/tests/kraken/driver.html?testName=audio-dft) 12:26:16 INFO - PID 13667 | RSS: Main: 153825280 12:26:16 INFO - PID 13667 | 12:26:19 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58151/tests/kraken/driver.html?testName=audio-fft) 12:26:19 INFO - PID 13667 | RSS: Main: 149385216 12:26:19 INFO - PID 13667 | 12:26:21 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58151/tests/kraken/driver.html?testName=audio-oscillator) 12:26:22 INFO - PID 13667 | RSS: Main: 149504000 12:26:22 INFO - PID 13667 | 12:26:24 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58151/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:26:25 INFO - PID 13667 | RSS: Main: 149839872 12:26:25 INFO - PID 13667 | 12:26:28 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58151/tests/kraken/driver.html?testName=imaging-darkroom) 12:26:28 INFO - PID 13667 | RSS: Main: 149757952 12:26:28 INFO - PID 13667 | 12:26:32 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58151/tests/kraken/driver.html?testName=imaging-desaturate) 12:26:32 INFO - PID 13667 | RSS: Main: 151003136 12:26:32 INFO - PID 13667 | 12:26:35 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58151/tests/kraken/driver.html?testName=json-parse-financial) 12:26:36 INFO - PID 13667 | RSS: Main: 152686592 12:26:36 INFO - PID 13667 | 12:26:37 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58151/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:26:38 INFO - PID 13667 | RSS: Main: 152289280 12:26:38 INFO - PID 13667 | 12:26:40 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58151/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:26:40 INFO - PID 13667 | RSS: Main: 152461312 12:26:40 INFO - PID 13667 | 12:26:42 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58151/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:26:43 INFO - PID 13667 | RSS: Main: 149377024 12:26:43 INFO - PID 13667 | 12:26:45 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58151/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:26:46 INFO - PID 13667 | RSS: Main: 149172224 12:26:46 INFO - PID 13667 | 12:26:49 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58151/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:26:49 INFO - PID 13667 | RSS: Main: 149991424 12:26:49 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | Cycle 1(1): loaded http://localhost:58151/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58151/tests/kraken/driver.html?testName=ai-astar) 12:26:51 INFO - PID 13667 | RSS: Main: 151597056 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | __start_tp_report 12:26:51 INFO - PID 13667 | _x_x_mozilla_page_load 12:26:51 INFO - PID 13667 | _x_x_mozilla_page_load_details 12:26:51 INFO - PID 13667 | |i|pagename|runs| 12:26:51 INFO - PID 13667 | |0;ai-astar;105;106;104;106;107;106;104;104;106;106 12:26:51 INFO - PID 13667 | |1;audio-beat-detection;113;122;111;112;113;113;113;111;111;112 12:26:51 INFO - PID 13667 | |2;audio-dft;162;162;162;161;160;160;161;161;165;174 12:26:51 INFO - PID 13667 | |3;audio-fft;74;80;73;74;82;74;73;74;73;75 12:26:51 INFO - PID 13667 | |4;audio-oscillator;78;79;77;79;77;77;78;78;78;78 12:26:51 INFO - PID 13667 | |5;imaging-gaussian-blur;113;114;113;113;110;114;114;115;113;114 12:26:51 INFO - PID 13667 | |6;imaging-darkroom;149;149;158;149;150;151;150;151;150;151 12:26:51 INFO - PID 13667 | |7;imaging-desaturate;94;95;93;93;94;94;95;94;95;93 12:26:51 INFO - PID 13667 | |8;json-parse-financial;45;45;43;44;44;42;43;44;43;42 12:26:51 INFO - PID 13667 | |9;json-stringify-tinderbox;35;35;36;40;35;36;35;36;35;35 12:26:51 INFO - PID 13667 | |10;stanford-crypto-aes;74;72;70;68;68;68;70;71;70;70 12:26:51 INFO - PID 13667 | |11;stanford-crypto-ccm;111;106;102;100;110;104;121;100;106;103 12:26:51 INFO - PID 13667 | |12;stanford-crypto-pbkdf2;154;149;151;148;151;148;148;157;154;151 12:26:51 INFO - PID 13667 | |13;stanford-crypto-sha256-iterative;58;58;59;63;58;58;59;58;58;57 12:26:51 INFO - PID 13667 | __end_tp_report 12:26:51 INFO - PID 13667 | __start_cc_report 12:26:51 INFO - PID 13667 | _x_x_mozilla_cycle_collect,731 12:26:51 INFO - PID 13667 | __end_cc_report 12:26:51 INFO - PID 13667 | __startTimestamp1507145211522__endTimestamp 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | ------- Summary: start ------- 12:26:51 INFO - PID 13667 | Number of tests: 14 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#0] ai-astar Cycles:10 Average:105.40 Median:106.00 stddev:1.07 (1.0%) stddev-sans-first:1.13 12:26:51 INFO - PID 13667 | Values: 105.0 106.0 104.0 106.0 107.0 106.0 104.0 104.0 106.0 106.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#1] audio-beat-detection Cycles:10 Average:113.10 Median:113.00 stddev:3.25 (2.9%) stddev-sans-first:3.44 12:26:51 INFO - PID 13667 | Values: 113.0 122.0 111.0 112.0 113.0 113.0 113.0 111.0 111.0 112.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#2] audio-dft Cycles:10 Average:162.80 Median:162.00 stddev:4.18 (2.6%) stddev-sans-first:4.43 12:26:51 INFO - PID 13667 | Values: 162.0 162.0 162.0 161.0 160.0 160.0 161.0 161.0 165.0 174.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#3] audio-fft Cycles:10 Average:75.20 Median:74.00 stddev:3.16 (4.3%) stddev-sans-first:3.32 12:26:51 INFO - PID 13667 | Values: 74.0 80.0 73.0 74.0 82.0 74.0 73.0 74.0 73.0 75.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 12:26:51 INFO - PID 13667 | Values: 78.0 79.0 77.0 79.0 77.0 77.0 78.0 78.0 78.0 78.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:114.00 stddev:1.34 (1.2%) stddev-sans-first:1.41 12:26:51 INFO - PID 13667 | Values: 113.0 114.0 113.0 113.0 110.0 114.0 114.0 115.0 113.0 114.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:150.50 stddev:2.66 (1.8%) stddev-sans-first:2.74 12:26:51 INFO - PID 13667 | Values: 149.0 149.0 158.0 149.0 150.0 151.0 150.0 151.0 150.0 151.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 12:26:51 INFO - PID 13667 | Values: 94.0 95.0 93.0 93.0 94.0 94.0 95.0 94.0 95.0 93.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#8] json-parse-financial Cycles:10 Average:43.50 Median:44.00 stddev:1.08 (2.5%) stddev-sans-first:1.00 12:26:51 INFO - PID 13667 | Values: 45.0 45.0 43.0 44.0 44.0 42.0 43.0 44.0 43.0 42.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#9] json-stringify-tinderbox Cycles:10 Average:35.80 Median:35.50 stddev:1.55 (4.4%) stddev-sans-first:1.62 12:26:51 INFO - PID 13667 | Values: 35.0 35.0 36.0 40.0 35.0 36.0 35.0 36.0 35.0 35.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#10] stanford-crypto-aes Cycles:10 Average:70.10 Median:70.00 stddev:1.91 (2.7%) stddev-sans-first:1.41 12:26:51 INFO - PID 13667 | Values: 74.0 72.0 70.0 68.0 68.0 68.0 70.0 71.0 70.0 70.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#11] stanford-crypto-ccm Cycles:10 Average:106.30 Median:106.00 stddev:6.38 (6.0%) stddev-sans-first:6.53 12:26:51 INFO - PID 13667 | Values: 111.0 106.0 102.0 100.0 110.0 104.0 121.0 100.0 106.0 103.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:151.00 stddev:3.07 (2.0%) stddev-sans-first:3.07 12:26:51 INFO - PID 13667 | Values: 154.0 149.0 151.0 148.0 151.0 148.0 148.0 157.0 154.0 151.0 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.60 Median:58.00 stddev:1.65 (2.8%) stddev-sans-first:1.73 12:26:51 INFO - PID 13667 | Values: 58.0 58.0 59.0 63.0 58.0 58.0 59.0 58.0 58.0 57.0 12:26:51 INFO - PID 13667 | -------- Summary: end -------- 12:26:51 INFO - PID 13667 | 12:26:51 INFO - PID 13667 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:26:52 INFO - TEST-INFO | 13667: exit 0 12:26:52 INFO - TEST-OK | kraken | took 58775ms 12:26:52 INFO - SUITE-END | took 1166s 12:26:52 INFO - Completed test suite (00:19:26) 12:26:52 INFO - javascript benchmark 12:26:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8962.79089450927, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3878.5539662938772, 3898.0, 3899.8635047773314, 3904.4728961590185, 3938.271419787338, 6342.048692696087, 6359.141515895352, 6388.073729309253, 6417.647029413392, 6445.677716114194, 6222.906656400146, 6332.430081292683, 6345.587536810104, 6354.904676429857, 6362.077498762676, 5679.375268720448, 5709.543236541067, 5737.512311453532, 5751.942480575203, 5765.769369225226, 5723.713814309289, 5784.739686714108, 5804.303483581976, 5806.738696758656, 5832.912506312408, 5551.639143455683, 5713.857153571194, 5718.399568045358, 5760.135979603072, 5761.654300741927, 5706.686132262724, 5718.885622287573, 5738.569607279429, 5783.277090363705, 5793.5654825887805, 5662.546996240332, 5688.857778555528, 5702.885942281131, 5777.277840269966, 5777.711114444303, 6263.060540918853, 6322.146510221107, 6385.616862988236, 6408.198975128109, 6420.4542613877875, 6872.725090996354, 6875.831108711481, 6960.756373526953, 6969.825754356131, 6991.965040174767, 6938.687759050805, 6939.965300173467, 7017.754378596725, 7020.508564400494, 7039.296070392971, 6359.141515895341, 6393.1688880445245, 6421.775237866652, 6447.54867159299, 6473.708683109271, 6981.650917454107, 7155.534890232222, 7167.4266058715175, 7174.246704095995, 7174.820629484305, 6809.693563789641, 6845.281245469254, 6887.552309099892, 6901.275366086589, 6926.688299026556, 6811.318868113248, 6889.758858440031, 6902.447804175654, 6920.100386949664, 6938.687759050754, 6839.897401538981, 6858.1084459021, 6870.896936545956, 6915.101036865175, 6951.131108611424, 6843.247242803288, 6853.657317134123, 6970.860582788316, 6975.686094125776, 6987.685554150075, 4926.876828079255, 4939.851804445872, 4988.6009119270375, 5000.199968005102, 5022.096022715946, 4522.23122069249, 4531.954680453219, 4572.1312950539295, 4596.586307232298, 4613.446386433649, 6300.936990630035, 6376.744930202833, 6380.202474690664, 6386.936130638727, 6394.584352017104, 6832.111825462757, 6841.657917104224, 6857.897131543031, 6921.342472465108, 6933.479989000845, 6269.843253918597, 6270.749170033238, 6310.716017779211, 6340.682965851689, 6355.586886852339, 5549.472800083986, 5611.7474713637985, 5628.859278517988, 5630.465105814941, 5649.293838270216, 7175.103112110986, 7252.782416527512, 7269.527480713737, 7270.927290727025, 7285.562866228043, 6786.524943254122, 6792.592444453151, 6804.115464989619, 6868.244493105854, 6870.931290687229, 7161.4270858330165, 7223.349898509159, 7240.1311842579225, 7244.022057022235, 7246.347828695442, 6825.453963682993, 6833.384995350243, 6856.6228857413325, 6927.89608155868, 6975.337342952412, 6804.4896632753735, 6939.757108501381, 6949.270326615835, 6958.826029349104, 7029.61337126463, 6595.571287866465, 6652.767153149616, 6668.099806526056, 6668.83327916806, 6678.432333251728, 6632.071509988509, 6655.134832471747, 6662.267150613526, 6714.52998290115, 6733.0, 6575.375339342755, 6644.800655980328, 6660.500462465237, 6667.799966001027, 6675.7329706813125, 6704.0, 6735.865282694416, 6748.122744043243, 6839.931600684128, 6842.692078856364, 6451.3548645135115, 6527.2167339919515, 6553.4101930826455, 6590.373914478117, 6621.33786621334], "value": 6306.092717619054, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [939.4269495607682, 962.5668449197854, 990.5493000684689, 1020.6121673763971, 1023.5342918971869, 42307.78846105769, 43305.78347108268, 43368.78315608425, 43399.0, 43427.13145737084, 39768.20463590726, 39825.40261896074, 39991.40012899809, 40152.39771403431, 40162.39756403656, 7674.846503069935, 7884.724034658788, 7887.447878648497, 7889.0, 7889.684412623488, 7697.307242348187, 7772.533647981139, 7778.533288002738, 7790.143084260727, 7795.688172473095, 7936.8412631747615, 8125.512469251864, 8126.715564955228, 8139.552324622144, 8142.185781421869, 8027.197280271956, 8102.675892964275, 8105.635246413925, 8110.716124935599, 8119.878201826978, 8258.174182581753, 8367.28878045367, 8419.158084191593, 8449.493030418133, 8462.15378462155, 40998.180036399106, 41279.7936010318, 41384.58615413868, 41439.0, 41443.37834932478, 3705.0, 3744.0, 3747.5877653458097, 3747.5877653458097, 3750.4374343848367, 32399.190020249684, 32403.189920251945, 32511.837440812644, 32890.34219315624, 33274.0, 5439.428859969726, 5498.835034948958, 5516.282883225195, 5532.723363831833, 5558.638688485235, 212302.0, 213260.0, 213564.0, 213694.0, 213752.0, 52228.0, 52246.47753522492, 52260.738696306275, 52272.21591676128, 52284.21573676398, 54932.72533637306, 54961.725191374586, 54992.0, 55070.17394739002, 55102.173467398025, 16294.674106517803, 16566.254518546455, 16584.75122873158, 16730.16349182536, 16744.581385465463, 13383.598492045254, 13427.3957671905, 13434.0, 13455.596332110052, 13480.93259533696, 14857.034292770935, 15163.241837908061, 15191.392344306325, 15300.158491282864, 15393.769093463607, 6059.303180134253, 6119.602225855394, 6200.937990620127, 6264.404881536247, 6330.746770129143, 978.7357413498315, 985.8274801909637, 990.2226751999646, 1003.7189586915675, 1004.6835246897203, 24868.0, 25043.123490678103, 25167.496650066896, 25183.48899066062, 25237.11670091538, 1081.6430577909427, 1098.7363032872054, 1111.927724697892, 1112.404863398078, 1119.8264269038311, 13170.539031133865, 13239.602811915658, 13296.202227866359, 13491.527796527074, 13556.86443135556, 280080.0, 280926.0, 282211.0, 282307.0, 283000.0, 49447.50552494501, 50090.24864627034, 55823.0, 55984.0, 56052.15921761177, 1522.0943538594518, 1527.694461107783, 1539.6074001129864, 1539.899906506096, 1546.0988665929033, 30677.947478299517, 30812.69187308188, 31228.531572025982, 31316.0, 31402.68597314089, 40958.79520602378, 41030.38454423127, 41041.384379234936, 41059.1788164241, 41072.17855642811, 243284.0, 244289.0, 245545.0, 245653.0, 247344.0, 35785.821070894475, 35924.64075359213, 36104.097397565274, 36128.27743445169, 36282.637173627925, 44454.88862778457, 45558.772206138754, 46075.3088703663, 46087.76956115198, 46183.0, 34869.651303486644, 34990.30019399547, 35287.29425411529, 35349.0, 35468.290634186655, 34640.48039279362, 34735.82632086823, 34829.65170348366, 35421.114472138404, 35460.64539354573, 40275.3958690614, 40475.797621011705, 40811.79594102011, 40995.18009639731, 41060.0, 33418.33163336671, 33658.158546036546, 33737.0, 34702.82648586741, 34722.65277347295, 25322.873385632953, 25380.619290710285, 26147.0, 26240.95036198607, 26253.50762440248], "value": 18476.00457623088, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.3776223776224, 631.8673078653483, 634.6604566556892, 635.1489004733658, 646.4505170604987, 22674.206402775908, 22896.77103228967, 23213.18753843616, 23399.532009359824, 23703.644445333313, 25662.615060774104, 26113.347166320804, 26125.085622003233, 26147.084852030184, 26147.738522614767, 6779.220389655179, 6802.931970680304, 6837.589744615314, 6924.653767311639, 6926.238113807477, 6535.477161827066, 6768.153980752406, 6800.115984921953, 6840.863182736367, 6841.692123854438, 6788.321167883221, 6789.660516974156, 6794.5243832931465, 6808.0, 6810.489213308997, 6399.200099987502, 6728.226253980782, 6730.226024007253, 6753.966230168842, 6755.459563234929, 6191.288001879751, 6221.564490485623, 6232.1898153240245, 6233.906491402633, 6267.153934218868, 30226.697733022607, 30570.930017449282, 30700.53949190764, 30729.231769205948, 30780.999235049872, 2006.9297574584966, 2024.757029156496, 2038.1949130093678, 2040.2349119080345, 2048.3957232616267, 20339.491512712153, 20973.16107355704, 21515.67726484104, 21583.352499424884, 21633.891830540746, 2561.8334808237405, 2596.1173201111524, 2616.175904590067, 2622.3706310485536, 2641.3660721426913, 136858.3157084218, 144976.0, 157921.0, 165645.17177414155, 166650.0, 15301.311440985182, 16212.513624591282, 16398.344066237456, 16540.503784886474, 16582.58543536397, 26043.479130417287, 26179.214623561325, 26223.77097946658, 26228.868855655983, 26285.34286642849, 10619.575216991389, 10848.830233953084, 10858.94570527158, 10864.456777161111, 10997.34015959045, 9744.658936937172, 9764.218862490983, 9831.213502919893, 9902.455364954852, 9930.751731206778, 9900.55447504864, 9923.305368624127, 10019.649312274036, 10041.096301332915, 10147.719333266705, 2829.5472724364004, 2874.238326843388, 2909.7526710229868, 2973.6431628204323, 2976.255936015996, 1026.414943482208, 1041.614602597044, 1043.9582416703247, 1045.8913551635292, 1060.4697651174413, 20701.0, 20720.48198795042, 20871.686924696143, 21118.260860869796, 21446.78553214448, 1091.252492042953, 1091.7543552700579, 1093.1965005720788, 1103.1615971861443, 1115.6151127860876, 11147.38689372076, 11533.769324613628, 11536.0, 11671.64985050433, 11727.35499547533, 612073.0, 616712.0, 619631.0, 621547.0, 628681.0, 121748.0, 126747.0, 127076.0, 128167.0, 132974.00538991924, 1094.6934858239706, 1148.8736239013701, 1188.3523479703583, 1217.78688729472, 1250.0749445410331, 21548.233980175755, 22105.66841497411, 22160.224392146196, 22228.444288892908, 22389.32832015042, 63114.68442657757, 64324.0, 65526.67236663786, 66464.0, 66583.66708166429, 77354.6132269335, 79522.60238699001, 80910.5954470224, 81440.0, 81985.18014819776, 15179.468718594797, 15377.461788837338, 15424.5372638821, 15487.457938972082, 15566.221688915735, 23905.28284151478, 23920.88039559791, 23961.0, 24044.15845445401, 24076.0, 19317.574285871186, 19417.0, 19624.313149040285, 19643.803561964196, 19782.109805059095, 16109.275082621545, 16134.515964521082, 16829.831701683317, 16845.41041063557, 16927.49217523476, 19123.0, 19186.616267674846, 19393.51516212106, 19399.90300048491, 19858.90070549638, 15182.848171518584, 15245.466408675644, 15371.30829112715, 15388.692226155637, 15455.459058933333, 19332.323368682028, 19353.612927741084, 19377.51556211106, 19406.320778772675, 19598.11808468594], "value": 12801.171566733907, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.6865973815632, 1505.9774103388459, 1512.2136489025706, 1515.5832146159812, 1527.9999999999995, 16051.59871003224, 16518.0, 16523.83476165238, 16532.25604847782, 16565.503034908957, 15934.0, 16283.348666053344, 16299.266533006014, 16325.673486530262, 16327.0, 2438.7195472520666, 2466.506698660266, 2472.208893154191, 2481.813863960201, 2488.191337815208, 2315.2128276386024, 2377.5363804058215, 2384.5588566115325, 2385.0, 2387.319613910036, 2273.124846933934, 2336.7312759032675, 2337.4857531343077, 2337.807718063791, 2338.181636427254, 2412.746661600524, 2428.2351059416314, 2434.2088821133025, 2445.5475736988624, 2447.4493239021167, 2255.0528777913314, 2406.867622280774, 2500.312414086141, 2505.361132911114, 2508.109621084507, 16069.758953615705, 16176.919115404466, 16212.675746485003, 16231.83768162315, 16257.349706011746, 8019.599020048974, 8214.958925205396, 8272.586370681502, 8306.626201820933, 8311.584420778936, 5223.843284701427, 5272.947270527284, 5320.361556613231, 5409.323834520685, 5500.724963751836, 2182.978170218293, 2191.2659259148204, 2203.46015226269, 2208.381653137124, 2241.652543855705, 29734.85132574345, 29897.0, 29971.70028299711, 29997.25006874824, 30029.549556756447, 7411.777646670608, 7416.332530072319, 7612.391008719289, 7660.084978755312, 7661.157272699998, 19068.618627627646, 19069.523261918563, 19211.903940480486, 19230.134643941055, 19235.0, 8051.8792218115605, 8245.0, 8249.381296402673, 8251.0, 8264.380171487042, 7515.586642734702, 7553.244675532513, 7597.088349397997, 7666.808329791688, 7681.155072941972, 5723.656580605094, 5789.392113828072, 5890.852728681732, 5948.405159484017, 6012.30858451275, 1596.4971034124214, 1682.8233035531339, 1702.2510095558162, 1708.7266037433951, 1738.8087310395847, 973.464594473037, 980.4019548075668, 989.4805227255748, 991.4150651115876, 1005.5776573839005, 9325.300602454843, 9352.859707104399, 9371.718848434557, 9506.667266645634, 9551.952240238754, 1031.9226058045676, 1059.300861431467, 1068.7167900506372, 1072.6192201768415, 1086.2287775679356, 5886.352501224862, 5892.646441213541, 5952.821415357546, 5953.821385358447, 6142.600730952474, 23774.64338034931, 24418.633720494207, 24482.14312499054, 24492.265232043068, 24619.384515387257, 22738.545229095325, 24284.757152428603, 24379.51240975206, 24395.39011524726, 24563.263102106965, 11941.223820451816, 12564.748705025848, 12699.365031748375, 12699.682507937376, 12731.172473789175, 9166.17504424605, 9308.767280818034, 9462.668806591737, 9507.429554226768, 9531.04689531069, 20475.59048819045, 20878.164873404894, 20961.37115886526, 21081.89459052695, 21186.788132119098, 21181.576368472237, 21502.89248553747, 21537.461563461038, 21969.890150549145, 22475.21336753206, 12726.87273127257, 12733.363331833556, 12821.807672885101, 12913.870861291267, 12978.935105324414, 15473.690526189639, 15635.218239087773, 15683.529494115195, 15692.450764223197, 15715.685686286439, 11345.546178152781, 11948.522059117886, 12050.457729402373, 12050.939745301217, 12102.576409825613, 12093.879061209627, 12312.75374492523, 12315.69210769738, 12481.438335275116, 12564.497420103458, 11202.43987800623, 11423.600173993871, 11427.428628568372, 11431.256968297033, 11443.542258309575, 8352.29005534512, 8391.916080839357, 8488.61801218959, 8505.149485051692, 8519.531425771394, 8498.575071246289, 8506.191911768372, 8538.316934645089, 8550.78623034429, 8614.44006139599], "value": 7164.941445665281, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.1506195520855, 558.2296430925322, 570.1305509098622, 583.7723287917712, 598.2551723104735, 6085.543584231184, 6173.166622505966, 6174.950258456062, 6235.8752824943585, 6248.781487609912, 6090.299615544202, 6142.508599312056, 6143.754249830002, 6181.59819611726, 6254.374562543755, 5123.847134394769, 5125.359330083739, 5133.407629147285, 5156.53591176794, 5192.494176688756, 4926.5566099051075, 4995.725235112068, 5048.1670524363435, 5050.368703912011, 5078.898422031576, 4984.0, 5037.11850426174, 5043.193089105744, 5057.772400241998, 5060.645754797166, 4980.463870651842, 5004.199328107486, 5004.549590536869, 5014.6991180529285, 5031.52200540948, 4618.6536009799065, 4624.606908412789, 4629.328747331618, 4644.722701257181, 4658.1848176568965, 6186.195794546725, 6194.845128871769, 6225.501959843202, 6234.220722409699, 6240.843978900519, 5861.794837180678, 5919.940800591982, 5939.940600593982, 5973.313068997034, 5981.431763982415, 6578.539502234846, 6634.701438435233, 6634.867302653968, 6649.135612370409, 6699.899501507482, 4871.3667223260745, 5097.413797413308, 5145.974270128663, 5146.150885103953, 5159.484051594848, 6772.966135169342, 6783.457323414164, 6839.316068393171, 6862.245153033163, 6892.689828957709, 5215.895682086337, 5227.3465816772905, 5252.0, 5272.947270527322, 5281.973590132025, 5287.762050707727, 5298.841034768963, 5333.306670132858, 5346.77024284409, 5358.303420555303, 5877.235959325346, 5881.911771323434, 5883.823485295448, 5888.352281249059, 5889.528837692974, 6665.900011499734, 6689.397954184147, 6693.330666933268, 6704.631245281556, 6713.899291510631, 1818.7453756474104, 1825.872188946761, 1844.5849683821034, 1846.8891866488054, 1854.6754317994566, 1278.5077745068215, 1320.1748906933167, 1339.9062065655505, 1344.4084602774944, 1351.7026254224054, 6094.725737341831, 6106.969465152646, 6119.265688117454, 6152.78465253714, 6180.6909654517995, 6594.73621055162, 6611.438027767597, 6626.00609908508, 6651.66741662915, 6699.698513566804, 6036.607620504653, 6131.202943617302, 6140.478059364915, 6146.385361463907, 6169.321374648785, 3472.774269672463, 3528.523649307362, 3539.0975301298517, 3542.3446662367546, 3566.9108272292883, 7041.612711300928, 7049.506534542533, 7057.89413158803, 7060.823479413056, 7087.964560177269, 6910.991624857571, 6921.861562768717, 6965.268646792115, 6983.650817459106, 6985.091938048123, 6958.686859091252, 6992.93007069933, 7022.876339785712, 7025.505945389314, 7043.436525077981, 6636.13730215069, 6647.368499992493, 6662.533622646368, 6670.866582668223, 6680.298568650222, 6663.26704062543, 6697.296783837627, 6727.764528241488, 6727.966360168168, 6784.728610855511, 7005.586480517241, 7009.864180133745, 7010.773114705008, 7024.964875175591, 7103.147622285359, 5262.447443018428, 5267.499587539178, 5296.947030529645, 5324.0, 5329.360476742816, 6557.2131344239, 6746.763863264762, 6755.311172206948, 6768.153980752406, 6772.968109567011, 6210.192674952227, 6465.575409221542, 6525.249596296487, 6533.607983521004, 6553.737850485927, 6382.329855365306, 6387.297397286387, 6469.28837827848, 6522.28254891952, 6542.0, 6137.3248942617165, 6300.71646775887, 6377.330380310001, 6421.743130274736, 6499.415052645096, 6509.283978762427, 6534.509911756542, 6577.934220657924, 6595.934040659724, 6691.197056353074, 6232.937670623236, 6264.906026409516, 6371.649559274099, 6378.45783108441, 6397.488200943821], "value": 5191.808843304817, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [865.221300829254, 894.7271082319894, 897.4032268541416, 983.0955520920752, 1011.5599714124354, 15551.611209719762, 15972.281247343868, 15994.440194593191, 15998.999999999985, 16030.0, 15838.84161158388, 15899.84100158998, 16128.516144515654, 16144.354225830954, 17406.30374785007, 1003.0420947994647, 1014.3254735600835, 1028.4857571214393, 1028.4857571214393, 1028.8096702110097, 1044.780596074825, 1069.7325668582855, 1074.0977578833779, 1075.0754351257913, 1075.596651255779, 1091.0834898685102, 1122.5509796081592, 1124.5220781168011, 1138.2203190814319, 1143.8055530559825, 1132.4394424759755, 1132.8640563132394, 1133.5465813674555, 1133.6429024857184, 1134.9262297950606, 821.035283541836, 821.876718492225, 822.416084579943, 823.3824631526355, 825.1418524734269, 18116.818831811644, 18497.907510462363, 18601.627967440574, 18626.25494980199, 18635.161417736235, 21905.671414928787, 21930.122795088177, 22277.55444891093, 22607.88696056509, 22613.208537701103, 15504.612384690361, 15778.9211053944, 15824.208789560591, 15980.76028859568, 16031.599210019844, 3977.3039718049226, 3983.1237127831832, 3988.641022308006, 3989.122393073519, 3990.3615421532418, 160735.19632401763, 162249.0, 164077.0, 165007.17496412442, 166887.0, 14907.92546037263, 15261.9236903817, 15474.613134671497, 15504.534863954099, 15521.301541430663, 28779.712202878123, 29363.265918352212, 29422.411551768844, 29449.263768405533, 29641.110766677033, 8772.956135219283, 8844.690435834715, 8892.15524525169, 8966.462012279284, 8997.055309192441, 4093.263212621757, 4179.2059508693255, 4180.958190418118, 4193.5387107418155, 4195.538490766014, 5806.274215723035, 5862.8534286642325, 5966.76132954677, 5981.551383646244, 5982.790602328898, 1007.9344842585208, 1020.831562792131, 1023.4115383654429, 1023.892491288404, 1028.3469996552094, 634.3941535833268, 642.8939225027818, 653.167211804943, 660.1220376898809, 660.1253339325413, 12269.20250183753, 12546.937265313798, 12556.937215314048, 12662.556810511587, 13090.672733181747, 734.1483878700682, 739.0466298474907, 743.2530307041388, 750.2947229604155, 755.8148253677774, 5808.157817116495, 5822.33043200029, 5850.5612079094235, 5859.853503662443, 5961.821145365646, 154843.0, 158116.0, 160274.0, 162986.1850690739, 164860.17569912312, 146812.0, 148192.0, 148497.0, 150773.0, 153004.0, 130096.34951825181, 130398.0, 131193.0, 132755.0, 133428.66571334357, 10957.397343146204, 11058.0, 11064.446777661246, 11080.667579972614, 11449.542018319175, 59980.0, 61547.384526154165, 61858.69070654618, 62317.688411557654, 62362.0, 75108.62445687737, 75156.0, 75225.24774752402, 76672.0, 78760.2123978753, 16122.838771612604, 16228.675426491169, 16388.344466221693, 16451.0, 16599.751003735197, 18120.546986325448, 19071.52321191981, 19117.617647647246, 19141.425757227305, 19306.806931931063, 14869.330880110638, 15242.237888105772, 15269.694606108038, 15309.08145511273, 15324.233788310763, 14426.0, 14534.63663408381, 14581.781273280698, 14613.92693036528, 14653.0, 13746.381412836246, 13771.242581657703, 13899.652508687364, 13900.443982240597, 13927.4429022842, 10918.399488028235, 10945.616903408343, 10990.560377585127, 11165.72085697864, 11182.832257515982, 11636.883631163919, 11754.882451175721, 11774.882251177722, 11813.881861181279, 11853.288802671867], "value": 9342.945257396801, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1357.8999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 104.0, 106.0, 107.0, 106.0, 104.0, 104.0, 106.0, 106.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 122.0, 111.0, 112.0, 113.0, 113.0, 113.0, 111.0, 111.0, 112.0], "value": 113.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 161.0, 160.0, 160.0, 161.0, 161.0, 165.0, 174.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 73.0, 74.0, 82.0, 74.0, 73.0, 74.0, 73.0, 75.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 77.0, 79.0, 77.0, 77.0, 78.0, 78.0, 78.0, 78.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 110.0, 114.0, 114.0, 115.0, 113.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 158.0, 149.0, 150.0, 151.0, 150.0, 151.0, 150.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 93.0, 93.0, 94.0, 94.0, 95.0, 94.0, 95.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 43.0, 44.0, 44.0, 42.0, 43.0, 44.0, 43.0, 42.0], "value": 43.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [35.0, 35.0, 36.0, 40.0, 35.0, 36.0, 35.0, 36.0, 35.0, 35.0], "value": 35.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 70.0, 68.0, 68.0, 68.0, 70.0, 71.0, 70.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 102.0, 100.0, 110.0, 104.0, 121.0, 100.0, 106.0, 103.0], "value": 106.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 149.0, 151.0, 148.0, 151.0, 148.0, 148.0, 157.0, 154.0, 151.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 63.0, 58.0, 58.0, 59.0, 58.0, 58.0, 57.0], "value": 58.6, "unit": "score"}]}]} 12:26:52 INFO - Return code: 0 12:26:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:26:52 INFO - # TBPL SUCCESS # 12:26:52 INFO - Running post-action listener: _package_coverage_data 12:26:52 INFO - Running post-action listener: _resource_record_post_action 12:26:52 INFO - [mozharness: 2017-10-04 19:26:52.318516Z] Finished run-tests step (success) 12:26:52 INFO - Running post-run listener: _resource_record_post_run 12:26:52 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 57344; Write bytes: 394366976; Read time: 456; Write time: 287604 12:26:52 INFO - TinderboxPrint: CPU usage
13.1% 12:26:52 INFO - TinderboxPrint: I/O read bytes / time
57,344 / 456 12:26:52 INFO - TinderboxPrint: I/O write bytes / time
394,366,976 / 287,604 12:26:52 INFO - TinderboxPrint: CPU idle
8,212.2 (86.9%) 12:26:52 INFO - TinderboxPrint: CPU user
1,187.9 (12.6%) 12:26:52 INFO - TinderboxPrint: Swap in / out
0 / 0 12:26:52 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 12288; Write bytes: 77086720; Read time: 260; Write time: 55324 12:26:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:26:52 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 40960; Write bytes: 317280256; Read time: 188; Write time: 232280 12:26:52 INFO - Running post-run listener: _upload_blobber_files 12:26:52 INFO - Blob upload gear active. 12:26:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:26:52 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 12:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:26:53 INFO - (blobuploader) - INFO - Open directory for files ... 12:26:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:26:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:26:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:54 INFO - (blobuploader) - INFO - Done attempting. 12:26:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:26:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:26:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:58 INFO - (blobuploader) - INFO - Done attempting. 12:26:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:26:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:26:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:59 INFO - (blobuploader) - INFO - Done attempting. 12:26:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:26:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:27:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:00 INFO - (blobuploader) - INFO - Done attempting. 12:27:00 INFO - (blobuploader) - INFO - Iteration through files over. 12:27:00 INFO - Return code: 0 12:27:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:27:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:27:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/678a9cf728257582de748168f04f16b617f219b8119f3a76a346c566f2a1b9485eabe9272057882108afc84d7df140ee8f9dad1ec2d46bb76b69462a82d02cd6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80f74c6166534707498d2df4db98e2cbdb2ed9d3e2f5aeade097716103a040746c4e0ea4286aa8f1a5b682440321b02a0045de23c3d3e084fff8f90432a8193e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b91b26ac2e1b0e39ce7302ef599b42d897acc8d4848575c9eb07ed84a79ba952acaf0ee4a33128f4c7428fc4e98f423dd93ba828595bf6eb0b2713bb37c82c85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea97a05c694964a1beb921c5f1c66f66b466db2444d70257862260a0d93448dca784383a3f04e5a7e224b0b28a67ff6bbc708aa8e892edba7d71d2ed8aa4e3ed"} 12:27:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:27:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:27:00 INFO - Contents: 12:27:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/678a9cf728257582de748168f04f16b617f219b8119f3a76a346c566f2a1b9485eabe9272057882108afc84d7df140ee8f9dad1ec2d46bb76b69462a82d02cd6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80f74c6166534707498d2df4db98e2cbdb2ed9d3e2f5aeade097716103a040746c4e0ea4286aa8f1a5b682440321b02a0045de23c3d3e084fff8f90432a8193e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b91b26ac2e1b0e39ce7302ef599b42d897acc8d4848575c9eb07ed84a79ba952acaf0ee4a33128f4c7428fc4e98f423dd93ba828595bf6eb0b2713bb37c82c85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea97a05c694964a1beb921c5f1c66f66b466db2444d70257862260a0d93448dca784383a3f04e5a7e224b0b28a67ff6bbc708aa8e892edba7d71d2ed8aa4e3ed"} 12:27:00 INFO - Running post-run listener: copy_logs_to_upload_dir 12:27:00 INFO - Copying logs to upload dir... 12:27:00 INFO - mkdir: /builds/slave/test/build/upload/logs 12:27:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.765131 ========= master_lag: 0.25 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-04 12:27:00.971401) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-04 12:27:00.976291) ========= 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=ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/678a9cf728257582de748168f04f16b617f219b8119f3a76a346c566f2a1b9485eabe9272057882108afc84d7df140ee8f9dad1ec2d46bb76b69462a82d02cd6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80f74c6166534707498d2df4db98e2cbdb2ed9d3e2f5aeade097716103a040746c4e0ea4286aa8f1a5b682440321b02a0045de23c3d3e084fff8f90432a8193e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b91b26ac2e1b0e39ce7302ef599b42d897acc8d4848575c9eb07ed84a79ba952acaf0ee4a33128f4c7428fc4e98f423dd93ba828595bf6eb0b2713bb37c82c85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea97a05c694964a1beb921c5f1c66f66b466db2444d70257862260a0d93448dca784383a3f04e5a7e224b0b28a67ff6bbc708aa8e892edba7d71d2ed8aa4e3ed"} build_url:https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/EGsrJkSyRyyUTBZ-iPsEpw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/678a9cf728257582de748168f04f16b617f219b8119f3a76a346c566f2a1b9485eabe9272057882108afc84d7df140ee8f9dad1ec2d46bb76b69462a82d02cd6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80f74c6166534707498d2df4db98e2cbdb2ed9d3e2f5aeade097716103a040746c4e0ea4286aa8f1a5b682440321b02a0045de23c3d3e084fff8f90432a8193e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b91b26ac2e1b0e39ce7302ef599b42d897acc8d4848575c9eb07ed84a79ba952acaf0ee4a33128f4c7428fc4e98f423dd93ba828595bf6eb0b2713bb37c82c85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea97a05c694964a1beb921c5f1c66f66b466db2444d70257862260a0d93448dca784383a3f04e5a7e224b0b28a67ff6bbc708aa8e892edba7d71d2ed8aa4e3ed"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-04 12:27:01.008069) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 12:27:01.008393) ========= 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=ed14279e38b05397cf4aa391000001a4-1507143346.866841-755798144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 12:27:01.088576) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-04 12:27:01.091888) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-04 12:27:01.092180) ========= ========= Total master_lag: 0.42 =========