builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1504549220.98 results: success (0) revision: 1401e3eec44df87963d3af329ef8a4183ab0483f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:20.981985) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:20.982794) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:20.983328) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:21.003475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:21.004107) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 11:20:21-- 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% 42.0M=0s 2017-09-04 11:20:21 (42.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121903 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:21.145905) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:21.146472) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024471 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:21.197223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-04 11:20:21.197615) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1401e3eec44df87963d3af329ef8a4183ab0483f --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 1401e3eec44df87963d3af329ef8a4183ab0483f --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=934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 11:20:21,251 truncating revision to first 12 chars 2017-09-04 11:20:21,251 Setting DEBUG logging. 2017-09-04 11:20:21,251 attempt 1/10 2017-09-04 11:20:21,251 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1401e3eec44d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 11:20:22,758 unpacking tar archive at: mozilla-central-1401e3eec44d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.897673 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-04 11:20:23.205379) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:23.205709) ========= script_repo_revision: 1401e3eec44df87963d3af329ef8a4183ab0483f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:23.206180) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:23.206442) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 11:20:23.222967) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-04 11:20:23.223361) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:20:23 INFO - MultiFileLogger online at 20170904 11:20:23 in /builds/slave/test 11:20:23 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 11:20:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:20:23 INFO - {'append_to_log': False, 11:20:23 INFO - 'base_work_dir': '/builds/slave/test', 11:20:23 INFO - 'blob_upload_branch': 'Firefox', 11:20:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:20:23 INFO - 'branch': 'Firefox', 11:20:23 INFO - 'buildbot_json_path': 'buildprops.json', 11:20:23 INFO - 'code_coverage': False, 11:20:23 INFO - 'config_files': ('talos/linux_config.py',), 11:20:23 INFO - 'default_actions': ('clobber', 11:20:23 INFO - 'read-buildbot-config', 11:20:23 INFO - 'download-and-extract', 11:20:23 INFO - 'populate-webroot', 11:20:23 INFO - 'create-virtualenv', 11:20:23 INFO - 'install', 11:20:23 INFO - 'setup-mitmproxy', 11:20:23 INFO - 'run-tests'), 11:20:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:20:23 INFO - 'disable_ccov_upload': False, 11:20:23 INFO - 'disable_stylo': False, 11:20:23 INFO - 'download_minidump_stackwalk': True, 11:20:23 INFO - 'download_symbols': 'ondemand', 11:20:23 INFO - 'enable_stylo': False, 11:20:23 INFO - 'enable_webrender': False, 11:20:23 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:20:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:20:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:20:23 INFO - 'gecko_profile': False, 11:20:23 INFO - 'gecko_profile_interval': 0, 11:20:23 INFO - 'installer_path': 'installer.exe', 11:20:23 INFO - 'log_level': 'info', 11:20:23 INFO - 'log_name': 'talos', 11:20:23 INFO - 'log_to_console': True, 11:20:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:20:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:20:23 INFO - 'opt_config_files': (), 11:20:23 INFO - 'pip_index': False, 11:20:23 INFO - 'suite': 'dromaeojs-e10s', 11:20:23 INFO - 'system_bits': '32', 11:20:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:20:23 INFO - 'title': 'talos-linux64-ix-057', 11:20:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:20:23 INFO - 'use_talos_json': True, 11:20:23 INFO - 'verify': 'False', 11:20:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:20:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:20:23 INFO - 'work_dir': 'build'} 11:20:23 INFO - [mozharness: 2017-09-04 18:20:23.412709Z] Running clobber step. 11:20:23 INFO - Running pre-action listener: _resource_record_pre_action 11:20:23 INFO - Running main action method: clobber 11:20:23 INFO - rmtree: /builds/slave/test/build 11:20:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:20:24 INFO - Running post-action listener: _resource_record_post_action 11:20:24 INFO - [mozharness: 2017-09-04 18:20:24.938916Z] Finished clobber step (success) 11:20:24 INFO - [mozharness: 2017-09-04 18:20:24.939015Z] Running read-buildbot-config step. 11:20:24 INFO - Running pre-action listener: _resource_record_pre_action 11:20:24 INFO - Running main action method: read_buildbot_config 11:20:24 INFO - Using buildbot properties: 11:20:24 INFO - { 11:20:24 INFO - "project": "", 11:20:24 INFO - "product": "firefox", 11:20:24 INFO - "who": "ffxbld@noreply.mozilla.org", 11:20:24 INFO - "installer_path": "public/build/target.tar.bz2", 11:20:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 11:20:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:20:24 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 11:20:24 INFO - "stage_platform": "linux64-stylosequential", 11:20:24 INFO - "basedir": "/builds/slave/test", 11:20:24 INFO - "buildnumber": 231, 11:20:24 INFO - "platform": "ubuntu64_hw_styloseq", 11:20:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:20:24 INFO - "slavebuilddir": "test", 11:20:24 INFO - "taskId": "D8jDaL3-QMemromepLub_g", 11:20:24 INFO - "branch": "mozilla-central", 11:20:24 INFO - "script_repo_revision": "production", 11:20:24 INFO - "revision": "1401e3eec44df87963d3af329ef8a4183ab0483f", 11:20:24 INFO - "slavename": "talos-linux64-ix-057", 11:20:24 INFO - "repo_path": "mozilla-central" 11:20:24 INFO - } 11:20:24 INFO - Finding installer, test and symbols from parent task. 11:20:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8jDaL3-QMemromepLub_g'}, attempt #1 11:20:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8jDaL3-QMemromepLub_g'}, attempt #1 11:20:25 INFO - Task dependencies: MRCASI0zQO2Hf-L6s5C12g 11:20:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g'}, attempt #1 11:20:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.tar.bz2 11:20:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.test_packages.json 11:20:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.crashreporter-symbols.zip 11:20:26 INFO - Running post-action listener: _resource_record_post_action 11:20:26 INFO - [mozharness: 2017-09-04 18:20:26.381241Z] Finished read-buildbot-config step (success) 11:20:26 INFO - [mozharness: 2017-09-04 18:20:26.381461Z] Running download-and-extract step. 11:20:26 INFO - Running pre-action listener: _resource_record_pre_action 11:20:26 INFO - Running main action method: download_and_extract 11:20:26 INFO - mkdir: /builds/slave/test/build/tests 11:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:20:26 INFO - trying https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.test_packages.json 11:20:26 INFO - Downloading https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:20:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:20:26 INFO - Downloaded 989 bytes. 11:20:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:20:26 INFO - Using the following test package requirements: 11:20:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:20:26 INFO - u'common': [u'target.common.tests.zip'], 11:20:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:20:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:20:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:20:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:20:26 INFO - u'mozbase': [u'target.common.tests.zip'], 11:20:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:20:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:20:26 INFO - u'web-platform': [u'target.common.tests.zip', 11:20:26 INFO - u'target.web-platform.tests.tar.gz'], 11:20:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:20:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:20:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.common.tests.zip 11:20:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:20:26 INFO - Fetch https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.common.tests.zip into memory 11:20:28 INFO - Content-Length response header: 55022376 11:20:28 INFO - Bytes received: 55022376 11:20:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:20:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.common.tests.zip 11:20:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:20:39 INFO - Fetch https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.common.tests.zip into memory 11:20:40 INFO - Content-Length response header: 55022376 11:20:40 INFO - Bytes received: 55022376 11:20:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.talos.tests.zip 11:20:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:20:52 INFO - Fetch https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.talos.tests.zip into memory 11:20:52 INFO - Content-Length response header: 14496650 11:20:52 INFO - Bytes received: 14496650 11:20: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')]} 11:20:53 INFO - trying https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.tar.bz2 11:20:53 INFO - Downloading https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:20:54 INFO - Downloaded 62647258 bytes. 11:20:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.tar.bz2 11:20:54 INFO - mkdir: /builds/slave/test/properties 11:20:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:20:54 INFO - Writing to file /builds/slave/test/properties/build_url 11:20:54 INFO - Contents: 11:20:54 INFO - build_url:https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.tar.bz2 11:20:54 INFO - Running post-action listener: _resource_record_post_action 11:20:54 INFO - Running post-action listener: find_tests_for_verification 11:20:54 INFO - Running post-action listener: set_extra_try_arguments 11:20:54 INFO - [mozharness: 2017-09-04 18:20:54.208414Z] Finished download-and-extract step (success) 11:20:54 INFO - [mozharness: 2017-09-04 18:20:54.208512Z] Running populate-webroot step. 11:20:54 INFO - Running pre-action listener: _resource_record_pre_action 11:20:54 INFO - Running main action method: populate_webroot 11:20:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:20:54 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:20:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:20:54 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:20:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:20:54 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:20:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'tests': ['damp', 'tps']}, 11:20:54 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['damp', 'tps']}, 11:20:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:20:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:20:54 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:20:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:20:54 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:20:54 INFO - 'other-e10s': {'tests': ['a11yr', 11:20:54 INFO - 'ts_paint', 11:20:54 INFO - 'tpaint', 11:20:54 INFO - 'sessionrestore', 11:20:54 INFO - 'sessionrestore_many_windows', 11:20:54 INFO - 'sessionrestore_no_auto_restore', 11:20:54 INFO - 'tabpaint']}, 11:20:54 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['a11yr', 11:20:54 INFO - 'ts_paint', 11:20:54 INFO - 'tpaint', 11:20:54 INFO - 'sessionrestore', 11:20:54 INFO - 'sessionrestore_many_windows', 11:20:54 INFO - 'sessionrestore_no_auto_restore', 11:20:54 INFO - 'tabpaint']}, 11:20:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:20:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:20:54 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['bloom_basic_singleton']}, 11:20:54 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['bloom_basic']}, 11:20:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:20:54 INFO - 'tsvgr_opacity', 11:20:54 INFO - 'tart', 11:20:54 INFO - 'tscrollx', 11:20:54 INFO - 'tsvg_static']}, 11:20:54 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['tsvgx', 11:20:54 INFO - 'tsvgr_opacity', 11:20:54 INFO - 'tart', 11:20:54 INFO - 'tscrollx', 11:20:54 INFO - 'tsvg_static']}, 11:20:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:20:54 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'talos_options': ['--enable-stylo'], 11:20:54 INFO - 'tests': ['tp5o']}, 11:20:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:20:54 INFO - 'talos_options': ['--mitmproxy', 11:20:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:20:54 INFO - '--firstNonBlankPaint'], 11:20:54 INFO - 'tests': ['tp6_google', 11:20:54 INFO - 'tp6_youtube', 11:20:54 INFO - 'tp6_amazon', 11:20:54 INFO - 'tp6_facebook']}, 11:20:54 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:20:54 INFO - 'talos_options': ['--enable-stylo', 11:20:54 INFO - '--mitmproxy', 11:20:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:20:54 INFO - '--firstNonBlankPaint'], 11:20:54 INFO - 'tests': ['tp6_google', 11:20:54 INFO - 'tp6_youtube', 11:20:54 INFO - 'tp6_amazon', 11:20:54 INFO - 'tp6_facebook']}, 11:20:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:20:54 INFO - 'talos_options': ['--enable-stylo', 11:20:54 INFO - '--stylo-threads=1', 11:20:54 INFO - '--mitmproxy', 11:20:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:20:54 INFO - '--firstNonBlankPaint'], 11:20:54 INFO - 'tests': ['tp6_google', 11:20:54 INFO - 'tp6_youtube', 11:20:54 INFO - 'tp6_amazon', 11:20:54 INFO - 'tp6_facebook']}, 11:20:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'talos_options': ['--xperf_path', 11:20:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:20:54 INFO - 'tests': ['tp5n']}, 11:20:54 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:20:54 INFO - 'talos_options': ['--enable-stylo', 11:20:54 INFO - '--xperf_path', 11:20:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:20:54 INFO - 'tests': ['tp5n']}}} 11:20:54 INFO - Running post-action listener: _resource_record_post_action 11:20:54 INFO - [mozharness: 2017-09-04 18:20:54.222817Z] Finished populate-webroot step (success) 11:20:54 INFO - [mozharness: 2017-09-04 18:20:54.222907Z] Running create-virtualenv step. 11:20:54 INFO - Running pre-action listener: _resource_record_pre_action 11:20:54 INFO - Running main action method: create_virtualenv 11:20:54 INFO - Creating virtualenv /builds/slave/test/build/venv 11:20:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:20:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:20:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:20:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:20:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:20:54 INFO - Installing distribute.............................................................................................................................................................................................done. 11:20:55 INFO - Installing pip...............done. 11:20:55 INFO - Return code: 0 11:20:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:20:55 INFO - Reading from file tmpfile_stdout 11:20:55 INFO - Output received: 11:20:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:20:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:20: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:20: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', 'pip>=1.5'] in /builds/slave/test/build 11:20: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 pip>=1.5 11:20:55 INFO - Using env: {'DISPLAY': ':0', 11:20:55 INFO - 'HOME': '/home/cltbld', 11:20:55 INFO - 'LANG': 'en_US.UTF-8', 11:20:55 INFO - 'LANGUAGE': 'en_US:en', 11:20:55 INFO - 'LOGNAME': 'cltbld', 11:20:55 INFO - 'MAIL': '/var/mail/cltbld', 11:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:20:55 INFO - 'MOZ_NO_REMOTE': '1', 11:20:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:20:55 INFO - 'NO_EM_RESTART': '1', 11:20:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:20:55 INFO - 'PWD': '/builds/slave/test', 11:20:55 INFO - 'SHELL': '/bin/bash', 11:20:55 INFO - 'SHLVL': '1', 11:20:55 INFO - 'TERM': 'linux', 11:20:55 INFO - 'TMOUT': '86400', 11:20:55 INFO - 'USER': 'cltbld', 11:20:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:20:55 INFO - '_': '/tools/buildbot/bin/python'} 11:20:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:20:56 INFO - Downloading/unpacking pip>=1.5 11:20:56 INFO - Running setup.py egg_info for package pip 11:20:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:20:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:20:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:20:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:20:56 INFO - Installing collected packages: pip 11:20:56 INFO - Found existing installation: pip 1.1 11:20:56 INFO - Uninstalling pip: 11:20:56 INFO - Successfully uninstalled pip 11:20:56 INFO - Running setup.py install for pip 11:20:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:20:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:20:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:20:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:20:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:20:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:20:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:20:56 INFO - Successfully installed pip 11:20:56 INFO - Cleaning up... 11:20:56 INFO - Return code: 0 11:20:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:20:56 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 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:20:56 INFO - Using env: {'DISPLAY': ':0', 11:20:56 INFO - 'HOME': '/home/cltbld', 11:20:56 INFO - 'LANG': 'en_US.UTF-8', 11:20:56 INFO - 'LANGUAGE': 'en_US:en', 11:20:56 INFO - 'LOGNAME': 'cltbld', 11:20:56 INFO - 'MAIL': '/var/mail/cltbld', 11:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:20:56 INFO - 'MOZ_NO_REMOTE': '1', 11:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:20:56 INFO - 'NO_EM_RESTART': '1', 11:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:20:56 INFO - 'PWD': '/builds/slave/test', 11:20:56 INFO - 'SHELL': '/bin/bash', 11:20:56 INFO - 'SHLVL': '1', 11:20:56 INFO - 'TERM': 'linux', 11:20:56 INFO - 'TMOUT': '86400', 11:20:56 INFO - 'USER': 'cltbld', 11:20:56 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:20:56 INFO - '_': '/tools/buildbot/bin/python'} 11:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:20:56 INFO - Downloading/unpacking psutil>=3.1.1 11:20: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 11:20: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 11:20: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 11:20: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 11:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:20:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:20:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:20:58 INFO - Installing collected packages: psutil 11:20:58 INFO - Running setup.py install for psutil 11:20:58 INFO - building 'psutil._psutil_linux' extension 11:20:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:20:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:20:58 INFO - building 'psutil._psutil_posix' extension 11:20:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:20:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:20:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:20:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:20:58 INFO - Successfully installed psutil 11:20:58 INFO - Cleaning up... 11:20:58 INFO - Return code: 0 11:20:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:20:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:20:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:20:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:20:58 INFO - Using env: {'DISPLAY': ':0', 11:20:58 INFO - 'HOME': '/home/cltbld', 11:20:58 INFO - 'LANG': 'en_US.UTF-8', 11:20:58 INFO - 'LANGUAGE': 'en_US:en', 11:20:58 INFO - 'LOGNAME': 'cltbld', 11:20:58 INFO - 'MAIL': '/var/mail/cltbld', 11:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:20:58 INFO - 'MOZ_NO_REMOTE': '1', 11:20:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:20:58 INFO - 'NO_EM_RESTART': '1', 11:20:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:20:58 INFO - 'PWD': '/builds/slave/test', 11:20:58 INFO - 'SHELL': '/bin/bash', 11:20:58 INFO - 'SHLVL': '1', 11:20:58 INFO - 'TERM': 'linux', 11:20:58 INFO - 'TMOUT': '86400', 11:20:58 INFO - 'USER': 'cltbld', 11:20:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:20:58 INFO - '_': '/tools/buildbot/bin/python'} 11:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:20:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:20:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:21:00 INFO - Installing collected packages: mozsystemmonitor 11:21:00 INFO - Running setup.py install for mozsystemmonitor 11:21:00 INFO - Successfully installed mozsystemmonitor 11:21:00 INFO - Cleaning up... 11:21:00 INFO - Return code: 0 11:21:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:00 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 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:21:00 INFO - Using env: {'DISPLAY': ':0', 11:21:00 INFO - 'HOME': '/home/cltbld', 11:21:00 INFO - 'LANG': 'en_US.UTF-8', 11:21:00 INFO - 'LANGUAGE': 'en_US:en', 11:21:00 INFO - 'LOGNAME': 'cltbld', 11:21:00 INFO - 'MAIL': '/var/mail/cltbld', 11:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:00 INFO - 'MOZ_NO_REMOTE': '1', 11:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:00 INFO - 'NO_EM_RESTART': '1', 11:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:00 INFO - 'PWD': '/builds/slave/test', 11:21:00 INFO - 'SHELL': '/bin/bash', 11:21:00 INFO - 'SHLVL': '1', 11:21:00 INFO - 'TERM': 'linux', 11:21:00 INFO - 'TMOUT': '86400', 11:21:00 INFO - 'USER': 'cltbld', 11:21:00 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:00 INFO - '_': '/tools/buildbot/bin/python'} 11:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:00 INFO - Downloading/unpacking jsonschema==2.5.1 11:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:21:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:01 INFO - Downloading functools32-3.2.3-2.tar.gz 11:21:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:21:01 INFO - warning: no files found matching '*.txt' 11:21:01 INFO - no previously-included directories found matching 'build' 11:21:01 INFO - no previously-included directories found matching 'dist' 11:21:01 INFO - no previously-included directories found matching '.git*' 11:21:01 INFO - Installing collected packages: jsonschema, functools32 11:21:01 INFO - Running setup.py install for functools32 11:21:01 INFO - warning: no files found matching '*.txt' 11:21:01 INFO - no previously-included directories found matching 'build' 11:21:01 INFO - no previously-included directories found matching 'dist' 11:21:01 INFO - no previously-included directories found matching '.git*' 11:21:01 INFO - Successfully installed jsonschema functools32 11:21:01 INFO - Cleaning up... 11:21:01 INFO - Return code: 0 11:21:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:21: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')]} 11:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21: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')]} 11:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:01 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 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:21:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:21:01 INFO - Using env: {'DISPLAY': ':0', 11:21:01 INFO - 'HOME': '/home/cltbld', 11:21:01 INFO - 'LANG': 'en_US.UTF-8', 11:21:01 INFO - 'LANGUAGE': 'en_US:en', 11:21:01 INFO - 'LOGNAME': 'cltbld', 11:21:01 INFO - 'MAIL': '/var/mail/cltbld', 11:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:01 INFO - 'MOZ_NO_REMOTE': '1', 11:21:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:01 INFO - 'NO_EM_RESTART': '1', 11:21:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:01 INFO - 'PWD': '/builds/slave/test', 11:21:01 INFO - 'SHELL': '/bin/bash', 11:21:01 INFO - 'SHLVL': '1', 11:21:01 INFO - 'TERM': 'linux', 11:21:01 INFO - 'TMOUT': '86400', 11:21:01 INFO - 'USER': 'cltbld', 11:21:01 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:01 INFO - '_': '/tools/buildbot/bin/python'} 11:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:21:02 INFO - Cleaning up... 11:21:02 INFO - Return code: 0 11:21:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:02 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 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:21:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:21:02 INFO - Using env: {'DISPLAY': ':0', 11:21:02 INFO - 'HOME': '/home/cltbld', 11:21:02 INFO - 'LANG': 'en_US.UTF-8', 11:21:02 INFO - 'LANGUAGE': 'en_US:en', 11:21:02 INFO - 'LOGNAME': 'cltbld', 11:21:02 INFO - 'MAIL': '/var/mail/cltbld', 11:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:02 INFO - 'MOZ_NO_REMOTE': '1', 11:21:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:02 INFO - 'NO_EM_RESTART': '1', 11:21:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:02 INFO - 'PWD': '/builds/slave/test', 11:21:02 INFO - 'SHELL': '/bin/bash', 11:21:02 INFO - 'SHLVL': '1', 11:21:02 INFO - 'TERM': 'linux', 11:21:02 INFO - 'TMOUT': '86400', 11:21:02 INFO - 'USER': 'cltbld', 11:21:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:02 INFO - '_': '/tools/buildbot/bin/python'} 11:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:02 INFO - Downloading/unpacking blobuploader==1.2.4 11:21: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 11:21: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 11:21: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 11:21: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 11:21:03 INFO - Downloading blobuploader-1.2.4.tar.gz 11:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:21:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:21:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:03 INFO - Downloading docopt-0.6.1.tar.gz 11:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:21:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:21:04 INFO - Running setup.py install for blobuploader 11:21:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:21:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:21:04 INFO - Running setup.py install for requests 11:21:04 INFO - Running setup.py install for docopt 11:21:04 INFO - Successfully installed blobuploader requests docopt 11:21:04 INFO - Cleaning up... 11:21:04 INFO - Return code: 0 11:21:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:21: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')]} 11:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21: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')]} 11:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:04 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 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:21:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:21:04 INFO - Using env: {'DISPLAY': ':0', 11:21:04 INFO - 'HOME': '/home/cltbld', 11:21:04 INFO - 'LANG': 'en_US.UTF-8', 11:21:04 INFO - 'LANGUAGE': 'en_US:en', 11:21:04 INFO - 'LOGNAME': 'cltbld', 11:21:04 INFO - 'MAIL': '/var/mail/cltbld', 11:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:04 INFO - 'MOZ_NO_REMOTE': '1', 11:21:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:04 INFO - 'NO_EM_RESTART': '1', 11:21:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:04 INFO - 'PWD': '/builds/slave/test', 11:21:04 INFO - 'SHELL': '/bin/bash', 11:21:04 INFO - 'SHLVL': '1', 11:21:04 INFO - 'TERM': 'linux', 11:21:04 INFO - 'TMOUT': '86400', 11:21:04 INFO - 'USER': 'cltbld', 11:21:04 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:04 INFO - '_': '/tools/buildbot/bin/python'} 11:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:21:04 INFO - Running setup.py (path:/tmp/pip-qHlK3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:21:04 INFO - Running setup.py (path:/tmp/pip-5keaVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:21:05 INFO - Running setup.py (path:/tmp/pip-OvGUPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:21:05 INFO - Running setup.py (path:/tmp/pip-ZF5lmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:21:05 INFO - Running setup.py (path:/tmp/pip-547g7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:21:05 INFO - Running setup.py (path:/tmp/pip-WXL3K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:21:05 INFO - Running setup.py (path:/tmp/pip-iq9Brb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:21:05 INFO - Running setup.py (path:/tmp/pip-wz0Q3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:21:05 INFO - Running setup.py (path:/tmp/pip-GaEF16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:21:05 INFO - Running setup.py (path:/tmp/pip-jVL4xA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:21:05 INFO - Running setup.py (path:/tmp/pip-vazUy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:21:05 INFO - Running setup.py (path:/tmp/pip-nK7TRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:21:05 INFO - Running setup.py (path:/tmp/pip-njbcXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:21:05 INFO - Running setup.py (path:/tmp/pip-oiFbSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:21:05 INFO - Running setup.py (path:/tmp/pip-hSCBlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:21:05 INFO - Running setup.py (path:/tmp/pip-4aoDzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:21:06 INFO - Running setup.py (path:/tmp/pip-dM2R4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:21:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:21:06 INFO - Running setup.py install for manifestparser 11:21:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:21:06 INFO - Running setup.py install for mozcrash 11:21:06 INFO - Running setup.py install for mozdebug 11:21:06 INFO - Running setup.py install for mozdevice 11:21:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:21:06 INFO - Running setup.py install for mozfile 11:21:06 INFO - Running setup.py install for mozhttpd 11:21:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:21:06 INFO - Running setup.py install for mozinfo 11:21:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:21:06 INFO - Running setup.py install for mozInstall 11:21:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:21:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:21:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:21:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:21:06 INFO - Running setup.py install for mozleak 11:21:06 INFO - Running setup.py install for mozlog 11:21:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:21:07 INFO - Running setup.py install for moznetwork 11:21:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:21:07 INFO - Running setup.py install for mozprocess 11:21:07 INFO - Running setup.py install for mozprofile 11:21:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:21:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:21:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:21:07 INFO - Running setup.py install for mozrunner 11:21:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:21:07 INFO - Running setup.py install for mozscreenshot 11:21:07 INFO - Running setup.py install for moztest 11:21:07 INFO - Running setup.py install for mozversion 11:21:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:21:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:21:07 INFO - Cleaning up... 11:21:07 INFO - Return code: 0 11:21:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:07 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 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:21:07 INFO - Using env: {'DISPLAY': ':0', 11:21:07 INFO - 'HOME': '/home/cltbld', 11:21:07 INFO - 'LANG': 'en_US.UTF-8', 11:21:07 INFO - 'LANGUAGE': 'en_US:en', 11:21:07 INFO - 'LOGNAME': 'cltbld', 11:21:07 INFO - 'MAIL': '/var/mail/cltbld', 11:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:07 INFO - 'MOZ_NO_REMOTE': '1', 11:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:07 INFO - 'NO_EM_RESTART': '1', 11:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:07 INFO - 'PWD': '/builds/slave/test', 11:21:07 INFO - 'SHELL': '/bin/bash', 11:21:07 INFO - 'SHLVL': '1', 11:21:07 INFO - 'TERM': 'linux', 11:21:07 INFO - 'TMOUT': '86400', 11:21:07 INFO - 'USER': 'cltbld', 11:21:07 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:07 INFO - '_': '/tools/buildbot/bin/python'} 11:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:21:08 INFO - Running setup.py (path:/tmp/pip-UYJWMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:21:08 INFO - Running setup.py (path:/tmp/pip-LqOvDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:21:08 INFO - Running setup.py (path:/tmp/pip-qInx4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:21:08 INFO - Running setup.py (path:/tmp/pip-r0ks7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:21:08 INFO - Running setup.py (path:/tmp/pip-7R0yQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:21:08 INFO - Running setup.py (path:/tmp/pip-XZsuWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:21:08 INFO - Running setup.py (path:/tmp/pip-TZoiLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:21:08 INFO - Running setup.py (path:/tmp/pip-QADRr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:21:08 INFO - Running setup.py (path:/tmp/pip-UedDWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:21:08 INFO - Running setup.py (path:/tmp/pip-juDw8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:21:08 INFO - Running setup.py (path:/tmp/pip-jT5Qmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:21:08 INFO - Running setup.py (path:/tmp/pip-WLT61a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:21:08 INFO - Running setup.py (path:/tmp/pip-UQkXg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:21:08 INFO - Running setup.py (path:/tmp/pip-bvJ5Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:21:09 INFO - Running setup.py (path:/tmp/pip-1Z4f_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:21:09 INFO - Running setup.py (path:/tmp/pip-DMrrN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:21:09 INFO - Running setup.py (path:/tmp/pip-29fZ7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:21:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:10 INFO - Downloading blessings-1.6.tar.gz 11:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:21:10 INFO - Installing collected packages: blessings 11:21:10 INFO - Running setup.py install for blessings 11:21:10 INFO - Successfully installed blessings 11:21:10 INFO - Cleaning up... 11:21:10 INFO - Return code: 0 11:21:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:21:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:21:10 INFO - Reading from file tmpfile_stdout 11:21:10 INFO - Current package versions: 11:21:10 INFO - argparse == 1.2.1 11:21:10 INFO - blessings == 1.6 11:21:10 INFO - blobuploader == 1.2.4 11:21:10 INFO - docopt == 0.6.1 11:21:10 INFO - functools32 == 3.2.3-2 11:21:10 INFO - jsonschema == 2.5.1 11:21:10 INFO - manifestparser == 1.1 11:21:10 INFO - mozInstall == 1.13 11:21:10 INFO - mozcrash == 1.0 11:21:10 INFO - mozdebug == 0.1 11:21:10 INFO - mozdevice == 0.51 11:21:10 INFO - mozfile == 1.2 11:21:10 INFO - mozhttpd == 0.7 11:21:10 INFO - mozinfo == 0.10 11:21:10 INFO - mozleak == 0.1 11:21:10 INFO - mozlog == 3.5 11:21:10 INFO - moznetwork == 0.27 11:21:10 INFO - mozprocess == 0.25 11:21:10 INFO - mozprofile == 0.28 11:21:10 INFO - mozrunner == 6.13 11:21:10 INFO - mozscreenshot == 0.1 11:21:10 INFO - mozsystemmonitor == 0.3 11:21:10 INFO - moztest == 0.8 11:21:10 INFO - mozversion == 1.4 11:21:10 INFO - psutil == 3.1.1 11:21:10 INFO - requests == 1.2.3 11:21:10 INFO - wsgiref == 0.1.2 11:21:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:10 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 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:21:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:21:10 INFO - Using env: {'DISPLAY': ':0', 11:21:10 INFO - 'HOME': '/home/cltbld', 11:21:10 INFO - 'LANG': 'en_US.UTF-8', 11:21:10 INFO - 'LANGUAGE': 'en_US:en', 11:21:10 INFO - 'LOGNAME': 'cltbld', 11:21:10 INFO - 'MAIL': '/var/mail/cltbld', 11:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:10 INFO - 'MOZ_NO_REMOTE': '1', 11:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:10 INFO - 'NO_EM_RESTART': '1', 11:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:10 INFO - 'PWD': '/builds/slave/test', 11:21:10 INFO - 'SHELL': '/bin/bash', 11:21:10 INFO - 'SHLVL': '1', 11:21:10 INFO - 'TERM': 'linux', 11:21:10 INFO - 'TMOUT': '86400', 11:21:10 INFO - 'USER': 'cltbld', 11:21:10 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:10 INFO - '_': '/tools/buildbot/bin/python'} 11:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:21:11 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:21:12 INFO - Installing collected packages: simplejson 11:21:12 INFO - Running setup.py install for simplejson 11:21:12 INFO - building 'simplejson._speedups' extension 11:21:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:21:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:21:13 INFO - Successfully installed simplejson 11:21:13 INFO - Cleaning up... 11:21:13 INFO - Return code: 0 11:21:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:13 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 0x1d7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f44a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:21:13 INFO - Using env: {'DISPLAY': ':0', 11:21:13 INFO - 'HOME': '/home/cltbld', 11:21:13 INFO - 'LANG': 'en_US.UTF-8', 11:21:13 INFO - 'LANGUAGE': 'en_US:en', 11:21:13 INFO - 'LOGNAME': 'cltbld', 11:21:13 INFO - 'MAIL': '/var/mail/cltbld', 11:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:13 INFO - 'MOZ_NO_REMOTE': '1', 11:21:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:13 INFO - 'NO_EM_RESTART': '1', 11:21:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:13 INFO - 'PWD': '/builds/slave/test', 11:21:13 INFO - 'SHELL': '/bin/bash', 11:21:13 INFO - 'SHLVL': '1', 11:21:13 INFO - 'TERM': 'linux', 11:21:13 INFO - 'TMOUT': '86400', 11:21:13 INFO - 'USER': 'cltbld', 11:21:13 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:13 INFO - '_': '/tools/buildbot/bin/python'} 11:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:21:13 INFO - Cleaning up... 11:21:13 INFO - Return code: 0 11:21:13 INFO - Running post-action listener: _resource_record_post_action 11:21:13 INFO - Running post-action listener: _start_resource_monitoring 11:21:13 INFO - Starting resource monitoring. 11:21:13 INFO - [mozharness: 2017-09-04 18:21:13.484944Z] Finished create-virtualenv step (success) 11:21:13 INFO - [mozharness: 2017-09-04 18:21:13.485463Z] Running install step. 11:21:13 INFO - Running pre-action listener: _resource_record_pre_action 11:21:13 INFO - Running main action method: install 11:21:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:21:13 INFO - Reading from file tmpfile_stdout 11:21:13 INFO - Detecting whether we're running mozinstall >=1.0... 11:21:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:21:13 INFO - Reading from file tmpfile_stdout 11:21:13 INFO - Output received: 11:21:13 INFO - Usage: mozinstall [options] installer 11:21:13 INFO - Options: 11:21:13 INFO - -h, --help show this help message and exit 11:21:13 INFO - -d DEST, --destination=DEST 11:21:13 INFO - Directory to install application into. [default: 11:21:13 INFO - "/builds/slave/test"] 11:21:13 INFO - --app=APP Application being installed. [default: firefox] 11:21:13 INFO - mkdir: /builds/slave/test/build/application 11:21:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:21:30 INFO - Reading from file tmpfile_stdout 11:21:30 INFO - Output received: 11:21:30 INFO - /builds/slave/test/build/application/firefox/firefox 11:21:30 INFO - Running post-action listener: _resource_record_post_action 11:21:30 INFO - [mozharness: 2017-09-04 18:21:30.363310Z] Finished install step (success) 11:21:30 INFO - [mozharness: 2017-09-04 18:21:30.363455Z] Running setup-mitmproxy step. 11:21:30 INFO - Running pre-action listener: _resource_record_pre_action 11:21:30 INFO - Running main action method: setup_mitmproxy 11:21:30 INFO - Skipping: mitmproxy is not required 11:21:30 INFO - Running post-action listener: _resource_record_post_action 11:21:30 INFO - [mozharness: 2017-09-04 18:21:30.364495Z] Finished setup-mitmproxy step (success) 11:21:30 INFO - [mozharness: 2017-09-04 18:21:30.364715Z] Running run-tests step. 11:21:30 INFO - Running pre-action listener: _resource_record_pre_action 11:21:30 INFO - Running pre-action listener: _set_gcov_prefix 11:21:30 INFO - Running main action method: run_tests 11:21:30 WARNING - Try message not found. 11:21:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:21:30 INFO - Python 2.7.3 11:21:30 INFO - Return code: 0 11:21:30 INFO - grabbing minidump binary from tooltool 11:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:30 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 0x20eb090>, '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 0x20ebd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:21:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:21:30 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:21:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:21:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:21:30 INFO - Return code: 0 11:21:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:21:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:21:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:21:30 INFO - ENV: RUST_BACKTRACE is now full 11:21:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:21:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:21:30 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/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:21:30 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/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:21:30 INFO - Using env: {'DISPLAY': ':0', 11:21:30 INFO - 'HOME': '/home/cltbld', 11:21:30 INFO - 'LANG': 'en_US.UTF-8', 11:21:30 INFO - 'LANGUAGE': 'en_US:en', 11:21:30 INFO - 'LOGNAME': 'cltbld', 11:21:30 INFO - 'MAIL': '/var/mail/cltbld', 11:21:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:21:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:30 INFO - 'MOZ_NO_REMOTE': '1', 11:21:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:21:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:30 INFO - 'NO_EM_RESTART': '1', 11:21:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:30 INFO - 'PWD': '/builds/slave/test', 11:21:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:21:30 INFO - 'RUST_BACKTRACE': 'full', 11:21:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:21:30 INFO - 'SHELL': '/bin/bash', 11:21:30 INFO - 'SHLVL': '1', 11:21:30 INFO - 'STYLO_FORCE_ENABLED': '1', 11:21:30 INFO - 'STYLO_THREADS': '1', 11:21:30 INFO - 'TERM': 'linux', 11:21:30 INFO - 'TMOUT': '86400', 11:21:30 INFO - 'USER': 'cltbld', 11:21:30 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:30 INFO - '_': '/tools/buildbot/bin/python'} 11:21:30 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/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:21:30 INFO - mozversion application_buildid: 20170904172256 11:21:30 INFO - mozversion application_changeset: 1401e3eec44df87963d3af329ef8a4183ab0483f 11:21:30 INFO - mozversion application_display_name: Nightly 11:21:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:21:30 INFO - mozversion application_name: Firefox 11:21:30 INFO - mozversion application_remotingname: firefox 11:21:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:21:30 INFO - mozversion application_vendor: Mozilla 11:21:30 INFO - mozversion application_version: 57.0a1 11:21:30 INFO - mozversion platform_buildid: 20170904172256 11:21:30 INFO - mozversion platform_changeset: 1401e3eec44df87963d3af329ef8a4183ab0483f 11:21:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:21:30 INFO - mozversion platform_version: 57.0a1 11:21:30 INFO - using testdate: 1504549290 11:21:30 INFO - actual date: 1504549290 11:21:30 INFO - starting webserver on 'localhost:58553' 11:21:30 INFO - SUITE-START | Running 2 tests 11:21:30 INFO - TEST-START | dromaeo_css 11:21:30 INFO - Initialising browser for dromaeo_css test... 11:21:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58553/getInfo.html -profile /tmp/tmpDotVrp/profile 11:21:30 INFO - TEST-INFO | started process 6424 (/builds/slave/test/build/application/firefox/firefox http://localhost:58553/getInfo.html) 11:21:36 INFO - TEST-INFO | 6424: exit 0 11:21:36 INFO - Browser initialized. 11:21:36 INFO - Running cycle 1/1 for dromaeo_css test... 11:21:36 INFO - Using env: {'DISPLAY': ':0', 11:21:36 INFO - 'HOME': '/home/cltbld', 11:21:36 INFO - 'JSGC_DISABLE_POISONING': '1', 11:21:36 INFO - 'LANG': 'en_US.UTF-8', 11:21:36 INFO - 'LANGUAGE': 'en_US:en', 11:21:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:21:36 INFO - 'LOGNAME': 'cltbld', 11:21:36 INFO - 'MAIL': '/var/mail/cltbld', 11:21:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:21:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:21:36 INFO - 'MOZ_CRASHREPORTER': '1', 11:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:21:36 INFO - 'MOZ_NO_REMOTE': '1', 11:21:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:21:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:36 INFO - 'NO_EM_RESTART': '1', 11:21:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:36 INFO - 'PWD': '/builds/slave/test', 11:21:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:21:36 INFO - 'RUST_BACKTRACE': 'full', 11:21:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:21:36 INFO - 'SHELL': '/bin/bash', 11:21:36 INFO - 'SHLVL': '1', 11:21:36 INFO - 'STYLO_FORCE_ENABLED': '1', 11:21:36 INFO - 'STYLO_THREADS': '1', 11:21:36 INFO - 'TERM': 'linux', 11:21:36 INFO - 'TMOUT': '86400', 11:21:36 INFO - 'USER': 'cltbld', 11:21:36 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:36 INFO - '_': '/tools/buildbot/bin/python'} 11:21:36 INFO - TEST-INFO | started process 6608 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDotVrp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:21:37 INFO - PID 6608 | 11:21:37 INFO - PID 6608 | (/builds/slave/test/build/application/firefox/firefox:6660): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:21:37 INFO - PID 6608 | 11:21:48 INFO - PID 6608 | RSS: Main: 172232704 11:21:48 INFO - PID 6608 | 11:24:47 INFO - PID 6608 | Cycle 1(1): loaded http://localhost:58553/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58553/tests/dromaeo/cssquery-ext.html) 11:24:48 INFO - PID 6608 | RSS: Main: 173772800 11:24:48 INFO - PID 6608 | 11:27:50 INFO - PID 6608 | Cycle 1(1): loaded http://localhost:58553/tests/dromaeo/cssquery-ext.html (next: http://localhost:58553/tests/dromaeo/cssquery-jquery.html) 11:27:50 INFO - PID 6608 | RSS: Main: 170676224 11:27:50 INFO - PID 6608 | 11:30:52 INFO - PID 6608 | Cycle 1(1): loaded http://localhost:58553/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58553/tests/dromaeo/cssquery-mootools.html) 11:30:53 INFO - PID 6608 | RSS: Main: 173309952 11:30:53 INFO - PID 6608 | 11:33:55 INFO - PID 6608 | Cycle 1(1): loaded http://localhost:58553/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58553/tests/dromaeo/cssquery-prototype.html) 11:33:55 INFO - PID 6608 | RSS: Main: 171585536 11:33:55 INFO - PID 6608 | 11:36:59 INFO - PID 6608 | Cycle 1(1): loaded http://localhost:58553/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58553/tests/dromaeo/cssquery-yui.html) 11:36:59 INFO - PID 6608 | RSS: Main: 171241472 11:36:59 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | Cycle 1(1): loaded http://localhost:58553/tests/dromaeo/cssquery-yui.html (next: http://localhost:58553/tests/dromaeo/cssquery-dojo.html) 11:40:01 INFO - PID 6608 | RSS: Main: 175599616 11:40:01 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | __start_tp_report 11:40:01 INFO - PID 6608 | _x_x_mozilla_page_load 11:40:01 INFO - PID 6608 | _x_x_mozilla_page_load_details 11:40:01 INFO - PID 6608 | |i|pagename|runs| 11:40:01 INFO - PID 6608 | |0;dojo.html;3351.0617027232383;3379.205886616645;3392.4232880410327;3404.4893266010113;3423.537822393976;6273.592216505924;6372.9681351593235;6384.425401713845;6407.807765767023;6427.164468619073;6272.780452684157;6363.968180159092;6370.968145159268;6379.106925030499;6383.2978372379;5909.054551271797;5918.970405147968;5942.078977758454;5962.25471816023;5969.671668058255;5966.075258334965;5966.40335966402;6000.75996960121;6019.7893073742425;6026.608270462428;5962.493188079018;6030.788922387717;6033.245844269466;6039.456448919596;6115.633062016271;5753.338366087912;6010.519158467312;6027.21646185993;6033.125196846433;6103.389661033883;5828.708564571797;5876.177335173079;5890.469857712784;5951.940480595225;6000.159977603139;6465.515086368494;6489.253735820346;6514.348565143495;6542.018697195411;6550.574212676161;6745.628990405526;6832.282610325894;6870.484713646447;6882.5870447772795;7047.083879095685;7050.224475307712;7054.08296921397;7059;7060.258672839381;7094.645267736644;6473.676316184172;6551.934480655228;6561.770338038241;6564.573302735307;6568.573042752206;7155.856882862314;7175.497715159889;7182.604956727326;7260.528065675714;7285.927140728525;6733.966330168416;6774.3225677431865;6789.966050169717;6849.178098628096;6854.965725171442;6879.3120687930805;6907.309269073053;6990.650467476606;6997.265287144878;6999.720011199495;6836.179658441019;6854.451643868479;6869.107016087876;6931.653417329113;6942.652867356612;6692.86614267712;6837.35045170708;6882.931170688329;6971.895421568681;6986.895196572055;5371.62398632092;5441.319835020622;5443.700596467233;5445.83662490126;5490.862728431821;4696.201645720235;4820.734859582757;4859.757012149378;4877.365942427462;4877.6829506081995;5897.970510147507;5960.850978725566;5967.6419414835245;5968.045112781934;5980.0431930890845;6829.351211634887;6847.589144651337;6854.897176542256;6857.622830744357;6860.828479288058;5976.462118409364;5979.880402391927;6133.202683651097;6140.478059364915;6154.292256390483;5944.138099975479;5977.641341519436;6029.698515074228;6037.698115094227;6039.365866584033;6609.933900660932;6728.024436456686;6733.629650369178;6807.081044059088;6824.931750682529;6844.1444819397575;6910.412614927787;6914.446844252348;6936.549124306904;6943.861122777415;6640.269570347161;6643.46852251809;6721.764738234138;6738.629375384205;6787.5588086774205;6857.5199736018;6861.245263020964;6943.826404339932;6946.5137440378685;6969.268226836312;6925.6190909500465;7016.473764467583;7111.9687645290105;7115.679794409368;7117.6085315308155;6582.670866456754;6685.465162787063;6724.831879202863;6737.29258427858;6741.966290168714;6610.206775187008;6625.734970601122;6630.237522684953;6632.071509988509;6635.933640663725;6264.68676566179;6592.143021407186;6594.802155935329;6706.094677218513;6737.191537015589;6517.511186660925;6797.354251346312;6814.795556133324;6839.213490448662;6920.031195632515;6411.326810684997;6486.902696459651;6500.512461565496;6578.375054369827;6670.599764014175 11:40:01 INFO - PID 6608 | |1;ext.html;792.15635348354;848.5969164646793;881.9206271435571;886.8004698942739;1136.108155098248;30242.54636180456;30822.075337739847;30834.99999999997;40142.7992860036;40446.7977660112;40455.19089618206;40490;40516.18967620646;40570.79714601423;40651.59348406515;7673.8081547961465;7752.379809615217;7858.842823143534;7910.208979102102;7923.445358824884;7702.191269916662;7792.844143117134;7797.8050548736455;7801.531908085533;7806.648700808449;7784.766457006271;7847.019122609674;7847.64685589147;7860.606969651523;7879.645415956295;7774;7788.987431633908;7811.336036436853;7818;7827.412944029164;7646.847063058708;7707;7718.845623087563;7750.7674769756995;7753.689852405897;36373.272534549425;36620.81689591562;36670.449943250875;36801.26397472063;36904.261914761824;3525.612182659931;3553.5202747628996;3554.5201397811225;3558.5373901392913;3579.6420357964257;30258.84870575633;30268;30318.84840575805;30345.69654303473;30532.236694082603;5161.7161056141895;5196.948030519684;5199.870003249911;5222.190560463135;5259.684418934838;184389;184804;184844;185280.99999999866;185364;45959.31061034087;46019.76990115028;46089.5391046092;46125;46145.76927115343;48533;48650.75674621675;51996;52684;52778.20832687513;13256.867431325756;15441.227938603024;15591.922040389953;15606.687866242612;15620.453284135228;11101.72245693848;12665.683357915941;12809.935950320189;12815.487380504861;12831.10182287231;13961.162330260217;14043.1574105554;14052.648683782987;14071.929640351733;14213.218272995084;5701.771929122789;5771.624844385102;5778.393268706809;5802;5870.911936320958;997.2819569909799;1010.434156872154;1012.7215015870695;1027.753339198587;1029.2743615750878;22651.660225096635;23037.308880733606;23061.53876922486;23189.652155217686;23190.144788416885;1107.2194103157415;1111.488715191005;1114.9275297105662;1117.1230583991528;1121.9495122719497;12061.216020958609;12237.015653385795;12263.485985372978;12301.892829645374;12346.75306493883;220955;223248;224586;234576;235229;48837.26744098841;48962.510374896505;48982.265266020324;49056;49062.50937490579;1410.189141243789;1438.2305466575333;1451.4049239811627;1459.1172340733915;1462.283481094256;21597.35207943764;21630.026648800525;22278.77721222767;22486.100555977577;22591.43521411912;35696;35746.28507429889;35950.28099438049;35970.28059438849;35990.46014309735;182002;182948.08525957286;183063;183143;183788;32867;33943.490847636815;33977.62156649011;34180.82909585436;34223.48664769981;41476.96307592334;41530.37704434496;41561;41914.1617167661;42121.36817947795;31127.68872311248;31164.68835311618;31515.527267090554;31522.211944701565;31580.368392632477;30622.234444138183;30697.079087627408;30730.385392292475;30730.98408039174;31071;33574.16064598405;34243.82878085693;34664.82667586646;34690.306193875476;37084.44373334456;32300.19249518781;32592.837035814668;32719.34561308808;32746.508802367505;33018;23074.653880192145;23373.181938632068;23531.647025294977;24350.391240219134;24459.38851528726 11:40:01 INFO - PID 6608 | |2;jquery.html;572.1132245020218;574.018428487287;577.3216470646992;580.8257522743178;582.1849410824844;16325.34698612057;21799.455013624647;22555.210567630136;22560.323190304298;22581.887090564564;18709.62580748384;23881.880590597066;24647.75352246477;24650.75349246507;24781.62827557588;6270.24757029156;6372.808815735535;6454.709538070774;6518.413342799147;6533.673316334188;6096.237970253718;6301.558890877641;6353.587016843913;6392.3927226913365;6395;6349.682515874188;6355.078513615546;6360.077788720633;6378.776742814002;6396.168498095264;6328.303886572497;6341.746330146789;6344.143540622026;6345.302016778151;6350.237971443434;5883.441073098049;5885.146653735184;5889.263842019747;5896.115582662592;5935.821925342247;26562.46875062488;27356.179314620593;27616.723832761818;27761.86119069392;27835.860820695765;2064.5870825834895;2113.746350437942;2116.386247988082;2121.4060063182333;2121.8832964186963;17968.101594920332;18236.908815455972;18535.443936681786;19209.903950480297;19311.130999105073;2366.183666635008;2426.5766012961017;2440.792532634728;2471.209213051824;2480.6155045967726;127215.36392317979;129765.35117324447;131390.3430482851;137472.31263843717;137796.6220337794;14275.714485710436;14361.928190359191;14406.567802965928;14505.31651644449;14540.85459145395;22320.21879234219;22358.552828943655;22433.10267589278;22435.663465048037;22459.663105053438;9254.583543740548;9337.953310233406;9762.511874406251;9782.26096692927;9800.117989380848;8880.200781929658;8913.420627659181;8933.776655583662;8992.190702836777;9089.727308180765;8781.517016564023;8848.91151088481;9055.501947392957;9058.184763371328;9102.40707876119;2712;2716.8369897806197;2736.38431352944;2784.1786672931535;2797.2866918935947;978.7699890525721;986.3785814936545;989.8812142542797;995.2983146881576;1012.3875055591261;19000.144993475325;19202.615947681246;19394.127264273142;19409.902950485157;21951;1032.829583118792;1053.1522124689698;1057.318029870729;1060.692399204222;1062.033549469994;10844.945775271073;10948.178886583539;11083.168762342857;11109.944450277697;11142.944285278521;478882;496271;498563;498565;498927;111543.44228278806;115182;115334;116545.4172729131;116770.41614791872;1238.8761123887539;1251.9374031298398;1272.9299888506036;1279;1284.8843604075864;17598;17827.28690852403;17835.554111147325;18179.45461636153;18305.816941830944;56648.71675641595;57822.42177578338;58812.70593647005;59158;60130;65742.67128664326;65985;66053;66499.66750166219;67378.32621673917;14028.78956815669;14353.784693229401;14444.494442694455;14456;14458.638534036732;21953.890230549383;22345.217917372815;23266.65100023535;23369.532609347378;23438.88280558586;18182;18301.633967320846;18348.449546513624;18441.907790460962;18485.168167432766;16447.25987330549;16626.418075367303;16809.831901681315;16991.830081699023;17075.914620426818;18783.624327513648;19415;19420.12609432607;19423.611527769648;19474.415767526996;14051.36768845425;14316.713665726835;14389.424423022961;14408.85591144075;14417.7837332438;18236.45290641283;18286.085695714894;18310.26758929681;18394.81605183931;18398.448046558624 11:40:01 INFO - PID 6608 | |3;mootools.html;1289.432649634161;1349.547901453013;1352.7497412978596;1378.8276465441827;1379.027785411285;14980.250987450625;15060.548183554483;15764.99999999997;15787.36850525979;15805.999999999985;15813.446529371475;16042.679146417095;16077.598060048505;16079.517614471555;16121.355145794154;2798.4822807780574;2862.627858378408;2864.1693908766433;2866.512692842222;2868.311605214744;2737.6167336572894;2813.071686343502;2816.310004049011;2817.0844475545427;2819.4220184862156;2739.4521095780824;2784.832910025395;2787.2474431903374;2789.1493094606135;2790.2326860113426;2662.4009597840522;2774.4728501584736;2783.178962206154;2783.4293969736154;2786.1641507547856;2743.533599288126;2752.3669556002233;2762.557990721475;2768.1003673806094;2768.820026698258;14618.122912625277;14912.925435372754;14927.104373737502;14928.850711492854;14951;8203.384746144064;8482.236598706084;8486.872696909551;8493.702720404817;8508.957455212685;5431.782728690848;5666.433356664342;5805.100209467497;5823.708814559297;5825.592208545403;1910.2740958435845;1941.9514512137168;1951.3950675290564;1995.8103980121866;1999.6700544410155;25510.489790204276;25725.742742572522;25759.871200644066;25772.87113564439;25806.48387032249;6851.725930962705;6875.656217189121;6910.930890691129;7043.429148360914;7124.679389427488;15722.213889305489;15737.134457604941;15747;15747.921260393625;15755.369785208693;7806.4925779824125;8078.272955434039;8080.8383832323025;8111.18888111196;8111.75664730059;6162.26052873649;6443.6455994920725;6668.83327916806;6670.699818508082;7705.8844117338285;5107.795688172431;5270.868228294324;5271.499207575274;5300.568846411525;5393.2179833923865;1523.4536945000832;1537.331260901489;1550.8836837237252;1560.9375624974873;1578.289769603683;866.3459088388353;881.581248906777;895.6104094718818;896.0457113174448;896.0770406481334;7452.925470745332;7474;7474.177840437656;7489.588072655947;7528.397728181732;685.2873012067494;705.7318219076718;717.3543810570528;732.0629594119536;738.9691380524139;4719.693219940693;4948.430930443045;5216.347956505437;5352.00987817255;5406.648567843077;21164.36506904795;21898.671519927215;22112;22212;22246;21237.36287911365;21359.893200533897;21681.566368672855;22161.445963851034;22334.441638959157;11469.311841289382;11608.883911160949;11611.303321800719;11623.6512904613;11651.825222621668;8187.795305117182;8310.168983101641;8442.113578074213;8464.407491475538;8499.1500849917;18702.625947481247;18883.150258238136;18915.810841891405;19168;19531.31640392636;19342.903285483484;19370.12834422426;19442.4167274982;19592;19624.655234440717;11298.09615230796;11644.475998579916;11783.76432471363;11786.11604129711;11790.528378864748;13691.315434228449;13739.24434156131;13871.930640347138;13887.02790804634;13894.166350019032;11028;11056.834147487632;11128.109751220045;11204.943975280072;11384.943075284571;10588.152947764314;10666.146708263164;10667.359958402521;10788.352698838095;10924.344539327665;9983.650572229937;10285.691429257135;10297.17622590176;10300.433476158883;10303.896961030594;7200.99186113955;7358.300961408658;7390.29792169743;7443.181250062597;7456.925430745623;7584.393248540214;7675.347595454449;7692.576908270099;7714.729984450517;7714.922850771421 11:40:01 INFO - PID 6608 | |4;prototype.html;504.23906864077924;512.3390825834673;523.4556061695837;532.8827657915258;540.2652392745867;6327.905081423776;6389.105525226472;6406.583572067812;6437.521554201631;6452.225732912046;6329.272133704625;6335.366463353663;6347.90478142827;6352.396522330371;6355.682215889198;5192.1952097424955;5218.791248350061;5249.606279529032;5256.8685782855355;5281.86795330116;4856.684315519497;5175.9979212056915;5210.869728256786;5244.213367994812;5270.262163297141;4991.725455099968;4996.975015124937;4999.625028122887;5021.648484606079;5026.547610715035;4998.925016124761;5023.974880125613;5027.4469808321055;5041.168207245818;5048.469910659402;4494.101179764028;4501.774911254424;4520.299353600197;4526.140033393677;4533.637309015271;6260.467860231885;6266.686665666698;6285.120083188357;6289.433950944437;6291.1192433059405;5756.280464941882;5773.595848290622;5791.594588378815;5802.390748971339;5851.1223316502865;6647.135872336614;6648.601083934979;6653.70252800704;6713.93286067138;6745.291744366869;4860.659753817235;4882.853514394574;4927.433345165316;4927.679700819435;4940.184869496528;6658.267590565034;6724.865502689919;6926.99999999995;6943.27095654954;6973.3375329343635;5021.748912554431;5026.120428924996;5030.170021946338;5040.722760248148;5084.847454576368;5028.823991160292;5049.5960323174795;5060.721660308718;5080.628230377852;5102.897942041212;5688.175214593944;5708.429157084258;5710.3433105193435;5724.370319264877;5783.306003279633;6577.736890524421;6620.205575330991;6651.401373876278;6687.197536295578;6723.361280678328;1738.608813017061;1765.7263124215767;1779.7897429747636;1783.482789990914;1803.6573051120245;1164.895159435655;1191.970200744971;1211.0735287505065;1220.7680540697238;1238.2570457725474;5851.180834683063;5936.584439089309;5960.8807823844145;5969.552683250919;5979.5515336349945;6744.730210791611;6767.661616919135;6826.931730682729;6862.691178896962;6906.06768086312;5815.244018277597;5846.941530584725;5857.589968702235;5867.853303667357;5916.585838991229;3560.537130173113;3584.62361452049;3608.296382205445;3650.9817450912574;3667.7712966156637;6489.156409666807;6586.506012049115;6599.835004124935;6660.134182556237;6681.632510211985;6816.7614133505085;6836.4530837532875;6866.553674011273;6964.791056268219;7014.333638304353;6137.723802428902;6428.903566446414;6512.185976753;6526.869462610816;6556.0825432681995;6673.1992160941;6690.866182676416;6732.148659118781;6755.392014718699;6757.155355580552;6666.566673166229;6670.966645166937;6735.0570920072205;6786.898196527155;6801.149856267966;6557.606543607399;6594.736210551716;6620.139381880517;6629.4696424284975;6670.899936500859;5003.299538064746;5033.92449113271;5040.697558146523;5041;5095.312132862016;6512.6743662815725;6552.199724160071;6651.63416012124;6764.4588432924265;6853.862922741617;6408.93591064102;6483.935160648333;6487.610743355413;6539.411452969255;6588.926526574415;6433.903491447539;6448.742050317935;6509.283978762427;6533.575317604531;6542.476601871934;6384.457321127757;6433.903491447539;6441.935580644321;6495.870082598416;6495.870082598416;6620.503462240447;6641.236257830411;6641.933580664325;6683.933160668331;6712.194536655633;6401.519886008475;6402.647854368054;6428.682120165448;6480.25477070143;6483.157189565326 11:40:01 INFO - PID 6608 | |5;yui.html;893.3567831161562;895.6999405199258;915.6612053540191;919.3013309884486;925.1211349218243;17193.3122675093;17716.55708607284;17754.20106095224;17788.199531021102;21677;19856.404307870787;20374.796252037475;20441.795582044175;20625.07187176577;20633.793662063374;930.8650245714367;938.4041133880002;940.4780346907463;941.5245301122924;946.5976959792096;960.4717405427023;1006.9244806639495;1009.6516701737888;1011.4234886114881;1011.4942528735633;1063.4310643805566;1066.5253961986919;1066.6373433032768;1066.802641511319;1066.8933106689346;1053.2679787547656;1055.5883205549842;1055.8785739639964;1056.6671498477954;1059.9947000265026;777.1451403456153;778.085749244641;778.532880271838;779.4582764978343;779.9142094369615;17470;17681.646367072713;17737.733933990872;17772.377966771102;18437.1703273353;21038.474038149016;21043.158273669036;21483.67774483384;21493.462663433384;21500.892495537577;17145.05702186379;17468.737968930476;17527.474175774747;17552.824471755248;17839.46481605541;3799.2781371539595;3822.0827001519715;3822.3502004659276;3822.9426558601645;3824.6366595173413;127272;128162;128676;131049.34475327657;132451.33774331163;16531.33874645001;16673;16833.905796123214;17034.659306813795;17094;29140.125796225722;29985.250368740955;31034.224144396572;31052.68947310498;31072.22319442032;7641.7707468776025;7957.5225486471;8327.292180164754;8688.782780430414;8832.823343533093;4124.1133156370925;4163.458750362494;4166.250074986471;4168.270552653274;4227.42929704486;5001.174806156943;5109.105906466353;5130.897382052338;5160.561352285023;5178.404483484373;1096.8245080787035;1115.9107271418268;1120.1990576737671;1132.7224829916722;1134.3420815926743;644.2172760096505;682.8088135322018;695.2352412346479;700.9579425234502;704.7674267491716;10930;11082.722931926766;11247.325160490233;12021.639350819489;12821.679458013437;601.8224623736008;608.248812716295;622.2066864747483;666.9895108910006;681.9147606549182;5347.3048503695045;5410.627057118842;5414.972925135349;5457.072297709399;5542.501174894279;109773.45113274542;110861;112012;113175;113799.43100284612;98242;101838.49080754549;102314;103851;106711;88317.11682883218;89616.10383896208;90805.54597227104;91161.08838911659;94088.52955735178;10069.798604028025;10089.697309080446;10195.133413659922;10299.639512617023;11858.76282474363;41048.38427423651;44165.558344416146;47434;47708;49059.26411103765;77827;80888.59555702184;81526.18473815423;82564.58717706373;88656;17401.390951316644;17447.912760436117;17489.912550437166;17511.562210944827;17685.646287074444;20402.795972040683;20786.27248046311;20907.1637134519;21334.679979800007;21357.67963480518;16357.182140893146;16870.57823554421;16902.23939922682;16942.491725248263;17450.563735906704;16811.495655129875;17815.643687126445;17890.5527361817;18163;18297.81702182961;16827.495175144766;17081;18242.543936401165;18474.261029558667;18676.6264674703;12671.239725616493;13500.594982150551;13864;13934.442622294995;14155.575332740034;13286.867131328563;13453.327333633475;13678.726425471235;14018.509352172625;14323.212223327817 11:40:01 INFO - PID 6608 | __end_tp_report 11:40:01 INFO - PID 6608 | __start_cc_report 11:40:01 INFO - PID 6608 | _x_x_mozilla_cycle_collect,365 11:40:01 INFO - PID 6608 | __end_cc_report 11:40:01 INFO - PID 6608 | __startTimestamp1504550401840__endTimestamp 11:40:01 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | ------- Summary: start ------- 11:40:01 INFO - PID 6608 | Number of tests: 6 11:40:01 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | [#0] dojo.html Cycles:165 Average:6375.74 Median:6592.14 stddev:740.00 (11.2%) stddev-sans-first:703.20 11:40:01 INFO - PID 6608 | Values: 3351.1 3379.2 3392.4 3404.5 3423.5 6273.6 6373.0 6384.4 6407.8 6427.2 6272.8 6364.0 6371.0 6379.1 6383.3 5909.1 5919.0 5942.1 5962.3 5969.7 5966.1 5966.4 6000.8 6019.8 6026.6 5962.5 6030.8 6033.2 6039.5 6115.6 5753.3 6010.5 6027.2 6033.1 6103.4 5828.7 5876.2 5890.5 5951.9 6000.2 6465.5 6489.3 6514.3 6542.0 6550.6 6745.6 6832.3 6870.5 6882.6 7047.1 7050.2 7054.1 7059.0 7060.3 7094.6 6473.7 6551.9 6561.8 6564.6 6568.6 7155.9 7175.5 7182.6 7260.5 7285.9 6734.0 6774.3 6790.0 6849.2 6855.0 6879.3 6907.3 6990.7 6997.3 6999.7 6836.2 6854.5 6869.1 6931.7 6942.7 6692.9 6837.4 6882.9 6971.9 6986.9 5371.6 5441.3 5443.7 5445.8 5490.9 4696.2 4820.7 4859.8 4877.4 4877.7 5898.0 5960.9 5967.6 5968.0 5980.0 6829.4 6847.6 6854.9 6857.6 6860.8 5976.5 5979.9 6133.2 6140.5 6154.3 5944.1 5977.6 6029.7 6037.7 6039.4 6609.9 6728.0 6733.6 6807.1 6824.9 6844.1 6910.4 6914.4 6936.5 6943.9 6640.3 6643.5 6721.8 6738.6 6787.6 6857.5 6861.2 6943.8 6946.5 6969.3 6925.6 7016.5 7112.0 7115.7 7117.6 6582.7 6685.5 6724.8 6737.3 6742.0 6610.2 6625.7 6630.2 6632.1 6635.9 6264.7 6592.1 6594.8 6706.1 6737.2 6517.5 6797.4 6814.8 6839.2 6920.0 6411.3 6486.9 6500.5 6578.4 6670.6 11:40:01 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | [#1] ext.html Cycles:180 Average:36265.01 Median:33960.56 stddev:51665.05 (152.1%) stddev-sans-first:51741.32 11:40:01 INFO - PID 6608 | Values: 792.2 848.6 881.9 886.8 1136.1 30242.5 30822.1 30835.0 40142.8 40446.8 40455.2 40490.0 40516.2 40570.8 40651.6 7673.8 7752.4 7858.8 7910.2 7923.4 7702.2 7792.8 7797.8 7801.5 7806.6 7784.8 7847.0 7847.6 7860.6 7879.6 7774.0 7789.0 7811.3 7818.0 7827.4 7646.8 7707.0 7718.8 7750.8 7753.7 36373.3 36620.8 36670.4 36801.3 36904.3 3525.6 3553.5 3554.5 3558.5 3579.6 30258.8 30268.0 30318.8 30345.7 30532.2 5161.7 5196.9 5199.9 5222.2 5259.7 184389.0 184804.0 184844.0 185281.0 185364.0 45959.3 46019.8 46089.5 46125.0 46145.8 48533.0 48650.8 51996.0 52684.0 52778.2 13256.9 15441.2 15591.9 15606.7 15620.5 11101.7 12665.7 12809.9 12815.5 12831.1 13961.2 14043.2 14052.6 14071.9 14213.2 5701.8 5771.6 5778.4 5802.0 5870.9 997.3 1010.4 1012.7 1027.8 1029.3 22651.7 23037.3 23061.5 23189.7 23190.1 1107.2 1111.5 1114.9 1117.1 1121.9 12061.2 12237.0 12263.5 12301.9 12346.8 220955.0 223248.0 224586.0 234576.0 235229.0 48837.3 48962.5 48982.3 49056.0 49062.5 1410.2 1438.2 1451.4 1459.1 1462.3 21597.4 21630.0 22278.8 22486.1 22591.4 35696.0 35746.3 35950.3 35970.3 35990.5 182002.0 182948.1 183063.0 183143.0 183788.0 32867.0 33943.5 33977.6 34180.8 34223.5 41477.0 41530.4 41561.0 41914.2 42121.4 31127.7 31164.7 31515.5 31522.2 31580.4 30622.2 30697.1 30730.4 30731.0 31071.0 33574.2 34243.8 34664.8 34690.3 37084.4 32300.2 32592.8 32719.3 32746.5 33018.0 23074.7 23373.2 23531.6 24350.4 24459.4 11:40:01 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | [#2] jquery.html Cycles:180 Average:34372.56 Median:22507.44 stddev:83044.65 (369.0%) stddev-sans-first:83238.84 11:40:01 INFO - PID 6608 | Values: 572.1 574.0 577.3 580.8 582.2 16325.3 21799.5 22555.2 22560.3 22581.9 18709.6 23881.9 24647.8 24650.8 24781.6 6270.2 6372.8 6454.7 6518.4 6533.7 6096.2 6301.6 6353.6 6392.4 6395.0 6349.7 6355.1 6360.1 6378.8 6396.2 6328.3 6341.7 6344.1 6345.3 6350.2 5883.4 5885.1 5889.3 5896.1 5935.8 26562.5 27356.2 27616.7 27761.9 27835.9 2064.6 2113.7 2116.4 2121.4 2121.9 17968.1 18236.9 18535.4 19209.9 19311.1 2366.2 2426.6 2440.8 2471.2 2480.6 127215.4 129765.4 131390.3 137472.3 137796.6 14275.7 14361.9 14406.6 14505.3 14540.9 22320.2 22358.6 22433.1 22435.7 22459.7 9254.6 9338.0 9762.5 9782.3 9800.1 8880.2 8913.4 8933.8 8992.2 9089.7 8781.5 8848.9 9055.5 9058.2 9102.4 2712.0 2716.8 2736.4 2784.2 2797.3 978.8 986.4 989.9 995.3 1012.4 19000.1 19202.6 19394.1 19409.9 21951.0 1032.8 1053.2 1057.3 1060.7 1062.0 10844.9 10948.2 11083.2 11109.9 11142.9 478882.0 496271.0 498563.0 498565.0 498927.0 111543.4 115182.0 115334.0 116545.4 116770.4 1238.9 1251.9 1272.9 1279.0 1284.9 17598.0 17827.3 17835.6 18179.5 18305.8 56648.7 57822.4 58812.7 59158.0 60130.0 65742.7 65985.0 66053.0 66499.7 67378.3 14028.8 14353.8 14444.5 14456.0 14458.6 21953.9 22345.2 23266.7 23369.5 23438.9 18182.0 18301.6 18348.4 18441.9 18485.2 16447.3 16626.4 16809.8 16991.8 17075.9 18783.6 19415.0 19420.1 19423.6 19474.4 14051.4 14316.7 14389.4 14408.9 14417.8 18236.5 18286.1 18310.3 18394.8 18398.4 11:40:01 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | [#3] mootools.html Cycles:180 Average:9355.93 Median:2738.53 stddev:6647.51 (242.7%) stddev-sans-first:6638.53 11:40:01 INFO - PID 6608 | Values: 1289.4 1349.5 1352.7 1378.8 1379.0 14980.3 15060.5 15765.0 15787.4 15806.0 15813.4 16042.7 16077.6 16079.5 16121.4 2798.5 2862.6 2864.2 2866.5 2868.3 2737.6 2813.1 2816.3 2817.1 2819.4 2739.5 2784.8 2787.2 2789.1 2790.2 2662.4 2774.5 2783.2 2783.4 2786.2 2743.5 2752.4 2762.6 2768.1 2768.8 14618.1 14912.9 14927.1 14928.9 14951.0 8203.4 8482.2 8486.9 8493.7 8509.0 5431.8 5666.4 5805.1 5823.7 5825.6 1910.3 1942.0 1951.4 1995.8 1999.7 25510.5 25725.7 25759.9 25772.9 25806.5 6851.7 6875.7 6910.9 7043.4 7124.7 15722.2 15737.1 15747.0 15747.9 15755.4 7806.5 8078.3 8080.8 8111.2 8111.8 6162.3 6443.6 6668.8 6670.7 7705.9 5107.8 5270.9 5271.5 5300.6 5393.2 1523.5 1537.3 1550.9 1560.9 1578.3 866.3 881.6 895.6 896.0 896.1 7452.9 7474.0 7474.2 7489.6 7528.4 685.3 705.7 717.4 732.1 739.0 4719.7 4948.4 5216.3 5352.0 5406.6 21164.4 21898.7 22112.0 22212.0 22246.0 21237.4 21359.9 21681.6 22161.4 22334.4 11469.3 11608.9 11611.3 11623.7 11651.8 8187.8 8310.2 8442.1 8464.4 8499.2 18702.6 18883.2 18915.8 19168.0 19531.3 19342.9 19370.1 19442.4 19592.0 19624.7 11298.1 11644.5 11783.8 11786.1 11790.5 13691.3 13739.2 13871.9 13887.0 13894.2 11028.0 11056.8 11128.1 11204.9 11384.9 10588.2 10666.1 10667.4 10788.4 10924.3 9983.7 10285.7 10297.2 10300.4 10303.9 7201.0 7358.3 7390.3 7443.2 7456.9 7584.4 7675.3 7692.6 7714.7 7714.9 11:40:01 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | [#4] prototype.html Cycles:180 Average:5574.30 Median:6309.51 stddev:1557.33 (24.7%) stddev-sans-first:1514.49 11:40:01 INFO - PID 6608 | Values: 504.2 512.3 523.5 532.9 540.3 6327.9 6389.1 6406.6 6437.5 6452.2 6329.3 6335.4 6347.9 6352.4 6355.7 5192.2 5218.8 5249.6 5256.9 5281.9 4856.7 5176.0 5210.9 5244.2 5270.3 4991.7 4997.0 4999.6 5021.6 5026.5 4998.9 5024.0 5027.4 5041.2 5048.5 4494.1 4501.8 4520.3 4526.1 4533.6 6260.5 6266.7 6285.1 6289.4 6291.1 5756.3 5773.6 5791.6 5802.4 5851.1 6647.1 6648.6 6653.7 6713.9 6745.3 4860.7 4882.9 4927.4 4927.7 4940.2 6658.3 6724.9 6927.0 6943.3 6973.3 5021.7 5026.1 5030.2 5040.7 5084.8 5028.8 5049.6 5060.7 5080.6 5102.9 5688.2 5708.4 5710.3 5724.4 5783.3 6577.7 6620.2 6651.4 6687.2 6723.4 1738.6 1765.7 1779.8 1783.5 1803.7 1164.9 1192.0 1211.1 1220.8 1238.3 5851.2 5936.6 5960.9 5969.6 5979.6 6744.7 6767.7 6826.9 6862.7 6906.1 5815.2 5846.9 5857.6 5867.9 5916.6 3560.5 3584.6 3608.3 3651.0 3667.8 6489.2 6586.5 6599.8 6660.1 6681.6 6816.8 6836.5 6866.6 6964.8 7014.3 6137.7 6428.9 6512.2 6526.9 6556.1 6673.2 6690.9 6732.1 6755.4 6757.2 6666.6 6671.0 6735.1 6786.9 6801.1 6557.6 6594.7 6620.1 6629.5 6670.9 5003.3 5033.9 5040.7 5041.0 5095.3 6512.7 6552.2 6651.6 6764.5 6853.9 6408.9 6483.9 6487.6 6539.4 6588.9 6433.9 6448.7 6509.3 6533.6 6542.5 6384.5 6433.9 6441.9 6495.9 6495.9 6620.5 6641.2 6641.9 6683.9 6712.2 6401.5 6402.6 6428.7 6480.3 6483.2 11:40:01 INFO - PID 6608 | 11:40:01 INFO - PID 6608 | [#5] yui.html Cycles:180 Average:24480.52 Median:18575.44 stddev:33806.52 (182.0%) stddev-sans-first:33854.96 11:40:01 INFO - PID 6608 | Values: 893.4 895.7 915.7 919.3 925.1 17193.3 17716.6 17754.2 17788.2 21677.0 19856.4 20374.8 20441.8 20625.1 20633.8 930.9 938.4 940.5 941.5 946.6 960.5 1006.9 1009.7 1011.4 1011.5 1063.4 1066.5 1066.6 1066.8 1066.9 1053.3 1055.6 1055.9 1056.7 1060.0 777.1 778.1 778.5 779.5 779.9 17470.0 17681.6 17737.7 17772.4 18437.2 21038.5 21043.2 21483.7 21493.5 21500.9 17145.1 17468.7 17527.5 17552.8 17839.5 3799.3 3822.1 3822.4 3822.9 3824.6 127272.0 128162.0 128676.0 131049.3 132451.3 16531.3 16673.0 16833.9 17034.7 17094.0 29140.1 29985.3 31034.2 31052.7 31072.2 7641.8 7957.5 8327.3 8688.8 8832.8 4124.1 4163.5 4166.3 4168.3 4227.4 5001.2 5109.1 5130.9 5160.6 5178.4 1096.8 1115.9 1120.2 1132.7 1134.3 644.2 682.8 695.2 701.0 704.8 10930.0 11082.7 11247.3 12021.6 12821.7 601.8 608.2 622.2 667.0 681.9 5347.3 5410.6 5415.0 5457.1 5542.5 109773.5 110861.0 112012.0 113175.0 113799.4 98242.0 101838.5 102314.0 103851.0 106711.0 88317.1 89616.1 90805.5 91161.1 94088.5 10069.8 10089.7 10195.1 10299.6 11858.8 41048.4 44165.6 47434.0 47708.0 49059.3 77827.0 80888.6 81526.2 82564.6 88656.0 17401.4 17447.9 17489.9 17511.6 17685.6 20402.8 20786.3 20907.2 21334.7 21357.7 16357.2 16870.6 16902.2 16942.5 17450.6 16811.5 17815.6 17890.6 18163.0 18297.8 16827.5 17081.0 18242.5 18474.3 18676.6 12671.2 13500.6 13864.0 13934.4 14155.6 13286.9 13453.3 13678.7 14018.5 14323.2 11:40:01 INFO - PID 6608 | -------- Summary: end -------- 11:40:01 INFO - PID 6608 | 11:40:02 INFO - TEST-INFO | 6608: exit 0 11:40:02 INFO - TEST-OK | dromaeo_css | took 1111888ms 11:40:02 INFO - TEST-START | kraken 11:40:02 INFO - Initialising browser for kraken test... 11:40:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58553/getInfo.html -profile /tmp/tmpgF3ru3/profile 11:40:02 INFO - TEST-INFO | started process 6979 (/builds/slave/test/build/application/firefox/firefox http://localhost:58553/getInfo.html) 11:40:08 INFO - TEST-INFO | 6979: exit 0 11:40:08 INFO - Browser initialized. 11:40:08 INFO - Running cycle 1/1 for kraken test... 11:40:08 INFO - Using env: {'DISPLAY': ':0', 11:40:08 INFO - 'HOME': '/home/cltbld', 11:40:08 INFO - 'JSGC_DISABLE_POISONING': '1', 11:40:08 INFO - 'LANG': 'en_US.UTF-8', 11:40:08 INFO - 'LANGUAGE': 'en_US:en', 11:40:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:40:08 INFO - 'LOGNAME': 'cltbld', 11:40:08 INFO - 'MAIL': '/var/mail/cltbld', 11:40:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:40:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:40:08 INFO - 'MOZ_CRASHREPORTER': '1', 11:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:40:08 INFO - 'MOZ_NO_REMOTE': '1', 11:40:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:40:08 INFO - 'NO_EM_RESTART': '1', 11:40:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:08 INFO - 'PWD': '/builds/slave/test', 11:40:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:40:08 INFO - 'RUST_BACKTRACE': 'full', 11:40:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:40:08 INFO - 'SHELL': '/bin/bash', 11:40:08 INFO - 'SHLVL': '1', 11:40:08 INFO - 'STYLO_FORCE_ENABLED': '1', 11:40:08 INFO - 'STYLO_THREADS': '1', 11:40:08 INFO - 'TERM': 'linux', 11:40:08 INFO - 'TMOUT': '86400', 11:40:08 INFO - 'USER': 'cltbld', 11:40:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843', 11:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:08 INFO - '_': '/tools/buildbot/bin/python'} 11:40:08 INFO - TEST-INFO | started process 7166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgF3ru3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:40:09 INFO - PID 7166 | 11:40:09 INFO - PID 7166 | (/builds/slave/test/build/application/firefox/firefox:7218): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:40:09 INFO - PID 7166 | 11:40:20 INFO - PID 7166 | RSS: Main: 167026688 11:40:20 INFO - PID 7166 | 11:40:22 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58553/tests/kraken/driver.html?testName=audio-beat-detection) 11:40:23 INFO - PID 7166 | RSS: Main: 171991040 11:40:23 INFO - PID 7166 | 11:40:26 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58553/tests/kraken/driver.html?testName=audio-dft) 11:40:26 INFO - PID 7166 | RSS: Main: 172736512 11:40:26 INFO - PID 7166 | 11:40:29 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58553/tests/kraken/driver.html?testName=audio-fft) 11:40:30 INFO - PID 7166 | RSS: Main: 167129088 11:40:30 INFO - PID 7166 | 11:40:32 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58553/tests/kraken/driver.html?testName=audio-oscillator) 11:40:33 INFO - PID 7166 | RSS: Main: 167624704 11:40:33 INFO - PID 7166 | 11:40:35 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58553/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:40:36 INFO - PID 7166 | RSS: Main: 168202240 11:40:36 INFO - PID 7166 | 11:40:39 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58553/tests/kraken/driver.html?testName=imaging-darkroom) 11:40:39 INFO - PID 7166 | RSS: Main: 170573824 11:40:39 INFO - PID 7166 | 11:40:43 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58553/tests/kraken/driver.html?testName=imaging-desaturate) 11:40:43 INFO - PID 7166 | RSS: Main: 170590208 11:40:43 INFO - PID 7166 | 11:40:46 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58553/tests/kraken/driver.html?testName=json-parse-financial) 11:40:47 INFO - PID 7166 | RSS: Main: 170848256 11:40:47 INFO - PID 7166 | 11:40:49 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58553/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:40:49 INFO - PID 7166 | RSS: Main: 171261952 11:40:49 INFO - PID 7166 | 11:40:51 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58553/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:40:51 INFO - PID 7166 | RSS: Main: 171229184 11:40:51 INFO - PID 7166 | 11:40:54 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58553/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:40:54 INFO - PID 7166 | RSS: Main: 171212800 11:40:54 INFO - PID 7166 | 11:40:57 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:40:58 INFO - PID 7166 | RSS: Main: 172359680 11:40:58 INFO - PID 7166 | 11:41:01 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:41:01 INFO - PID 7166 | RSS: Main: 172072960 11:41:01 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | Cycle 1(1): loaded http://localhost:58553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58553/tests/kraken/driver.html?testName=ai-astar) 11:41:03 INFO - PID 7166 | RSS: Main: 173416448 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | __start_tp_report 11:41:03 INFO - PID 7166 | _x_x_mozilla_page_load 11:41:03 INFO - PID 7166 | _x_x_mozilla_page_load_details 11:41:03 INFO - PID 7166 | |i|pagename|runs| 11:41:03 INFO - PID 7166 | |0;ai-astar;100;107;100;109;108;109;113;98;110;109 11:41:03 INFO - PID 7166 | |1;audio-beat-detection;145;116;143;177;117;151;143;119;171;119 11:41:03 INFO - PID 7166 | |2;audio-dft;165;161;164;168;161;162;164;162;169;161 11:41:03 INFO - PID 7166 | |3;audio-fft;75;75;72;179;74;88;76;91;98;75 11:41:03 INFO - PID 7166 | |4;audio-oscillator;80;79;127;78;79;78;130;86;79;132 11:41:03 INFO - PID 7166 | |5;imaging-gaussian-blur;113;117;117;115;114;113;114;114;115;114 11:41:03 INFO - PID 7166 | |6;imaging-darkroom;148;148;147;148;149;149;148;149;150;149 11:41:03 INFO - PID 7166 | |7;imaging-desaturate;94;94;96;95;95;94;95;94;94;94 11:41:03 INFO - PID 7166 | |8;json-parse-financial;52;53;52;52;52;52;53;52;53;53 11:41:03 INFO - PID 7166 | |9;json-stringify-tinderbox;43;38;38;37;38;37;39;37;38;37 11:41:03 INFO - PID 7166 | |10;stanford-crypto-aes;75;76;78;75;74;77;75;74;78;76 11:41:03 INFO - PID 7166 | |11;stanford-crypto-ccm;132;120;122;126;119;117;124;124;118;157 11:41:03 INFO - PID 7166 | |12;stanford-crypto-pbkdf2;178;174;178;169;169;171;171;167;173;168 11:41:03 INFO - PID 7166 | |13;stanford-crypto-sha256-iterative;67;66;71;65;64;64;65;66;69;63 11:41:03 INFO - PID 7166 | __end_tp_report 11:41:03 INFO - PID 7166 | __start_cc_report 11:41:03 INFO - PID 7166 | _x_x_mozilla_cycle_collect,921 11:41:03 INFO - PID 7166 | __end_cc_report 11:41:03 INFO - PID 7166 | __startTimestamp1504550463701__endTimestamp 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | ------- Summary: start ------- 11:41:03 INFO - PID 7166 | Number of tests: 14 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#0] ai-astar Cycles:10 Average:106.30 Median:109.00 stddev:5.08 (4.7%) stddev-sans-first:4.85 11:41:03 INFO - PID 7166 | Values: 100.0 107.0 100.0 109.0 108.0 109.0 113.0 98.0 110.0 109.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:144.00 stddev:22.31 (15.5%) stddev-sans-first:23.60 11:41:03 INFO - PID 7166 | Values: 145.0 116.0 143.0 177.0 117.0 151.0 143.0 119.0 171.0 119.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#2] audio-dft Cycles:10 Average:163.70 Median:164.00 stddev:2.91 (1.8%) stddev-sans-first:3.05 11:41:03 INFO - PID 7166 | Values: 165.0 161.0 164.0 168.0 161.0 162.0 164.0 162.0 169.0 161.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#3] audio-fft Cycles:10 Average:90.30 Median:75.50 stddev:32.39 (42.9%) stddev-sans-first:33.87 11:41:03 INFO - PID 7166 | Values: 75.0 75.0 72.0 179.0 74.0 88.0 76.0 91.0 98.0 75.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:24.20 (30.6%) stddev-sans-first:25.07 11:41:03 INFO - PID 7166 | Values: 80.0 79.0 127.0 78.0 79.0 78.0 130.0 86.0 79.0 132.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.50 stddev:1.43 (1.2%) stddev-sans-first:1.39 11:41:03 INFO - PID 7166 | Values: 113.0 117.0 117.0 115.0 114.0 113.0 114.0 114.0 115.0 114.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 11:41:03 INFO - PID 7166 | Values: 148.0 148.0 147.0 148.0 149.0 149.0 148.0 149.0 150.0 149.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 11:41:03 INFO - PID 7166 | Values: 94.0 94.0 96.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:0.52 (1.0%) stddev-sans-first:0.53 11:41:03 INFO - PID 7166 | Values: 52.0 53.0 52.0 52.0 52.0 52.0 53.0 52.0 53.0 53.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:1.81 (4.8%) stddev-sans-first:0.71 11:41:03 INFO - PID 7166 | Values: 43.0 38.0 38.0 37.0 38.0 37.0 39.0 37.0 38.0 37.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:1.48 (1.9%) stddev-sans-first:1.54 11:41:03 INFO - PID 7166 | Values: 75.0 76.0 78.0 75.0 74.0 77.0 75.0 74.0 78.0 76.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:124.00 stddev:11.79 (9.5%) stddev-sans-first:12.30 11:41:03 INFO - PID 7166 | Values: 132.0 120.0 122.0 126.0 119.0 117.0 124.0 124.0 118.0 157.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:172.00 stddev:3.91 (2.3%) stddev-sans-first:3.44 11:41:03 INFO - PID 7166 | Values: 178.0 174.0 178.0 169.0 169.0 171.0 171.0 167.0 173.0 168.0 11:41:03 INFO - PID 7166 | 11:41:03 INFO - PID 7166 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:2.45 (3.7%) stddev-sans-first:2.57 11:41:03 INFO - PID 7166 | Values: 67.0 66.0 71.0 65.0 64.0 64.0 65.0 66.0 69.0 63.0 11:41:03 INFO - PID 7166 | -------- Summary: end -------- 11:41:03 INFO - PID 7166 | 11:41:04 INFO - PID 7166 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:41:04 INFO - TEST-INFO | 7166: exit 0 11:41:04 INFO - TEST-OK | kraken | took 61760ms 11:41:04 INFO - SUITE-END | took 1173s 11:41:04 INFO - Completed test suite (00:19:34) 11:41:04 INFO - javascript benchmark 11:41:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8553.459382234541, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.0617027232383, 3379.205886616645, 3392.4232880410327, 3404.4893266010113, 3423.537822393976, 6273.592216505924, 6372.9681351593235, 6384.425401713845, 6407.807765767023, 6427.164468619073, 6272.780452684157, 6363.968180159092, 6370.968145159268, 6379.106925030499, 6383.2978372379, 5909.054551271797, 5918.970405147968, 5942.078977758454, 5962.25471816023, 5969.671668058255, 5966.075258334965, 5966.40335966402, 6000.75996960121, 6019.7893073742425, 6026.608270462428, 5962.493188079018, 6030.788922387717, 6033.245844269466, 6039.456448919596, 6115.633062016271, 5753.338366087912, 6010.519158467312, 6027.21646185993, 6033.125196846433, 6103.389661033883, 5828.708564571797, 5876.177335173079, 5890.469857712784, 5951.940480595225, 6000.159977603139, 6465.515086368494, 6489.253735820346, 6514.348565143495, 6542.018697195411, 6550.574212676161, 6745.628990405526, 6832.282610325894, 6870.484713646447, 6882.5870447772795, 7047.083879095685, 7050.224475307712, 7054.08296921397, 7059.0, 7060.258672839381, 7094.645267736644, 6473.676316184172, 6551.934480655228, 6561.770338038241, 6564.573302735307, 6568.573042752206, 7155.856882862314, 7175.497715159889, 7182.604956727326, 7260.528065675714, 7285.927140728525, 6733.966330168416, 6774.3225677431865, 6789.966050169717, 6849.178098628096, 6854.965725171442, 6879.3120687930805, 6907.309269073053, 6990.650467476606, 6997.265287144878, 6999.720011199495, 6836.179658441019, 6854.451643868479, 6869.107016087876, 6931.653417329113, 6942.652867356612, 6692.86614267712, 6837.35045170708, 6882.931170688329, 6971.895421568681, 6986.895196572055, 5371.62398632092, 5441.319835020622, 5443.700596467233, 5445.83662490126, 5490.862728431821, 4696.201645720235, 4820.734859582757, 4859.757012149378, 4877.365942427462, 4877.6829506081995, 5897.970510147507, 5960.850978725566, 5967.6419414835245, 5968.045112781934, 5980.0431930890845, 6829.351211634887, 6847.589144651337, 6854.897176542256, 6857.622830744357, 6860.828479288058, 5976.462118409364, 5979.880402391927, 6133.202683651097, 6140.478059364915, 6154.292256390483, 5944.138099975479, 5977.641341519436, 6029.698515074228, 6037.698115094227, 6039.365866584033, 6609.933900660932, 6728.024436456686, 6733.629650369178, 6807.081044059088, 6824.931750682529, 6844.1444819397575, 6910.412614927787, 6914.446844252348, 6936.549124306904, 6943.861122777415, 6640.269570347161, 6643.46852251809, 6721.764738234138, 6738.629375384205, 6787.5588086774205, 6857.5199736018, 6861.245263020964, 6943.826404339932, 6946.5137440378685, 6969.268226836312, 6925.6190909500465, 7016.473764467583, 7111.9687645290105, 7115.679794409368, 7117.6085315308155, 6582.670866456754, 6685.465162787063, 6724.831879202863, 6737.29258427858, 6741.966290168714, 6610.206775187008, 6625.734970601122, 6630.237522684953, 6632.071509988509, 6635.933640663725, 6264.68676566179, 6592.143021407186, 6594.802155935329, 6706.094677218513, 6737.191537015589, 6517.511186660925, 6797.354251346312, 6814.795556133324, 6839.213490448662, 6920.031195632515, 6411.326810684997, 6486.902696459651, 6500.512461565496, 6578.375054369827, 6670.599764014175], "value": 6322.368766555147, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.15635348354, 848.5969164646793, 881.9206271435571, 886.8004698942739, 1136.108155098248, 30242.54636180456, 30822.075337739847, 30834.99999999997, 40142.7992860036, 40446.7977660112, 40455.19089618206, 40490.0, 40516.18967620646, 40570.79714601423, 40651.59348406515, 7673.8081547961465, 7752.379809615217, 7858.842823143534, 7910.208979102102, 7923.445358824884, 7702.191269916662, 7792.844143117134, 7797.8050548736455, 7801.531908085533, 7806.648700808449, 7784.766457006271, 7847.019122609674, 7847.64685589147, 7860.606969651523, 7879.645415956295, 7774.0, 7788.987431633908, 7811.336036436853, 7818.0, 7827.412944029164, 7646.847063058708, 7707.0, 7718.845623087563, 7750.7674769756995, 7753.689852405897, 36373.272534549425, 36620.81689591562, 36670.449943250875, 36801.26397472063, 36904.261914761824, 3525.612182659931, 3553.5202747628996, 3554.5201397811225, 3558.5373901392913, 3579.6420357964257, 30258.84870575633, 30268.0, 30318.84840575805, 30345.69654303473, 30532.236694082603, 5161.7161056141895, 5196.948030519684, 5199.870003249911, 5222.190560463135, 5259.684418934838, 184389.0, 184804.0, 184844.0, 185280.99999999866, 185364.0, 45959.31061034087, 46019.76990115028, 46089.5391046092, 46125.0, 46145.76927115343, 48533.0, 48650.75674621675, 51996.0, 52684.0, 52778.20832687513, 13256.867431325756, 15441.227938603024, 15591.922040389953, 15606.687866242612, 15620.453284135228, 11101.72245693848, 12665.683357915941, 12809.935950320189, 12815.487380504861, 12831.10182287231, 13961.162330260217, 14043.1574105554, 14052.648683782987, 14071.929640351733, 14213.218272995084, 5701.771929122789, 5771.624844385102, 5778.393268706809, 5802.0, 5870.911936320958, 997.2819569909799, 1010.434156872154, 1012.7215015870695, 1027.753339198587, 1029.2743615750878, 22651.660225096635, 23037.308880733606, 23061.53876922486, 23189.652155217686, 23190.144788416885, 1107.2194103157415, 1111.488715191005, 1114.9275297105662, 1117.1230583991528, 1121.9495122719497, 12061.216020958609, 12237.015653385795, 12263.485985372978, 12301.892829645374, 12346.75306493883, 220955.0, 223248.0, 224586.0, 234576.0, 235229.0, 48837.26744098841, 48962.510374896505, 48982.265266020324, 49056.0, 49062.50937490579, 1410.189141243789, 1438.2305466575333, 1451.4049239811627, 1459.1172340733915, 1462.283481094256, 21597.35207943764, 21630.026648800525, 22278.77721222767, 22486.100555977577, 22591.43521411912, 35696.0, 35746.28507429889, 35950.28099438049, 35970.28059438849, 35990.46014309735, 182002.0, 182948.08525957286, 183063.0, 183143.0, 183788.0, 32867.0, 33943.490847636815, 33977.62156649011, 34180.82909585436, 34223.48664769981, 41476.96307592334, 41530.37704434496, 41561.0, 41914.1617167661, 42121.36817947795, 31127.68872311248, 31164.68835311618, 31515.527267090554, 31522.211944701565, 31580.368392632477, 30622.234444138183, 30697.079087627408, 30730.385392292475, 30730.98408039174, 31071.0, 33574.16064598405, 34243.82878085693, 34664.82667586646, 34690.306193875476, 37084.44373334456, 32300.19249518781, 32592.837035814668, 32719.34561308808, 32746.508802367505, 33018.0, 23074.653880192145, 23373.181938632068, 23531.647025294977, 24350.391240219134, 24459.38851528726], "value": 16830.989410394664, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.1132245020218, 574.018428487287, 577.3216470646992, 580.8257522743178, 582.1849410824844, 16325.34698612057, 21799.455013624647, 22555.210567630136, 22560.323190304298, 22581.887090564564, 18709.62580748384, 23881.880590597066, 24647.75352246477, 24650.75349246507, 24781.62827557588, 6270.24757029156, 6372.808815735535, 6454.709538070774, 6518.413342799147, 6533.673316334188, 6096.237970253718, 6301.558890877641, 6353.587016843913, 6392.3927226913365, 6395.0, 6349.682515874188, 6355.078513615546, 6360.077788720633, 6378.776742814002, 6396.168498095264, 6328.303886572497, 6341.746330146789, 6344.143540622026, 6345.302016778151, 6350.237971443434, 5883.441073098049, 5885.146653735184, 5889.263842019747, 5896.115582662592, 5935.821925342247, 26562.46875062488, 27356.179314620593, 27616.723832761818, 27761.86119069392, 27835.860820695765, 2064.5870825834895, 2113.746350437942, 2116.386247988082, 2121.4060063182333, 2121.8832964186963, 17968.101594920332, 18236.908815455972, 18535.443936681786, 19209.903950480297, 19311.130999105073, 2366.183666635008, 2426.5766012961017, 2440.792532634728, 2471.209213051824, 2480.6155045967726, 127215.36392317979, 129765.35117324447, 131390.3430482851, 137472.31263843717, 137796.6220337794, 14275.714485710436, 14361.928190359191, 14406.567802965928, 14505.31651644449, 14540.85459145395, 22320.21879234219, 22358.552828943655, 22433.10267589278, 22435.663465048037, 22459.663105053438, 9254.583543740548, 9337.953310233406, 9762.511874406251, 9782.26096692927, 9800.117989380848, 8880.200781929658, 8913.420627659181, 8933.776655583662, 8992.190702836777, 9089.727308180765, 8781.517016564023, 8848.91151088481, 9055.501947392957, 9058.184763371328, 9102.40707876119, 2712.0, 2716.8369897806197, 2736.38431352944, 2784.1786672931535, 2797.2866918935947, 978.7699890525721, 986.3785814936545, 989.8812142542797, 995.2983146881576, 1012.3875055591261, 19000.144993475325, 19202.615947681246, 19394.127264273142, 19409.902950485157, 21951.0, 1032.829583118792, 1053.1522124689698, 1057.318029870729, 1060.692399204222, 1062.033549469994, 10844.945775271073, 10948.178886583539, 11083.168762342857, 11109.944450277697, 11142.944285278521, 478882.0, 496271.0, 498563.0, 498565.0, 498927.0, 111543.44228278806, 115182.0, 115334.0, 116545.4172729131, 116770.41614791872, 1238.8761123887539, 1251.9374031298398, 1272.9299888506036, 1279.0, 1284.8843604075864, 17598.0, 17827.28690852403, 17835.554111147325, 18179.45461636153, 18305.816941830944, 56648.71675641595, 57822.42177578338, 58812.70593647005, 59158.0, 60130.0, 65742.67128664326, 65985.0, 66053.0, 66499.66750166219, 67378.32621673917, 14028.78956815669, 14353.784693229401, 14444.494442694455, 14456.0, 14458.638534036732, 21953.890230549383, 22345.217917372815, 23266.65100023535, 23369.532609347378, 23438.88280558586, 18182.0, 18301.633967320846, 18348.449546513624, 18441.907790460962, 18485.168167432766, 16447.25987330549, 16626.418075367303, 16809.831901681315, 16991.830081699023, 17075.914620426818, 18783.624327513648, 19415.0, 19420.12609432607, 19423.611527769648, 19474.415767526996, 14051.36768845425, 14316.713665726835, 14389.424423022961, 14408.85591144075, 14417.7837332438, 18236.45290641283, 18286.085695714894, 18310.26758929681, 18394.81605183931, 18398.448046558624], "value": 11799.933995279853, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.432649634161, 1349.547901453013, 1352.7497412978596, 1378.8276465441827, 1379.027785411285, 14980.250987450625, 15060.548183554483, 15764.99999999997, 15787.36850525979, 15805.999999999985, 15813.446529371475, 16042.679146417095, 16077.598060048505, 16079.517614471555, 16121.355145794154, 2798.4822807780574, 2862.627858378408, 2864.1693908766433, 2866.512692842222, 2868.311605214744, 2737.6167336572894, 2813.071686343502, 2816.310004049011, 2817.0844475545427, 2819.4220184862156, 2739.4521095780824, 2784.832910025395, 2787.2474431903374, 2789.1493094606135, 2790.2326860113426, 2662.4009597840522, 2774.4728501584736, 2783.178962206154, 2783.4293969736154, 2786.1641507547856, 2743.533599288126, 2752.3669556002233, 2762.557990721475, 2768.1003673806094, 2768.820026698258, 14618.122912625277, 14912.925435372754, 14927.104373737502, 14928.850711492854, 14951.0, 8203.384746144064, 8482.236598706084, 8486.872696909551, 8493.702720404817, 8508.957455212685, 5431.782728690848, 5666.433356664342, 5805.100209467497, 5823.708814559297, 5825.592208545403, 1910.2740958435845, 1941.9514512137168, 1951.3950675290564, 1995.8103980121866, 1999.6700544410155, 25510.489790204276, 25725.742742572522, 25759.871200644066, 25772.87113564439, 25806.48387032249, 6851.725930962705, 6875.656217189121, 6910.930890691129, 7043.429148360914, 7124.679389427488, 15722.213889305489, 15737.134457604941, 15747.0, 15747.921260393625, 15755.369785208693, 7806.4925779824125, 8078.272955434039, 8080.8383832323025, 8111.18888111196, 8111.75664730059, 6162.26052873649, 6443.6455994920725, 6668.83327916806, 6670.699818508082, 7705.8844117338285, 5107.795688172431, 5270.868228294324, 5271.499207575274, 5300.568846411525, 5393.2179833923865, 1523.4536945000832, 1537.331260901489, 1550.8836837237252, 1560.9375624974873, 1578.289769603683, 866.3459088388353, 881.581248906777, 895.6104094718818, 896.0457113174448, 896.0770406481334, 7452.925470745332, 7474.0, 7474.177840437656, 7489.588072655947, 7528.397728181732, 685.2873012067494, 705.7318219076718, 717.3543810570528, 732.0629594119536, 738.9691380524139, 4719.693219940693, 4948.430930443045, 5216.347956505437, 5352.00987817255, 5406.648567843077, 21164.36506904795, 21898.671519927215, 22112.0, 22212.0, 22246.0, 21237.36287911365, 21359.893200533897, 21681.566368672855, 22161.445963851034, 22334.441638959157, 11469.311841289382, 11608.883911160949, 11611.303321800719, 11623.6512904613, 11651.825222621668, 8187.795305117182, 8310.168983101641, 8442.113578074213, 8464.407491475538, 8499.1500849917, 18702.625947481247, 18883.150258238136, 18915.810841891405, 19168.0, 19531.31640392636, 19342.903285483484, 19370.12834422426, 19442.4167274982, 19592.0, 19624.655234440717, 11298.09615230796, 11644.475998579916, 11783.76432471363, 11786.11604129711, 11790.528378864748, 13691.315434228449, 13739.24434156131, 13871.930640347138, 13887.02790804634, 13894.166350019032, 11028.0, 11056.834147487632, 11128.109751220045, 11204.943975280072, 11384.943075284571, 10588.152947764314, 10666.146708263164, 10667.359958402521, 10788.352698838095, 10924.344539327665, 9983.650572229937, 10285.691429257135, 10297.17622590176, 10300.433476158883, 10303.896961030594, 7200.99186113955, 7358.300961408658, 7390.29792169743, 7443.181250062597, 7456.925430745623, 7584.393248540214, 7675.347595454449, 7692.576908270099, 7714.729984450517, 7714.922850771421], "value": 6669.09709836643, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.23906864077924, 512.3390825834673, 523.4556061695837, 532.8827657915258, 540.2652392745867, 6327.905081423776, 6389.105525226472, 6406.583572067812, 6437.521554201631, 6452.225732912046, 6329.272133704625, 6335.366463353663, 6347.90478142827, 6352.396522330371, 6355.682215889198, 5192.1952097424955, 5218.791248350061, 5249.606279529032, 5256.8685782855355, 5281.86795330116, 4856.684315519497, 5175.9979212056915, 5210.869728256786, 5244.213367994812, 5270.262163297141, 4991.725455099968, 4996.975015124937, 4999.625028122887, 5021.648484606079, 5026.547610715035, 4998.925016124761, 5023.974880125613, 5027.4469808321055, 5041.168207245818, 5048.469910659402, 4494.101179764028, 4501.774911254424, 4520.299353600197, 4526.140033393677, 4533.637309015271, 6260.467860231885, 6266.686665666698, 6285.120083188357, 6289.433950944437, 6291.1192433059405, 5756.280464941882, 5773.595848290622, 5791.594588378815, 5802.390748971339, 5851.1223316502865, 6647.135872336614, 6648.601083934979, 6653.70252800704, 6713.93286067138, 6745.291744366869, 4860.659753817235, 4882.853514394574, 4927.433345165316, 4927.679700819435, 4940.184869496528, 6658.267590565034, 6724.865502689919, 6926.99999999995, 6943.27095654954, 6973.3375329343635, 5021.748912554431, 5026.120428924996, 5030.170021946338, 5040.722760248148, 5084.847454576368, 5028.823991160292, 5049.5960323174795, 5060.721660308718, 5080.628230377852, 5102.897942041212, 5688.175214593944, 5708.429157084258, 5710.3433105193435, 5724.370319264877, 5783.306003279633, 6577.736890524421, 6620.205575330991, 6651.401373876278, 6687.197536295578, 6723.361280678328, 1738.608813017061, 1765.7263124215767, 1779.7897429747636, 1783.482789990914, 1803.6573051120245, 1164.895159435655, 1191.970200744971, 1211.0735287505065, 1220.7680540697238, 1238.2570457725474, 5851.180834683063, 5936.584439089309, 5960.8807823844145, 5969.552683250919, 5979.5515336349945, 6744.730210791611, 6767.661616919135, 6826.931730682729, 6862.691178896962, 6906.06768086312, 5815.244018277597, 5846.941530584725, 5857.589968702235, 5867.853303667357, 5916.585838991229, 3560.537130173113, 3584.62361452049, 3608.296382205445, 3650.9817450912574, 3667.7712966156637, 6489.156409666807, 6586.506012049115, 6599.835004124935, 6660.134182556237, 6681.632510211985, 6816.7614133505085, 6836.4530837532875, 6866.553674011273, 6964.791056268219, 7014.333638304353, 6137.723802428902, 6428.903566446414, 6512.185976753, 6526.869462610816, 6556.0825432681995, 6673.1992160941, 6690.866182676416, 6732.148659118781, 6755.392014718699, 6757.155355580552, 6666.566673166229, 6670.966645166937, 6735.0570920072205, 6786.898196527155, 6801.149856267966, 6557.606543607399, 6594.736210551716, 6620.139381880517, 6629.4696424284975, 6670.899936500859, 5003.299538064746, 5033.92449113271, 5040.697558146523, 5041.0, 5095.312132862016, 6512.6743662815725, 6552.199724160071, 6651.63416012124, 6764.4588432924265, 6853.862922741617, 6408.93591064102, 6483.935160648333, 6487.610743355413, 6539.411452969255, 6588.926526574415, 6433.903491447539, 6448.742050317935, 6509.283978762427, 6533.575317604531, 6542.476601871934, 6384.457321127757, 6433.903491447539, 6441.935580644321, 6495.870082598416, 6495.870082598416, 6620.503462240447, 6641.236257830411, 6641.933580664325, 6683.933160668331, 6712.194536655633, 6401.519886008475, 6402.647854368054, 6428.682120165448, 6480.25477070143, 6483.157189565326], "value": 5113.971375726083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [893.3567831161562, 895.6999405199258, 915.6612053540191, 919.3013309884486, 925.1211349218243, 17193.3122675093, 17716.55708607284, 17754.20106095224, 17788.199531021102, 21677.0, 19856.404307870787, 20374.796252037475, 20441.795582044175, 20625.07187176577, 20633.793662063374, 930.8650245714367, 938.4041133880002, 940.4780346907463, 941.5245301122924, 946.5976959792096, 960.4717405427023, 1006.9244806639495, 1009.6516701737888, 1011.4234886114881, 1011.4942528735633, 1063.4310643805566, 1066.5253961986919, 1066.6373433032768, 1066.802641511319, 1066.8933106689346, 1053.2679787547656, 1055.5883205549842, 1055.8785739639964, 1056.6671498477954, 1059.9947000265026, 777.1451403456153, 778.085749244641, 778.532880271838, 779.4582764978343, 779.9142094369615, 17470.0, 17681.646367072713, 17737.733933990872, 17772.377966771102, 18437.1703273353, 21038.474038149016, 21043.158273669036, 21483.67774483384, 21493.462663433384, 21500.892495537577, 17145.05702186379, 17468.737968930476, 17527.474175774747, 17552.824471755248, 17839.46481605541, 3799.2781371539595, 3822.0827001519715, 3822.3502004659276, 3822.9426558601645, 3824.6366595173413, 127272.0, 128162.0, 128676.0, 131049.34475327657, 132451.33774331163, 16531.33874645001, 16673.0, 16833.905796123214, 17034.659306813795, 17094.0, 29140.125796225722, 29985.250368740955, 31034.224144396572, 31052.68947310498, 31072.22319442032, 7641.7707468776025, 7957.5225486471, 8327.292180164754, 8688.782780430414, 8832.823343533093, 4124.1133156370925, 4163.458750362494, 4166.250074986471, 4168.270552653274, 4227.42929704486, 5001.174806156943, 5109.105906466353, 5130.897382052338, 5160.561352285023, 5178.404483484373, 1096.8245080787035, 1115.9107271418268, 1120.1990576737671, 1132.7224829916722, 1134.3420815926743, 644.2172760096505, 682.8088135322018, 695.2352412346479, 700.9579425234502, 704.7674267491716, 10930.0, 11082.722931926766, 11247.325160490233, 12021.639350819489, 12821.679458013437, 601.8224623736008, 608.248812716295, 622.2066864747483, 666.9895108910006, 681.9147606549182, 5347.3048503695045, 5410.627057118842, 5414.972925135349, 5457.072297709399, 5542.501174894279, 109773.45113274542, 110861.0, 112012.0, 113175.0, 113799.43100284612, 98242.0, 101838.49080754549, 102314.0, 103851.0, 106711.0, 88317.11682883218, 89616.10383896208, 90805.54597227104, 91161.08838911659, 94088.52955735178, 10069.798604028025, 10089.697309080446, 10195.133413659922, 10299.639512617023, 11858.76282474363, 41048.38427423651, 44165.558344416146, 47434.0, 47708.0, 49059.26411103765, 77827.0, 80888.59555702184, 81526.18473815423, 82564.58717706373, 88656.0, 17401.390951316644, 17447.912760436117, 17489.912550437166, 17511.562210944827, 17685.646287074444, 20402.795972040683, 20786.27248046311, 20907.1637134519, 21334.679979800007, 21357.67963480518, 16357.182140893146, 16870.57823554421, 16902.23939922682, 16942.491725248263, 17450.563735906704, 16811.495655129875, 17815.643687126445, 17890.5527361817, 18163.0, 18297.81702182961, 16827.495175144766, 17081.0, 18242.543936401165, 18474.261029558667, 18676.6264674703, 12671.239725616493, 13500.594982150551, 13864.0, 13934.442622294995, 14155.575332740034, 13286.867131328563, 13453.327333633475, 13678.726425471235, 14018.509352172625, 14323.212223327817], "value": 9143.915718332484, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 107.0, 100.0, 109.0, 108.0, 109.0, 113.0, 98.0, 110.0, 109.0], "value": 106.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 143.0, 177.0, 117.0, 151.0, 143.0, 119.0, 171.0, 119.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 161.0, 164.0, 168.0, 161.0, 162.0, 164.0, 162.0, 169.0, 161.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 72.0, 179.0, 74.0, 88.0, 76.0, 91.0, 98.0, 75.0], "value": 90.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 127.0, 78.0, 79.0, 78.0, 130.0, 86.0, 79.0, 132.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 117.0, 115.0, 114.0, 113.0, 114.0, 114.0, 115.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 149.0, 149.0, 148.0, 149.0, 150.0, 149.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 52.0, 52.0, 52.0, 53.0, 52.0, 53.0, 53.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 38.0, 38.0, 37.0, 38.0, 37.0, 39.0, 37.0, 38.0, 37.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 78.0, 75.0, 74.0, 77.0, 75.0, 74.0, 78.0, 76.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 120.0, 122.0, 126.0, 119.0, 117.0, 124.0, 124.0, 118.0, 157.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 174.0, 178.0, 169.0, 169.0, 171.0, 171.0, 167.0, 173.0, 168.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 71.0, 65.0, 64.0, 64.0, 65.0, 66.0, 69.0, 63.0], "value": 66.0, "unit": "score"}]}]} 11:41:04 INFO - Return code: 0 11:41:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:41:04 INFO - # TBPL SUCCESS # 11:41:04 INFO - Running post-action listener: _package_coverage_data 11:41:04 INFO - Running post-action listener: _resource_record_post_action 11:41:04 INFO - [mozharness: 2017-09-04 18:41:04.999894Z] Finished run-tests step (success) 11:41:05 INFO - Running post-run listener: _resource_record_post_run 11:41:05 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 1601536; Write bytes: 391274496; Read time: 1096; Write time: 238752 11:41:05 INFO - TinderboxPrint: CPU usage
13.1% 11:41:05 INFO - TinderboxPrint: I/O read bytes / time
1,601,536 / 1,096 11:41:05 INFO - TinderboxPrint: I/O write bytes / time
391,274,496 / 238,752 11:41:05 INFO - TinderboxPrint: CPU idle
8,250.0 (86.9%) 11:41:05 INFO - TinderboxPrint: CPU user
1,194.0 (12.6%) 11:41:05 INFO - TinderboxPrint: Swap in / out
0 / 0 11:41:05 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 106565632; Read time: 0; Write time: 114608 11:41:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:41:05 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 1601536; Write bytes: 284303360; Read time: 1096; Write time: 124112 11:41:05 INFO - Running post-run listener: _upload_blobber_files 11:41:05 INFO - Blob upload gear active. 11:41:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:41:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:41:05 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'] 11:41:05 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 11:41:06 INFO - (blobuploader) - INFO - Open directory for files ... 11:41:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:41:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:41:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:41:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:41:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:41:07 INFO - (blobuploader) - INFO - Done attempting. 11:41:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:41:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:41:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:41:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:41:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:41:13 INFO - (blobuploader) - INFO - Done attempting. 11:41:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:41:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:41:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:41:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:41:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:41:14 INFO - (blobuploader) - INFO - Done attempting. 11:41:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:41:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:41:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:41:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:41:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:41:15 INFO - (blobuploader) - INFO - Done attempting. 11:41:15 INFO - (blobuploader) - INFO - Iteration through files over. 11:41:15 INFO - Return code: 0 11:41:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:41:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:41:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a08b1063b096823b9d7f64f2f6a8915223c8e4dafc1f890c6ec58fa55343768164f87e977ef7906b013314e72f2401db206b5d7cd405d9ffd1f743c2b409a4a5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/208ed9ca89ef73fb62363ccf6cdf9f51c20c5c565d11ae25affde8dd37cd65e66bd7db9f4892510da8ab57b4c715d88900a1c1956df30d65713dead738502d03", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7391545fcb3e203635d14a2e39645717603d851ea03d297882564351bf0b4391ce465b5780d0db111378712367df3374d33c7e736d7c4b2ef534371ae186afa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e1043679e4fc3f49e5538e40cca1092d256dd3d4b700b354b3d02eb0a8149e34774eb989b102837e1bc960aed44172789db13f71588bc9ffd1076afe77ff36f"} 11:41:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:41:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:41:15 INFO - Contents: 11:41:15 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a08b1063b096823b9d7f64f2f6a8915223c8e4dafc1f890c6ec58fa55343768164f87e977ef7906b013314e72f2401db206b5d7cd405d9ffd1f743c2b409a4a5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/208ed9ca89ef73fb62363ccf6cdf9f51c20c5c565d11ae25affde8dd37cd65e66bd7db9f4892510da8ab57b4c715d88900a1c1956df30d65713dead738502d03", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7391545fcb3e203635d14a2e39645717603d851ea03d297882564351bf0b4391ce465b5780d0db111378712367df3374d33c7e736d7c4b2ef534371ae186afa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e1043679e4fc3f49e5538e40cca1092d256dd3d4b700b354b3d02eb0a8149e34774eb989b102837e1bc960aed44172789db13f71588bc9ffd1076afe77ff36f"} 11:41:15 INFO - Running post-run listener: copy_logs_to_upload_dir 11:41:15 INFO - Copying logs to upload dir... 11:41:15 INFO - mkdir: /builds/slave/test/build/upload/logs 11:41:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.522796 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-04 11:41:15.779214) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 11:41:15.784264) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a08b1063b096823b9d7f64f2f6a8915223c8e4dafc1f890c6ec58fa55343768164f87e977ef7906b013314e72f2401db206b5d7cd405d9ffd1f743c2b409a4a5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/208ed9ca89ef73fb62363ccf6cdf9f51c20c5c565d11ae25affde8dd37cd65e66bd7db9f4892510da8ab57b4c715d88900a1c1956df30d65713dead738502d03", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7391545fcb3e203635d14a2e39645717603d851ea03d297882564351bf0b4391ce465b5780d0db111378712367df3374d33c7e736d7c4b2ef534371ae186afa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e1043679e4fc3f49e5538e40cca1092d256dd3d4b700b354b3d02eb0a8149e34774eb989b102837e1bc960aed44172789db13f71588bc9ffd1076afe77ff36f"} build_url:https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/MRCASI0zQO2Hf-L6s5C12g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a08b1063b096823b9d7f64f2f6a8915223c8e4dafc1f890c6ec58fa55343768164f87e977ef7906b013314e72f2401db206b5d7cd405d9ffd1f743c2b409a4a5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/208ed9ca89ef73fb62363ccf6cdf9f51c20c5c565d11ae25affde8dd37cd65e66bd7db9f4892510da8ab57b4c715d88900a1c1956df30d65713dead738502d03", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7391545fcb3e203635d14a2e39645717603d851ea03d297882564351bf0b4391ce465b5780d0db111378712367df3374d33c7e736d7c4b2ef534371ae186afa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e1043679e4fc3f49e5538e40cca1092d256dd3d4b700b354b3d02eb0a8149e34774eb989b102837e1bc960aed44172789db13f71588bc9ffd1076afe77ff36f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 11:41:15.812836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:41:15.813158) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504549220.117925-1406113843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004547 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 11:41:15.897888) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 11:41:15.898195) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 11:41:15.898484) ========= ========= Total master_lag: 0.29 =========